0s autopkgtest [21:49:08]: starting date and time: 2026-01-23 21:49:08+0000 0s autopkgtest [21:49:08]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [21:49:08]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.z8m52h0g/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:openssl --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=openssl/3.5.4-1ubuntu1 -- lxd -r lxd-armhf-10.145.243.58 lxd-armhf-10.145.243.58:autopkgtest/ubuntu/resolute/armhf 21s autopkgtest [21:49:29]: testbed dpkg architecture: armhf 23s autopkgtest [21:49:31]: testbed apt version: 3.1.13 27s autopkgtest [21:49:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 29s autopkgtest [21:49:37]: testbed release detected to be: None 37s autopkgtest [21:49:45]: updating testbed package index (apt update) 39s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 39s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 39s Get:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease [124 kB] 39s Get:4 http://ftpmaster.internal/ubuntu resolute-security InRelease [124 kB] 40s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1021 kB] 40s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [97.3 kB] 40s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 40s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 40s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [109 kB] 40s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [827 kB] 40s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [8712 B] 40s Get:12 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.4 MB] 41s Get:13 http://ftpmaster.internal/ubuntu resolute/main Sources [1399 kB] 41s Get:14 http://ftpmaster.internal/ubuntu resolute/multiverse Sources [310 kB] 41s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1371 kB] 41s Get:16 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.3 MB] 41s Get:17 http://ftpmaster.internal/ubuntu resolute/multiverse armhf Packages [175 kB] 45s Fetched 42.5 MB in 6s (7556 kB/s) 46s Reading package lists... 52s autopkgtest [21:50:00]: upgrading testbed (apt dist-upgrade and autopurge) 53s Reading package lists... 54s Building dependency tree... 54s Reading state information... 55s Calculating upgrade... 56s The following packages will be upgraded: 56s dhcpcd-base libplymouth5 libssl3t64 libtasn1-6 openssl 56s openssl-provider-legacy plymouth plymouth-theme-ubuntu-text 56s python3-jaraco.context 56s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 56s Need to get 3689 kB of archives. 56s After this operation, 19.5 kB disk space will be freed. 56s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl-provider-legacy armhf 3.5.4-1ubuntu1 [30.7 kB] 56s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libssl3t64 armhf 3.5.4-1ubuntu1 [1902 kB] 57s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf dhcpcd-base armhf 1:10.3.0-7 [194 kB] 57s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf libtasn1-6 armhf 4.21.0-2 [38.5 kB] 57s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl armhf 3.5.4-1ubuntu1 [1214 kB] 58s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf libplymouth5 armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [147 kB] 58s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf plymouth-theme-ubuntu-text armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [9996 B] 58s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf plymouth armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [146 kB] 58s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf python3-jaraco.context all 6.0.1-2 [8198 B] 59s Fetched 3689 kB in 3s (1365 kB/s) 59s (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 ... 66485 files and directories currently installed.) 59s Preparing to unpack .../openssl-provider-legacy_3.5.4-1ubuntu1_armhf.deb ... 59s Unpacking openssl-provider-legacy (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 59s Setting up openssl-provider-legacy (3.5.4-1ubuntu1) ... 59s (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 ... 66485 files and directories currently installed.) 59s Preparing to unpack .../libssl3t64_3.5.4-1ubuntu1_armhf.deb ... 59s Unpacking libssl3t64:armhf (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 59s Setting up libssl3t64:armhf (3.5.4-1ubuntu1) ... 60s (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 ... 66485 files and directories currently installed.) 60s Preparing to unpack .../0-dhcpcd-base_1%3a10.3.0-7_armhf.deb ... 60s Unpacking dhcpcd-base (1:10.3.0-7) over (1:10.3.0-3) ... 60s Preparing to unpack .../1-libtasn1-6_4.21.0-2_armhf.deb ... 60s Unpacking libtasn1-6:armhf (4.21.0-2) over (4.20.0-2ubuntu1) ... 60s Preparing to unpack .../2-openssl_3.5.4-1ubuntu1_armhf.deb ... 60s Unpacking openssl (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 60s Preparing to unpack .../3-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 60s Unpacking libplymouth5:armhf (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 60s Preparing to unpack .../4-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 60s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 60s Preparing to unpack .../5-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 60s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 60s Preparing to unpack .../6-python3-jaraco.context_6.0.1-2_all.deb ... 60s Unpacking python3-jaraco.context (6.0.1-2) over (6.0.1-1build1) ... 60s Setting up python3-jaraco.context (6.0.1-2) ... 60s Setting up dhcpcd-base (1:10.3.0-7) ... 60s Setting up libplymouth5:armhf (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 60s Setting up libtasn1-6:armhf (4.21.0-2) ... 60s Setting up openssl (3.5.4-1ubuntu1) ... 60s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 61s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 61s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 61s Processing triggers for libc-bin (2.42-2ubuntu4) ... 61s Processing triggers for man-db (2.13.1-1) ... 63s Processing triggers for initramfs-tools (0.150ubuntu7) ... 63s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 63s Processing triggers for initramfs-tools (0.150ubuntu7) ... 65s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Solving dependencies... 66s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 68s autopkgtest [21:50:16]: rebooting testbed after setup commands that affected boot 110s autopkgtest [21:50:58]: testbed running kernel: Linux 6.8.0-87-generic #88~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 14 14:00:09 UTC 2 138s autopkgtest [21:51:26]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 146s Error: Only running operations can be connected 181s Get:1 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (dsc) [3499 B] 181s Get:2 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (tar) [1975 kB] 181s Get:3 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (asc) [833 B] 181s Get:4 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (diff) [215 kB] 181s gpgv: Signature made Wed Dec 3 14:30:09 2025 UTC 181s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 181s gpgv: Can't check signature: No public key 181s dpkg-source: warning: cannot verify inline signature for ./openssh_10.2p1-2ubuntu1.dsc: no acceptable signature found 182s autopkgtest [21:52:10]: testing package openssh version 1:10.2p1-2ubuntu1 186s autopkgtest [21:52:14]: build not needed 194s autopkgtest [21:52:22]: test regress: preparing testbed 196s Reading package lists... 196s Building dependency tree... 196s Reading state information... 197s Solving dependencies... 198s The following NEW packages will be installed: 198s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 198s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 198s libconfig-inifiles-perl libdevel-callchecker-perl 198s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 198s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 198s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 198s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 198s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 198s libio-html-perl libio-socket-ssl-perl libio-string-perl 198s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 198s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 198s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 198s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 198s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 198s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 198s python3-pyasn1 python3-pyasn1-modules python3-service-identity 198s python3-twisted python3-zope.interface wdiff 198s 0 upgraded, 60 newly installed, 0 to remove and 0 not upgraded. 198s Need to get 8094 kB of archives. 198s After this operation, 35.1 MB of additional disk space will be used. 198s Get:1 http://ftpmaster.internal/ubuntu resolute/universe armhf libtommath1 armhf 1.3.0-1 [45.8 kB] 198s Get:2 http://ftpmaster.internal/ubuntu resolute/universe armhf libtomcrypt1 armhf 1.18.2+dfsg-7build2 [381 kB] 199s Get:3 http://ftpmaster.internal/ubuntu resolute/universe armhf dropbear-bin armhf 2025.89-1 [151 kB] 199s Get:4 http://ftpmaster.internal/ubuntu resolute/universe armhf dropbear all 2025.89-1 [8666 B] 199s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf libconfig-inifiles-perl all 3.000003-4 [38.5 kB] 199s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-dirlist-perl all 0.05-3 [7286 B] 199s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-which-perl all 1.27-2 [12.5 kB] 199s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-homedir-perl all 1.006-2 [37.0 kB] 199s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-touch-perl all 0.12-2 [7498 B] 199s Get:10 http://ftpmaster.internal/ubuntu resolute/main armhf libio-string-perl all 1.08-4 [11.1 kB] 199s Get:11 http://ftpmaster.internal/ubuntu resolute/main armhf libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 199s Get:12 http://ftpmaster.internal/ubuntu resolute/main armhf libclass-xsaccessor-perl armhf 1.19-4build6 [32.4 kB] 199s Get:13 http://ftpmaster.internal/ubuntu resolute/main armhf libb-hooks-op-check-perl armhf 0.22-3build2 [9174 B] 199s Get:14 http://ftpmaster.internal/ubuntu resolute/main armhf libdynaloader-functions-perl all 0.004-2 [11.5 kB] 199s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf libdevel-callchecker-perl armhf 0.009-2 [13.8 kB] 199s Get:16 http://ftpmaster.internal/ubuntu resolute/main armhf libparams-classify-perl armhf 0.015-2build6 [18.8 kB] 199s Get:17 http://ftpmaster.internal/ubuntu resolute/main armhf libmodule-runtime-perl all 0.018-1 [15.2 kB] 199s Get:18 http://ftpmaster.internal/ubuntu resolute/main armhf libimport-into-perl all 1.002005-2 [10.7 kB] 199s Get:19 http://ftpmaster.internal/ubuntu resolute/main armhf librole-tiny-perl all 2.002004-1 [16.3 kB] 199s Get:20 http://ftpmaster.internal/ubuntu resolute/main armhf libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 199s Get:21 http://ftpmaster.internal/ubuntu resolute/main armhf libmoo-perl all 2.005005-1 [47.4 kB] 199s Get:22 http://ftpmaster.internal/ubuntu resolute/main armhf libencode-locale-perl all 1.05-3 [11.6 kB] 199s Get:23 http://ftpmaster.internal/ubuntu resolute/main armhf libtimedate-perl all 2.3300-2 [34.0 kB] 199s Get:24 http://ftpmaster.internal/ubuntu resolute/main armhf libhttp-date-perl all 6.06-1 [10.2 kB] 199s Get:25 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-listing-perl all 6.16-1 [11.3 kB] 199s Get:26 http://ftpmaster.internal/ubuntu resolute/main armhf libhtml-tagset-perl all 3.24-1 [14.1 kB] 199s Get:27 http://ftpmaster.internal/ubuntu resolute/main armhf liburi-perl all 5.34-2build1 [100 kB] 199s Get:28 http://ftpmaster.internal/ubuntu resolute/main armhf libhtml-parser-perl armhf 3.83-1build1 [83.1 kB] 199s Get:29 http://ftpmaster.internal/ubuntu resolute/main armhf libhtml-tree-perl all 5.07-3 [200 kB] 199s Get:30 http://ftpmaster.internal/ubuntu resolute/main armhf libclone-perl armhf 0.47-1 [10.0 kB] 199s Get:31 http://ftpmaster.internal/ubuntu resolute/main armhf libio-html-perl all 1.004-3 [15.9 kB] 199s Get:32 http://ftpmaster.internal/ubuntu resolute/main armhf liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 199s Get:33 http://ftpmaster.internal/ubuntu resolute/main armhf libhttp-message-perl all 7.01-1ubuntu1 [76.1 kB] 199s Get:34 http://ftpmaster.internal/ubuntu resolute/main armhf libhttp-cookies-perl all 6.11-1 [18.2 kB] 199s Get:35 http://ftpmaster.internal/ubuntu resolute/main armhf libhttp-negotiate-perl all 6.01-2 [12.4 kB] 199s Get:36 http://ftpmaster.internal/ubuntu resolute/main armhf perl-openssl-defaults armhf 7build4 [6710 B] 199s Get:37 http://ftpmaster.internal/ubuntu resolute/main armhf libnet-ssleay-perl armhf 1.94-3 [298 kB] 199s Get:38 http://ftpmaster.internal/ubuntu resolute/main armhf libio-socket-ssl-perl all 2.098-1 [205 kB] 199s Get:39 http://ftpmaster.internal/ubuntu resolute/main armhf libnet-http-perl all 6.24-1build1 [21.7 kB] 199s Get:40 http://ftpmaster.internal/ubuntu resolute/main armhf liblwp-protocol-https-perl all 6.14-1 [9040 B] 199s Get:41 http://ftpmaster.internal/ubuntu resolute/main armhf libtry-tiny-perl all 0.32-1 [21.2 kB] 199s Get:42 http://ftpmaster.internal/ubuntu resolute/main armhf libwww-robotrules-perl all 6.02-1build1 [12.4 kB] 199s Get:43 http://ftpmaster.internal/ubuntu resolute/main armhf libwww-perl all 6.81-1build1 [141 kB] 199s Get:44 http://ftpmaster.internal/ubuntu resolute/main armhf patchutils armhf 0.4.3-1 [77.8 kB] 199s Get:45 http://ftpmaster.internal/ubuntu resolute/main armhf wdiff armhf 1.2.2-9 [29.3 kB] 199s Get:46 http://ftpmaster.internal/ubuntu resolute/main armhf devscripts all 2.26.5 [1094 kB] 200s Get:47 http://ftpmaster.internal/ubuntu resolute/universe armhf libhavege2 armhf 1.9.19-13 [23.3 kB] 200s Get:48 http://ftpmaster.internal/ubuntu resolute/universe armhf putty-tools armhf 0.83-3 [579 kB] 200s Get:49 http://ftpmaster.internal/ubuntu resolute/main armhf python3-hamcrest all 2.1.0-3 [28.5 kB] 200s Get:50 http://ftpmaster.internal/ubuntu resolute/main armhf python3-pyasn1 all 0.6.2-1 [56.7 kB] 200s Get:51 http://ftpmaster.internal/ubuntu resolute/main armhf python3-pyasn1-modules all 0.4.1-2build1 [80.5 kB] 200s Get:52 http://ftpmaster.internal/ubuntu resolute/main armhf python3-service-identity all 24.2.0-1build1 [11.2 kB] 200s Get:53 http://ftpmaster.internal/ubuntu resolute/main armhf python3-automat all 25.4.16-1 [35.7 kB] 200s Get:54 http://ftpmaster.internal/ubuntu resolute/main armhf python3-constantly all 23.10.4-2build1 [14.1 kB] 200s Get:55 http://ftpmaster.internal/ubuntu resolute/main armhf python3-hyperlink all 21.0.0-6build1 [68.2 kB] 200s Get:56 http://ftpmaster.internal/ubuntu resolute/main armhf python3-incremental all 24.7.2-3build1 [18.7 kB] 200s Get:57 http://ftpmaster.internal/ubuntu resolute/main armhf python3-zope.interface armhf 8.2-1 [144 kB] 200s Get:58 http://ftpmaster.internal/ubuntu resolute/main armhf python3-twisted all 25.5.0-5 [2076 kB] 201s Get:59 http://ftpmaster.internal/ubuntu resolute/universe armhf openssh-tests armhf 1:10.2p1-2ubuntu1 [1480 kB] 201s Get:60 http://ftpmaster.internal/ubuntu resolute/universe armhf haveged armhf 1.9.19-13 [32.4 kB] 201s Fetched 8094 kB in 3s (2908 kB/s) 202s Selecting previously unselected package libtommath1:armhf. 202s (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 ... 66479 files and directories currently installed.) 202s Preparing to unpack .../00-libtommath1_1.3.0-1_armhf.deb ... 202s Unpacking libtommath1:armhf (1.3.0-1) ... 202s Selecting previously unselected package libtomcrypt1:armhf. 202s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build2_armhf.deb ... 202s Unpacking libtomcrypt1:armhf (1.18.2+dfsg-7build2) ... 202s Selecting previously unselected package dropbear-bin. 202s Preparing to unpack .../02-dropbear-bin_2025.89-1_armhf.deb ... 202s Unpacking dropbear-bin (2025.89-1) ... 202s Selecting previously unselected package dropbear. 202s Preparing to unpack .../03-dropbear_2025.89-1_all.deb ... 202s Unpacking dropbear (2025.89-1) ... 202s Selecting previously unselected package libconfig-inifiles-perl. 202s Preparing to unpack .../04-libconfig-inifiles-perl_3.000003-4_all.deb ... 202s Unpacking libconfig-inifiles-perl (3.000003-4) ... 202s Selecting previously unselected package libfile-dirlist-perl. 202s Preparing to unpack .../05-libfile-dirlist-perl_0.05-3_all.deb ... 202s Unpacking libfile-dirlist-perl (0.05-3) ... 202s Selecting previously unselected package libfile-which-perl. 202s Preparing to unpack .../06-libfile-which-perl_1.27-2_all.deb ... 202s Unpacking libfile-which-perl (1.27-2) ... 202s Selecting previously unselected package libfile-homedir-perl. 202s Preparing to unpack .../07-libfile-homedir-perl_1.006-2_all.deb ... 202s Unpacking libfile-homedir-perl (1.006-2) ... 202s Selecting previously unselected package libfile-touch-perl. 202s Preparing to unpack .../08-libfile-touch-perl_0.12-2_all.deb ... 202s Unpacking libfile-touch-perl (0.12-2) ... 202s Selecting previously unselected package libio-string-perl. 202s Preparing to unpack .../09-libio-string-perl_1.08-4_all.deb ... 202s Unpacking libio-string-perl (1.08-4) ... 202s Selecting previously unselected package libclass-method-modifiers-perl. 202s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 202s Unpacking libclass-method-modifiers-perl (2.15-1) ... 202s Selecting previously unselected package libclass-xsaccessor-perl. 202s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_armhf.deb ... 202s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 202s Selecting previously unselected package libb-hooks-op-check-perl:armhf. 202s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_armhf.deb ... 202s Unpacking libb-hooks-op-check-perl:armhf (0.22-3build2) ... 202s Selecting previously unselected package libdynaloader-functions-perl. 202s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-2_all.deb ... 202s Unpacking libdynaloader-functions-perl (0.004-2) ... 202s Selecting previously unselected package libdevel-callchecker-perl:armhf. 202s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-2_armhf.deb ... 202s Unpacking libdevel-callchecker-perl:armhf (0.009-2) ... 202s Selecting previously unselected package libparams-classify-perl:armhf. 202s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_armhf.deb ... 202s Unpacking libparams-classify-perl:armhf (0.015-2build6) ... 202s Selecting previously unselected package libmodule-runtime-perl. 202s Preparing to unpack .../16-libmodule-runtime-perl_0.018-1_all.deb ... 202s Unpacking libmodule-runtime-perl (0.018-1) ... 202s Selecting previously unselected package libimport-into-perl. 202s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 202s Unpacking libimport-into-perl (1.002005-2) ... 203s Selecting previously unselected package librole-tiny-perl. 203s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 203s Unpacking librole-tiny-perl (2.002004-1) ... 203s Selecting previously unselected package libsub-quote-perl. 203s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 203s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 203s Selecting previously unselected package libmoo-perl. 203s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 203s Unpacking libmoo-perl (2.005005-1) ... 203s Selecting previously unselected package libencode-locale-perl. 203s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 203s Unpacking libencode-locale-perl (1.05-3) ... 203s Selecting previously unselected package libtimedate-perl. 203s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 203s Unpacking libtimedate-perl (2.3300-2) ... 203s Selecting previously unselected package libhttp-date-perl. 203s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 203s Unpacking libhttp-date-perl (6.06-1) ... 203s Selecting previously unselected package libfile-listing-perl. 203s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 203s Unpacking libfile-listing-perl (6.16-1) ... 203s Selecting previously unselected package libhtml-tagset-perl. 203s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 203s Unpacking libhtml-tagset-perl (3.24-1) ... 203s Selecting previously unselected package liburi-perl. 203s Preparing to unpack .../26-liburi-perl_5.34-2build1_all.deb ... 203s Unpacking liburi-perl (5.34-2build1) ... 203s Selecting previously unselected package libhtml-parser-perl:armhf. 203s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_armhf.deb ... 203s Unpacking libhtml-parser-perl:armhf (3.83-1build1) ... 203s Selecting previously unselected package libhtml-tree-perl. 203s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 203s Unpacking libhtml-tree-perl (5.07-3) ... 203s Selecting previously unselected package libclone-perl:armhf. 203s Preparing to unpack .../29-libclone-perl_0.47-1_armhf.deb ... 203s Unpacking libclone-perl:armhf (0.47-1) ... 203s Selecting previously unselected package libio-html-perl. 203s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 203s Unpacking libio-html-perl (1.004-3) ... 203s Selecting previously unselected package liblwp-mediatypes-perl. 203s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 203s Unpacking liblwp-mediatypes-perl (6.04-2) ... 203s Selecting previously unselected package libhttp-message-perl. 203s Preparing to unpack .../32-libhttp-message-perl_7.01-1ubuntu1_all.deb ... 203s Unpacking libhttp-message-perl (7.01-1ubuntu1) ... 203s Selecting previously unselected package libhttp-cookies-perl. 203s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 203s Unpacking libhttp-cookies-perl (6.11-1) ... 203s Selecting previously unselected package libhttp-negotiate-perl. 203s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 203s Unpacking libhttp-negotiate-perl (6.01-2) ... 203s Selecting previously unselected package perl-openssl-defaults:armhf. 203s Preparing to unpack .../35-perl-openssl-defaults_7build4_armhf.deb ... 203s Unpacking perl-openssl-defaults:armhf (7build4) ... 203s Selecting previously unselected package libnet-ssleay-perl:armhf. 204s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_armhf.deb ... 204s Unpacking libnet-ssleay-perl:armhf (1.94-3) ... 204s Selecting previously unselected package libio-socket-ssl-perl. 204s Preparing to unpack .../37-libio-socket-ssl-perl_2.098-1_all.deb ... 204s Unpacking libio-socket-ssl-perl (2.098-1) ... 204s Selecting previously unselected package libnet-http-perl. 204s Preparing to unpack .../38-libnet-http-perl_6.24-1build1_all.deb ... 204s Unpacking libnet-http-perl (6.24-1build1) ... 204s Selecting previously unselected package liblwp-protocol-https-perl. 204s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 204s Unpacking liblwp-protocol-https-perl (6.14-1) ... 204s Selecting previously unselected package libtry-tiny-perl. 204s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 204s Unpacking libtry-tiny-perl (0.32-1) ... 204s Selecting previously unselected package libwww-robotrules-perl. 204s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1build1_all.deb ... 204s Unpacking libwww-robotrules-perl (6.02-1build1) ... 204s Selecting previously unselected package libwww-perl. 204s Preparing to unpack .../42-libwww-perl_6.81-1build1_all.deb ... 204s Unpacking libwww-perl (6.81-1build1) ... 204s Selecting previously unselected package patchutils. 204s Preparing to unpack .../43-patchutils_0.4.3-1_armhf.deb ... 204s Unpacking patchutils (0.4.3-1) ... 204s Selecting previously unselected package wdiff. 204s Preparing to unpack .../44-wdiff_1.2.2-9_armhf.deb ... 204s Unpacking wdiff (1.2.2-9) ... 204s Selecting previously unselected package devscripts. 204s Preparing to unpack .../45-devscripts_2.26.5_all.deb ... 204s Unpacking devscripts (2.26.5) ... 204s Selecting previously unselected package libhavege2:armhf. 204s Preparing to unpack .../46-libhavege2_1.9.19-13_armhf.deb ... 204s Unpacking libhavege2:armhf (1.9.19-13) ... 204s Selecting previously unselected package putty-tools. 204s Preparing to unpack .../47-putty-tools_0.83-3_armhf.deb ... 204s Unpacking putty-tools (0.83-3) ... 204s Selecting previously unselected package python3-hamcrest. 204s Preparing to unpack .../48-python3-hamcrest_2.1.0-3_all.deb ... 204s Unpacking python3-hamcrest (2.1.0-3) ... 204s Selecting previously unselected package python3-pyasn1. 204s Preparing to unpack .../49-python3-pyasn1_0.6.2-1_all.deb ... 204s Unpacking python3-pyasn1 (0.6.2-1) ... 204s Selecting previously unselected package python3-pyasn1-modules. 204s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-2build1_all.deb ... 204s Unpacking python3-pyasn1-modules (0.4.1-2build1) ... 205s Selecting previously unselected package python3-service-identity. 205s Preparing to unpack .../51-python3-service-identity_24.2.0-1build1_all.deb ... 205s Unpacking python3-service-identity (24.2.0-1build1) ... 205s Selecting previously unselected package python3-automat. 205s Preparing to unpack .../52-python3-automat_25.4.16-1_all.deb ... 205s Unpacking python3-automat (25.4.16-1) ... 205s Selecting previously unselected package python3-constantly. 205s Preparing to unpack .../53-python3-constantly_23.10.4-2build1_all.deb ... 205s Unpacking python3-constantly (23.10.4-2build1) ... 205s Selecting previously unselected package python3-hyperlink. 205s Preparing to unpack .../54-python3-hyperlink_21.0.0-6build1_all.deb ... 205s Unpacking python3-hyperlink (21.0.0-6build1) ... 205s Selecting previously unselected package python3-incremental. 205s Preparing to unpack .../55-python3-incremental_24.7.2-3build1_all.deb ... 205s Unpacking python3-incremental (24.7.2-3build1) ... 205s Selecting previously unselected package python3-zope.interface. 205s Preparing to unpack .../56-python3-zope.interface_8.2-1_armhf.deb ... 205s Unpacking python3-zope.interface (8.2-1) ... 205s Selecting previously unselected package python3-twisted. 205s Preparing to unpack .../57-python3-twisted_25.5.0-5_all.deb ... 205s Unpacking python3-twisted (25.5.0-5) ... 205s Selecting previously unselected package openssh-tests. 205s Preparing to unpack .../58-openssh-tests_1%3a10.2p1-2ubuntu1_armhf.deb ... 205s Unpacking openssh-tests (1:10.2p1-2ubuntu1) ... 205s Selecting previously unselected package haveged. 205s Preparing to unpack .../59-haveged_1.9.19-13_armhf.deb ... 205s Unpacking haveged (1.9.19-13) ... 205s Setting up libconfig-inifiles-perl (3.000003-4) ... 205s Setting up wdiff (1.2.2-9) ... 205s Setting up libfile-which-perl (1.27-2) ... 205s Setting up libdynaloader-functions-perl (0.004-2) ... 205s Setting up libclass-method-modifiers-perl (2.15-1) ... 205s Setting up python3-zope.interface (8.2-1) ... 206s Setting up libclone-perl:armhf (0.47-1) ... 206s Setting up libtommath1:armhf (1.3.0-1) ... 206s Setting up libhtml-tagset-perl (3.24-1) ... 206s Setting up python3-automat (25.4.16-1) ... 206s Setting up liblwp-mediatypes-perl (6.04-2) ... 206s Setting up libtry-tiny-perl (0.32-1) ... 206s Setting up perl-openssl-defaults:armhf (7build4) ... 206s Setting up libencode-locale-perl (1.05-3) ... 206s Setting up python3-hamcrest (2.1.0-3) ... 206s Setting up putty-tools (0.83-3) ... 206s Setting up libhavege2:armhf (1.9.19-13) ... 206s Setting up patchutils (0.4.3-1) ... 206s Setting up libio-string-perl (1.08-4) ... 206s Setting up python3-incremental (24.7.2-3build1) ... 206s Setting up python3-hyperlink (21.0.0-6build1) ... 207s Setting up libio-html-perl (1.004-3) ... 207s Setting up libb-hooks-op-check-perl:armhf (0.22-3build2) ... 207s Setting up libtimedate-perl (2.3300-2) ... 207s Setting up librole-tiny-perl (2.002004-1) ... 207s Setting up python3-pyasn1 (0.6.2-1) ... 207s Setting up python3-constantly (23.10.4-2build1) ... 207s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 207s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 207s Setting up libfile-dirlist-perl (0.05-3) ... 207s Setting up libfile-homedir-perl (1.006-2) ... 207s Setting up liburi-perl (5.34-2build1) ... 207s Setting up libfile-touch-perl (0.12-2) ... 207s Setting up libnet-ssleay-perl:armhf (1.94-3) ... 207s Setting up libtomcrypt1:armhf (1.18.2+dfsg-7build2) ... 207s Setting up libhttp-date-perl (6.06-1) ... 207s Setting up haveged (1.9.19-13) ... 207s apparmor_parser: Unable to replace "/usr/sbin/haveged". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 207s 207s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 208s Setting up dropbear-bin (2025.89-1) ... 208s Setting up libfile-listing-perl (6.16-1) ... 208s Setting up libnet-http-perl (6.24-1build1) ... 208s Setting up libdevel-callchecker-perl:armhf (0.009-2) ... 208s Setting up dropbear (2025.89-1) ... 208s Converting existing OpenSSH RSA host key to Dropbear format. 208s Key is a ssh-rsa key 208s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 208s 3072 SHA256:WusPL6955E+INqH2xQ77FrtqQoZPYS9BwUnkNqYeU7E /etc/dropbear/dropbear_rsa_host_key (RSA) 208s +---[RSA 3072]----+ 208s | +=o | 208s | .+o | 208s | .E | 208s | =+. | 208s | +o +S | 208s | ..o=+.=o. | 208s | .=+.Bo+o. | 208s | .o+oX=. | 208s | oBXBo. | 208s +----[SHA256]-----+ 208s Converting existing OpenSSH ECDSA host key to Dropbear format. 208s Key is a ecdsa-sha2-nistp256 key 208s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 208s 256 SHA256:EWyW8P7rqjL9dxl7VOy1p0YA69WG5T/UDTF2tIBUC3w /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 208s +---[ECDSA 256]---+ 208s | .o..oooo+oo| 208s | .=..o.E=o.| 208s | oo o.* +o| 208s | . .. + +.*| 208s | S. . o.+o| 208s | .. . oo+| 208s | . . * .o| 208s | o . ..+ + | 208s | o.oo+o. o | 208s +----[SHA256]-----+ 208s Converting existing OpenSSH ED25519 host key to Dropbear format. 208s Key is a ssh-ed25519 key 208s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 208s 256 SHA256:IYDuYRQ4szVd1ldFbLeQ+vgpPJ5+mWKvNGioBs3x0c0 /etc/dropbear/dropbear_ed25519_host_key (ED25519) 208s +--[ED25519 256]--+ 208s | ..+..o. ..++ | 208s |+ = .o . . oo .| 208s | B . . o.o .....| 208s |. + . o o E . | 208s | o .o o S o | 208s | .. o .. .. . | 208s | . . o.o. + | 208s | .. . .*o* | 208s | .. +=B. | 208s +----[SHA256]-----+ 208s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 209s Setting up python3-pyasn1-modules (0.4.1-2build1) ... 209s Setting up python3-service-identity (24.2.0-1build1) ... 209s Setting up libwww-robotrules-perl (6.02-1build1) ... 209s Setting up libhtml-parser-perl:armhf (3.83-1build1) ... 209s Setting up libio-socket-ssl-perl (2.098-1) ... 209s Setting up libhttp-message-perl (7.01-1ubuntu1) ... 209s Setting up libhttp-negotiate-perl (6.01-2) ... 209s Setting up libhttp-cookies-perl (6.11-1) ... 209s Setting up libhtml-tree-perl (5.07-3) ... 209s Setting up libparams-classify-perl:armhf (0.015-2build6) ... 209s Setting up libmodule-runtime-perl (0.018-1) ... 209s Setting up python3-twisted (25.5.0-5) ... 213s Setting up libimport-into-perl (1.002005-2) ... 213s Setting up libmoo-perl (2.005005-1) ... 213s Setting up openssh-tests (1:10.2p1-2ubuntu1) ... 213s Setting up liblwp-protocol-https-perl (6.14-1) ... 213s Setting up libwww-perl (6.81-1build1) ... 213s Setting up devscripts (2.26.5) ... 213s Processing triggers for install-info (7.2-5) ... 213s Processing triggers for libc-bin (2.42-2ubuntu4) ... 213s Processing triggers for man-db (2.13.1-1) ... 226s autopkgtest [21:52:54]: test regress: [----------------------- 229s I: annotate-output 2.26.5 229s I: prefix='%H:%M:%S.%N ' 229s 21:52:57.044549359 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp 229s 21:52:57.084162703 O: make: Entering directory '/tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress' 229s 21:52:57.087758974 E: + /usr/bin/ssh -Q key 229s 21:52:57.088671702 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/valgrind-out 229s 21:52:57.092460215 E: + grep -q ^ssh-rsa 229s 21:52:57.094636994 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.100408684 O: ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv ; \ 229s 21:52:57.100268283 E: + ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2.prv 229s 21:52:57.105918012 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv 229s 21:52:57.107527185 O: tr '\n' '\r' /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2_cr.prv ; \ 229s 21:52:57.111526500 E: + tr \n \r 229s 21:52:57.113095314 O: ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv ; \ 229s 21:52:57.116422063 E: + ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2_cr.prv 229s 21:52:57.119013605 O: awk '{print $0 "\r"}' /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2.prv > /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2_crnl.prv ; \ 229s 21:52:57.122930159 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv 229s 21:52:57.124356491 O: ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv ; \ 229s 21:52:57.128705609 E: + awk {print $0 "\r"} /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2.prv 229s 21:52:57.129460496 O: fi 229s 21:52:57.133480490 E: + ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_ssh2_crnl.prv 229s 21:52:57.134372938 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.138142571 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv 229s 21:52:57.139613184 O: cat /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv > /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t2.out ; \ 229s 21:52:57.143158814 E: + /usr/bin/ssh -Q key 229s 21:52:57.144769108 O: chmod 600 /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t2.out ; \ 229s 21:52:57.147783254 E: + grep -q ^ssh-rsa 229s 21:52:57.151586127 O: ssh-keygen -yf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t2.out | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub ; \ 229s 21:52:57.152867659 E: + cat /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.prv 229s 21:52:57.157378898 O: fi 229s 21:52:57.158252185 E: + chmod 600 /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t2.out 229s 21:52:57.162134459 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.162943586 E: + ssh-keygen -yf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t2.out 229s 21:52:57.167021781 O: ssh-keygen -ef /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub >/tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t3.out ; \ 229s 21:52:57.167796708 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub 229s 21:52:57.171650501 O: ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t3.out | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub ; \ 229s 21:52:57.172614910 E: + /usr/bin/ssh -Q key 229s 21:52:57.176757706 O: fi 229s 21:52:57.177477472 E: + grep -q ^ssh-rsa 229s 21:52:57.181900070 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.182302474 E: + ssh-keygen -ef /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub 229s 21:52:57.186492550 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub |\ 229s 21:52:57.187077635 E: + ssh-keygen -if /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t3.out 229s 21:52:57.191322072 O: awk '{print $2}' | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t4.ok ; \ 229s 21:52:57.194318458 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub 229s 21:52:57.195879991 O: fi 229s 21:52:57.200857155 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.202172166 E: + /usr/bin/ssh -Q key 229s 21:52:57.206002959 O: ssh-keygen -Bf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub |\ 229s 21:52:57.207726254 E: + grep -q ^ssh-rsa 229s 21:52:57.213395463 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub 229s 21:52:57.213456464 O: awk '{print $2}' | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t5.ok ; \ 229s 21:52:57.218495267 E: + awk {print $2} 229s 21:52:57.218952471 O: fi 229s 21:52:57.223537351 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t4.ok 229s 21:52:57.225853171 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.228455034 E: + /usr/bin/ssh -Q key 229s 21:52:57.231857423 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t7.out ; \ 229s 21:52:57.232907232 E: + grep -q ^ssh-rsa 229s 21:52:57.236906947 O: fi 229s 21:52:57.237644634 E: + ssh-keygen -Bf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub 229s 21:52:57.242622237 E: + awk {print $2} 229s 21:52:57.246840633 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t5.ok 229s 21:52:57.251334112 E: + /usr/bin/ssh -Q key 229s 21:52:57.255891712 E: + grep -q ^ssh-rsa 229s 21:52:57.260577352 E: + ssh-keygen -q -t rsa -N -f /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t7.out 229s 21:52:57.576145688 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.577342938 E: + + /usr/bin/sshgrep -q ^ssh-rsa -Q 229s 21:52:57.580939730 O: ssh-keygen -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t7.out > /dev/null ; \ 229s 21:52:57.582259901 E: key 229s 21:52:57.585504729 O: ssh-keygen -Bf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t7.out > /dev/null ; \ 229s 21:52:57.589298802 E: + ssh-keygen -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t7.out 229s 21:52:57.590045049 O: fi 229s 21:52:57.595288014 E: + ssh-keygen -Bf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t7.out 229s 21:52:57.596640626 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 229s 21:52:57.601273026 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t9.out 229s 21:52:57.609580818 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 229s 21:52:57.614112977 O: ssh-keygen -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t9.out > /dev/null 229s 21:52:57.623498539 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 229s 21:52:57.628186019 O: ssh-keygen -Bf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t9.out > /dev/null 229s 21:52:57.635849246 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t10.out 229s 21:52:57.645227127 O: ssh-keygen -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t10.out > /dev/null 229s 21:52:57.653920002 O: ssh-keygen -Bf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t10.out > /dev/null 229s 21:52:57.659285089 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 229s 21:52:57.660233777 E: + /usr/bin/ssh -Q key 229s 21:52:57.663683327 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub |\ 229s 21:52:57.664909018 E: + grep -q ^ssh-rsa 229s 21:52:57.670514986 E: + ssh-keygen -E sha256 -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/rsa_openssh.pub 229s 21:52:57.670361585 O: awk '{print $2}' | diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t11.ok ; \ 229s 21:52:57.675920433 E: + awk {print $2} 229s 21:52:57.675920633 O: fi 229s 21:52:57.681092518 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t12.out 229s 21:52:57.681405321 E: + diff - /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t11.ok 229s 21:52:57.685676918 O: ssh-keygen -lf /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/t12.out.pub | grep test-comment-1234 >/dev/null 229s 21:52:57.690071156 E: run test connect.sh ... 233s 21:53:01.777098962 E: run test proxy-connect.sh ... 233s 21:53:01.776562758 O: ok simple connect 234s 21:53:02.182340558 O: plain username comp=no 234s 21:53:02.495550516 O: plain username comp=yes 234s 21:53:02.807659624 O: username with style 235s 21:53:03.111297619 O: ok proxy connect 235s 21:53:03.111686383 E: run test sshfp-connect.sh ... 235s 21:53:03.459662523 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 235s 21:53:03.460217168 E: run test connect-privsep.sh ... 240s 21:53:08.275533937 O: ok proxy connect with privsep 240s 21:53:08.279514492 E: run test connect-uri.sh ... 240s 21:53:08.815031904 O: uri connect: no trailing slash 241s 21:53:09.110373709 O: uri connect: trailing slash 241s 21:53:09.411507486 O: uri connect: with path name 241s 21:53:09.467510932 O: ok uri connect 241s 21:53:09.469309708 E: run test proto-version.sh ... 242s 21:53:09.964212568 O: ok sshd version with different protocol combinations 242s 21:53:09.964647371 E: run test proto-mismatch.sh ... 242s 21:53:10.456192243 O: ok protocol version mismatch 242s 21:53:10.456957729 E: run test exit-status.sh ... 242s 21:53:10.797737131 O: test remote exit status: status 0 248s 21:53:16.394755994 O: test remote exit status: status 1 254s 21:53:22.018967503 O: test remote exit status: status 4 259s 21:53:27.701056244 O: test remote exit status: status 5 265s 21:53:33.380533691 O: test remote exit status: status 44 271s 21:53:39.088913037 E: run test exit-status-signal.sh ... 271s 21:53:39.089088799 O: ok remote exit status 272s 21:53:40.423170091 O: ok exit status on signal 272s 21:53:40.423340212 E: run test envpass.sh ... 272s 21:53:40.831491177 O: test environment passing: pass env, don't accept 273s 21:53:41.156506337 O: test environment passing: setenv, don't accept 273s 21:53:41.492367911 O: test environment passing: don't pass env, accept 273s 21:53:41.821854309 O: test environment passing: pass single env, accept single env 274s 21:53:42.134402640 O: test environment passing: pass multiple env, accept multiple env 274s 21:53:42.439728228 O: test environment passing: setenv, accept 274s 21:53:42.736109978 O: test environment passing: setenv, first match wins 275s 21:53:43.033647738 O: test environment passing: server setenv wins 275s 21:53:43.321105490 O: test environment passing: server setenv wins 275s 21:53:43.616823154 O: ok environment passing 275s 21:53:43.617051116 E: run test transfer.sh ... 278s 21:53:46.486316198 O: ok transfer data 278s 21:53:46.488404096 E: run test banner.sh ... 278s 21:53:46.879093473 O: test banner: missing banner file 279s 21:53:47.187295768 O: test banner: size 0 279s 21:53:47.487623514 O: test banner: size 10 279s 21:53:47.826873361 O: test banner: size 100 280s 21:53:48.617382795 O: test banner: size 1000 280s 21:53:48.932401390 O: test banner: size 10000 281s 21:53:49.248092992 O: test banner: size 100000 281s 21:53:49.543372535 O: test banner: suppress banner (-q) 281s 21:53:49.832949308 O: ok banner 281s 21:53:49.833504433 E: run test rekey.sh ... 282s 21:53:50.211423899 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 282s 21:53:50.564161186 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 283s 21:53:50.953256510 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 283s 21:53:51.345612663 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 283s 21:53:51.871967630 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 284s 21:53:52.944025892 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 286s 21:53:54.025479799 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 287s 21:53:55.118254927 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 287s 21:53:55.468400073 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 287s 21:53:55.892126903 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 288s 21:53:56.389864742 O: client rekey KexAlgorithms=curve25519-sha256 288s 21:53:56.739921768 O: client rekey KexAlgorithms=sntrup761x25519-sha512 289s 21:53:57.210405089 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 289s 21:53:57.683181270 O: client rekey KexAlgorithms=mlkem768x25519-sha256 290s 21:53:58.046302491 O: client rekey Ciphers=3des-cbc 290s 21:53:58.426931106 O: client rekey Ciphers=aes128-cbc 290s 21:53:58.776139365 O: client rekey Ciphers=aes192-cbc 291s 21:53:59.129934665 O: client rekey Ciphers=aes256-cbc 291s 21:53:59.484905975 O: client rekey Ciphers=aes128-ctr 291s 21:53:59.852058632 O: client rekey Ciphers=aes192-ctr 292s 21:54:00.200246923 O: client rekey Ciphers=aes256-ctr 292s 21:54:00.548264333 O: client rekey Ciphers=aes128-gcm@openssh.com 298s 21:54:06.299087835 O: client rekey Ciphers=aes256-gcm@openssh.com 305s 21:54:13.020611106 O: client rekey Ciphers=chacha20-poly1305@openssh.com 312s 21:54:20.580214686 O: client rekey MACs=hmac-sha1 312s 21:54:20.928264306 O: client rekey MACs=hmac-sha1-96 313s 21:54:21.276534848 O: client rekey MACs=hmac-sha2-256 313s 21:54:21.623247257 O: client rekey MACs=hmac-sha2-512 314s 21:54:21.979190987 O: client rekey MACs=hmac-md5 314s 21:54:22.325343111 O: client rekey MACs=hmac-md5-96 314s 21:54:22.684079705 O: client rekey MACs=umac-64@openssh.com 315s 21:54:23.033292296 O: client rekey MACs=umac-128@openssh.com 315s 21:54:23.392914979 O: client rekey MACs=hmac-sha1-etm@openssh.com 315s 21:54:23.740828359 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 316s 21:54:24.086842003 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 316s 21:54:24.444425308 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 316s 21:54:24.786910081 O: client rekey MACs=hmac-md5-etm@openssh.com 317s 21:54:25.130821306 O: client rekey MACs=hmac-md5-96-etm@openssh.com 317s 21:54:25.476681389 O: client rekey MACs=umac-64-etm@openssh.com 317s 21:54:25.819310364 O: client rekey MACs=umac-128-etm@openssh.com 318s 21:54:26.169480885 O: client rekeylimit 16 318s 21:54:26.535696067 O: client rekeylimit 1k 318s 21:54:26.884662858 O: client rekeylimit 128k 319s 21:54:27.756346409 O: client rekeylimit 256k 320s 21:54:28.170856257 O: client rekeylimit default 5 335s 21:54:43.496029937 O: client rekeylimit default 10 355s 21:55:03.820872579 O: client rekeylimit default 5 no data 371s 21:55:19.123440732 O: client rekeylimit default 10 no data 391s 21:55:39.435935686 O: server rekeylimit 16 391s 21:55:39.791120509 O: server rekeylimit 1k 392s 21:55:40.142873460 O: server rekeylimit 128k 392s 21:55:40.508462126 O: server rekeylimit 256k 392s 21:55:40.916663722 O: server rekeylimit default 5 no data 408s 21:55:56.234198742 O: server rekeylimit default 10 no data 428s 21:56:16.626689734 O: rekeylimit parsing: bytes 428s 21:56:16.839932201 O: rekeylimit parsing: time 429s 21:56:17.075615109 O: ok rekey 429s 21:56:17.077386678 E: run test dhgex.sh ... 429s 21:56:17.547407164 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 429s 21:56:17.917884180 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 430s 21:56:18.290776896 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 430s 21:56:18.643564080 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 431s 21:56:19.012163815 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 431s 21:56:19.780213258 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 432s 21:56:20.154953024 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 432s 21:56:20.532803611 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 432s 21:56:20.925162882 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 433s 21:56:21.385476519 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 433s 21:56:21.842283857 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 434s 21:56:22.303424629 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 434s 21:56:22.787683662 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 435s 21:56:23.256044926 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 435s 21:56:23.721644957 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 436s 21:56:24.188555601 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 436s 21:56:24.657309078 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 437s 21:56:25.142375514 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 437s 21:56:25.627844667 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 438s 21:56:26.231129927 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 438s 21:56:26.822432807 O: ok dhgex 438s 21:56:26.824536311 E: run test stderr-data.sh ... 439s 21:56:27.254913726 O: test stderr data transfer: () 446s 21:56:33.964702517 O: test stderr data transfer: (-n) 452s 21:56:40.677979676 O: ok stderr data transfer 452s 21:56:40.679414185 E: run test stderr-after-eof.sh ... 455s 21:56:43.469091202 E: run test broken-pipe.sh ... 455s 21:56:43.468812709 O: ok stderr data after eof 455s 21:56:43.920664047 E: run test try-ciphers.sh ... 455s 21:56:43.920448357 O: ok broken pipe test 456s 21:56:44.362026655 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 456s 21:56:44.713961977 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 457s 21:56:45.065781609 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 457s 21:56:45.422689028 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 457s 21:56:45.773717763 O: test try ciphers: cipher 3des-cbc mac hmac-md5 458s 21:56:46.133510552 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 458s 21:56:46.483809069 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 458s 21:56:46.832189533 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 459s 21:56:47.200200330 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 459s 21:56:47.561550636 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 459s 21:56:47.924642265 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 460s 21:56:48.297641752 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 460s 21:56:48.651367147 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 461s 21:56:49.018974328 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 461s 21:56:49.377899710 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 461s 21:56:49.749859958 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 462s 21:56:50.135024350 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 462s 21:56:50.493151030 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 462s 21:56:50.866738931 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 463s 21:56:51.242617406 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 463s 21:56:51.639744569 O: test try ciphers: cipher aes128-cbc mac hmac-md5 464s 21:56:52.018642099 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 464s 21:56:52.408570972 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 464s 21:56:52.783617934 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 465s 21:56:53.172920246 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 465s 21:56:53.555829119 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 465s 21:56:53.920639168 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 466s 21:56:54.689561618 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 467s 21:56:55.064358835 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 467s 21:56:55.422497440 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 467s 21:56:55.773882603 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 468s 21:56:56.158346210 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 468s 21:56:56.543121898 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 468s 21:56:56.910287828 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 469s 21:56:57.279809133 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 469s 21:56:57.652709832 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 470s 21:56:58.024965179 O: test try ciphers: cipher aes192-cbc mac hmac-md5 470s 21:56:58.394070074 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 470s 21:56:58.758417624 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 471s 21:56:59.125919995 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 471s 21:56:59.498730042 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 471s 21:56:59.865245151 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 472s 21:57:00.225932889 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 472s 21:57:00.582965446 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 472s 21:57:00.920259231 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 473s 21:57:01.278909886 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 473s 21:57:01.632025076 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 474s 21:57:01.987241125 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 474s 21:57:02.356927714 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 474s 21:57:02.715951559 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 475s 21:57:03.077922379 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 475s 21:57:03.438934415 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 475s 21:57:03.808674142 O: test try ciphers: cipher aes256-cbc mac hmac-md5 476s 21:57:04.181101224 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 476s 21:57:04.546202470 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 476s 21:57:04.913515500 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 477s 21:57:05.275517582 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 477s 21:57:05.647264196 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 478s 21:57:06.022287803 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 478s 21:57:06.391258384 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 478s 21:57:06.753817544 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 479s 21:57:07.114740581 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 479s 21:57:07.472237201 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 479s 21:57:07.836581582 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 480s 21:57:08.209386146 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 481s 21:57:08.966644437 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 481s 21:57:09.340467217 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 481s 21:57:09.705768557 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 482s 21:57:10.057940160 O: test try ciphers: cipher aes128-ctr mac hmac-md5 482s 21:57:10.409679284 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 482s 21:57:10.768566302 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 483s 21:57:11.132225734 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 483s 21:57:11.492923333 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 483s 21:57:11.851904853 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 484s 21:57:12.211155052 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 484s 21:57:12.573570988 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 485s 21:57:12.925679846 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 485s 21:57:13.281482778 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 485s 21:57:13.641801594 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 486s 21:57:13.996453748 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 486s 21:57:14.368632213 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 486s 21:57:14.729268022 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 487s 21:57:15.091546141 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 487s 21:57:15.447881569 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 487s 21:57:15.811322885 O: test try ciphers: cipher aes192-ctr mac hmac-md5 488s 21:57:16.170025452 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 488s 21:57:16.553072253 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 488s 21:57:16.945415404 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 489s 21:57:17.338547091 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 489s 21:57:17.714163085 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 491s 21:57:18.098835612 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 491s 21:57:18.468228695 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 491s 21:57:18.826289576 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 491s 21:57:19.184573616 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 491s 21:57:19.546721183 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 491s 21:57:19.897528908 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 492s 21:57:20.261811877 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 492s 21:57:20.642143539 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 493s 21:57:21.017754342 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 493s 21:57:21.389342338 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 493s 21:57:21.752356621 O: test try ciphers: cipher aes256-ctr mac hmac-md5 494s 21:57:22.116923007 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 494s 21:57:22.490724322 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 494s 21:57:22.855735474 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 495s 21:57:23.220673089 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 495s 21:57:23.583026377 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 495s 21:57:23.935112114 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 496s 21:57:24.296100241 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 496s 21:57:24.659723484 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 497s 21:57:25.026491670 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 497s 21:57:25.386165711 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 497s 21:57:25.749250628 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 498s 21:57:26.129280992 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 498s 21:57:26.509835044 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 499s 21:57:27.146925878 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 499s 21:57:27.680664491 O: ok try ciphers 499s 21:57:27.681647816 E: run test yes-head.sh ... 503s 21:57:31.117076685 O: ok yes pipe head 503s 21:57:31.118138253 E: run test login-timeout.sh ... 519s 21:57:47.108209602 O: ok connect after login grace timeout 519s 21:57:47.109263888 E: run test agent.sh ... 532s 21:57:59.997722857 O: ok simple agent test 532s 21:57:59.997880104 E: run test agent-getpeereid.sh ... 532s 21:58:00.454726406 E: run test agent-timeout.sh ... 532s 21:58:00.455564082 O: ok disallow agent attach from other uid 553s 21:58:21.012795820 O: ok agent timeout test 553s 21:58:21.013418726 E: run test agent-ptrace.sh ... 553s 21:58:21.409789524 E: run test agent-subprocess.sh ... 553s 21:58:21.409967372 O: skipped (gdb not found) 563s 21:58:31.860871387 O: ok agent subprocess 563s 21:58:31.862018275 E: run test keyscan.sh ... 565s 21:58:33.529202567 O: ok keyscan 565s 21:58:33.529479058 E: run test keygen-change.sh ... 573s 21:58:41.006178985 O: ok change passphrase for key 573s 21:58:41.007154386 E: run test keygen-comment.sh ... 581s 21:58:49.367131118 O: ok Comment extraction from private key 581s 21:58:49.367959552 E: run test keygen-convert.sh ... 585s 21:58:53.910162736 O: ok convert keys 585s 21:58:53.910470308 E: run test keygen-knownhosts.sh ... 586s 21:58:54.580291959 O: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts updated. 586s 21:58:54.586263722 O: Original contents retained as /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts.old 586s 21:58:54.613888168 O: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts updated. 586s 21:58:54.619501316 O: Original contents retained as /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts.old 586s 21:58:54.627861177 O: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts updated. 586s 21:58:54.633522847 O: Original contents retained as /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts.old 586s 21:58:54.653459340 O: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts updated. 586s 21:58:54.659400222 O: Original contents retained as /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hosts.old 586s 21:58:54.703334971 O: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hashed updated. 586s 21:58:54.709441540 O: Original contents retained as /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/kh.hashed.old 586s 21:58:54.712451583 E: run test keygen-moduli.sh ... 586s 21:58:54.715566110 O: ok ssh-keygen known_hosts 593s 21:59:01.503616941 O: ok keygen moduli 593s 21:59:01.504793949 E: run test keygen-sshfp.sh ... 593s 21:59:01.945010485 O: ok keygen-sshfp 593s 21:59:01.945787516 E: run test key-options.sh ... 594s 21:59:02.328195902 O: key option command="echo bar" 594s 21:59:02.650521341 O: key option no-pty,command="echo bar" 595s 21:59:02.966115549 O: key option pty default 595s 21:59:03.289476696 O: key option pty no-pty 595s 21:59:03.601904120 O: key option pty restrict 595s 21:59:03.918943770 O: key option pty restrict,pty 596s 21:59:04.249397229 O: key option environment 597s 21:59:05.052593738 O: key option from="127.0.0.1" 598s 21:59:06.019373502 O: key option from="127.0.0.0/8" 598s 21:59:06.676971811 O: key option expiry-time default 599s 21:59:07.002652160 O: key option expiry-time invalid 599s 21:59:07.373960642 O: key option expiry-time expired 599s 21:59:07.768039077 O: key option expiry-time valid-64b 600s 21:59:08.111211465 O: ok key options 600s 21:59:08.111900533 E: run test scp.sh ... 601s 21:59:09.049724311 O: scp: scp mode: simple copy local file to local file 601s 21:59:09.073680589 O: scp: scp mode: simple copy local file to remote file 601s 21:59:09.095351376 O: scp: scp mode: simple copy remote file to local file 601s 21:59:09.117039763 O: scp: scp mode: copy local file to remote file in place 601s 21:59:09.145270691 O: scp: scp mode: copy remote file to local file in place 601s 21:59:09.170849714 O: scp: scp mode: copy local file to remote file clobber 601s 21:59:09.196694547 O: -rw-rw-r-- 1 ubuntu ubuntu 723016 Jan 23 21:59 /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/copy 601s 21:59:09.202678426 O: -rw-rw-r-- 1 ubuntu ubuntu 723016 Jan 23 21:59 /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/data 601s 21:59:09.208777110 O: scp: scp mode: copy remote file to local file clobber 601s 21:59:09.233956357 O: scp: scp mode: simple copy local file to remote dir 601s 21:59:09.261044800 O: scp: scp mode: simple copy local file to local dir 601s 21:59:09.284844071 O: scp: scp mode: simple copy remote file to local dir 601s 21:59:09.311354091 O: scp: scp mode: recursive local dir to remote dir 601s 21:59:09.366926273 O: scp: scp mode: recursive local dir to local dir 601s 21:59:09.422780426 O: scp: scp mode: recursive remote dir to local dir 601s 21:59:09.479796706 O: scp: scp mode: unmatched glob file local->remote 601s 21:59:09.503739863 O: scp: scp mode: unmatched glob file remote->local 601s 21:59:09.515330326 O: scp: scp mode: unmatched glob dir recursive local->remote 601s 21:59:09.551267683 O: scp: scp mode: unmatched glob dir recursive remote->local 601s 21:59:09.564151838 O: scp: scp mode: shell metacharacters 601s 21:59:09.586638977 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 601s 21:59:09.662062833 O: scp: scp mode: disallow bad server #0 601s 21:59:09.711401565 O: scp: scp mode: disallow bad server #1 601s 21:59:09.762771499 O: scp: scp mode: disallow bad server #2 601s 21:59:09.815834820 O: scp: scp mode: disallow bad server #3 601s 21:59:09.870381361 O: scp: scp mode: disallow bad server #4 601s 21:59:09.925891260 O: scp: scp mode: disallow bad server #5 602s 21:59:09.976163750 O: scp: scp mode: disallow bad server #6 602s 21:59:10.027941779 O: scp: scp mode: disallow bad server #7 602s 21:59:10.082126263 O: scp: scp mode: detect non-directory target 602s 21:59:10.098883892 E: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/copy2: Not a directory 602s 21:59:10.104911613 O: scp: sftp mode: simple copy local file to local file 602s 21:59:10.126402791 O: scp: sftp mode: simple copy local file to remote file 602s 21:59:10.154954491 O: scp: sftp mode: simple copy remote file to local file 602s 21:59:10.178966850 O: scp: sftp mode: copy local file to remote file in place 602s 21:59:10.208141735 O: scp: sftp mode: copy remote file to local file in place 602s 21:59:10.238069050 O: scp: sftp mode: copy local file to remote file clobber 602s 21:59:10.268956563 O: -rw-rw-r-- 1 ubuntu ubuntu 723016 Jan 23 21:59 /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/copy 602s 21:59:10.275171571 O: -rw-rw-r-- 1 ubuntu ubuntu 723016 Jan 23 21:59 /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/data 602s 21:59:10.281775675 O: scp: sftp mode: copy remote file to local file clobber 602s 21:59:10.305379777 O: scp: sftp mode: simple copy local file to remote dir 602s 21:59:10.336506060 O: scp: sftp mode: simple copy local file to local dir 602s 21:59:10.362492458 O: scp: sftp mode: simple copy remote file to local dir 602s 21:59:10.392257446 O: scp: sftp mode: recursive local dir to remote dir 602s 21:59:10.455115396 O: scp: sftp mode: recursive local dir to local dir 602s 21:59:10.509212156 O: scp: sftp mode: recursive remote dir to local dir 602s 21:59:10.580508083 O: scp: sftp mode: unmatched glob file local->remote 602s 21:59:10.604644447 O: scp: sftp mode: unmatched glob file remote->local 602s 21:59:10.621639846 O: scp: sftp mode: unmatched glob dir recursive local->remote 602s 21:59:10.664135583 O: scp: sftp mode: unmatched glob dir recursive remote->local 602s 21:59:10.688613160 O: scp: sftp mode: shell metacharacters 602s 21:59:10.712554196 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 602s 21:59:10.797242418 O: scp: sftp mode: disallow bad server #0 602s 21:59:10.872609547 O: scp: sftp mode: disallow bad server #1 603s 21:59:10.952222486 O: scp: sftp mode: disallow bad server #2 603s 21:59:11.027769581 O: scp: sftp mode: disallow bad server #3 603s 21:59:11.105357715 O: scp: sftp mode: disallow bad server #4 603s 21:59:11.176287944 O: scp: sftp mode: disallow bad server #5 603s 21:59:11.254679151 O: scp: sftp mode: disallow bad server #6 603s 21:59:11.329464893 O: scp: sftp mode: disallow bad server #7 603s 21:59:11.404670212 O: scp: sftp mode: detect non-directory target 603s 21:59:11.415648610 E: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/copy2: Not a directory 603s 21:59:11.428291114 O: ok scp 603s 21:59:11.429506243 E: run test scp3.sh ... 603s 21:59:11.836149900 O: scp3: scp mode: simple copy remote file to remote file 604s 21:59:12.227573179 O: scp3: scp mode: simple copy remote file to remote dir 604s 21:59:12.603670960 O: scp3: scp mode: recursive remote dir to remote dir 605s 21:59:13.037258308 O: scp3: scp mode: detect non-directory target 605s 21:59:13.704518853 O: scp3: sftp mode: simple copy remote file to remote file 605s 21:59:13.731288918 O: scp3: sftp mode: simple copy remote file to remote dir 605s 21:59:13.761695048 O: scp3: sftp mode: recursive remote dir to remote dir 605s 21:59:13.832126169 O: scp3: sftp mode: detect non-directory target 605s 21:59:13.846612586 E: scp: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/copy2: destination is not a directory 605s 21:59:13.852798832 E: scp: /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/copy2: destination is not a directory 605s 21:59:13.863341211 O: ok scp3 605s 21:59:13.863323931 E: run test scp-uri.sh ... 606s 21:59:14.255693327 O: scp-uri: scp mode: simple copy local file to remote file 606s 21:59:14.274278666 O: scp-uri: scp mode: simple copy remote file to local file 606s 21:59:14.293314902 O: scp-uri: scp mode: simple copy local file to remote dir 606s 21:59:14.317870198 O: scp-uri: scp mode: simple copy remote file to local dir 606s 21:59:14.343256886 O: scp-uri: scp mode: recursive local dir to remote dir 606s 21:59:14.371512569 O: scp-uri: scp mode: recursive remote dir to local dir 606s 21:59:14.399206229 O: scp-uri: sftp mode: simple copy local file to remote file 606s 21:59:14.421370390 O: scp-uri: sftp mode: simple copy remote file to local file 606s 21:59:14.442719278 O: scp-uri: sftp mode: simple copy local file to remote dir 606s 21:59:14.469457581 O: scp-uri: sftp mode: simple copy remote file to local dir 606s 21:59:14.495275847 O: scp-uri: sftp mode: recursive local dir to remote dir 606s 21:59:14.524814420 O: scp-uri: sftp mode: recursive remote dir to local dir 606s 21:59:14.563403913 O: ok scp-uri 606s 21:59:14.564647763 E: run test sftp.sh ... 606s 21:59:14.944453974 O: test basic sftp put/get: buffer_size 5 num_requests 1 617s 21:59:25.694258073 O: test basic sftp put/get: buffer_size 5 num_requests 2 623s 21:59:31.909826845 O: test basic sftp put/get: buffer_size 5 num_requests 10 628s 21:59:36.744376626 O: test basic sftp put/get: buffer_size 1000 num_requests 1 628s 21:59:36.834882326 O: test basic sftp put/get: buffer_size 1000 num_requests 2 628s 21:59:36.890202226 O: test basic sftp put/get: buffer_size 1000 num_requests 10 628s 21:59:36.936546898 O: test basic sftp put/get: buffer_size 32000 num_requests 1 629s 21:59:36.958414344 O: test basic sftp put/get: buffer_size 32000 num_requests 2 629s 21:59:36.980543880 O: test basic sftp put/get: buffer_size 32000 num_requests 10 629s 21:59:37.001412327 O: test basic sftp put/get: buffer_size 64000 num_requests 1 629s 21:59:37.023124165 O: test basic sftp put/get: buffer_size 64000 num_requests 2 629s 21:59:37.043090777 O: test basic sftp put/get: buffer_size 64000 num_requests 10 629s 21:59:37.068835091 O: ok basic sftp put/get 629s 21:59:37.069738726 E: run test sftp-chroot.sh ... 629s 21:59:37.633689669 O: test sftp in chroot: get 630s 21:59:37.971114222 O: test sftp in chroot: match 630s 21:59:38.495470613 O: ok sftp in chroot 630s 21:59:38.510535794 E: run test sftp-cmds.sh ... 630s 21:59:38.903027056 O: sftp commands: lls 630s 21:59:38.919573094 O: sftp commands: lls w/path 630s 21:59:38.935379944 O: sftp commands: ls 630s 21:59:38.946675100 O: sftp commands: shell 631s 21:59:38.957189626 O: sftp commands: pwd 631s 21:59:38.964366102 O: sftp commands: lpwd 631s 21:59:38.971910793 O: sftp commands: quit 631s 21:59:38.979107551 O: sftp commands: help 631s 21:59:38.987970893 O: sftp commands: get 631s 21:59:39.005504609 O: sftp commands: get quoted 631s 21:59:39.023497983 O: sftp commands: get filename with quotes 631s 21:59:39.046919645 O: sftp commands: get filename with spaces 631s 21:59:39.067267869 O: sftp commands: get filename with glob metacharacters 631s 21:59:39.085173719 O: sftp commands: get to directory 631s 21:59:39.101824841 O: sftp commands: glob get to directory 631s 21:59:39.314951453 O: sftp commands: get to local dir 631s 21:59:39.331305243 O: sftp commands: glob get to local dir 631s 21:59:39.496327842 O: sftp commands: put 631s 21:59:39.514271013 O: sftp commands: put filename with quotes 631s 21:59:39.532068099 O: sftp commands: put filename with spaces 631s 21:59:39.560950172 O: sftp commands: put to directory 631s 21:59:39.578199036 O: sftp commands: glob put to directory 631s 21:59:39.623880557 O: sftp commands: put to local dir 631s 21:59:39.639977937 O: sftp commands: glob put to local dir 631s 21:59:39.763445854 O: sftp commands: rename 631s 21:59:39.773513842 O: sftp commands: rename directory 631s 21:59:39.780710760 O: sftp commands: ln 631s 21:59:39.790076080 O: sftp commands: ln -s 631s 21:59:39.797517007 O: sftp commands: cp 631s 21:59:39.811956924 O: sftp commands: mkdir 631s 21:59:39.820177000 O: sftp commands: chdir 631s 21:59:39.826729373 O: sftp commands: rmdir 631s 21:59:39.834017334 O: sftp commands: lmkdir 631s 21:59:39.842040243 O: sftp commands: lchdir 631s 21:59:39.864708756 O: ok sftp commands 631s 21:59:39.865675793 E: run test sftp-badcmds.sh ... 632s 21:59:40.254036586 O: sftp invalid commands: get nonexistent 632s 21:59:40.262385747 O: sftp invalid commands: glob get to nonexistent directory 632s 21:59:40.285453275 O: sftp invalid commands: put nonexistent 632s 21:59:40.293785476 O: sftp invalid commands: glob put to nonexistent directory 632s 21:59:40.302070875 O: sftp invalid commands: rename nonexistent 632s 21:59:40.317195257 O: sftp invalid commands: rename target exists (directory) 632s 21:59:40.334173870 O: sftp invalid commands: glob put files to local file 632s 21:59:40.346955962 O: ok sftp invalid commands 632s 21:59:40.347768313 E: run test sftp-batch.sh ... 633s 21:59:41.241475017 O: sftp batchfile: good commands 633s 21:59:41.255648801 O: sftp batchfile: bad commands 633s 21:59:41.272737618 O: sftp batchfile: comments and blanks 633s 21:59:41.284164057 O: sftp batchfile: junk command 633s 21:59:41.293018798 O: ok sftp batchfile 633s 21:59:41.294222524 E: run test sftp-glob.sh ... 633s 21:59:41.687759891 O: sftp glob: file glob 633s 21:59:41.705668899 O: sftp glob: dir glob 633s 21:59:41.718639198 O: sftp glob: quoted glob 633s 21:59:41.734277959 O: sftp glob: escaped glob 633s 21:59:41.749550066 O: sftp glob: escaped quote 633s 21:59:41.761733295 O: sftp glob: quoted quote 633s 21:59:41.774747595 O: sftp glob: single-quoted quote 633s 21:59:41.787306238 O: sftp glob: escaped space 633s 21:59:41.799850200 O: sftp glob: quoted space 633s 21:59:41.812319079 O: sftp glob: escaped slash 633s 21:59:41.824075891 O: sftp glob: quoted slash 633s 21:59:41.837421884 O: sftp glob: escaped slash at EOL 633s 21:59:41.849497028 O: sftp glob: quoted slash at EOL 633s 21:59:41.861713658 O: sftp glob: escaped slash+quote 633s 21:59:41.874301381 O: sftp glob: quoted slash+quote 633s 21:59:41.888197836 O: ok sftp glob 633s 21:59:41.889489485 E: run test sftp-perm.sh ... 634s 21:59:42.267292395 O: sftp permissions: read-only upload 634s 21:59:42.301262859 O: sftp permissions: read-only setstat 634s 21:59:42.341344598 O: sftp permissions: read-only rm 634s 21:59:42.379239173 O: sftp permissions: read-only mkdir 634s 21:59:42.408868350 O: sftp permissions: read-only rmdir 634s 21:59:42.446283547 O: sftp permissions: read-only posix-rename 634s 21:59:42.489620011 O: sftp permissions: read-only oldrename 634s 21:59:42.526778877 O: sftp permissions: read-only symlink 634s 21:59:42.561463809 O: sftp permissions: read-only hardlink 634s 21:59:42.598525512 O: sftp permissions: explicit open 634s 21:59:42.663489406 O: sftp permissions: explicit read 634s 21:59:42.727495783 O: sftp permissions: explicit write 634s 21:59:42.798596993 O: sftp permissions: explicit lstat 634s 21:59:42.865055384 O: sftp permissions: explicit opendir 634s 21:59:42.926217852 O: sftp permissions: explicit readdir 635s 21:59:42.984241040 O: sftp permissions: explicit setstat 635s 21:59:43.062899257 O: sftp permissions: explicit remove 635s 21:59:43.137579961 O: sftp permissions: explicit mkdir 635s 21:59:43.192322620 O: sftp permissions: explicit rmdir 635s 21:59:43.268441019 O: sftp permissions: explicit rename 635s 21:59:43.344295007 O: sftp permissions: explicit symlink 635s 21:59:43.415680385 O: sftp permissions: explicit hardlink 635s 21:59:43.490085798 O: sftp permissions: explicit statvfs 635s 21:59:43.533404499 O: ok sftp permissions 635s 21:59:43.534767431 E: run test sftp-resume.sh ... 635s 21:59:43.948539097 O: sftp resume: put 636s 21:59:44.154122853 O: sftp resume: get 636s 21:59:44.365809801 O: ok sftp resume 636s 21:59:44.367884040 E: run test sftp-uri.sh ... 637s 21:59:44.969271233 O: sftp-uri: non-interactive fetch to local file 637s 21:59:45.275731437 O: sftp-uri: non-interactive fetch to local dir 637s 21:59:45.597723034 O: sftp-uri: put to remote directory (trailing slash) 637s 21:59:45.921704027 O: sftp-uri: put to remote directory (no slash) 638s 21:59:46.275049011 O: ok sftp-uri 638s 21:59:46.275258379 E: run test reconfigure.sh ... 652s 22:00:00.156375620 O: ok simple connect after reconfigure 652s 22:00:00.157051966 E: run test dynamic-forward.sh ... 652s 22:00:00.745944009 O: test -D forwarding 654s 22:00:02.523586780 O: test -R forwarding 656s 22:00:04.412568414 O: PermitRemoteOpen=any 658s 22:00:06.287353440 O: PermitRemoteOpen=none 658s 22:00:06.718635886 O: PermitRemoteOpen=explicit 661s 22:00:09.372708780 O: PermitRemoteOpen=disallowed 661s 22:00:09.850919754 O: ok dynamic forwarding 661s 22:00:09.852203562 E: run test forwarding.sh ... 669s 22:00:17.161903912 O: ok local and remote forwarding 669s 22:00:17.163765181 E: run test multiplex.sh ... 670s 22:00:18.752825291 O: test connection multiplexing: setenv 670s 22:00:18.783810830 O: test connection multiplexing: envpass 670s 22:00:18.812746294 O: test connection multiplexing: transfer 670s 22:00:18.891372106 O: test connection multiplexing: transfer -Oproxy 671s 22:00:19.063605036 O: test connection multiplexing: forward 673s 22:00:21.159362076 O: test connection multiplexing: status 0 () 678s 22:00:26.224108107 O: test connection multiplexing: status 0 (-Oproxy) 683s 22:00:31.676191507 O: test connection multiplexing: status 1 () 688s 22:00:36.738796274 O: test connection multiplexing: status 1 (-Oproxy) 693s 22:00:41.804048583 O: test connection multiplexing: status 4 () 698s 22:00:46.865535812 O: test connection multiplexing: status 4 (-Oproxy) 703s 22:00:51.934784347 O: test connection multiplexing: status 5 () 709s 22:00:56.999391670 O: test connection multiplexing: status 5 (-Oproxy) 714s 22:01:02.063805831 O: test connection multiplexing: status 44 () 719s 22:01:07.126678277 O: test connection multiplexing: status 44 (-Oproxy) 724s 22:01:12.191379576 O: test connection multiplexing: cmd check 724s 22:01:12.210126304 O: test connection multiplexing: cmd forward local (TCP) 725s 22:01:13.560143842 O: test connection multiplexing: cmd forward remote (TCP) 726s 22:01:14.906591390 O: test connection multiplexing: cmd forward local (UNIX) 728s 22:01:16.006158413 O: test connection multiplexing: cmd forward remote (UNIX) 729s 22:01:17.107484485 O: test connection multiplexing: cmd exit 729s 22:01:17.128429564 O: test connection multiplexing: cmd stop 740s 22:01:28.238835266 O: ok connection multiplexing 740s 22:01:28.239632494 E: run test reexec.sh ... 740s 22:01:28.637421951 O: test config passing 741s 22:01:29.169623083 O: test reexec fallback 741s 22:01:29.174517209 E: ln: failed to create hard link '/usr/sbin/sshd' => '/tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/sshd': Permission denied 741s 22:01:29.721871041 O: ok reexec tests 741s 22:01:29.723078402 E: run test brokenkeys.sh ... 742s 22:01:30.665659445 O: ok broken keys 742s 22:01:30.667191017 E: run test sshcfgparse.sh ... 743s 22:01:31.062562400 O: reparse minimal config 743s 22:01:31.100502923 O: ssh -W opts 743s 22:01:31.213457783 O: user first match 743s 22:01:31.289907769 O: pubkeyacceptedalgorithms 743s 22:01:31.422732181 O: agentforwarding 744s 22:01:31.521916375 O: command line override 744s 22:01:31.583352773 O: ok ssh config parse 744s 22:01:31.584704618 E: run test cfgparse.sh ... 744s 22:01:31.984084365 O: reparse minimal config 744s 22:01:32.151468940 O: reparse regress config 744s 22:01:32.321440202 O: listenaddress order 744s 22:01:32.519078998 O: maxstartups idempotent 744s 22:01:32.611575683 O: ok sshd config parse 744s 22:01:32.612003057 E: run test cfgmatch.sh ... 755s 22:01:43.340756614 O: ok sshd_config match 755s 22:01:43.342373148 E: run test cfgmatchlisten.sh ... 767s 22:01:55.688348410 O: ok sshd_config matchlisten 767s 22:01:55.689626652 E: run test percent.sh ... 768s 22:01:56.092097784 O: percent expansions matchexec percent 771s 22:01:59.934864364 O: percent expansions localcommand percent 775s 22:02:03.782672340 O: percent expansions remotecommand percent 776s 22:02:04.084924246 O: percent expansions controlpath percent 776s 22:02:04.390244004 O: percent expansions identityagent percent 776s 22:02:04.681071450 O: percent expansions forwardagent percent 777s 22:02:04.974327494 O: percent expansions localforward percent 777s 22:02:05.275544349 O: percent expansions remoteforward percent 777s 22:02:05.586682086 O: percent expansions revokedhostkeys percent 777s 22:02:05.892408647 O: percent expansions user percent 778s 22:02:06.128039719 O: percent expansions setenv percent 778s 22:02:06.691719304 O: percent expansions userknownhostsfile percent 782s 22:02:10.184522181 O: percent expansions controlpath dollar 782s 22:02:10.211188886 O: percent expansions identityagent dollar 782s 22:02:10.238044716 O: percent expansions forwardagent dollar 782s 22:02:10.263396017 O: percent expansions localforward dollar 782s 22:02:10.290798625 O: percent expansions remoteforward dollar 782s 22:02:10.316770507 O: percent expansions user dollar 782s 22:02:10.352756553 O: percent expansions setenv dollar 782s 22:02:10.399615912 O: percent expansions userknownhostsfile dollar 782s 22:02:10.694616391 O: percent expansions controlpath tilde 782s 22:02:10.744587370 O: percent expansions identityagent tilde 782s 22:02:10.794199658 O: percent expansions forwardagent tilde 782s 22:02:10.845538562 O: percent expansions user-l noexpand 782s 22:02:10.871162112 O: percent expansions user-at noexpand 782s 22:02:10.897428443 O: percent expansions user-l noenv 782s 22:02:10.913734772 O: percent expansions user-at noenv 782s 22:02:10.931445585 O: percent expansions user-l badchar 782s 22:02:10.947132734 O: percent expansions user-at badchar 783s 22:02:10.963379260 O: percent expansions user control-literal 783s 22:02:11.004444191 O: ok percent expansions 783s 22:02:11.005516266 E: run test addrmatch.sh ... 783s 22:02:11.386569920 O: test first entry for user 192.168.0.1 somehost 783s 22:02:11.455938726 O: test negative match for user 192.168.30.1 somehost 783s 22:02:11.526520411 O: test no match for user 19.0.0.1 somehost 783s 22:02:11.596178825 O: test list middle for user 10.255.255.254 somehost 783s 22:02:11.667547856 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 783s 22:02:11.749717235 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 783s 22:02:11.809589653 O: test localaddress for user 19.0.0.1 somehost 783s 22:02:11.881439659 O: test localport for user 19.0.0.1 somehost 783s 22:02:11.951978023 O: test bare IP6 address for user ::1 somehost.example.com 784s 22:02:12.024781658 O: test deny IPv6 for user ::2 somehost.example.com 784s 22:02:12.095229776 O: test IP6 negated for user ::3 somehost 784s 22:02:12.166530082 O: test IP6 no match for user ::4 somehost 784s 22:02:12.241026691 O: test IP6 network for user 2000::1 somehost 784s 22:02:12.310900630 O: test IP6 network for user 2001::1 somehost 784s 22:02:12.381108580 O: test IP6 localaddress for user ::5 somehost 784s 22:02:12.451592860 O: test IP6 localport for user ::5 somehost 784s 22:02:12.521258952 O: test invalid Match address 10.0.1.0/8 784s 22:02:12.540504975 O: test invalid Match localaddress 10.0.1.0/8 784s 22:02:12.558895409 O: test invalid Match address 10.0.0.1/24 784s 22:02:12.577173160 O: test invalid Match localaddress 10.0.0.1/24 784s 22:02:12.595787162 O: test invalid Match address 2000:aa:bb:01::/56 784s 22:02:12.613825865 O: test invalid Match localaddress 2000:aa:bb:01::/56 784s 22:02:12.636998535 O: ok address match 784s 22:02:12.637957166 E: run test localcommand.sh ... 785s 22:02:13.015670339 O: test localcommand: proto localcommand 785s 22:02:13.324585837 O: ok localcommand 785s 22:02:13.325646671 E: run test forcecommand.sh ... 787s 22:02:15.099714053 E: Connection closed. 787s 22:02:15.105884052 E: Connection closed 787s 22:02:15.717066551 E: Connection closed. 787s 22:02:15.723429676 E: Connection closed 788s 22:02:16.054865917 O: ok forced command 788s 22:02:16.056151519 E: run test portnum.sh ... 788s 22:02:16.422900647 O: port number parsing: invalid port 0 788s 22:02:16.440309127 O: port number parsing: invalid port 65536 788s 22:02:16.457865973 O: port number parsing: invalid port 131073 788s 22:02:16.475228972 O: port number parsing: invalid port 2000blah 788s 22:02:16.494210223 O: port number parsing: invalid port blah2000 788s 22:02:16.512641896 O: port number parsing: valid port 1 788s 22:02:16.828225537 O: port number parsing: valid port 22 789s 22:02:17.140568709 O: port number parsing: valid port 2222 789s 22:02:17.472135013 O: port number parsing: valid port 22222 789s 22:02:17.788862879 O: port number parsing: valid port 65535 790s 22:02:18.106496572 O: ok port number parsing 790s 22:02:18.107443962 E: run test keytype.sh ... 790s 22:02:18.514074906 O: keygen ed25519, 512 bits 790s 22:02:18.534593566 O: keygen ed25519-sk, n/a bits 790s 22:02:18.561420747 O: keygen ecdsa, 256 bits 790s 22:02:18.580633365 O: keygen ecdsa, 384 bits 790s 22:02:18.605410521 O: keygen ecdsa, 521 bits 790s 22:02:18.635501288 O: keygen ecdsa-sk, n/a bits 790s 22:02:18.662159304 O: keygen rsa, 2048 bits 791s 22:02:19.113767849 O: keygen rsa, 3072 bits 794s 22:02:22.435173939 O: userkey ed25519-512, hostkey ed25519-512 794s 22:02:22.588602847 O: userkey ed25519-512, hostkey ed25519-512 794s 22:02:22.742842061 O: userkey ed25519-512, hostkey ed25519-512 794s 22:02:22.915237497 O: userkey ed25519-sk, hostkey ed25519-sk 795s 22:02:23.079412026 O: userkey ed25519-sk, hostkey ed25519-sk 795s 22:02:23.246284879 O: userkey ed25519-sk, hostkey ed25519-sk 795s 22:02:23.430597489 O: userkey ecdsa-256, hostkey ecdsa-256 795s 22:02:23.595367835 O: userkey ecdsa-256, hostkey ecdsa-256 795s 22:02:23.751683270 O: userkey ecdsa-256, hostkey ecdsa-256 795s 22:02:23.929755721 O: userkey ecdsa-384, hostkey ecdsa-384 796s 22:02:24.177650757 O: userkey ecdsa-384, hostkey ecdsa-384 796s 22:02:24.422090120 O: userkey ecdsa-384, hostkey ecdsa-384 796s 22:02:24.692008497 O: userkey ecdsa-521, hostkey ecdsa-521 797s 22:02:25.090441893 O: userkey ecdsa-521, hostkey ecdsa-521 797s 22:02:25.496136070 O: userkey ecdsa-521, hostkey ecdsa-521 797s 22:02:25.912054294 O: userkey ecdsa-sk, hostkey ecdsa-sk 798s 22:02:26.083870970 O: userkey ecdsa-sk, hostkey ecdsa-sk 798s 22:02:26.253808063 O: userkey ecdsa-sk, hostkey ecdsa-sk 798s 22:02:26.448661110 O: userkey rsa-2048, hostkey rsa-2048 798s 22:02:26.609972329 O: userkey rsa-2048, hostkey rsa-2048 798s 22:02:26.776885086 O: userkey rsa-2048, hostkey rsa-2048 799s 22:02:26.960347971 O: userkey rsa-3072, hostkey rsa-3072 799s 22:02:27.148096266 O: userkey rsa-3072, hostkey rsa-3072 799s 22:02:27.328886499 O: userkey rsa-3072, hostkey rsa-3072 799s 22:02:27.515780127 E: run test kextype.sh ... 799s 22:02:27.514818416 O: ok login with different key types 799s 22:02:27.919680980 O: kex diffie-hellman-group1-sha1 801s 22:02:29.561858725 O: kex diffie-hellman-group14-sha1 802s 22:02:30.821082603 O: kex diffie-hellman-group14-sha256 804s 22:02:32.079744410 O: kex diffie-hellman-group16-sha512 805s 22:02:33.544139586 O: kex diffie-hellman-group18-sha512 807s 22:02:35.756594061 O: kex diffie-hellman-group-exchange-sha1 810s 22:02:37.987014461 O: kex diffie-hellman-group-exchange-sha256 812s 22:02:40.212329171 O: kex ecdh-sha2-nistp256 813s 22:02:41.407548114 O: kex ecdh-sha2-nistp384 814s 22:02:42.661131879 O: kex ecdh-sha2-nistp521 816s 22:02:44.056370146 O: kex curve25519-sha256 817s 22:02:45.289071147 O: kex curve25519-sha256@libssh.org 818s 22:02:46.506773754 O: kex sntrup761x25519-sha512 820s 22:02:48.314711375 O: kex sntrup761x25519-sha512@openssh.com 821s 22:02:49.680858437 O: kex mlkem768x25519-sha256 822s 22:02:50.886837029 O: ok login with different key exchange algorithms 822s 22:02:50.887432847 E: run test cert-hostkey.sh ... 823s 22:02:51.729884473 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/host_ca_key.pub 823s 22:02:51.735604530 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/host_ca_key2.pub 823s 22:02:51.741069060 O: certified host keys: sign host ed25519 cert 823s 22:02:51.746859640 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ed25519.pub 823s 22:02:51.764573429 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ed25519-cert.pub 823s 22:02:51.776116788 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 823s 22:02:51.794879810 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 823s 22:02:51.816344676 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 823s 22:02:51.827683028 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 823s 22:02:51.840286179 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp256.pub 823s 22:02:51.862220179 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 823s 22:02:51.873543011 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 823s 22:02:51.894018446 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp384.pub 823s 22:02:51.924435790 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 823s 22:02:51.935392690 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 824s 22:02:51.966784304 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp521.pub 824s 22:02:52.007059714 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 824s 22:02:52.017343752 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 824s 22:02:52.037178047 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 824s 22:02:52.059914712 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 824s 22:02:52.070475639 O: certified host keys: sign host rsa cert 825s 22:02:53.745338334 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_rsa.pub 825s 22:02:53.766625633 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_rsa-cert.pub 825s 22:02:53.779230423 O: certified host keys: sign host rsa-sha2-256 cert 826s 22:02:54.268176116 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-256.pub 826s 22:02:54.299176955 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-256-cert.pub 826s 22:02:54.310898557 O: certified host keys: sign host rsa-sha2-512 cert 828s 22:02:56.004465194 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-512.pub 828s 22:02:56.036432741 O: Revoking from /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-512-cert.pub 828s 22:02:56.048481713 O: certified host keys: host ed25519 cert connect 828s 22:02:56.055464129 O: certified host keys: ed25519 basic connect expect success yes 828s 22:02:56.362547768 O: certified host keys: ed25519 empty KRL expect success yes 828s 22:02:56.665559843 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 828s 22:02:56.872492071 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 829s 22:02:57.080579092 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 829s 22:02:57.289271248 O: certified host keys: ed25519 empty plaintext revocation expect success yes 829s 22:02:57.591421725 O: certified host keys: ed25519 plain key plaintext revocation expect success no 829s 22:02:57.799840953 O: certified host keys: ed25519 cert plaintext revocation expect success no 830s 22:02:58.013911354 O: certified host keys: ed25519 CA plaintext revocation expect success no 830s 22:02:58.230110294 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 830s 22:02:58.237328637 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 830s 22:02:58.550667050 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 830s 22:02:58.869013817 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 831s 22:02:59.087687551 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 831s 22:02:59.310592691 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 831s 22:02:59.527909259 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 831s 22:02:59.851480657 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 832s 22:03:00.072645781 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 832s 22:03:00.297461652 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 832s 22:03:00.514598568 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 832s 22:03:00.522820061 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 832s 22:03:00.835937007 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 833s 22:03:01.144503049 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 833s 22:03:01.357344066 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 833s 22:03:01.570530373 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 833s 22:03:01.789829268 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 834s 22:03:02.103446736 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 834s 22:03:02.320392392 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 834s 22:03:02.545066405 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 834s 22:03:02.759254097 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 834s 22:03:02.767208581 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 835s 22:03:03.138976902 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 835s 22:03:03.510248081 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 835s 22:03:03.775164361 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 836s 22:03:04.510559124 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 836s 22:03:04.777001761 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 837s 22:03:05.149714808 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 837s 22:03:05.418374746 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 837s 22:03:05.696914785 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 838s 22:03:05.960573649 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 838s 22:03:05.968643376 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 838s 22:03:06.444724924 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 838s 22:03:06.916770267 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 839s 22:03:07.254708064 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 839s 22:03:07.588290887 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 839s 22:03:07.924528190 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 840s 22:03:08.403050463 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 840s 22:03:08.750086165 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 841s 22:03:09.113226355 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 841s 22:03:09.446848077 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 841s 22:03:09.454323705 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 841s 22:03:09.763079630 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 842s 22:03:10.066637075 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 842s 22:03:10.283030460 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 842s 22:03:10.499371923 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 842s 22:03:10.715608263 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 843s 22:03:11.019519431 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 843s 22:03:11.247038947 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 843s 22:03:11.495604383 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 843s 22:03:11.714733044 O: certified host keys: host rsa cert connect 843s 22:03:11.722445879 O: certified host keys: rsa basic connect expect success yes 844s 22:03:12.025751578 O: certified host keys: rsa empty KRL expect success yes 845s 22:03:12.335053891 O: certified host keys: rsa KRL w/ plain key revoked expect success no 845s 22:03:12.561517569 O: certified host keys: rsa KRL w/ cert revoked expect success no 845s 22:03:12.788323296 O: certified host keys: rsa KRL w/ CA revoked expect success no 845s 22:03:13.014507445 O: certified host keys: rsa empty plaintext revocation expect success yes 845s 22:03:13.323667904 O: certified host keys: rsa plain key plaintext revocation expect success no 845s 22:03:13.560105797 O: certified host keys: rsa cert plaintext revocation expect success no 845s 22:03:13.821420604 O: certified host keys: rsa CA plaintext revocation expect success no 846s 22:03:14.050613196 O: certified host keys: host rsa-sha2-256 cert connect 846s 22:03:14.057366321 O: certified host keys: rsa-sha2-256 basic connect expect success yes 846s 22:03:14.348739031 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 846s 22:03:14.644214546 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 846s 22:03:14.866626406 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 847s 22:03:15.079905308 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 847s 22:03:15.291679399 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 847s 22:03:15.582493884 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 847s 22:03:15.803857066 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 848s 22:03:16.050354447 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 848s 22:03:16.259243805 O: certified host keys: host rsa-sha2-512 cert connect 848s 22:03:16.266292698 O: certified host keys: rsa-sha2-512 basic connect expect success yes 848s 22:03:16.551356160 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 848s 22:03:16.842718452 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 849s 22:03:17.053254339 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 849s 22:03:17.266338017 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 849s 22:03:17.481625402 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 849s 22:03:17.773882352 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 850s 22:03:17.996447117 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 850s 22:03:18.250871276 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 850s 22:03:18.857321581 O: certified host keys: host ed25519 revoked cert 851s 22:03:19.105039255 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 851s 22:03:19.360030984 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 851s 22:03:19.607351042 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 851s 22:03:19.905780240 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 852s 22:03:20.273942413 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 852s 22:03:20.531150441 O: certified host keys: host rsa revoked cert 852s 22:03:20.794278047 O: certified host keys: host rsa-sha2-256 revoked cert 853s 22:03:21.048881875 O: certified host keys: host rsa-sha2-512 revoked cert 853s 22:03:21.310629352 O: certified host keys: host ed25519 revoked cert 853s 22:03:21.524834318 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 853s 22:03:21.747185849 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 854s 22:03:21.963849209 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 854s 22:03:22.228610409 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 854s 22:03:22.569074044 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 854s 22:03:22.795075919 O: certified host keys: host rsa revoked cert 855s 22:03:23.024725102 O: certified host keys: host rsa-sha2-256 revoked cert 855s 22:03:23.240796271 O: certified host keys: host rsa-sha2-512 revoked cert 872s 22:03:40.923568463 O: certified host keys: host ed25519 cert downgrade to raw key 873s 22:03:41.537178485 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 874s 22:03:42.171471510 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 874s 22:03:42.792218762 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 875s 22:03:43.553954112 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 876s 22:03:44.541549250 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 877s 22:03:45.190534411 O: certified host keys: host rsa cert downgrade to raw key 879s 22:03:47.341870450 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 880s 22:03:48.695976937 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 884s 22:03:52.331596468 O: certified host keys: host ed25519 connect wrong cert 884s 22:03:52.567202143 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 884s 22:03:52.815141578 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 885s 22:03:53.035379803 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 885s 22:03:53.354479345 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 885s 22:03:53.822689314 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 886s 22:03:54.066655984 O: certified host keys: host rsa connect wrong cert 888s 22:03:56.240441807 O: certified host keys: host rsa-sha2-256 connect wrong cert 890s 22:03:58.611148290 O: certified host keys: host rsa-sha2-512 connect wrong cert 891s 22:03:59.809682196 O: ok certified host keys 891s 22:03:59.810776947 E: run test cert-userkey.sh ... 894s 22:04:01.974589533 O: certified user keys: sign user ed25519 cert 894s 22:04:02.003711535 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 894s 22:04:02.038846630 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 894s 22:04:02.067206850 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 894s 22:04:02.101729368 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 894s 22:04:02.147666575 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 894s 22:04:02.182174852 O: certified user keys: sign user rsa cert 895s 22:04:03.279041300 O: certified user keys: sign user rsa-sha2-256 cert 895s 22:04:03.599529672 O: certified user keys: sign user rsa-sha2-512 cert 896s 22:04:04.326811018 O: certified user keys: ed25519 missing authorized_principals 896s 22:04:04.586640192 O: certified user keys: ed25519 empty authorized_principals 896s 22:04:04.844831518 O: certified user keys: ed25519 wrong authorized_principals 897s 22:04:05.104880655 O: certified user keys: ed25519 correct authorized_principals 897s 22:04:05.413162737 O: certified user keys: ed25519 authorized_principals bad key opt 897s 22:04:05.672974143 O: certified user keys: ed25519 authorized_principals command=false 898s 22:04:05.982875832 O: certified user keys: ed25519 authorized_principals command=true 898s 22:04:06.306578070 O: certified user keys: ed25519 wrong principals key option 898s 22:04:06.573830163 O: certified user keys: ed25519 correct principals key option 898s 22:04:06.915035425 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 899s 22:04:07.174049435 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 900s 22:04:07.431178750 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 900s 22:04:07.691711002 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 900s 22:04:08.010938621 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 900s 22:04:08.274173984 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 901s 22:04:09.109068207 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 901s 22:04:09.439750338 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 901s 22:04:09.723238234 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 902s 22:04:10.074726721 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 902s 22:04:10.330209086 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 902s 22:04:10.586538796 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 902s 22:04:10.843266598 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 903s 22:04:11.145555261 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 903s 22:04:11.402401099 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 903s 22:04:11.703937577 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 904s 22:04:12.021475873 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 904s 22:04:12.290880023 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 904s 22:04:12.634701982 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 904s 22:04:12.919467972 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 905s 22:04:13.202552668 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 905s 22:04:13.485235070 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 905s 22:04:13.837449821 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 906s 22:04:14.120547992 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 906s 22:04:14.474640106 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 906s 22:04:14.842379770 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 907s 22:04:15.128335175 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 907s 22:04:15.526250690 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 907s 22:04:15.853684594 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 908s 22:04:16.157586302 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 908s 22:04:16.463656107 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 908s 22:04:16.874889391 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 909s 22:04:17.178800171 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 910s 22:04:17.591487205 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 910s 22:04:18.014709699 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 910s 22:04:18.366354185 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 910s 22:04:18.851837199 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 911s 22:04:19.123212398 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 911s 22:04:19.397588758 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 911s 22:04:19.668372296 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 912s 22:04:19.991797570 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 912s 22:04:20.263065754 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 912s 22:04:20.585580074 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 912s 22:04:20.912080387 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 913s 22:04:21.187061711 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 913s 22:04:21.542383313 O: certified user keys: rsa missing authorized_principals 913s 22:04:21.804598153 O: certified user keys: rsa empty authorized_principals 914s 22:04:22.066082331 O: certified user keys: rsa wrong authorized_principals 914s 22:04:22.326646717 O: certified user keys: rsa correct authorized_principals 915s 22:04:22.647842902 O: certified user keys: rsa authorized_principals bad key opt 915s 22:04:22.908968584 O: certified user keys: rsa authorized_principals command=false 915s 22:04:23.234279439 O: certified user keys: rsa authorized_principals command=true 915s 22:04:23.569197044 O: certified user keys: rsa wrong principals key option 915s 22:04:23.836116043 O: certified user keys: rsa correct principals key option 916s 22:04:24.209972065 O: certified user keys: rsa-sha2-256 missing authorized_principals 916s 22:04:24.476751333 O: certified user keys: rsa-sha2-256 empty authorized_principals 916s 22:04:24.739592569 O: certified user keys: rsa-sha2-256 wrong authorized_principals 917s 22:04:25.008923389 O: certified user keys: rsa-sha2-256 correct authorized_principals 917s 22:04:25.335514300 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 917s 22:04:25.594406978 O: certified user keys: rsa-sha2-256 authorized_principals command=false 917s 22:04:25.910315388 O: certified user keys: rsa-sha2-256 authorized_principals command=true 918s 22:04:26.259189882 O: certified user keys: rsa-sha2-256 wrong principals key option 918s 22:04:26.532593683 O: certified user keys: rsa-sha2-256 correct principals key option 918s 22:04:26.893898606 O: certified user keys: rsa-sha2-512 missing authorized_principals 920s 22:04:27.520869097 O: certified user keys: rsa-sha2-512 empty authorized_principals 920s 22:04:27.807195415 O: certified user keys: rsa-sha2-512 wrong authorized_principals 920s 22:04:28.063399682 O: certified user keys: rsa-sha2-512 correct authorized_principals 920s 22:04:28.375796567 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 920s 22:04:28.628622733 O: certified user keys: rsa-sha2-512 authorized_principals command=false 920s 22:04:28.940477203 O: certified user keys: rsa-sha2-512 authorized_principals command=true 921s 22:04:29.268964495 O: certified user keys: rsa-sha2-512 wrong principals key option 921s 22:04:29.536660473 O: certified user keys: rsa-sha2-512 correct principals key option 921s 22:04:29.890862127 O: certified user keys: ed25519 authorized_keys connect 922s 22:04:30.212830348 O: certified user keys: ed25519 authorized_keys revoked key 922s 22:04:30.476478405 O: certified user keys: ed25519 authorized_keys revoked via KRL 922s 22:04:30.742448847 O: certified user keys: ed25519 authorized_keys empty KRL 923s 22:04:31.061518822 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 923s 22:04:31.388932544 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 923s 22:04:31.658172432 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 923s 22:04:31.927067509 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 924s 22:04:32.249141675 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 926s 22:04:32.574921383 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 926s 22:04:32.851540310 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 926s 22:04:33.127535137 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 926s 22:04:33.450855287 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 926s 22:04:33.815785885 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 926s 22:04:34.110863920 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 926s 22:04:34.407442552 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 926s 22:04:34.771976729 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 927s 22:04:35.184729612 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 927s 22:04:35.608516479 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 927s 22:04:35.950398691 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 928s 22:04:36.382053488 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 928s 22:04:36.711460223 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 929s 22:04:36.977795474 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 929s 22:04:37.242748799 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 930s 22:04:37.565681945 O: certified user keys: rsa authorized_keys connect 930s 22:04:37.896546432 O: certified user keys: rsa authorized_keys revoked key 930s 22:04:38.162296135 O: certified user keys: rsa authorized_keys revoked via KRL 930s 22:04:38.426629636 O: certified user keys: rsa authorized_keys empty KRL 930s 22:04:38.751106536 O: certified user keys: rsa-sha2-256 authorized_keys connect 931s 22:04:39.073034443 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 931s 22:04:39.343110537 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 931s 22:04:39.609044275 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 931s 22:04:39.939496414 O: certified user keys: rsa-sha2-512 authorized_keys connect 932s 22:04:40.267598240 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 932s 22:04:40.947001895 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 933s 22:04:41.215641216 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 933s 22:04:41.540036008 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 933s 22:04:41.806339703 O: certified user keys: authorized_keys CA does not authenticate 933s 22:04:41.815315553 O: certified user keys: ensure CA key does not authenticate user 934s 22:04:42.081150833 O: certified user keys: ed25519 TrustedUserCAKeys connect 934s 22:04:42.402355808 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 935s 22:04:42.676818284 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 935s 22:04:42.948689446 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 935s 22:04:43.269959617 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 935s 22:04:43.602864829 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 935s 22:04:43.877083130 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 936s 22:04:44.148139219 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 936s 22:04:44.466614902 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 936s 22:04:44.800053281 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 937s 22:04:45.077639066 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 937s 22:04:45.356342598 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 937s 22:04:45.672241641 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 938s 22:04:46.035555919 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 938s 22:04:46.350704013 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 938s 22:04:46.632253816 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 939s 22:04:46.986495114 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 939s 22:04:47.421420157 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 939s 22:04:47.775902132 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 940s 22:04:48.126548278 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 940s 22:04:48.566858018 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 940s 22:04:48.895448508 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 941s 22:04:49.170774641 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 941s 22:04:49.446945353 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 941s 22:04:49.771610046 O: certified user keys: rsa TrustedUserCAKeys connect 942s 22:04:50.097578773 O: certified user keys: rsa TrustedUserCAKeys revoked key 942s 22:04:50.365961944 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 942s 22:04:50.633340807 O: certified user keys: rsa TrustedUserCAKeys empty KRL 943s 22:04:50.974836596 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 943s 22:04:51.314955900 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 943s 22:04:51.587754425 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 943s 22:04:51.856971652 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 944s 22:04:52.186809267 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 944s 22:04:52.517821990 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 944s 22:04:52.788164081 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 945s 22:04:53.059103187 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 945s 22:04:53.396911449 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 946s 22:04:54.031286436 O: certified user keys: TrustedUserCAKeys CA does not authenticate 946s 22:04:54.039538183 O: certified user keys: ensure CA key does not authenticate user 946s 22:04:54.299156686 O: certified user keys: correct principal auth authorized_keys expect success rsa 946s 22:04:54.645522895 O: certified user keys: correct principal auth authorized_keys expect success ed25519 947s 22:04:54.978565897 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 947s 22:04:55.322813959 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 947s 22:04:55.668088969 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 948s 22:04:55.961019701 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 948s 22:04:56.243343134 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 948s 22:04:56.523546429 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 948s 22:04:56.809066389 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 949s 22:04:57.106656238 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 949s 22:04:57.405637681 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 949s 22:04:57.699542744 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 950s 22:04:57.989335615 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 950s 22:04:58.282493530 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 950s 22:04:58.578203876 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 950s 22:04:58.871630479 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 951s 22:04:59.157330866 O: certified user keys: cert expired auth authorized_keys expect failure rsa 951s 22:04:59.445568359 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 951s 22:04:59.731105378 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 952s 22:05:00.010864839 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 952s 22:05:00.297236394 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 952s 22:05:00.644687621 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 953s 22:05:01.003408475 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 953s 22:05:01.357928046 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 953s 22:05:01.707355997 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 954s 22:05:01.997075117 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 954s 22:05:02.282532512 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 954s 22:05:02.568849012 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 954s 22:05:02.858567124 O: certified user keys: force-command auth authorized_keys expect failure rsa 955s 22:05:03.208681080 O: certified user keys: force-command auth authorized_keys expect failure ed25519 955s 22:05:03.544775291 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 955s 22:05:03.892817787 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 956s 22:05:04.235176002 O: certified user keys: empty principals auth authorized_keys expect success rsa 956s 22:05:04.584054753 O: certified user keys: empty principals auth authorized_keys expect success ed25519 956s 22:05:04.916640619 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 957s 22:05:05.198920509 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 957s 22:05:05.482123782 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 957s 22:05:05.829218275 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 958s 22:05:06.169085008 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 958s 22:05:06.455319676 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 958s 22:05:06.748723260 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 959s 22:05:07.098756822 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 959s 22:05:07.438397615 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 959s 22:05:07.726454767 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 960s 22:05:08.011813844 O: certified user keys: force-command match true auth authorized_keys expect success rsa 960s 22:05:08.794784550 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 961s 22:05:09.137733542 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 961s 22:05:09.496828367 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 961s 22:05:09.845720954 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 962s 22:05:10.143530432 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 962s 22:05:10.438621833 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 962s 22:05:10.728620935 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 963s 22:05:11.025690109 O: certified user keys: user ed25519 connect wrong cert 963s 22:05:11.308882100 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 963s 22:05:11.566644402 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 963s 22:05:11.838237959 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 964s 22:05:12.133529314 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 964s 22:05:12.393251823 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 964s 22:05:12.667629929 O: certified user keys: user rsa connect wrong cert 964s 22:05:12.949307112 O: certified user keys: user rsa-sha2-256 connect wrong cert 965s 22:05:13.229332804 O: certified user keys: user rsa-sha2-512 connect wrong cert 965s 22:05:13.493083816 O: ok certified user keys 965s 22:05:13.493751114 E: run test host-expand.sh ... 966s 22:05:14.180492998 O: ok expand %h and %n 966s 22:05:14.181460384 E: run test keys-command.sh ... 966s 22:05:14.610791582 O: AuthorizedKeysCommand with arguments 966s 22:05:14.955276608 O: AuthorizedKeysCommand without arguments 967s 22:05:15.300949299 O: ok authorized keys from command 967s 22:05:15.315085881 E: run test forward-control.sh ... 969s 22:05:17.463825828 O: check_lfwd done (expecting Y): default configuration 971s 22:05:19.126804801 O: check_rfwd done (expecting Y): default configuration 972s 22:05:20.728391096 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 973s 22:05:21.375823203 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 974s 22:05:22.727731277 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 976s 22:05:24.369666154 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 977s 22:05:25.956845266 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 979s 22:05:27.605417992 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 981s 22:05:29.339123885 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 983s 22:05:30.992254845 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 984s 22:05:32.597314338 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 986s 22:05:34.247406529 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 987s 22:05:35.602737361 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 987s 22:05:35.943618999 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 989s 22:05:37.306961388 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 989s 22:05:37.647615404 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 991s 22:05:39.234933781 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 992s 22:05:40.878504144 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 993s 22:05:41.462814358 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 993s 22:05:41.797446823 O: check_rfwd done (expecting N): AllowTcpForwarding=local 995s 22:05:43.151909836 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 995s 22:05:43.483755693 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 997s 22:05:45.064956577 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 997s 22:05:45.395752831 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 998s 22:05:46.753620221 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 999s 22:05:47.085483614 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 1000s 22:05:48.680689491 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 1001s 22:05:49.017013067 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 1002s 22:05:50.373261859 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 1002s 22:05:50.710066713 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 1004s 22:05:52.069236934 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 1004s 22:05:52.403419304 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 1005s 22:05:52.990929211 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 1005s 22:05:53.339829518 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 1006s 22:05:54.691735227 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 1008s 22:05:56.761492505 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 1010s 22:05:58.112645372 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 1011s 22:05:59.751505065 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 1013s 22:06:01.102494078 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 1014s 22:06:02.745452169 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 1016s 22:06:04.095606311 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 1017s 22:06:05.741128079 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 1019s 22:06:07.091421495 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 1020s 22:06:08.733436825 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 1022s 22:06:10.084328648 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 1022s 22:06:10.412948981 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 1023s 22:06:11.764799178 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 1024s 22:06:12.089722127 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 1025s 22:06:13.446631562 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 1027s 22:06:15.100926422 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 1028s 22:06:16.459917743 O: check_lfwd done (expecting N): AllowTcpForwarding=no 1028s 22:06:16.791309144 O: check_rfwd done (expecting N): AllowTcpForwarding=no 1030s 22:06:18.142690222 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1030s 22:06:18.480853822 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1031s 22:06:19.843270854 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1032s 22:06:20.200008517 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1033s 22:06:21.570495342 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1033s 22:06:21.936299944 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1035s 22:06:23.324073078 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 1035s 22:06:23.670806220 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 1037s 22:06:25.031079407 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1037s 22:06:25.375880685 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1038s 22:06:26.736818078 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 1039s 22:06:27.515536151 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 1040s 22:06:28.874240267 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1041s 22:06:29.205438814 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1042s 22:06:30.791559672 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 1043s 22:06:31.128207945 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 1044s 22:06:32.712440290 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1046s 22:06:34.356132523 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1047s 22:06:35.946227432 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 1048s 22:06:36.275954812 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 1049s 22:06:37.865324999 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 1050s 22:06:38.197537670 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 1051s 22:06:39.553353328 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1051s 22:06:39.883421455 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1053s 22:06:41.233770849 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 1054s 22:06:42.874133070 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 1056s 22:06:44.218250946 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1056s 22:06:44.544802111 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1057s 22:06:45.889575359 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1058s 22:06:46.227052667 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1058s 22:06:46.264407839 O: ok sshd control of local and remote forwarding 1058s 22:06:46.266316767 E: run test integrity.sh ... 1058s 22:06:46.721452649 O: test integrity: hmac-sha1 @2900 1059s 22:06:47.054114553 O: test integrity: hmac-sha1 @2901 1059s 22:06:47.384099344 O: test integrity: hmac-sha1 @2902 1060s 22:06:48.238265526 O: test integrity: hmac-sha1 @2903 1060s 22:06:48.575713616 O: test integrity: hmac-sha1 @2904 1060s 22:06:48.910528121 O: test integrity: hmac-sha1 @2905 1061s 22:06:49.245722191 O: test integrity: hmac-sha1 @2906 1061s 22:06:49.582027366 O: test integrity: hmac-sha1 @2907 1061s 22:06:49.916903425 O: test integrity: hmac-sha1 @2908 1062s 22:06:50.251042222 O: test integrity: hmac-sha1 @2909 1062s 22:06:50.559613020 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1062s 22:06:50.593380860 O: test integrity: hmac-sha1-96 @2900 1062s 22:06:50.933417122 O: test integrity: hmac-sha1-96 @2901 1063s 22:06:51.270645548 O: test integrity: hmac-sha1-96 @2902 1063s 22:06:51.603124494 O: test integrity: hmac-sha1-96 @2903 1063s 22:06:51.937210761 O: test integrity: hmac-sha1-96 @2904 1064s 22:06:52.270235995 O: test integrity: hmac-sha1-96 @2905 1064s 22:06:52.601507905 O: test integrity: hmac-sha1-96 @2906 1064s 22:06:52.932482088 O: test integrity: hmac-sha1-96 @2907 1065s 22:06:53.270068829 O: test integrity: hmac-sha1-96 @2908 1065s 22:06:53.605480395 O: test integrity: hmac-sha1-96 @2909 1065s 22:06:53.918298720 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1065s 22:06:53.952233642 O: test integrity: hmac-sha2-256 @2900 1066s 22:06:54.285591832 O: test integrity: hmac-sha2-256 @2901 1066s 22:06:54.619581556 O: test integrity: hmac-sha2-256 @2902 1066s 22:06:54.958457681 O: test integrity: hmac-sha2-256 @2903 1067s 22:06:55.291597378 O: test integrity: hmac-sha2-256 @2904 1067s 22:06:55.623901933 O: test integrity: hmac-sha2-256 @2905 1067s 22:06:55.955553513 O: test integrity: hmac-sha2-256 @2906 1068s 22:06:56.285829452 O: test integrity: hmac-sha2-256 @2907 1068s 22:06:56.614480911 O: test integrity: hmac-sha2-256 @2908 1068s 22:06:56.947667442 O: test integrity: hmac-sha2-256 @2909 1069s 22:06:57.264420120 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1069s 22:06:57.298116154 O: test integrity: hmac-sha2-512 @2900 1069s 22:06:57.591078963 O: test integrity: hmac-sha2-512 @2901 1069s 22:06:57.882120364 O: test integrity: hmac-sha2-512 @2902 1070s 22:06:58.171531962 O: test integrity: hmac-sha2-512 @2903 1070s 22:06:58.466460293 O: test integrity: hmac-sha2-512 @2904 1070s 22:06:58.760590085 O: test integrity: hmac-sha2-512 @2905 1071s 22:06:59.052909351 O: test integrity: hmac-sha2-512 @2906 1071s 22:06:59.348361810 O: test integrity: hmac-sha2-512 @2907 1071s 22:06:59.643693385 O: test integrity: hmac-sha2-512 @2908 1071s 22:06:59.938606031 O: test integrity: hmac-sha2-512 @2909 1072s 22:07:00.207599152 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1072s 22:07:00.245202440 O: test integrity: hmac-md5 @2900 1072s 22:07:00.576702823 O: test integrity: hmac-md5 @2901 1072s 22:07:00.907894198 O: test integrity: hmac-md5 @2902 1073s 22:07:01.239659222 O: test integrity: hmac-md5 @2903 1073s 22:07:01.572402589 O: test integrity: hmac-md5 @2904 1073s 22:07:01.911856161 O: test integrity: hmac-md5 @2905 1074s 22:07:02.246141521 O: test integrity: hmac-md5 @2906 1074s 22:07:02.573961000 O: test integrity: hmac-md5 @2907 1075s 22:07:02.906895765 O: test integrity: hmac-md5 @2908 1075s 22:07:03.614873240 O: test integrity: hmac-md5 @2909 1075s 22:07:03.925392607 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1076s 22:07:03.960856720 O: test integrity: hmac-md5-96 @2900 1076s 22:07:04.295823682 O: test integrity: hmac-md5-96 @2901 1076s 22:07:04.630600000 O: test integrity: hmac-md5-96 @2902 1077s 22:07:04.973325792 O: test integrity: hmac-md5-96 @2903 1077s 22:07:05.308859842 O: test integrity: hmac-md5-96 @2904 1077s 22:07:05.648066582 O: test integrity: hmac-md5-96 @2905 1078s 22:07:05.980335070 O: test integrity: hmac-md5-96 @2906 1078s 22:07:06.313909585 O: test integrity: hmac-md5-96 @2907 1078s 22:07:06.662592791 O: test integrity: hmac-md5-96 @2908 1079s 22:07:07.004505470 O: test integrity: hmac-md5-96 @2909 1079s 22:07:07.323191093 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1079s 22:07:07.359299739 O: test integrity: umac-64@openssh.com @2900 1079s 22:07:07.700026583 O: test integrity: umac-64@openssh.com @2901 1080s 22:07:08.048868704 O: test integrity: umac-64@openssh.com @2902 1080s 22:07:08.397059004 O: test integrity: umac-64@openssh.com @2903 1080s 22:07:08.750884122 O: test integrity: umac-64@openssh.com @2904 1081s 22:07:09.087869466 O: test integrity: umac-64@openssh.com @2905 1081s 22:07:09.423901861 O: test integrity: umac-64@openssh.com @2906 1081s 22:07:09.761318690 O: test integrity: umac-64@openssh.com @2907 1082s 22:07:10.095654122 O: test integrity: umac-64@openssh.com @2908 1082s 22:07:10.429186129 O: test integrity: umac-64@openssh.com @2909 1082s 22:07:10.742498722 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1082s 22:07:10.776752400 O: test integrity: umac-128@openssh.com @2900 1083s 22:07:11.122311780 O: test integrity: umac-128@openssh.com @2901 1083s 22:07:11.463901978 O: test integrity: umac-128@openssh.com @2902 1083s 22:07:11.813180004 O: test integrity: umac-128@openssh.com @2903 1084s 22:07:12.154174145 O: test integrity: umac-128@openssh.com @2904 1084s 22:07:12.497702344 O: test integrity: umac-128@openssh.com @2905 1084s 22:07:12.836507547 O: test integrity: umac-128@openssh.com @2906 1085s 22:07:13.178299140 O: test integrity: umac-128@openssh.com @2907 1085s 22:07:13.521620208 O: test integrity: umac-128@openssh.com @2908 1085s 22:07:13.863793087 O: test integrity: umac-128@openssh.com @2909 1086s 22:07:14.183647777 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1086s 22:07:14.218777195 O: test integrity: hmac-sha1-etm@openssh.com @2900 1086s 22:07:14.560450215 O: test integrity: hmac-sha1-etm@openssh.com @2901 1086s 22:07:14.897838891 O: test integrity: hmac-sha1-etm@openssh.com @2902 1087s 22:07:15.236375070 O: test integrity: hmac-sha1-etm@openssh.com @2903 1087s 22:07:15.577296506 O: test integrity: hmac-sha1-etm@openssh.com @2904 1087s 22:07:15.915094065 O: test integrity: hmac-sha1-etm@openssh.com @2905 1088s 22:07:16.250019589 O: test integrity: hmac-sha1-etm@openssh.com @2906 1088s 22:07:16.586949481 O: test integrity: hmac-sha1-etm@openssh.com @2907 1088s 22:07:16.923205916 O: test integrity: hmac-sha1-etm@openssh.com @2908 1089s 22:07:17.262321216 O: test integrity: hmac-sha1-etm@openssh.com @2909 1089s 22:07:17.579494900 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1089s 22:07:17.613490688 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 1089s 22:07:17.949905641 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 1090s 22:07:18.757348010 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 1091s 22:07:19.096832029 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 1091s 22:07:19.432340747 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 1091s 22:07:19.765670731 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 1092s 22:07:20.102582361 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 1092s 22:07:20.438464642 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 1092s 22:07:20.783385982 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 1093s 22:07:21.120299445 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 1093s 22:07:21.432259058 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1093s 22:07:21.466679694 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 1093s 22:07:21.808408550 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 1094s 22:07:22.146794322 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 1094s 22:07:22.486675287 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 1095s 22:07:22.824396479 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 1095s 22:07:23.159749051 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 1095s 22:07:23.496832901 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 1095s 22:07:23.834289161 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 1096s 22:07:24.166229244 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 1096s 22:07:24.501631207 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 1096s 22:07:24.816271107 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1096s 22:07:24.848490208 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 1097s 22:07:25.139051842 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 1097s 22:07:25.427288218 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 1097s 22:07:25.716445656 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 1098s 22:07:26.005078241 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 1098s 22:07:26.300133216 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 1098s 22:07:26.590484477 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 1098s 22:07:26.880723896 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 1099s 22:07:27.177567911 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 1099s 22:07:27.471308609 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 1099s 22:07:27.743647829 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1099s 22:07:27.782810576 O: test integrity: hmac-md5-etm@openssh.com @2900 1100s 22:07:28.129386828 O: test integrity: hmac-md5-etm@openssh.com @2901 1100s 22:07:28.475443343 O: test integrity: hmac-md5-etm@openssh.com @2902 1100s 22:07:28.818508666 O: test integrity: hmac-md5-etm@openssh.com @2903 1101s 22:07:29.180786530 O: test integrity: hmac-md5-etm@openssh.com @2904 1101s 22:07:29.543891090 O: test integrity: hmac-md5-etm@openssh.com @2905 1101s 22:07:29.889512908 O: test integrity: hmac-md5-etm@openssh.com @2906 1102s 22:07:30.231490194 O: test integrity: hmac-md5-etm@openssh.com @2907 1102s 22:07:30.582288171 O: test integrity: hmac-md5-etm@openssh.com @2908 1102s 22:07:30.923516317 O: test integrity: hmac-md5-etm@openssh.com @2909 1103s 22:07:31.246804066 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1103s 22:07:31.282490846 O: test integrity: hmac-md5-96-etm@openssh.com @2900 1103s 22:07:31.627084827 O: test integrity: hmac-md5-96-etm@openssh.com @2901 1104s 22:07:31.975390697 O: test integrity: hmac-md5-96-etm@openssh.com @2902 1104s 22:07:32.319931590 O: test integrity: hmac-md5-96-etm@openssh.com @2903 1104s 22:07:32.666768659 O: test integrity: hmac-md5-96-etm@openssh.com @2904 1105s 22:07:33.005235365 O: test integrity: hmac-md5-96-etm@openssh.com @2905 1105s 22:07:33.346433252 O: test integrity: hmac-md5-96-etm@openssh.com @2906 1105s 22:07:33.686224384 O: test integrity: hmac-md5-96-etm@openssh.com @2907 1106s 22:07:34.027951243 O: test integrity: hmac-md5-96-etm@openssh.com @2908 1106s 22:07:34.364778137 O: test integrity: hmac-md5-96-etm@openssh.com @2909 1106s 22:07:34.686777796 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1106s 22:07:34.721545912 O: test integrity: umac-64-etm@openssh.com @2900 1107s 22:07:35.054559474 O: test integrity: umac-64-etm@openssh.com @2901 1107s 22:07:35.386257999 O: test integrity: umac-64-etm@openssh.com @2902 1108s 22:07:36.226275048 O: test integrity: umac-64-etm@openssh.com @2903 1108s 22:07:36.558162171 O: test integrity: umac-64-etm@openssh.com @2904 1108s 22:07:36.899222355 O: test integrity: umac-64-etm@openssh.com @2905 1109s 22:07:37.232943239 O: test integrity: umac-64-etm@openssh.com @2906 1109s 22:07:37.579356705 O: test integrity: umac-64-etm@openssh.com @2907 1109s 22:07:37.934659184 O: test integrity: umac-64-etm@openssh.com @2908 1110s 22:07:38.278614106 O: test integrity: umac-64-etm@openssh.com @2909 1110s 22:07:38.587362744 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1110s 22:07:38.621142273 O: test integrity: umac-128-etm@openssh.com @2900 1111s 22:07:38.970328559 O: test integrity: umac-128-etm@openssh.com @2901 1111s 22:07:39.320146615 O: test integrity: umac-128-etm@openssh.com @2902 1111s 22:07:39.671100697 O: test integrity: umac-128-etm@openssh.com @2903 1112s 22:07:40.018532894 O: test integrity: umac-128-etm@openssh.com @2904 1112s 22:07:40.369258764 O: test integrity: umac-128-etm@openssh.com @2905 1112s 22:07:40.710900417 O: test integrity: umac-128-etm@openssh.com @2906 1113s 22:07:41.064454114 O: test integrity: umac-128-etm@openssh.com @2907 1113s 22:07:41.404790089 O: test integrity: umac-128-etm@openssh.com @2908 1113s 22:07:41.749005357 O: test integrity: umac-128-etm@openssh.com @2909 1114s 22:07:42.073640196 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1114s 22:07:42.107711530 O: test integrity: aes128-gcm@openssh.com @2900 1114s 22:07:42.379709027 O: test integrity: aes128-gcm@openssh.com @2901 1114s 22:07:42.650181847 O: test integrity: aes128-gcm@openssh.com @2902 1114s 22:07:42.934539520 O: test integrity: aes128-gcm@openssh.com @2903 1115s 22:07:43.207758002 O: test integrity: aes128-gcm@openssh.com @2904 1115s 22:07:43.473392262 O: test integrity: aes128-gcm@openssh.com @2905 1115s 22:07:43.744874582 O: test integrity: aes128-gcm@openssh.com @2906 1116s 22:07:44.014177209 O: test integrity: aes128-gcm@openssh.com @2907 1116s 22:07:44.280794168 O: test integrity: aes128-gcm@openssh.com @2908 1116s 22:07:44.559680659 O: test integrity: aes128-gcm@openssh.com @2909 1116s 22:07:44.803536355 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1116s 22:07:44.836659985 O: test integrity: aes256-gcm@openssh.com @2900 1117s 22:07:45.123634187 O: test integrity: aes256-gcm@openssh.com @2901 1117s 22:07:45.396886379 O: test integrity: aes256-gcm@openssh.com @2902 1117s 22:07:45.667299543 O: test integrity: aes256-gcm@openssh.com @2903 1117s 22:07:45.933179719 O: test integrity: aes256-gcm@openssh.com @2904 1118s 22:07:46.214388897 O: test integrity: aes256-gcm@openssh.com @2905 1118s 22:07:46.488500144 O: test integrity: aes256-gcm@openssh.com @2906 1118s 22:07:46.755897672 O: test integrity: aes256-gcm@openssh.com @2907 1119s 22:07:47.024386305 O: test integrity: aes256-gcm@openssh.com @2908 1119s 22:07:47.291918471 O: test integrity: aes256-gcm@openssh.com @2909 1119s 22:07:47.539765649 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1119s 22:07:47.575229372 O: test integrity: chacha20-poly1305@openssh.com @2900 1119s 22:07:47.847633654 O: test integrity: chacha20-poly1305@openssh.com @2901 1120s 22:07:48.120700710 O: test integrity: chacha20-poly1305@openssh.com @2902 1120s 22:07:48.703673851 O: test integrity: chacha20-poly1305@openssh.com @2903 1121s 22:07:48.978726471 O: test integrity: chacha20-poly1305@openssh.com @2904 1121s 22:07:49.252535737 O: test integrity: chacha20-poly1305@openssh.com @2905 1121s 22:07:49.526470525 O: test integrity: chacha20-poly1305@openssh.com @2906 1121s 22:07:49.795675402 O: test integrity: chacha20-poly1305@openssh.com @2907 1122s 22:07:50.065683856 O: test integrity: chacha20-poly1305@openssh.com @2908 1122s 22:07:50.335784228 O: test integrity: chacha20-poly1305@openssh.com @2909 1122s 22:07:50.583035098 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1122s 22:07:50.590220349 O: ok integrity 1122s 22:07:50.590350232 E: run test krl.sh ... 1123s 22:07:50.982016890 O: key revocation lists: generating test keys 1126s 22:07:54.904181395 O: key revocation lists: generating KRLs 1127s 22:07:55.085116436 O: key revocation lists: checking revocations for revoked keys 1127s 22:07:55.888092628 O: key revocation lists: checking revocations for unrevoked keys 1128s 22:07:56.654254298 O: key revocation lists: checking revocations for revoked certs 1129s 22:07:57.943728836 O: key revocation lists: checking revocations for unrevoked certs 1131s 22:07:59.211896361 O: key revocation lists: testing KRL update 1132s 22:08:00.630814489 O: key revocation lists: checking revocations for revoked keys 1133s 22:08:01.425666731 O: key revocation lists: checking revocations for unrevoked keys 1134s 22:08:02.187332022 O: key revocation lists: checking revocations for revoked certs 1135s 22:08:03.461020502 O: key revocation lists: checking revocations for unrevoked certs 1136s 22:08:04.734527590 O: ok key revocation lists 1136s 22:08:04.735069483 E: run test multipubkey.sh ... 1139s 22:08:07.693143409 O: ok multiple pubkey 1139s 22:08:07.694420319 E: run test limit-keytype.sh ... 1144s 22:08:12.256740430 O: allow rsa,ed25519 1145s 22:08:13.115839292 O: allow ed25519 1145s 22:08:13.872032780 O: allow cert only 1146s 22:08:14.684271684 O: match w/ no match 1147s 22:08:15.488317466 O: match w/ matching 1148s 22:08:16.349811416 O: ok restrict pubkey type 1148s 22:08:16.350941362 E: run test hostkey-agent.sh ... 1149s 22:08:17.101057788 O: key type ssh-ed25519 1149s 22:08:17.257641591 O: key type sk-ssh-ed25519@openssh.com 1149s 22:08:17.423232444 O: key type ecdsa-sha2-nistp256 1149s 22:08:17.590061126 O: key type ecdsa-sha2-nistp384 1149s 22:08:17.800564065 O: key type ecdsa-sha2-nistp521 1150s 22:08:18.099549180 O: key type sk-ecdsa-sha2-nistp256@openssh.com 1150s 22:08:18.258385113 O: key type ssh-rsa 1150s 22:08:18.439510325 O: cert type ssh-ed25519-cert-v01@openssh.com 1150s 22:08:18.766036477 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 1151s 22:08:19.097825190 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 1151s 22:08:19.425728129 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 1151s 22:08:19.790320041 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 1152s 22:08:20.195239446 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 1152s 22:08:20.534761969 O: cert type ssh-rsa-cert-v01@openssh.com 1152s 22:08:20.871042417 O: cert type rsa-sha2-256-cert-v01@openssh.com 1153s 22:08:21.205740064 O: cert type rsa-sha2-512-cert-v01@openssh.com 1153s 22:08:21.541083085 O: multiple hostkeys 1153s 22:08:21.550689188 O: Addkey type ssh-ed25519 1153s 22:08:21.557230860 O: Addkey type sk-ssh-ed25519@openssh.com 1153s 22:08:21.563444684 O: Addkey type ecdsa-sha2-nistp256 1153s 22:08:21.571021660 O: Addkey type ecdsa-sha2-nistp384 1153s 22:08:21.578333669 O: Addkey type ecdsa-sha2-nistp521 1153s 22:08:21.585778682 O: Addkey type sk-ecdsa-sha2-nistp256@openssh.com 1153s 22:08:21.593255415 O: Addkey type ssh-rsa 1153s 22:08:21.919280620 E: run test hostkey-rotate.sh ... 1153s 22:08:21.918968852 O: ok hostkey agent 1155s 22:08:23.366474687 O: learn hostkey with StrictHostKeyChecking=no 1155s 22:08:23.641239813 O: learn additional hostkeys 1156s 22:08:24.059553063 O: learn additional hostkeys, type=ssh-ed25519 1156s 22:08:24.373152563 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 1156s 22:08:24.695546867 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 1157s 22:08:25.009043925 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 1157s 22:08:25.354407594 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 1157s 22:08:25.718727703 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 1158s 22:08:26.045740787 O: learn additional hostkeys, type=ssh-rsa 1158s 22:08:26.387633811 O: learn changed non-primary hostkey type=ssh-rsa 1159s 22:08:27.744681850 O: learn new primary hostkey 1160s 22:08:28.103257811 O: rotate primary hostkey 1160s 22:08:28.467082890 O: check rotate primary hostkey 1160s 22:08:28.829109927 O: ok hostkey rotate 1160s 22:08:28.830850127 E: run test principals-command.sh ... 1161s 22:08:29.740093744 O: authorized principals command: empty authorized_principals 1162s 22:08:30.026908439 O: authorized principals command: wrong authorized_principals 1162s 22:08:30.314122740 O: authorized principals command: correct authorized_principals 1162s 22:08:30.672450999 O: authorized principals command: authorized_principals bad key opt 1163s 22:08:30.961294297 O: authorized principals command: authorized_principals command=false 1163s 22:08:31.309443117 O: authorized principals command: authorized_principals command=true 1163s 22:08:31.663958683 O: authorized principals command: wrong principals key option 1164s 22:08:31.958337024 O: authorized principals command: correct principals key option 1164s 22:08:32.304688957 O: ok authorized principals command 1164s 22:08:32.318538276 E: run test cert-file.sh ... 1164s 22:08:32.776496897 O: identity cert with no plain public file 1165s 22:08:33.100458592 O: CertificateFile with no plain public file 1165s 22:08:33.431228880 O: plain keys 1165s 22:08:33.715936629 O: untrusted cert 1166s 22:08:34.006120183 O: good cert, bad key 1166s 22:08:34.337214273 O: single trusted 1166s 22:08:34.653258617 O: multiple trusted 1167s 22:08:35.926130499 O: ok ssh with certificates 1167s 22:08:35.927007519 E: run test cfginclude.sh ... 1168s 22:08:36.310736047 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.322691762 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.335945066 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.350245514 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.362643558 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.375625456 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.388601634 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.401590012 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.415785538 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.452502701 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.469781857 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.486698646 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.517957603 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.532221731 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.543419828 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.556949138 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.569698191 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.583858836 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.598413090 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.611540351 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.623165578 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.659139884 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.673727458 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.692256044 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.722780944 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.743409938 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1168s 22:08:36.753522010 O: ok config include 1168s 22:08:36.754429471 E: run test servcfginclude.sh ... 1169s 22:08:37.538897867 O: ok server config include 1169s 22:08:37.540132255 E: run test allow-deny-users.sh ... 1172s 22:08:40.198159679 O: ok AllowUsers/DenyUsers 1172s 22:08:40.198968378 E: run test authinfo.sh ... 1172s 22:08:40.567598854 O: ExposeAuthInfo=no 1173s 22:08:41.299304635 O: ExposeAuthInfo=yes 1173s 22:08:41.601271181 O: ok authinfo 1173s 22:08:41.602476449 E: run test sshsig.sh ... 1174s 22:08:41.985873617 O: sshsig: make certificates 1174s 22:08:42.052670224 O: sshsig: check signature for ssh-ed25519 1174s 22:08:42.683860889 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 1175s 22:08:43.359716769 O: sshsig: check signature for ecdsa-sha2-nistp256 1176s 22:08:43.969852824 O: sshsig: check signature for ecdsa-sha2-nistp384 1177s 22:08:44.991113411 O: sshsig: check signature for ecdsa-sha2-nistp521 1178s 22:08:46.676791203 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 1179s 22:08:47.401920841 O: sshsig: check signature for ssh-rsa 1180s 22:08:48.073721059 O: sshsig: check signature for ssh-ed25519-cert.pub 1181s 22:08:49.374945306 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 1182s 22:08:50.708420579 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 1183s 22:08:51.960654741 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 1185s 22:08:53.722171585 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 1188s 22:08:56.713479619 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1190s 22:08:58.077950399 O: sshsig: check signature for ssh-rsa-cert.pub 1191s 22:08:59.315198874 O: sshsig: match principals 1191s 22:08:59.353522659 O: sshsig: nomatch principals 1191s 22:08:59.377891970 E: run test knownhosts.sh ... 1191s 22:08:59.378345220 O: ok sshsig 1193s 22:09:01.644009189 O: ok known hosts 1193s 22:09:01.644657124 E: run test knownhosts-command.sh ... 1194s 22:09:02.000705793 O: simple connection 1194s 22:09:02.289672065 O: no keys 1194s 22:09:02.484530817 O: bad exit status 1194s 22:09:02.664767599 E: mm_log_handler: write: Broken pipe 1194s 22:09:02.666446716 O: keytype ssh-ed25519 1194s 22:09:02.939655873 O: keytype sk-ssh-ed25519@openssh.com 1195s 22:09:03.218650357 O: keytype ecdsa-sha2-nistp256 1195s 22:09:03.491952992 O: keytype ecdsa-sha2-nistp384 1195s 22:09:03.792122792 O: keytype ecdsa-sha2-nistp521 1196s 22:09:04.132815943 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1196s 22:09:04.413361616 O: keytype ssh-rsa 1196s 22:09:04.698369030 O: ok known hosts command 1196s 22:09:04.699256610 E: run test agent-restrict.sh ... 1197s 22:09:05.045860930 O: generate keys 1197s 22:09:05.161420249 O: prepare client config 1197s 22:09:05.222330459 O: prepare known_hosts 1197s 22:09:05.248925417 O: prepare server configs 1197s 22:09:05.333723084 O: authentication w/o agent 1199s 22:09:07.130302988 O: start agent 1203s 22:09:11.140700634 O: authentication with agent (no restrict) 1205s 22:09:13.003541141 O: unrestricted keylist 1206s 22:09:14.032517435 O: authentication with agent (basic restrict) 1207s 22:09:14.997559924 O: authentication with agent incorrect key (basic restrict) 1207s 22:09:15.090776406 E: mm_log_handler: write: Broken pipe 1207s 22:09:15.173370451 E: mm_log_handler: write: Broken pipe 1207s 22:09:15.261656503 E: mm_log_handler: write: Broken pipe 1207s 22:09:15.369816599 E: mm_log_handler: write: Broken pipe 1207s 22:09:15.474385415 O: keylist (basic restrict) 1207s 22:09:15.475800166 E: mm_log_handler: write: Broken pipe 1208s 22:09:16.464205676 O: username 1209s 22:09:17.461728975 O: username wildcard 1210s 22:09:18.457612981 O: username incorrect 1210s 22:09:18.647806741 O: agent restriction honours certificate principal 1210s 22:09:18.760329969 O: multihop without agent 1211s 22:09:19.902139447 O: multihop agent unrestricted 1213s 22:09:21.131552773 O: multihop restricted 1214s 22:09:22.502669827 O: multihop username 1215s 22:09:23.749187406 O: multihop wildcard username 1216s 22:09:24.895938708 O: multihop wrong username 1217s 22:09:25.642331628 O: multihop cycle no agent 1219s 22:09:27.909718729 O: multihop cycle agent unrestricted 1221s 22:09:29.674296190 O: multihop cycle restricted deny 1222s 22:09:30.255801972 E: mm_log_handler: write: Broken pipe 1222s 22:09:30.283063935 O: multihop cycle restricted allow 1224s 22:09:32.081515044 E: run test hostbased.sh ... 1224s 22:09:32.080620944 O: ok agent restrictions 1224s 22:09:32.428297341 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1224s 22:09:32.429536489 E: run test password.sh ... 1224s 22:09:32.771592082 O: SKIPPED: Password auth requires SUDO and password file. 1224s 22:09:32.772684026 E: run test channel-timeout.sh ... 1225s 22:09:33.110704209 O: no timeout 1230s 22:09:38.417693702 O: command timeout 1231s 22:09:39.409747475 O: command long timeout 1231s 22:09:39.712529810 O: command wildcard timeout 1232s 22:09:40.409907692 O: command irrelevant timeout 1237s 22:09:45.725086005 O: multiplexed command timeout 1248s 22:09:56.478025443 O: irrelevant multiplexed command timeout 1263s 22:10:11.850009990 O: global command timeout 1285s 22:10:33.498637991 O: sftp no timeout 1290s 22:10:38.828688758 O: sftp timeout 1291s 22:10:39.408193782 E: Connection closed 1291s 22:10:39.410521071 O: sftp irrelevant timeout 1296s 22:10:44.734341180 O: ok channel timeout 1296s 22:10:44.735296801 E: run test connection-timeout.sh ... 1297s 22:10:45.088830017 O: no timeout 1302s 22:10:50.382514906 O: timeout 1310s 22:10:58.649922737 O: session inhibits timeout 1319s 22:11:06.964719867 O: timeout after session 1327s 22:11:14.988112557 O: timeout with listeners 1335s 22:11:23.293939931 O: ok unused connection timeout 1335s 22:11:23.294591104 E: run test match-subsystem.sh ... 1338s 22:11:26.537957071 O: ok sshd_config match subsystem 1338s 22:11:26.538134795 E: run test agent-pkcs11-restrict.sh ... 1338s 22:11:26.882779848 O: SKIPPED: No PKCS#11 library found 1338s 22:11:26.884090755 E: run test agent-pkcs11-cert.sh ... 1339s 22:11:27.230446400 O: SKIPPED: No PKCS#11 library found 1339s 22:11:27.230866368 E: run test penalty.sh ... 1339s 22:11:27.755681523 O: test connect 1340s 22:11:28.046231458 O: penalty for authentication failure 1348s 22:11:35.982939721 O: penalty for no authentication 1350s 22:11:38.103578302 O: ok penalties 1350s 22:11:38.104440239 E: run test penalty-expire.sh ... 1350s 22:11:38.615399563 O: test connect 1350s 22:11:38.902610914 O: penalty expiry 1364s 22:11:52.581775789 O: ok penalties 1364s 22:11:52.582729649 E: run test connect-bigconf.sh ... 1365s 22:11:53.763683752 O: ok simple connect 1365s 22:11:53.768968179 O: set -e ; if test -z "" ; then \ 1365s 22:11:53.774330407 O: V="" ; \ 1365s 22:11:53.779482192 O: test "x" = "x" || \ 1365s 22:11:53.784509814 O: V=/tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/valgrind-unit.sh ; \ 1365s 22:11:53.789746800 O: ARGS=""; \ 1365s 22:11:53.794979466 O: test "xunit" = "xunit-bench" && ARGS="-b"; \ 1365s 22:11:53.800053249 O: test "x" = "x" || ARGS="$ARGS -f"; \ 1365s 22:11:53.805438158 O: test "x" = "x" || ARGS="$ARGS -F"; \ 1365s 22:11:53.810767906 O: test "x" = "x" || ARGS="$ARGS -v"; \ 1365s 22:11:53.815727527 O: test "x" = "x" || ARGS="$ARGS -B"; \ 1365s 22:11:53.821024434 O: test "x" = "x" || ARGS="$ARGS -O "; \ 1365s 22:11:53.826418463 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/sshbuf/test_sshbuf ${ARGS}; \ 1365s 22:11:53.831425845 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/sshkey/test_sshkey \ 1365s 22:11:53.836378665 O: -d /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/sshkey/testdata ${ARGS}; \ 1365s 22:11:53.841465248 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/sshsig/test_sshsig \ 1365s 22:11:53.846508950 O: -d /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/sshsig/testdata ${ARGS}; \ 1365s 22:11:53.851492411 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/authopt/test_authopt \ 1365s 22:11:53.856584795 O: -d /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/authopt/testdata ${ARGS}; \ 1365s 22:11:53.861857862 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/bitmap/test_bitmap ${ARGS}; \ 1365s 22:11:53.866905244 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/conversion/test_conversion ${ARGS}; \ 1365s 22:11:53.872148710 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/kex/test_kex ${ARGS}; \ 1365s 22:11:53.877354216 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/hostkeys/test_hostkeys \ 1365s 22:11:53.882464199 O: -d /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/hostkeys/testdata ${ARGS}; \ 1365s 22:11:53.887818228 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/match/test_match ${ARGS}; \ 1365s 22:11:53.892959372 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/misc/test_misc ${ARGS}; \ 1365s 22:11:53.898102516 O: if test "x" = "xyes" ; then \ 1365s 22:11:53.903408264 O: $V /tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress/unittests/utf8/test_utf8 ${ARGS}; \ 1365s 22:11:53.908765492 O: fi \ 1365s 22:11:53.913954318 O: fi 1375s 22:12:03.192745680 O: test_sshbuf: ................................................................................................................. 114 tests ok 1458s 22:13:26.225857736 O: test_sshkey: .................................................................................... 84 tests ok 1458s 22:13:26.250024363 O: test_sshsig: ....... 7 tests ok 1458s 22:13:26.648920670 O: test_authopt: .................................................................................................................................................. 146 tests ok 1473s 22:13:41.554937430 O: test_bitmap: .. 2 tests ok 1473s 22:13:41.561690519 O: test_conversion: . 1 tests ok 1487s 22:13:55.284897731 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1487s 22:13:55.590631158 O: test_hostkeys: .................. 18 tests ok 1487s 22:13:55.597260405 O: test_match: ...... 6 tests ok 1487s 22:13:55.604046334 O: test_misc: ...................................................................................................... 102 tests ok 1487s 22:13:55.609050749 E: run test putty-transfer.sh ... 1489s 22:13:56.989006761 O: plink version 0.83 major 0 minor 83 1489s 22:13:57.007381111 O: putty transfer data: compression 0 1491s 22:13:59.530153411 O: putty transfer data: compression 1 1494s 22:14:02.065717459 O: ok putty transfer data 1494s 22:14:02.066503874 E: run test putty-ciphers.sh ... 1495s 22:14:03.778286788 O: plink version 0.83 major 0 minor 83 1496s 22:14:04.110825981 O: putty ciphers: cipher default mac default 1496s 22:14:04.442591278 O: putty ciphers: cipher default mac hmac-sha1 1496s 22:14:04.763077321 O: putty ciphers: cipher default mac hmac-sha1-96 1497s 22:14:05.076006820 O: putty ciphers: cipher default mac hmac-sha2-256 1497s 22:14:05.392356262 O: putty ciphers: cipher default mac hmac-sha2-512 1497s 22:14:05.720458647 O: putty ciphers: cipher default mac hmac-md5 1498s 22:14:06.045705777 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1498s 22:14:06.369326594 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1498s 22:14:06.691689147 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1499s 22:14:07.013380567 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1499s 22:14:07.343279140 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1499s 22:14:07.668956193 O: putty ciphers: cipher 3des-cbc mac default 1500s 22:14:07.999175973 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1500s 22:14:08.877968782 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1501s 22:14:09.209294498 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1501s 22:14:09.538629855 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1501s 22:14:09.862959677 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1502s 22:14:10.183809951 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1502s 22:14:10.519505226 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1502s 22:14:10.852493209 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1503s 22:14:11.183864120 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1503s 22:14:11.525793709 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1503s 22:14:11.861475541 O: putty ciphers: cipher aes128-cbc mac default 1504s 22:14:12.192194796 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1504s 22:14:12.522036954 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1504s 22:14:12.854809488 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1505s 22:14:13.189695460 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1505s 22:14:13.515724343 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1505s 22:14:13.845903304 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1506s 22:14:14.173524656 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1506s 22:14:14.508260221 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1506s 22:14:14.835062876 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1507s 22:14:15.190903078 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1507s 22:14:15.518401503 O: putty ciphers: cipher aes192-cbc mac default 1507s 22:14:15.850799861 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1508s 22:14:16.177476989 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1508s 22:14:16.511100967 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1508s 22:14:16.844174215 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1509s 22:14:17.190243627 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1509s 22:14:17.515745289 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1509s 22:14:17.854929249 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1510s 22:14:18.187905371 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1510s 22:14:18.520194558 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1510s 22:14:18.855269278 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1511s 22:14:19.186291480 O: putty ciphers: cipher aes256-cbc mac default 1511s 22:14:19.537748746 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1511s 22:14:19.875489113 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1512s 22:14:20.201516218 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1512s 22:14:20.533430993 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1512s 22:14:20.869965615 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1513s 22:14:21.196719531 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1513s 22:14:21.540858893 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1513s 22:14:21.873034550 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1514s 22:14:22.202148787 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1514s 22:14:22.543588536 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1514s 22:14:22.870834617 O: putty ciphers: cipher aes128-ctr mac default 1515s 22:14:23.203567280 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1515s 22:14:23.530827079 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1515s 22:14:23.856708612 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1516s 22:14:24.198394841 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1516s 22:14:24.532504966 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1516s 22:14:24.855305238 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1517s 22:14:25.185313524 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1517s 22:14:25.511535018 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1517s 22:14:25.842114554 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1518s 22:14:26.180503555 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1518s 22:14:26.515971540 O: putty ciphers: cipher aes192-ctr mac default 1518s 22:14:26.858334455 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1519s 22:14:27.592604930 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1519s 22:14:27.918043364 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1520s 22:14:28.254776688 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1520s 22:14:28.586047868 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1521s 22:14:28.918554432 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1521s 22:14:29.255177951 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1521s 22:14:29.598224550 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1521s 22:14:29.926854398 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1522s 22:14:30.260140812 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1522s 22:14:30.589270907 O: putty ciphers: cipher aes256-ctr mac default 1522s 22:14:30.923216732 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1523s 22:14:31.249637175 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1523s 22:14:31.571477130 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1523s 22:14:31.895883854 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1524s 22:14:32.231571147 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1524s 22:14:32.556124151 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1524s 22:14:32.889128993 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1525s 22:14:33.220310880 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1525s 22:14:33.551240160 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1525s 22:14:33.912438968 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1526s 22:14:34.242060222 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1526s 22:14:34.575923515 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1526s 22:14:34.902046223 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1527s 22:14:35.237341901 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1527s 22:14:35.568596062 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1527s 22:14:35.895432461 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1528s 22:14:36.244940002 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1528s 22:14:36.579184496 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1528s 22:14:36.913636115 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1529s 22:14:37.247248436 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1529s 22:14:37.581386125 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1529s 22:14:37.938462684 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1530s 22:14:38.277263539 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1530s 22:14:38.616334719 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1530s 22:14:38.942962345 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1531s 22:14:39.287100777 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1531s 22:14:39.630576276 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1531s 22:14:39.964487797 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1532s 22:14:40.301160086 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1532s 22:14:40.636743875 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1533s 22:14:40.983274148 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1533s 22:14:41.324607442 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1533s 22:14:41.657021249 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1534s 22:14:41.991731538 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1534s 22:14:42.322052584 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1534s 22:14:42.649250931 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1535s 22:14:42.981796617 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1535s 22:14:43.316510942 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1536s 22:14:44.119924770 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1536s 22:14:44.451114986 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1536s 22:14:44.780897016 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1537s 22:14:45.110792887 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1537s 22:14:45.459153420 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1537s 22:14:45.804424856 O: ok putty ciphers 1537s 22:14:45.805810842 E: run test putty-kex.sh ... 1540s 22:14:48.013798015 O: plink version 0.83 major 0 minor 83 1540s 22:14:48.210147031 O: putty KEX: kex diffie-hellman-group1-sha1 1540s 22:14:48.495877110 O: putty KEX: kex diffie-hellman-group14-sha1 1540s 22:14:48.864532533 O: putty KEX: kex diffie-hellman-group14-sha256 1541s 22:14:49.243887113 O: putty KEX: kex diffie-hellman-group16-sha512 1542s 22:14:50.221052095 O: putty KEX: kex diffie-hellman-group18-sha512 1547s 22:14:55.300612327 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1547s 22:14:55.688833973 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1548s 22:14:56.705523759 O: putty KEX: kex ecdh-sha2-nistp256 1549s 22:14:56.999610335 O: putty KEX: kex ecdh-sha2-nistp384 1549s 22:14:57.301676137 O: putty KEX: kex ecdh-sha2-nistp521 1549s 22:14:57.654212075 O: putty KEX: kex curve25519-sha256 1549s 22:14:57.928514082 O: putty KEX: kex curve25519-sha256@libssh.org 1550s 22:14:58.670391716 O: putty KEX: kex sntrup761x25519-sha512 1551s 22:14:58.992003318 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1551s 22:14:59.310771945 O: putty KEX: kex mlkem768x25519-sha256 1551s 22:14:59.592418203 O: ok putty KEX 1551s 22:14:59.594413800 E: run test conch-ciphers.sh ... 1552s 22:15:00.152897348 O: conch ciphers: cipher aes256-ctr 1553s 22:15:01.286194737 O: conch ciphers: cipher aes256-cbc 1554s 22:15:02.330951558 O: conch ciphers: cipher aes192-ctr 1555s 22:15:03.295428125 O: conch ciphers: cipher aes192-cbc 1556s 22:15:04.250267027 O: conch ciphers: cipher aes128-ctr 1557s 22:15:05.508359567 O: conch ciphers: cipher aes128-cbc 1558s 22:15:06.587487909 O: conch ciphers: cipher cast128-cbc 1559s 22:15:07.617201490 O: conch ciphers: cipher blowfish 1560s 22:15:08.900713428 O: conch ciphers: cipher 3des-cbc 1561s 22:15:09.958009459 O: ok conch ciphers 1561s 22:15:09.958729432 E: run test dropbear-ciphers.sh ... 1562s 22:15:10.298131893 O: Create dropbear key type ed25519 1562s 22:15:10.332626249 O: Create dropbear key type rsa 1564s 22:15:12.092776508 O: Create dropbear key type ecdsa 1564s 22:15:12.242275583 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1564s 22:15:12.625174920 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1565s 22:15:12.991613634 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1565s 22:15:13.378720766 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1565s 22:15:13.792048100 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1566s 22:15:14.172868155 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1566s 22:15:14.573581414 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1567s 22:15:14.988206130 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1567s 22:15:15.358075218 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1567s 22:15:15.758219144 E: run test dropbear-kex.sh ... 1567s 22:15:15.758907837 O: ok dropbear ciphers 1568s 22:15:16.306663998 O: dropbear kex: kex curve25519-sha256 1568s 22:15:16.651286739 O: dropbear kex: kex curve25519-sha256@libssh.org 1569s 22:15:16.989865889 O: dropbear kex: kex diffie-hellman-group14-sha256 1569s 22:15:17.391181110 O: ok dropbear kex 1569s 22:15:17.392195889 E: run test dropbear-server.sh ... 1571s 22:15:19.951722985 O: ok dropbear server 1572s 22:15:19.968134286 O: make: Leaving directory '/tmp/autopkgtest.CcbTkJ/autopkgtest_tmp/regress' 1572s 22:15:19.975092294 I: Finished with exitcode 0 1572s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1572s autopkgtest [22:15:20]: test regress: -----------------------] 1577s regress PASS 1577s autopkgtest [22:15:25]: test regress: - - - - - - - - - - results - - - - - - - - - - 1581s autopkgtest [22:15:29]: test ssh-gssapi: preparing testbed 1605s autopkgtest [22:15:53]: testbed dpkg architecture: armhf 1606s autopkgtest [22:15:54]: testbed apt version: 3.1.13 1610s autopkgtest [22:15:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1612s autopkgtest [22:16:00]: testbed release detected to be: resolute 1619s autopkgtest [22:16:07]: updating testbed package index (apt update) 1621s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 1622s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 1622s Get:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease [124 kB] 1622s Get:4 http://ftpmaster.internal/ubuntu resolute-security InRelease [124 kB] 1622s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1021 kB] 1622s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [97.3 kB] 1622s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 1622s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 1622s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [109 kB] 1622s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [827 kB] 1622s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [8712 B] 1622s Get:12 http://ftpmaster.internal/ubuntu resolute/multiverse Sources [310 kB] 1622s Get:13 http://ftpmaster.internal/ubuntu resolute/main Sources [1399 kB] 1622s Get:14 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.4 MB] 1624s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1371 kB] 1624s Get:16 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.3 MB] 1624s Get:17 http://ftpmaster.internal/ubuntu resolute/multiverse armhf Packages [175 kB] 1628s Fetched 42.5 MB in 6s (6632 kB/s) 1629s Reading package lists... 1634s autopkgtest [22:16:22]: upgrading testbed (apt dist-upgrade and autopurge) 1636s Reading package lists... 1637s Building dependency tree... 1637s Reading state information... 1637s Calculating upgrade... 1639s The following packages will be upgraded: 1639s dhcpcd-base libplymouth5 libssl3t64 libtasn1-6 openssl 1639s openssl-provider-legacy plymouth plymouth-theme-ubuntu-text 1639s python3-jaraco.context 1639s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1639s Need to get 3689 kB of archives. 1639s After this operation, 19.5 kB disk space will be freed. 1639s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl-provider-legacy armhf 3.5.4-1ubuntu1 [30.7 kB] 1639s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libssl3t64 armhf 3.5.4-1ubuntu1 [1902 kB] 1640s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf dhcpcd-base armhf 1:10.3.0-7 [194 kB] 1640s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf libtasn1-6 armhf 4.21.0-2 [38.5 kB] 1640s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl armhf 3.5.4-1ubuntu1 [1214 kB] 1640s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf libplymouth5 armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [147 kB] 1640s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf plymouth-theme-ubuntu-text armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [9996 B] 1640s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf plymouth armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [146 kB] 1640s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf python3-jaraco.context all 6.0.1-2 [8198 B] 1641s Fetched 3689 kB in 1s (2599 kB/s) 1641s (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 ... 66485 files and directories currently installed.) 1641s Preparing to unpack .../openssl-provider-legacy_3.5.4-1ubuntu1_armhf.deb ... 1641s Unpacking openssl-provider-legacy (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 1641s Setting up openssl-provider-legacy (3.5.4-1ubuntu1) ... 1641s (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 ... 66485 files and directories currently installed.) 1641s Preparing to unpack .../libssl3t64_3.5.4-1ubuntu1_armhf.deb ... 1641s Unpacking libssl3t64:armhf (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 1641s Setting up libssl3t64:armhf (3.5.4-1ubuntu1) ... 1641s (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 ... 66485 files and directories currently installed.) 1641s Preparing to unpack .../0-dhcpcd-base_1%3a10.3.0-7_armhf.deb ... 1641s Unpacking dhcpcd-base (1:10.3.0-7) over (1:10.3.0-3) ... 1642s Preparing to unpack .../1-libtasn1-6_4.21.0-2_armhf.deb ... 1642s Unpacking libtasn1-6:armhf (4.21.0-2) over (4.20.0-2ubuntu1) ... 1642s Preparing to unpack .../2-openssl_3.5.4-1ubuntu1_armhf.deb ... 1642s Unpacking openssl (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 1642s Preparing to unpack .../3-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 1642s Unpacking libplymouth5:armhf (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 1642s Preparing to unpack .../4-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 1642s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 1642s Preparing to unpack .../5-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 1642s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 1642s Preparing to unpack .../6-python3-jaraco.context_6.0.1-2_all.deb ... 1642s Unpacking python3-jaraco.context (6.0.1-2) over (6.0.1-1build1) ... 1642s Setting up python3-jaraco.context (6.0.1-2) ... 1643s Setting up dhcpcd-base (1:10.3.0-7) ... 1643s Setting up libplymouth5:armhf (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 1643s Setting up libtasn1-6:armhf (4.21.0-2) ... 1643s Setting up openssl (3.5.4-1ubuntu1) ... 1643s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 1643s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 1643s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 1643s Processing triggers for libc-bin (2.42-2ubuntu4) ... 1643s Processing triggers for man-db (2.13.1-1) ... 1645s Processing triggers for initramfs-tools (0.150ubuntu7) ... 1645s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 1645s Processing triggers for initramfs-tools (0.150ubuntu7) ... 1647s Reading package lists... 1647s Building dependency tree... 1647s Reading state information... 1648s Solving dependencies... 1648s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1650s autopkgtest [22:16:38]: rebooting testbed after setup commands that affected boot 1713s Reading package lists... 1714s Building dependency tree... 1714s Reading state information... 1714s Solving dependencies... 1715s The following NEW packages will be installed: 1715s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1715s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1715s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1715s openssh-server-gssapi 1715s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1715s Need to get 727 kB of archives. 1715s After this operation, 3218 kB of additional disk space will be used. 1715s Get:1 http://ftpmaster.internal/ubuntu resolute/main armhf krb5-config all 2.7build1 [19.6 kB] 1715s Get:2 http://ftpmaster.internal/ubuntu resolute/main armhf libgssrpc4t64 armhf 1.22.1-2 [51.8 kB] 1715s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf libkadm5clnt-mit12 armhf 1.22.1-2 [35.7 kB] 1715s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf libkdb5-10t64 armhf 1.22.1-2 [36.0 kB] 1715s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf libkadm5srv-mit12 armhf 1.22.1-2 [46.7 kB] 1715s Get:6 http://ftpmaster.internal/ubuntu resolute/universe armhf krb5-user armhf 1.22.1-2 [111 kB] 1715s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf libevent-2.1-7t64 armhf 2.1.12-stable-10build1 [130 kB] 1715s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf libverto1t64 armhf 0.3.1-1.2ubuntu4 [9618 B] 1715s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf libverto-libevent1t64 armhf 0.3.1-1.2ubuntu4 [6520 B] 1715s Get:10 http://ftpmaster.internal/ubuntu resolute/universe armhf krb5-kdc armhf 1.22.1-2 [177 kB] 1715s Get:11 http://ftpmaster.internal/ubuntu resolute/universe armhf krb5-admin-server armhf 1.22.1-2 [92.8 kB] 1715s Get:12 http://ftpmaster.internal/ubuntu resolute/universe armhf openssh-client-gssapi all 1:10.2p1-2ubuntu1 [5032 B] 1715s Get:13 http://ftpmaster.internal/ubuntu resolute/universe armhf openssh-server-gssapi all 1:10.2p1-2ubuntu1 [5038 B] 1716s Preconfiguring packages ... 1716s Fetched 727 kB in 1s (1024 kB/s) 1716s Selecting previously unselected package krb5-config. 1716s (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 ... 66479 files and directories currently installed.) 1716s Preparing to unpack .../00-krb5-config_2.7build1_all.deb ... 1716s Unpacking krb5-config (2.7build1) ... 1716s Selecting previously unselected package libgssrpc4t64:armhf. 1716s Preparing to unpack .../01-libgssrpc4t64_1.22.1-2_armhf.deb ... 1716s Unpacking libgssrpc4t64:armhf (1.22.1-2) ... 1716s Selecting previously unselected package libkadm5clnt-mit12:armhf. 1716s Preparing to unpack .../02-libkadm5clnt-mit12_1.22.1-2_armhf.deb ... 1716s Unpacking libkadm5clnt-mit12:armhf (1.22.1-2) ... 1716s Selecting previously unselected package libkdb5-10t64:armhf. 1716s Preparing to unpack .../03-libkdb5-10t64_1.22.1-2_armhf.deb ... 1716s Unpacking libkdb5-10t64:armhf (1.22.1-2) ... 1716s Selecting previously unselected package libkadm5srv-mit12:armhf. 1716s Preparing to unpack .../04-libkadm5srv-mit12_1.22.1-2_armhf.deb ... 1716s Unpacking libkadm5srv-mit12:armhf (1.22.1-2) ... 1716s Selecting previously unselected package krb5-user. 1716s Preparing to unpack .../05-krb5-user_1.22.1-2_armhf.deb ... 1716s Unpacking krb5-user (1.22.1-2) ... 1716s Selecting previously unselected package libevent-2.1-7t64:armhf. 1716s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10build1_armhf.deb ... 1716s Unpacking libevent-2.1-7t64:armhf (2.1.12-stable-10build1) ... 1716s Selecting previously unselected package libverto1t64:armhf. 1716s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu4_armhf.deb ... 1716s Unpacking libverto1t64:armhf (0.3.1-1.2ubuntu4) ... 1716s Selecting previously unselected package libverto-libevent1t64:armhf. 1716s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu4_armhf.deb ... 1716s Unpacking libverto-libevent1t64:armhf (0.3.1-1.2ubuntu4) ... 1717s Selecting previously unselected package krb5-kdc. 1717s Preparing to unpack .../09-krb5-kdc_1.22.1-2_armhf.deb ... 1717s Unpacking krb5-kdc (1.22.1-2) ... 1717s Selecting previously unselected package krb5-admin-server. 1717s Preparing to unpack .../10-krb5-admin-server_1.22.1-2_armhf.deb ... 1717s Unpacking krb5-admin-server (1.22.1-2) ... 1717s Selecting previously unselected package openssh-client-gssapi. 1717s Preparing to unpack .../11-openssh-client-gssapi_1%3a10.2p1-2ubuntu1_all.deb ... 1717s Unpacking openssh-client-gssapi (1:10.2p1-2ubuntu1) ... 1717s Selecting previously unselected package openssh-server-gssapi. 1717s Preparing to unpack .../12-openssh-server-gssapi_1%3a10.2p1-2ubuntu1_all.deb ... 1717s Unpacking openssh-server-gssapi (1:10.2p1-2ubuntu1) ... 1717s Setting up libevent-2.1-7t64:armhf (2.1.12-stable-10build1) ... 1717s Setting up openssh-client-gssapi (1:10.2p1-2ubuntu1) ... 1717s Setting up libgssrpc4t64:armhf (1.22.1-2) ... 1717s Setting up krb5-config (2.7build1) ... 1718s Setting up libkadm5clnt-mit12:armhf (1.22.1-2) ... 1718s Setting up openssh-server-gssapi (1:10.2p1-2ubuntu1) ... 1718s Setting up libkdb5-10t64:armhf (1.22.1-2) ... 1718s Setting up libkadm5srv-mit12:armhf (1.22.1-2) ... 1718s Setting up krb5-user (1.22.1-2) ... 1718s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1718s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1718s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1718s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1718s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1718s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1718s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1718s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1718s Setting up libverto1t64:armhf (0.3.1-1.2ubuntu4) ... 1718s Setting up libverto-libevent1t64:armhf (0.3.1-1.2ubuntu4) ... 1718s Setting up krb5-kdc (1.22.1-2) ... 1718s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1719s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1719s Setting up krb5-admin-server (1.22.1-2) ... 1719s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1720s Processing triggers for libc-bin (2.42-2ubuntu4) ... 1720s Processing triggers for man-db (2.13.1-1) ... 1734s autopkgtest [22:18:02]: test ssh-gssapi: [----------------------- 1736s usermod: no changes 1736s usermod: no changes 1736s Generating public/private ed25519 key pair. 1736s Your identification has been saved in /root/.ssh/id_ed25519 1736s Your public key has been saved in /root/.ssh/id_ed25519.pub 1736s The key fingerprint is: 1736s SHA256:ITvGclEGFXVqFRfDv9oKtr5CP29TuTH7U0uCVcVpkgc root@autopkgtest-lxd-tspvwn 1736s The key's randomart image is: 1736s +--[ED25519 256]--+ 1736s | .o=o. +E*o+| 1736s | o + +.*.| 1736s | o . o =. | 1736s | . + o . .| 1736s | . * S o o| 1736s | + . . . . B.| 1736s | . .o * B| 1736s | ..oo+ * | 1736s | o+=oo +| 1736s +----[SHA256]-----+ 1736s ## Setting up test environment 1736s ## Creating Kerberos realm EXAMPLE.FAKE 1736s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1736s master key name 'K/M@EXAMPLE.FAKE' 1736s ## Creating principals 1736s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1736s Principal "testuser1170@EXAMPLE.FAKE" created. 1736s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1736s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1736s ## Extracting service principal host/sshd-gssapi.example.fake 1736s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1736s 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. 1736s 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. 1736s ## Adjusting /etc/krb5.conf 1736s ## TESTS 1736s 1736s ## TEST test_gssapi_login 1736s ## Configuring sshd for gssapi-with-mic authentication 1736s ## Restarting ssh 1736s ## Obtaining TGT 1736s Password for testuser1170@EXAMPLE.FAKE: 1737s Ticket cache: FILE:/tmp/krb5cc_0 1737s Default principal: testuser1170@EXAMPLE.FAKE 1737s 1737s Valid starting Expires Service principal 1737s 01/23/26 22:18:04 01/24/26 08:18:04 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1737s renew until 01/24/26 22:18:04 1737s 1737s ## ssh'ing into localhost using gssapi-with-mic auth 1737s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1737s Fri Jan 23 22:18:05 UTC 2026 1737s 1737s ## checking that we got a service ticket for ssh (host/) 1737s 01/23/26 22:18:05 01/24/26 08:18:04 host/sshd-gssapi.example.fake@ 1737s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1737s 1737s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1737s Jan 23 22:18:05 sshd-gssapi.example.fake sshd-session[1267]: Accepted gssapi-with-mic for testuser1170 from 127.0.0.1 port 42486 ssh2: testuser1170@EXAMPLE.FAKE 1737s ## PASS test_gssapi_login 1737s 1737s ## TEST test_gssapi_keyex_login 1737s ## Configuring sshd for gssapi-keyex authentication 1737s ## Restarting ssh 1737s ## Obtaining TGT 1737s Password for testuser1170@EXAMPLE.FAKE: 1737s Ticket cache: FILE:/tmp/krb5cc_0 1737s Default principal: testuser1170@EXAMPLE.FAKE 1737s 1737s Valid starting Expires Service principal 1737s 01/23/26 22:18:05 01/24/26 08:18:05 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1737s renew until 01/24/26 22:18:05 1737s 1737s ## ssh'ing into localhost using gssapi-keyex auth 1737s ** WARNING: connection is not using a post-quantum key exchange algorithm. 1737s ** This session may be vulnerable to "store now, decrypt later" attacks. 1737s ** The server may need to be upgraded. See https://openssh.com/pq.html 1737s Fri Jan 23 22:18:05 UTC 2026 1737s 1737s ## checking that we got a service ticket for ssh (host/) 1737s 01/23/26 22:18:05 01/24/26 08:18:05 host/sshd-gssapi.example.fake@ 1737s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1737s 1737s ## Checking ssh logs to confirm gssapi-keyex auth was used 1738s Jan 23 22:18:05 sshd-gssapi.example.fake sshd-session[1328]: Accepted gssapi-keyex for testuser1170 from 127.0.0.1 port 42500 ssh2: testuser1170@EXAMPLE.FAKE 1738s ## PASS test_gssapi_keyex_login 1738s 1738s ## TEST test_gssapi_keyex_pubkey_fallback 1738s ## Configuring sshd for gssapi-keyex authentication 1738s ## Restarting ssh 1738s ## Obtaining TGT 1738s Password for testuser1170@EXAMPLE.FAKE: 1738s Ticket cache: FILE:/tmp/krb5cc_0 1738s Default principal: testuser1170@EXAMPLE.FAKE 1738s 1738s Valid starting Expires Service principal 1738s 01/23/26 22:18:06 01/24/26 08:18:06 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1738s renew until 01/24/26 22:18:06 1738s 1738s ## ssh'ing into localhost using gssapi-keyex auth 1738s ** WARNING: connection is not using a post-quantum key exchange algorithm. 1738s ** This session may be vulnerable to "store now, decrypt later" attacks. 1738s ** The server may need to be upgraded. See https://openssh.com/pq.html 1738s Fri Jan 23 22:18:06 UTC 2026 1738s 1738s ## checking that we got a service ticket for ssh (host/) 1738s 01/23/26 22:18:06 01/24/26 08:18:06 host/sshd-gssapi.example.fake@ 1738s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1738s 1738s ## Checking ssh logs to confirm publickey auth was used 1738s Jan 23 22:18:06 sshd-gssapi.example.fake sshd-session[1370]: Accepted publickey for testuser1170-2 from 127.0.0.1 port 42512 ssh2: ED25519 SHA256:ITvGclEGFXVqFRfDv9oKtr5CP29TuTH7U0uCVcVpkgc 1738s ## PASS test_gssapi_keyex_pubkey_fallback 1738s 1738s ## ALL TESTS PASSED 1738s ## Cleaning up 1739s autopkgtest [22:18:07]: test ssh-gssapi: -----------------------] 1743s autopkgtest [22:18:11]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1743s ssh-gssapi PASS 1747s autopkgtest [22:18:15]: test socket-activation: preparing testbed 1769s autopkgtest [22:18:37]: testbed dpkg architecture: armhf 1771s autopkgtest [22:18:39]: testbed apt version: 3.1.13 1775s autopkgtest [22:18:43]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1776s autopkgtest [22:18:44]: testbed release detected to be: resolute 1784s autopkgtest [22:18:52]: updating testbed package index (apt update) 1786s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 1786s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 1786s Get:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease [124 kB] 1786s Get:4 http://ftpmaster.internal/ubuntu resolute-security InRelease [124 kB] 1786s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 1786s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 1786s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1021 kB] 1786s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [97.3 kB] 1786s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [109 kB] 1786s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [827 kB] 1787s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [8712 B] 1787s Get:12 http://ftpmaster.internal/ubuntu resolute/multiverse Sources [310 kB] 1787s Get:13 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.4 MB] 1789s Get:14 http://ftpmaster.internal/ubuntu resolute/main Sources [1399 kB] 1789s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1371 kB] 1789s Get:16 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.3 MB] 1790s Get:17 http://ftpmaster.internal/ubuntu resolute/multiverse armhf Packages [175 kB] 1793s Fetched 42.5 MB in 7s (5813 kB/s) 1794s Reading package lists... 1799s autopkgtest [22:19:07]: upgrading testbed (apt dist-upgrade and autopurge) 1801s Reading package lists... 1801s Building dependency tree... 1801s Reading state information... 1802s Calculating upgrade... 1802s The following packages will be upgraded: 1802s dhcpcd-base libplymouth5 libssl3t64 libtasn1-6 openssl 1802s openssl-provider-legacy plymouth plymouth-theme-ubuntu-text 1802s python3-jaraco.context 1803s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1803s Need to get 3689 kB of archives. 1803s After this operation, 19.5 kB disk space will be freed. 1803s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl-provider-legacy armhf 3.5.4-1ubuntu1 [30.7 kB] 1803s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libssl3t64 armhf 3.5.4-1ubuntu1 [1902 kB] 1804s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf dhcpcd-base armhf 1:10.3.0-7 [194 kB] 1804s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf libtasn1-6 armhf 4.21.0-2 [38.5 kB] 1804s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl armhf 3.5.4-1ubuntu1 [1214 kB] 1804s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf libplymouth5 armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [147 kB] 1804s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf plymouth-theme-ubuntu-text armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [9996 B] 1804s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf plymouth armhf 24.004.60+git20250831.4a3c171d-0ubuntu5 [146 kB] 1804s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf python3-jaraco.context all 6.0.1-2 [8198 B] 1805s Fetched 3689 kB in 2s (2115 kB/s) 1805s (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 ... 66485 files and directories currently installed.) 1805s Preparing to unpack .../openssl-provider-legacy_3.5.4-1ubuntu1_armhf.deb ... 1805s Unpacking openssl-provider-legacy (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 1805s Setting up openssl-provider-legacy (3.5.4-1ubuntu1) ... 1805s (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 ... 66485 files and directories currently installed.) 1805s Preparing to unpack .../libssl3t64_3.5.4-1ubuntu1_armhf.deb ... 1805s Unpacking libssl3t64:armhf (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 1805s Setting up libssl3t64:armhf (3.5.4-1ubuntu1) ... 1805s (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 ... 66485 files and directories currently installed.) 1805s Preparing to unpack .../0-dhcpcd-base_1%3a10.3.0-7_armhf.deb ... 1805s Unpacking dhcpcd-base (1:10.3.0-7) over (1:10.3.0-3) ... 1805s Preparing to unpack .../1-libtasn1-6_4.21.0-2_armhf.deb ... 1805s Unpacking libtasn1-6:armhf (4.21.0-2) over (4.20.0-2ubuntu1) ... 1805s Preparing to unpack .../2-openssl_3.5.4-1ubuntu1_armhf.deb ... 1805s Unpacking openssl (3.5.4-1ubuntu1) over (3.5.3-1ubuntu2) ... 1805s Preparing to unpack .../3-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 1805s Unpacking libplymouth5:armhf (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 1805s Preparing to unpack .../4-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 1805s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 1805s Preparing to unpack .../5-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu5_armhf.deb ... 1806s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 1806s Preparing to unpack .../6-python3-jaraco.context_6.0.1-2_all.deb ... 1806s Unpacking python3-jaraco.context (6.0.1-2) over (6.0.1-1build1) ... 1806s Setting up python3-jaraco.context (6.0.1-2) ... 1806s Setting up dhcpcd-base (1:10.3.0-7) ... 1806s Setting up libplymouth5:armhf (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 1806s Setting up libtasn1-6:armhf (4.21.0-2) ... 1806s Setting up openssl (3.5.4-1ubuntu1) ... 1806s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 1806s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 1806s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 1807s Processing triggers for libc-bin (2.42-2ubuntu4) ... 1807s Processing triggers for man-db (2.13.1-1) ... 1808s Processing triggers for initramfs-tools (0.150ubuntu7) ... 1808s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 1809s Processing triggers for initramfs-tools (0.150ubuntu7) ... 1811s Reading package lists... 1811s Building dependency tree... 1811s Reading state information... 1811s Solving dependencies... 1813s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1814s autopkgtest [22:19:22]: rebooting testbed after setup commands that affected boot 1877s Reading package lists... 1878s Building dependency tree... 1878s Reading state information... 1878s Solving dependencies... 1879s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1895s autopkgtest [22:20:43]: ERROR: unexpected error: 1895s Traceback (most recent call last): 1895s File "/home/ubuntu/autopkgtest/runner/autopkgtest", line 916, in main 1895s process_actions() 1895s File "/home/ubuntu/autopkgtest/runner/autopkgtest", line 868, in process_actions 1895s run_tests(tests, tests_tree) 1895s File "/home/ubuntu/autopkgtest/runner/autopkgtest", line 209, in run_tests 1895s testbed.run_test(tree, t, opts.env, opts.shell_fail, opts.shell, 1895s File "/home/ubuntu/autopkgtest/lib/adt_testbed.py", line 1335, in run_test 1895s tree.copydown(check_existing=True) 1895s File "/home/ubuntu/autopkgtest/lib/adt_testbed.py", line 2185, in copydown 1895s assert self.insecure 1895s AttributeError: 'TestbedPath' object has no attribute 'insecure'