0s autopkgtest [14:11:34]: starting date and time: 2025-05-05 14:11:34+0000 0s autopkgtest [14:11:34]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [14:11:34]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._jqb0wfv/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ucf,src:dropbear --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ucf/3.0051 dropbear/2025.87-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-9.secgroup --name adt-questing-amd64-openssh-20250505-141134-juju-7f2275-prod-proposed-migration-environment-2-080cc25d-1899-4bbc-aa48-20e8e3156f79 --image adt/ubuntu-questing-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 55s autopkgtest [14:12:29]: testbed dpkg architecture: amd64 55s autopkgtest [14:12:29]: testbed apt version: 3.0.0 56s autopkgtest [14:12:30]: @@@@@@@@@@@@@@@@@@@@ test bed setup 56s autopkgtest [14:12:30]: testbed release detected to be: None 56s autopkgtest [14:12:30]: updating testbed package index (apt update) 57s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 57s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 57s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 57s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 57s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 57s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 58s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 58s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [131 kB] 58s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [92.4 kB] 58s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [355 kB] 58s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [966 kB] 58s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [21.5 kB] 58s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [12.0 kB] 58s Fetched 2719 kB in 1s (2701 kB/s) 59s Reading package lists... 59s autopkgtest [14:12:33]: upgrading testbed (apt dist-upgrade and autopurge) 60s Reading package lists... 60s Building dependency tree... 60s Reading state information... 60s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 60s Starting 2 pkgProblemResolver with broken count: 0 60s Done 61s Entering ResolveByKeep 61s 61s Calculating upgrade... 61s The following packages will be upgraded: 61s groff-base libgpg-error-l10n libgpg-error0 libselinux1 netbase ucf 62s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 62s Need to get 1255 kB of archives. 62s After this operation, 225 kB disk space will be freed. 62s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libselinux1 amd64 3.8.1-1 [89.8 kB] 62s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error-l10n all 1.51-4 [8880 B] 62s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error0 amd64 1.51-4 [76.9 kB] 62s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 netbase all 6.5 [12.9 kB] 62s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB] 62s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 groff-base amd64 1.23.0-8 [1023 kB] 62s Preconfiguring packages ... 62s Fetched 1255 kB in 1s (2327 kB/s) 62s (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 ... 80527 files and directories currently installed.) 62s Preparing to unpack .../libselinux1_3.8.1-1_amd64.deb ... 62s Unpacking libselinux1:amd64 (3.8.1-1) over (3.7-3ubuntu3) ... 62s Setting up libselinux1:amd64 (3.8.1-1) ... 63s (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 ... 80527 files and directories currently installed.) 63s Preparing to unpack .../libgpg-error-l10n_1.51-4_all.deb ... 63s Unpacking libgpg-error-l10n (1.51-4) over (1.51-3) ... 63s Preparing to unpack .../libgpg-error0_1.51-4_amd64.deb ... 63s Unpacking libgpg-error0:amd64 (1.51-4) over (1.51-3) ... 63s Setting up libgpg-error0:amd64 (1.51-4) ... 63s (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 ... 80527 files and directories currently installed.) 63s Preparing to unpack .../archives/netbase_6.5_all.deb ... 63s Unpacking netbase (6.5) over (6.4) ... 63s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 63s Unpacking ucf (3.0051) over (3.0050) ... 63s Preparing to unpack .../groff-base_1.23.0-8_amd64.deb ... 63s Unpacking groff-base (1.23.0-8) over (1.23.0-7) ... 63s Setting up ucf (3.0051) ... 63s Setting up groff-base (1.23.0-8) ... 63s Setting up netbase (6.5) ... 63s Installing new version of config file /etc/ethertypes ... 63s Installing new version of config file /etc/services ... 63s Setting up libgpg-error-l10n (1.51-4) ... 63s Processing triggers for man-db (2.13.1-1) ... 64s Processing triggers for libc-bin (2.41-6ubuntu1) ... 64s Reading package lists... 64s Building dependency tree... 64s Reading state information... 64s Starting pkgProblemResolver with broken count: 0 64s Starting 2 pkgProblemResolver with broken count: 0 64s Done 65s Solving dependencies... 65s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s autopkgtest [14:12:41]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 15:05:05 UTC 2025 68s autopkgtest [14:12:42]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 73s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (dsc) [3480 B] 73s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (tar) [1965 kB] 73s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (asc) [833 B] 73s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (diff) [212 kB] 73s gpgv: Signature made Tue Feb 18 14:21:37 2025 UTC 73s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 73s gpgv: Can't check signature: No public key 73s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.dsc: no acceptable signature found 73s autopkgtest [14:12:47]: testing package openssh version 1:9.9p1-3ubuntu3 75s autopkgtest [14:12:49]: build not needed 79s autopkgtest [14:12:53]: test regress: preparing testbed 79s Reading package lists... 79s Building dependency tree... 79s Reading state information... 79s Starting pkgProblemResolver with broken count: 0 79s Starting 2 pkgProblemResolver with broken count: 0 79s Done 80s The following NEW packages will be installed: 80s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 80s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 80s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 80s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 80s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 80s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 80s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 80s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 80s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 80s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 80s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 80s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 80s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 80s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 80s python3-pyasn1 python3-pyasn1-modules python3-service-identity 80s python3-twisted python3-zope.interface wdiff 80s 0 upgraded, 60 newly installed, 0 to remove and 0 not upgraded. 80s Need to get 8446 kB of archives. 80s After this operation, 38.9 MB of additional disk space will be used. 80s Get:1 http://ftpmaster.internal/ubuntu questing/universe amd64 libtommath1 amd64 1.3.0-1 [67.2 kB] 80s Get:2 http://ftpmaster.internal/ubuntu questing/universe amd64 libtomcrypt1 amd64 1.18.2+dfsg-7build1 [384 kB] 80s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 dropbear-bin amd64 2025.87-1 [178 kB] 80s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 dropbear all 2025.87-1 [8668 B] 80s Get:5 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-dirlist-perl all 0.05-3 [7286 B] 80s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-which-perl all 1.27-2 [12.5 kB] 80s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-homedir-perl all 1.006-2 [37.0 kB] 80s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-touch-perl all 0.12-2 [7498 B] 80s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libio-pty-perl amd64 1:1.20-1build3 [31.4 kB] 80s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 libipc-run-perl all 20231003.0-2 [91.5 kB] 80s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 80s Get:12 http://ftpmaster.internal/ubuntu questing/main amd64 libclass-xsaccessor-perl amd64 1.19-4build6 [33.8 kB] 80s Get:13 http://ftpmaster.internal/ubuntu questing/main amd64 libb-hooks-op-check-perl amd64 0.22-3build2 [9624 B] 80s Get:14 http://ftpmaster.internal/ubuntu questing/main amd64 libdynaloader-functions-perl all 0.004-2 [11.5 kB] 81s Get:15 http://ftpmaster.internal/ubuntu questing/main amd64 libdevel-callchecker-perl amd64 0.009-1build1 [14.2 kB] 81s Get:16 http://ftpmaster.internal/ubuntu questing/main amd64 libparams-classify-perl amd64 0.015-2build6 [20.1 kB] 81s Get:17 http://ftpmaster.internal/ubuntu questing/main amd64 libmodule-runtime-perl all 0.018-1 [15.2 kB] 81s Get:18 http://ftpmaster.internal/ubuntu questing/main amd64 libimport-into-perl all 1.002005-2 [10.7 kB] 81s Get:19 http://ftpmaster.internal/ubuntu questing/main amd64 librole-tiny-perl all 2.002004-1 [16.3 kB] 81s Get:20 http://ftpmaster.internal/ubuntu questing/main amd64 libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 81s Get:21 http://ftpmaster.internal/ubuntu questing/main amd64 libmoo-perl all 2.005005-1 [47.4 kB] 81s Get:22 http://ftpmaster.internal/ubuntu questing/main amd64 libencode-locale-perl all 1.05-3 [11.6 kB] 81s Get:23 http://ftpmaster.internal/ubuntu questing/main amd64 libtimedate-perl all 2.3300-2 [34.0 kB] 81s Get:24 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-date-perl all 6.06-1 [10.2 kB] 81s Get:25 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-listing-perl all 6.16-1 [11.3 kB] 81s Get:26 http://ftpmaster.internal/ubuntu questing/main amd64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 81s Get:27 http://ftpmaster.internal/ubuntu questing/main amd64 liburi-perl all 5.30-1 [94.4 kB] 81s Get:28 http://ftpmaster.internal/ubuntu questing/main amd64 libhtml-parser-perl amd64 3.83-1build1 [86.2 kB] 81s Get:29 http://ftpmaster.internal/ubuntu questing/main amd64 libhtml-tree-perl all 5.07-3 [200 kB] 81s Get:30 http://ftpmaster.internal/ubuntu questing/main amd64 libclone-perl amd64 0.47-1 [10.7 kB] 81s Get:31 http://ftpmaster.internal/ubuntu questing/main amd64 libio-html-perl all 1.004-3 [15.9 kB] 81s Get:32 http://ftpmaster.internal/ubuntu questing/main amd64 liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 81s Get:33 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 81s Get:34 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-cookies-perl all 6.11-1 [18.2 kB] 81s Get:35 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-negotiate-perl all 6.01-2 [12.4 kB] 81s Get:36 http://ftpmaster.internal/ubuntu questing/main amd64 perl-openssl-defaults amd64 7build3 [6626 B] 81s Get:37 http://ftpmaster.internal/ubuntu questing/main amd64 libnet-ssleay-perl amd64 1.94-3 [318 kB] 81s Get:38 http://ftpmaster.internal/ubuntu questing/main amd64 libio-socket-ssl-perl all 2.089-1 [200 kB] 81s Get:39 http://ftpmaster.internal/ubuntu questing/main amd64 libnet-http-perl all 6.23-1 [22.3 kB] 81s Get:40 http://ftpmaster.internal/ubuntu questing/main amd64 liblwp-protocol-https-perl all 6.14-1 [9040 B] 81s Get:41 http://ftpmaster.internal/ubuntu questing/main amd64 libtry-tiny-perl all 0.32-1 [21.2 kB] 81s Get:42 http://ftpmaster.internal/ubuntu questing/main amd64 libwww-robotrules-perl all 6.02-1 [12.6 kB] 81s Get:43 http://ftpmaster.internal/ubuntu questing/main amd64 libwww-perl all 6.78-1 [139 kB] 81s Get:44 http://ftpmaster.internal/ubuntu questing/main amd64 patchutils amd64 0.4.2-1build3 [77.0 kB] 81s Get:45 http://ftpmaster.internal/ubuntu questing/main amd64 wdiff amd64 1.2.2-9 [29.5 kB] 81s Get:46 http://ftpmaster.internal/ubuntu questing/main amd64 devscripts all 2.25.5 [1059 kB] 81s Get:47 http://ftpmaster.internal/ubuntu questing/universe amd64 libhavege2 amd64 1.9.19-11ubuntu1 [28.0 kB] 81s Get:48 http://ftpmaster.internal/ubuntu questing/universe amd64 putty-tools amd64 0.83-2 [801 kB] 81s Get:49 http://ftpmaster.internal/ubuntu questing/main amd64 python3-hamcrest all 2.1.0-1 [28.1 kB] 81s Get:50 http://ftpmaster.internal/ubuntu questing/main amd64 python3-pyasn1 all 0.6.1-1 [56.4 kB] 81s Get:51 http://ftpmaster.internal/ubuntu questing/main amd64 python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 81s Get:52 http://ftpmaster.internal/ubuntu questing/main amd64 python3-service-identity all 24.2.0-1 [11.0 kB] 81s Get:53 http://ftpmaster.internal/ubuntu questing/main amd64 python3-automat all 25.4.16-1 [35.7 kB] 81s Get:54 http://ftpmaster.internal/ubuntu questing/main amd64 python3-constantly all 23.10.4-2 [13.9 kB] 81s Get:55 http://ftpmaster.internal/ubuntu questing/main amd64 python3-hyperlink all 21.0.0-6 [68.0 kB] 81s Get:56 http://ftpmaster.internal/ubuntu questing/main amd64 python3-incremental all 24.7.2-3 [18.6 kB] 81s Get:57 http://ftpmaster.internal/ubuntu questing/main amd64 python3-zope.interface amd64 7.2-1build1 [142 kB] 81s Get:58 http://ftpmaster.internal/ubuntu questing/main amd64 python3-twisted all 24.11.0-1 [2066 kB] 81s Get:59 http://ftpmaster.internal/ubuntu questing/universe amd64 openssh-tests amd64 1:9.9p1-3ubuntu3 [1514 kB] 81s Get:60 http://ftpmaster.internal/ubuntu questing/universe amd64 haveged amd64 1.9.19-11ubuntu1 [32.9 kB] 82s Fetched 8446 kB in 2s (5597 kB/s) 82s Selecting previously unselected package libtommath1:amd64. 82s (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 ... 80527 files and directories currently installed.) 82s Preparing to unpack .../00-libtommath1_1.3.0-1_amd64.deb ... 82s Unpacking libtommath1:amd64 (1.3.0-1) ... 82s Selecting previously unselected package libtomcrypt1:amd64. 82s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_amd64.deb ... 82s Unpacking libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ... 82s Selecting previously unselected package dropbear-bin. 82s Preparing to unpack .../02-dropbear-bin_2025.87-1_amd64.deb ... 82s Unpacking dropbear-bin (2025.87-1) ... 82s Selecting previously unselected package dropbear. 82s Preparing to unpack .../03-dropbear_2025.87-1_all.deb ... 82s Unpacking dropbear (2025.87-1) ... 82s Selecting previously unselected package libfile-dirlist-perl. 82s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 82s Unpacking libfile-dirlist-perl (0.05-3) ... 82s Selecting previously unselected package libfile-which-perl. 82s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 82s Unpacking libfile-which-perl (1.27-2) ... 82s Selecting previously unselected package libfile-homedir-perl. 82s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 82s Unpacking libfile-homedir-perl (1.006-2) ... 82s Selecting previously unselected package libfile-touch-perl. 82s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 82s Unpacking libfile-touch-perl (0.12-2) ... 82s Selecting previously unselected package libio-pty-perl. 82s Preparing to unpack .../08-libio-pty-perl_1%3a1.20-1build3_amd64.deb ... 82s Unpacking libio-pty-perl (1:1.20-1build3) ... 82s Selecting previously unselected package libipc-run-perl. 82s Preparing to unpack .../09-libipc-run-perl_20231003.0-2_all.deb ... 82s Unpacking libipc-run-perl (20231003.0-2) ... 82s Selecting previously unselected package libclass-method-modifiers-perl. 82s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 82s Unpacking libclass-method-modifiers-perl (2.15-1) ... 82s Selecting previously unselected package libclass-xsaccessor-perl. 82s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_amd64.deb ... 82s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 82s Selecting previously unselected package libb-hooks-op-check-perl:amd64. 82s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_amd64.deb ... 82s Unpacking libb-hooks-op-check-perl:amd64 (0.22-3build2) ... 82s Selecting previously unselected package libdynaloader-functions-perl. 82s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-2_all.deb ... 82s Unpacking libdynaloader-functions-perl (0.004-2) ... 82s Selecting previously unselected package libdevel-callchecker-perl:amd64. 82s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-1build1_amd64.deb ... 82s Unpacking libdevel-callchecker-perl:amd64 (0.009-1build1) ... 82s Selecting previously unselected package libparams-classify-perl:amd64. 82s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_amd64.deb ... 82s Unpacking libparams-classify-perl:amd64 (0.015-2build6) ... 82s Selecting previously unselected package libmodule-runtime-perl. 82s Preparing to unpack .../16-libmodule-runtime-perl_0.018-1_all.deb ... 82s Unpacking libmodule-runtime-perl (0.018-1) ... 82s Selecting previously unselected package libimport-into-perl. 82s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 82s Unpacking libimport-into-perl (1.002005-2) ... 82s Selecting previously unselected package librole-tiny-perl. 82s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 82s Unpacking librole-tiny-perl (2.002004-1) ... 82s Selecting previously unselected package libsub-quote-perl. 82s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 82s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 82s Selecting previously unselected package libmoo-perl. 82s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 82s Unpacking libmoo-perl (2.005005-1) ... 82s Selecting previously unselected package libencode-locale-perl. 82s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 82s Unpacking libencode-locale-perl (1.05-3) ... 82s Selecting previously unselected package libtimedate-perl. 82s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 82s Unpacking libtimedate-perl (2.3300-2) ... 82s Selecting previously unselected package libhttp-date-perl. 82s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 82s Unpacking libhttp-date-perl (6.06-1) ... 82s Selecting previously unselected package libfile-listing-perl. 82s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 82s Unpacking libfile-listing-perl (6.16-1) ... 82s Selecting previously unselected package libhtml-tagset-perl. 82s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 82s Unpacking libhtml-tagset-perl (3.24-1) ... 82s Selecting previously unselected package liburi-perl. 82s Preparing to unpack .../26-liburi-perl_5.30-1_all.deb ... 82s Unpacking liburi-perl (5.30-1) ... 82s Selecting previously unselected package libhtml-parser-perl:amd64. 82s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_amd64.deb ... 82s Unpacking libhtml-parser-perl:amd64 (3.83-1build1) ... 82s Selecting previously unselected package libhtml-tree-perl. 82s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 82s Unpacking libhtml-tree-perl (5.07-3) ... 82s Selecting previously unselected package libclone-perl:amd64. 82s Preparing to unpack .../29-libclone-perl_0.47-1_amd64.deb ... 82s Unpacking libclone-perl:amd64 (0.47-1) ... 82s Selecting previously unselected package libio-html-perl. 82s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 82s Unpacking libio-html-perl (1.004-3) ... 82s Selecting previously unselected package liblwp-mediatypes-perl. 82s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 82s Unpacking liblwp-mediatypes-perl (6.04-2) ... 82s Selecting previously unselected package libhttp-message-perl. 82s Preparing to unpack .../32-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 82s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 82s Selecting previously unselected package libhttp-cookies-perl. 82s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 82s Unpacking libhttp-cookies-perl (6.11-1) ... 82s Selecting previously unselected package libhttp-negotiate-perl. 82s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 82s Unpacking libhttp-negotiate-perl (6.01-2) ... 82s Selecting previously unselected package perl-openssl-defaults:amd64. 82s Preparing to unpack .../35-perl-openssl-defaults_7build3_amd64.deb ... 82s Unpacking perl-openssl-defaults:amd64 (7build3) ... 83s Selecting previously unselected package libnet-ssleay-perl:amd64. 83s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_amd64.deb ... 83s Unpacking libnet-ssleay-perl:amd64 (1.94-3) ... 83s Selecting previously unselected package libio-socket-ssl-perl. 83s Preparing to unpack .../37-libio-socket-ssl-perl_2.089-1_all.deb ... 83s Unpacking libio-socket-ssl-perl (2.089-1) ... 83s Selecting previously unselected package libnet-http-perl. 83s Preparing to unpack .../38-libnet-http-perl_6.23-1_all.deb ... 83s Unpacking libnet-http-perl (6.23-1) ... 83s Selecting previously unselected package liblwp-protocol-https-perl. 83s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 83s Unpacking liblwp-protocol-https-perl (6.14-1) ... 83s Selecting previously unselected package libtry-tiny-perl. 83s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 83s Unpacking libtry-tiny-perl (0.32-1) ... 83s Selecting previously unselected package libwww-robotrules-perl. 83s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1_all.deb ... 83s Unpacking libwww-robotrules-perl (6.02-1) ... 83s Selecting previously unselected package libwww-perl. 83s Preparing to unpack .../42-libwww-perl_6.78-1_all.deb ... 83s Unpacking libwww-perl (6.78-1) ... 83s Selecting previously unselected package patchutils. 83s Preparing to unpack .../43-patchutils_0.4.2-1build3_amd64.deb ... 83s Unpacking patchutils (0.4.2-1build3) ... 83s Selecting previously unselected package wdiff. 83s Preparing to unpack .../44-wdiff_1.2.2-9_amd64.deb ... 83s Unpacking wdiff (1.2.2-9) ... 83s Selecting previously unselected package devscripts. 83s Preparing to unpack .../45-devscripts_2.25.5_all.deb ... 83s Unpacking devscripts (2.25.5) ... 83s Selecting previously unselected package libhavege2:amd64. 83s Preparing to unpack .../46-libhavege2_1.9.19-11ubuntu1_amd64.deb ... 83s Unpacking libhavege2:amd64 (1.9.19-11ubuntu1) ... 83s Selecting previously unselected package putty-tools. 83s Preparing to unpack .../47-putty-tools_0.83-2_amd64.deb ... 83s Unpacking putty-tools (0.83-2) ... 83s Selecting previously unselected package python3-hamcrest. 83s Preparing to unpack .../48-python3-hamcrest_2.1.0-1_all.deb ... 83s Unpacking python3-hamcrest (2.1.0-1) ... 83s Selecting previously unselected package python3-pyasn1. 83s Preparing to unpack .../49-python3-pyasn1_0.6.1-1_all.deb ... 83s Unpacking python3-pyasn1 (0.6.1-1) ... 83s Selecting previously unselected package python3-pyasn1-modules. 83s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-2_all.deb ... 83s Unpacking python3-pyasn1-modules (0.4.1-2) ... 83s Selecting previously unselected package python3-service-identity. 83s Preparing to unpack .../51-python3-service-identity_24.2.0-1_all.deb ... 83s Unpacking python3-service-identity (24.2.0-1) ... 83s Selecting previously unselected package python3-automat. 83s Preparing to unpack .../52-python3-automat_25.4.16-1_all.deb ... 83s Unpacking python3-automat (25.4.16-1) ... 83s Selecting previously unselected package python3-constantly. 83s Preparing to unpack .../53-python3-constantly_23.10.4-2_all.deb ... 83s Unpacking python3-constantly (23.10.4-2) ... 83s Selecting previously unselected package python3-hyperlink. 83s Preparing to unpack .../54-python3-hyperlink_21.0.0-6_all.deb ... 83s Unpacking python3-hyperlink (21.0.0-6) ... 83s Selecting previously unselected package python3-incremental. 83s Preparing to unpack .../55-python3-incremental_24.7.2-3_all.deb ... 83s Unpacking python3-incremental (24.7.2-3) ... 83s Selecting previously unselected package python3-zope.interface. 83s Preparing to unpack .../56-python3-zope.interface_7.2-1build1_amd64.deb ... 83s Unpacking python3-zope.interface (7.2-1build1) ... 83s Selecting previously unselected package python3-twisted. 83s Preparing to unpack .../57-python3-twisted_24.11.0-1_all.deb ... 83s Unpacking python3-twisted (24.11.0-1) ... 83s Selecting previously unselected package openssh-tests. 83s Preparing to unpack .../58-openssh-tests_1%3a9.9p1-3ubuntu3_amd64.deb ... 83s Unpacking openssh-tests (1:9.9p1-3ubuntu3) ... 83s Selecting previously unselected package haveged. 83s Preparing to unpack .../59-haveged_1.9.19-11ubuntu1_amd64.deb ... 83s Unpacking haveged (1.9.19-11ubuntu1) ... 83s Setting up wdiff (1.2.2-9) ... 83s Setting up libfile-which-perl (1.27-2) ... 83s Setting up libdynaloader-functions-perl (0.004-2) ... 83s Setting up libclass-method-modifiers-perl (2.15-1) ... 83s Setting up libio-pty-perl (1:1.20-1build3) ... 83s Setting up python3-zope.interface (7.2-1build1) ... 84s Setting up libclone-perl:amd64 (0.47-1) ... 84s Setting up libtommath1:amd64 (1.3.0-1) ... 84s Setting up libhtml-tagset-perl (3.24-1) ... 84s Setting up python3-automat (25.4.16-1) ... 84s Setting up liblwp-mediatypes-perl (6.04-2) ... 84s Setting up libtry-tiny-perl (0.32-1) ... 84s Setting up perl-openssl-defaults:amd64 (7build3) ... 84s Setting up libencode-locale-perl (1.05-3) ... 84s Setting up python3-hamcrest (2.1.0-1) ... 84s Setting up putty-tools (0.83-2) ... 84s Setting up libhavege2:amd64 (1.9.19-11ubuntu1) ... 84s Setting up patchutils (0.4.2-1build3) ... 84s Setting up python3-incremental (24.7.2-3) ... 84s Setting up python3-hyperlink (21.0.0-6) ... 84s Setting up libio-html-perl (1.004-3) ... 84s Setting up libb-hooks-op-check-perl:amd64 (0.22-3build2) ... 84s Setting up libipc-run-perl (20231003.0-2) ... 84s Setting up libtimedate-perl (2.3300-2) ... 84s Setting up librole-tiny-perl (2.002004-1) ... 84s Setting up python3-pyasn1 (0.6.1-1) ... 84s Setting up python3-constantly (23.10.4-2) ... 84s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 84s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 84s Setting up libfile-dirlist-perl (0.05-3) ... 84s Setting up libfile-homedir-perl (1.006-2) ... 84s Setting up liburi-perl (5.30-1) ... 84s Setting up libfile-touch-perl (0.12-2) ... 84s Setting up libnet-ssleay-perl:amd64 (1.94-3) ... 84s Setting up libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ... 84s Setting up libhttp-date-perl (6.06-1) ... 84s Setting up haveged (1.9.19-11ubuntu1) ... 84s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 85s Setting up dropbear-bin (2025.87-1) ... 85s Setting up libfile-listing-perl (6.16-1) ... 85s Setting up libnet-http-perl (6.23-1) ... 85s Setting up libdevel-callchecker-perl:amd64 (0.009-1build1) ... 85s Setting up dropbear (2025.87-1) ... 85s Converting existing OpenSSH RSA host key to Dropbear format. 85s Key is a ssh-rsa key 85s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 85s 3072 SHA256:z5wt6sCYJeDuufaRUe8BZIjaOO6MEsyMBwcupmWGjhc /etc/dropbear/dropbear_rsa_host_key (RSA) 85s +---[RSA 3072]----+ 85s | . .o | 85s |. . .o | 85s |.*. o | 85s |B+E. . o | 85s |%B..o . S | 85s |=Bo O . = o | 85s |++. = o . * . | 85s |o+.. . . . . | 85s |..+o. .o | 85s +----[SHA256]-----+ 85s Converting existing OpenSSH ECDSA host key to Dropbear format. 85s Key is a ecdsa-sha2-nistp256 key 85s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 85s 256 SHA256:hkBy532EbiF1KMdenskfGrX5cBI7cQvsNI9Y6H8/YH4 /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 85s +---[ECDSA 256]---+ 85s | . o .o.ooo | 85s | + oo.*oo X . | 85s | . .*.*.O % . | 85s | . .+.O @ + | 85s | ..S = B | 85s | . . ooo | 85s | o... | 85s | . E.| 85s | . .| 85s +----[SHA256]-----+ 85s Converting existing OpenSSH ED25519 host key to Dropbear format. 85s Key is a ssh-ed25519 key 85s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 85s 256 SHA256:zi8GBGLYIXPjrolQzST7Ld8uPdz4XaN/q1UQSfbwLZ8 /etc/dropbear/dropbear_ed25519_host_key (ED25519) 85s +--[ED25519 256]--+ 85s |oo=.. .=. | 85s |.=+B. ..=.| 85s | .+.o. o +| 85s | o . .. +.| 85s |. . o.. S Eo| 85s |oo o.+ .| 85s |+ .++o o. | 85s | ..B... o...| 85s | o.+o oooo.| 85s +----[SHA256]-----+ 85s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 85s Setting up python3-pyasn1-modules (0.4.1-2) ... 86s Setting up python3-service-identity (24.2.0-1) ... 86s Setting up libwww-robotrules-perl (6.02-1) ... 86s Setting up libhtml-parser-perl:amd64 (3.83-1build1) ... 86s Setting up libio-socket-ssl-perl (2.089-1) ... 86s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 86s Setting up libhttp-negotiate-perl (6.01-2) ... 86s Setting up libhttp-cookies-perl (6.11-1) ... 86s Setting up libhtml-tree-perl (5.07-3) ... 86s Setting up libparams-classify-perl:amd64 (0.015-2build6) ... 86s Setting up libmodule-runtime-perl (0.018-1) ... 86s Setting up python3-twisted (24.11.0-1) ... 88s Setting up libimport-into-perl (1.002005-2) ... 88s Setting up libmoo-perl (2.005005-1) ... 88s Setting up openssh-tests (1:9.9p1-3ubuntu3) ... 88s Setting up liblwp-protocol-https-perl (6.14-1) ... 88s Setting up libwww-perl (6.78-1) ... 88s Setting up devscripts (2.25.5) ... 88s Processing triggers for install-info (7.1.1-1) ... 88s Processing triggers for libc-bin (2.41-6ubuntu1) ... 88s Processing triggers for man-db (2.13.1-1) ... 90s autopkgtest [14:13:04]: test regress: [----------------------- 90s info: Adding user `openssh-tests' ... 90s info: Selecting UID/GID from range 1000 to 59999 ... 90s info: Adding new group `openssh-tests' (1001) ... 90s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 90s info: Creating home directory `/home/openssh-tests' ... 90s info: Copying files from `/etc/skel' ... 90s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 90s info: Adding user `openssh-tests' to group `users' ... 91s I: annotate-output 2.25.5 91s I: prefix='%H:%M:%S.%N ' 91s 14:13:05.042908997 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user 91s 14:13:05.075881114 O: make: Entering directory '/tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress' 91s 14:13:05.078070228 E: + + grep -q ^ssh-rsa 91s 14:13:05.077465524 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/valgrind-out 91s 14:13:05.079334228 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 91s 14:13:05.080152502 E: /usr/bin/ssh -Q key 91s 14:13:05.080440331 O: ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 91s 14:13:05.081644729 O: tr '\n' '\r' /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 91s 14:13:05.082847023 O: ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 91s 14:13:05.083939992 O: awk '{print $0 "\r"}' /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 91s 14:13:05.085084959 O: ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 91s 14:13:05.086157209 O: fi 91s 14:13:05.087376695 E: + ssh-keygen+ -ifdiff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 91s 14:13:05.089261219 E: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv 91s 14:13:05.092844678 E: + tr \n \r 91s 14:13:05.093959808 E: + ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 91s 14:13:05.095054701 E: + diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 91s 14:13:05.098711777 E: + awk {print $0 "\r"} /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv 91s 14:13:05.101399757 E: + ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 91s 14:13:05.102502384 E: + diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 91s 14:13:05.105956890 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 91s 14:13:05.106883518 E: + /usr/bin/ssh -Q key 91s 14:13:05.108061847 O: cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t2.out ; \ 91s 14:13:05.108965020 E: + grep -q ^ssh-rsa 91s 14:13:05.109598127 O: chmod 600 /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t2.out ; \ 91s 14:13:05.110698671 E: + cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 91s 14:13:05.111727309 O: ssh-keygen -yf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 91s 14:13:05.112727093 E: + chmod 600 /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t2.out 91s 14:13:05.114842519 O: fi 91s 14:13:05.115129357 E: + ssh-keygen -yf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t2.out 91s 14:13:05.116252853 E: + diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 91s 14:13:05.118241993 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 91s 14:13:05.118030897 E: + /usr/bin/ssh -Q key 91s 14:13:05.120496870 E: + grep -q ^ssh-rsa 91s 14:13:05.119448294 O: ssh-keygen -ef /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t3.out ; \ 91s 14:13:05.121970694 O: ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 91s 14:13:05.124080749 O: fi 91s 14:13:05.123661864 E: + ssh-keygen -ef /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 91s 14:13:05.126587369 E: + ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t3.out 91s 14:13:05.127729089 E: + diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 91s 14:13:05.130763629 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 91s 14:13:05.131836260 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 91s 14:13:05.132913639 O: awk '{print $2}' | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t4.ok ; \ 91s 14:13:05.133950684 O: fi 91s 14:13:05.133546215 E: + /usr/bin/ssh -Q key 91s 14:13:05.135201829 E: + grep -q ^ssh-rsa 91s 14:13:05.137495029 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 91s 14:13:05.140797009 E: + awk {print $2} 91s 14:13:05.141834334 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 91s 14:13:05.142956709 O: ssh-keygen -Bf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 91s 14:13:05.144078541 O: awk '{print $2}' | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t5.ok ; \ 91s 14:13:05.142729672 E: + diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t4.ok 91s 14:13:05.145207749 O: fi 91s 14:13:05.146195020 E: + /usr/bin/ssh -Q key 91s 14:13:05.147240139 E: + grep -q ^ssh-rsa 91s 14:13:05.149041156 E: + ssh-keygen -Bf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 91s 14:13:05.152949894 E: + awk {print $2} 91s 14:13:05.153442387 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 91s 14:13:05.154662855 O: ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t6.out1 ; \ 91s 14:13:05.155878103 O: ssh-keygen -if /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t6.out2 ; \ 91s 14:13:05.155552724 E: + diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t5.ok 91s 14:13:05.157445983 O: chmod 600 /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t6.out1 ; \ 91s 14:13:05.158505750 O: ssh-keygen -yf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t6.out2 ; \ 91s 14:13:05.159186878 E: + /usr/bin/ssh -Q key 91s 14:13:05.160135256 O: fi 91s 14:13:05.161220159 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 91s 14:13:05.162341742 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t7.out ; \ 91s 14:13:05.160196551 E: + grep -q ^ssh-dss 91s 14:13:05.164346159 E: + /usr/bin/ssh -Q key 91s 14:13:05.163435883 O: fi 91s 14:13:05.165437666 E: + grep -q ^ssh-dss 91s 14:13:05.167498731 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 91s 14:13:05.168609633 O: ssh-keygen -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 91s 14:13:05.169803863 O: ssh-keygen -Bf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 91s 14:13:05.170455344 E: + /usr/bin/ssh -Q key+ 91s 14:13:05.171452502 E: grep -q ^ssh-dss 91s 14:13:05.171817026 O: fi 91s 14:13:05.174339596 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 91s 14:13:05.175425943 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t8.out ; \ 91s 14:13:05.176502531 O: fi 91s 14:13:05.177541689 E: + ssh -Q key 91s 14:13:05.178692927 E: + grep -q ^ssh-dss 91s 14:13:05.180119662 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 91s 14:13:05.181275840 O: ssh-keygen -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 91s 14:13:05.182390941 O: ssh-keygen -Bf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 91s 14:13:05.182567231 E: + ssh -Q key 91s 14:13:05.183427103 O: fi 91s 14:13:05.183606299 E: + grep -q ^ssh-dss 91s 14:13:05.188039591 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 91s 14:13:05.189150363 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t9.out 91s 14:13:05.196787201 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 91s 14:13:05.197868728 O: ssh-keygen -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t9.out > /dev/null 91s 14:13:05.205682589 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 91s 14:13:05.206783072 O: ssh-keygen -Bf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t9.out > /dev/null 91s 14:13:05.214470254 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t10.out 91s 14:13:05.218178986 O: ssh-keygen -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t10.out > /dev/null 91s 14:13:05.221941540 O: ssh-keygen -Bf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t10.out > /dev/null 91s 14:13:05.225670952 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 91s 14:13:05.226877645 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 91s 14:13:05.227960485 O: awk '{print $2}' | diff - /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t11.ok ; \ 91s 14:13:05.229027897 O: fi 91s 14:13:05.228584676 E: + /usr/bin/ssh -Q key 91s 14:13:05.230270636 E: + grep -q ^ssh-dss 91s 14:13:05.232611436 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t12.out 91s 14:13:05.236257031 O: ssh-keygen -lf /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 91s 14:13:05.241830149 E: run test connect.sh ... 92s 14:13:06.038574735 O: ok simple connect 92s 14:13:06.039659689 E: run test proxy-connect.sh ... 92s 14:13:06.175910111 O: plain username comp=no 92s 14:13:06.272707414 O: plain username comp=yes 92s 14:13:06.369307067 O: username with style 92s 14:13:06.465677288 O: ok proxy connect 92s 14:13:06.465280716 E: run test sshfp-connect.sh ... 92s 14:13:06.617616305 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 92s 14:13:06.617638567 E: run test connect-privsep.sh ... 94s 14:13:08.209338272 O: ok proxy connect with privsep 94s 14:13:08.209728955 E: run test connect-uri.sh ... 94s 14:13:08.429135526 O: uri connect: no trailing slash 94s 14:13:08.623444569 O: uri connect: trailing slash 94s 14:13:08.817689082 O: uri connect: with path name 94s 14:13:08.843307314 O: ok uri connect 94s 14:13:08.844028938 E: run test proto-version.sh ... 95s 14:13:09.009859505 O: ok sshd version with different protocol combinations 95s 14:13:09.011058844 E: run test proto-mismatch.sh ... 95s 14:13:09.172455595 O: ok protocol version mismatch 95s 14:13:09.173018039 E: run test exit-status.sh ... 95s 14:13:09.317596235 O: test remote exit status: status 0 100s 14:13:14.539268611 O: test remote exit status: status 1 105s 14:13:19.762541483 O: test remote exit status: status 4 111s 14:13:24.980400182 O: test remote exit status: status 5 116s 14:13:30.199472655 O: test remote exit status: status 44 121s 14:13:35.436730206 O: ok remote exit status 121s 14:13:35.436675454 E: run test exit-status-signal.sh ... 122s 14:13:36.575319168 E: run test envpass.sh ... 122s 14:13:36.575030246 O: ok exit status on signal 122s 14:13:36.717356314 O: test environment passing: pass env, don't accept 122s 14:13:36.822994463 O: test environment passing: setenv, don't accept 122s 14:13:36.928092056 O: test environment passing: don't pass env, accept 123s 14:13:37.032882344 O: test environment passing: pass single env, accept single env 123s 14:13:37.137435947 O: test environment passing: pass multiple env, accept multiple env 123s 14:13:37.246056570 O: test environment passing: setenv, accept 123s 14:13:37.362787133 O: test environment passing: setenv, first match wins 123s 14:13:37.470891368 O: test environment passing: server setenv wins 123s 14:13:37.577466665 O: test environment passing: server setenv wins 123s 14:13:37.686014110 O: ok environment passing 123s 14:13:37.686628312 E: run test transfer.sh ... 124s 14:13:38.961116942 O: ok transfer data 124s 14:13:38.962371124 E: run test banner.sh ... 125s 14:13:39.120287426 O: test banner: missing banner file 125s 14:13:39.230057246 O: test banner: size 0 125s 14:13:39.351514286 O: test banner: size 10 125s 14:13:39.485397231 O: test banner: size 100 125s 14:13:39.605441481 O: test banner: size 1000 125s 14:13:39.715141059 O: test banner: size 10000 125s 14:13:39.824536757 O: test banner: size 100000 125s 14:13:39.927816735 O: test banner: suppress banner (-q) 126s 14:13:40.026299906 O: ok banner 126s 14:13:40.027652513 E: run test rekey.sh ... 126s 14:13:40.194647203 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 126s 14:13:40.318705682 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 126s 14:13:40.451677389 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 126s 14:13:40.594648050 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 126s 14:13:40.762310752 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 127s 14:13:41.071199855 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 127s 14:13:41.406256117 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 127s 14:13:41.739567396 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 127s 14:13:41.855788045 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 128s 14:13:41.974334158 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 128s 14:13:42.097368387 O: client rekey KexAlgorithms=curve25519-sha256 128s 14:13:42.229336804 O: client rekey KexAlgorithms=sntrup761x25519-sha512 128s 14:13:42.386288168 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 128s 14:13:42.538917215 O: client rekey KexAlgorithms=mlkem768x25519-sha256 128s 14:13:42.671585495 O: client rekey Ciphers=3des-cbc 128s 14:13:42.804085218 O: client rekey Ciphers=aes128-cbc 128s 14:13:42.936416385 O: client rekey Ciphers=aes192-cbc 129s 14:13:43.059039454 O: client rekey Ciphers=aes256-cbc 129s 14:13:43.187886308 O: client rekey Ciphers=aes128-ctr 129s 14:13:43.315642825 O: client rekey Ciphers=aes192-ctr 129s 14:13:43.438652179 O: client rekey Ciphers=aes256-ctr 129s 14:13:43.579918155 O: client rekey Ciphers=aes128-gcm@openssh.com 131s 14:13:45.490650426 O: client rekey Ciphers=aes256-gcm@openssh.com 133s 14:13:47.548143646 O: client rekey Ciphers=chacha20-poly1305@openssh.com 135s 14:13:49.851402701 O: client rekey MACs=hmac-sha1 136s 14:13:50.004479263 O: client rekey MACs=hmac-sha1-96 136s 14:13:50.130684323 O: client rekey MACs=hmac-sha2-256 136s 14:13:50.270329904 O: client rekey MACs=hmac-sha2-512 136s 14:13:50.400685831 O: client rekey MACs=hmac-md5 136s 14:13:50.525141781 O: client rekey MACs=hmac-md5-96 136s 14:13:50.657666886 O: client rekey MACs=umac-64@openssh.com 136s 14:13:50.790527119 O: client rekey MACs=umac-128@openssh.com 136s 14:13:50.921678207 O: client rekey MACs=hmac-sha1-etm@openssh.com 137s 14:13:51.048685403 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 137s 14:13:51.175311464 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 137s 14:13:51.319948146 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 137s 14:13:51.472203918 O: client rekey MACs=hmac-md5-etm@openssh.com 137s 14:13:51.678875783 O: client rekey MACs=hmac-md5-96-etm@openssh.com 137s 14:13:51.854683871 O: client rekey MACs=umac-64-etm@openssh.com 138s 14:13:51.996685110 O: client rekey MACs=umac-128-etm@openssh.com 138s 14:13:52.122845208 O: client rekeylimit 16 138s 14:13:52.267562501 O: client rekeylimit 1k 138s 14:13:52.399539940 O: client rekeylimit 128k 138s 14:13:52.552717761 O: client rekeylimit 256k 138s 14:13:52.765250240 O: client rekeylimit default 5 153s 14:14:07.900244821 O: client rekeylimit default 10 174s 14:14:28.015589263 O: client rekeylimit default 5 no data 189s 14:14:43.128917242 O: client rekeylimit default 10 no data 209s 14:15:03.244001121 O: server rekeylimit 16 209s 14:15:03.372055219 O: server rekeylimit 1k 209s 14:15:03.498563371 O: server rekeylimit 128k 209s 14:15:03.733137625 O: server rekeylimit 256k 209s 14:15:03.923511516 O: server rekeylimit default 5 no data 225s 14:15:19.043590895 O: server rekeylimit default 10 no data 245s 14:15:39.160341944 O: rekeylimit parsing: bytes 245s 14:15:39.245908233 O: rekeylimit parsing: time 245s 14:15:39.328082810 O: ok rekey 245s 14:15:39.327950448 E: run test dhgex.sh ... 245s 14:15:39.507892806 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 245s 14:15:39.618133845 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 245s 14:15:39.724562491 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 245s 14:15:39.821942534 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 245s 14:15:39.923194647 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 246s 14:15:40.028166313 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 246s 14:15:40.124546236 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 246s 14:15:40.217665382 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 246s 14:15:40.320803398 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 246s 14:15:40.446642787 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 246s 14:15:40.579834804 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 246s 14:15:40.707639222 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 246s 14:15:40.855337684 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 247s 14:15:40.998221242 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 247s 14:15:41.127905699 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 247s 14:15:41.261320692 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 247s 14:15:41.395408143 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 247s 14:15:41.539532028 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 247s 14:15:41.676695269 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 247s 14:15:41.844541720 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 248s 14:15:42.012822360 O: ok dhgex 248s 14:15:42.013794807 E: run test stderr-data.sh ... 248s 14:15:42.152906004 O: test stderr data transfer: () 254s 14:15:48.375267212 O: test stderr data transfer: (-n) 260s 14:15:54.606241881 O: ok stderr data transfer 260s 14:15:54.607465602 E: run test stderr-after-eof.sh ... 262s 14:15:56.857327020 O: ok stderr data after eof 262s 14:15:56.858713278 E: run test broken-pipe.sh ... 263s 14:15:57.051208264 O: ok broken pipe test 263s 14:15:57.052168233 E: run test try-ciphers.sh ... 263s 14:15:57.214498792 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 263s 14:15:57.320196551 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 263s 14:15:57.427452682 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 263s 14:15:57.533975329 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 263s 14:15:57.642581822 O: test try ciphers: cipher 3des-cbc mac hmac-md5 263s 14:15:57.743416403 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 263s 14:15:57.843334416 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 263s 14:15:57.951107472 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 264s 14:15:58.052140188 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 264s 14:15:58.152304332 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 264s 14:15:58.258914883 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 264s 14:15:58.360341339 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 264s 14:15:58.465015964 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 264s 14:15:58.573005069 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 264s 14:15:58.677037609 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 264s 14:15:58.777197625 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 264s 14:15:58.898165951 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 265s 14:15:59.013010744 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 265s 14:15:59.119465511 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 265s 14:15:59.240336203 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 265s 14:15:59.363086216 O: test try ciphers: cipher aes128-cbc mac hmac-md5 265s 14:15:59.497617318 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 265s 14:15:59.622327753 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 265s 14:15:59.736154663 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 265s 14:15:59.857322649 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 266s 14:15:59.978029911 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 266s 14:16:00.094844681 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 266s 14:16:00.220611336 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 266s 14:16:00.351081345 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 266s 14:16:00.469201301 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 266s 14:16:00.582669607 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 266s 14:16:00.696415591 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 266s 14:16:00.817538308 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 266s 14:16:00.927402922 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 267s 14:16:01.036122034 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 267s 14:16:01.144590521 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 267s 14:16:01.255347611 O: test try ciphers: cipher aes192-cbc mac hmac-md5 267s 14:16:01.366581805 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 267s 14:16:01.513923679 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 267s 14:16:01.700705859 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 267s 14:16:01.809384611 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 267s 14:16:01.912889854 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 268s 14:16:02.032004341 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 268s 14:16:02.139684794 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 268s 14:16:02.250447294 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 268s 14:16:02.357131122 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 268s 14:16:02.466194829 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 268s 14:16:02.577466422 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 268s 14:16:02.691022541 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 268s 14:16:02.819879945 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 268s 14:16:02.936154694 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 269s 14:16:03.049294008 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 269s 14:16:03.162709980 O: test try ciphers: cipher aes256-cbc mac hmac-md5 269s 14:16:03.269365054 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 269s 14:16:03.370784011 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 269s 14:16:03.475924841 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 269s 14:16:03.586368286 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 269s 14:16:03.702252331 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 269s 14:16:03.816157058 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 269s 14:16:03.918072136 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 270s 14:16:04.022361939 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 270s 14:16:04.130583742 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 270s 14:16:04.233966146 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 270s 14:16:04.342956779 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 270s 14:16:04.458011854 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 270s 14:16:04.564362608 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 270s 14:16:04.672194510 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 270s 14:16:04.788665109 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 270s 14:16:04.894477112 O: test try ciphers: cipher aes128-ctr mac hmac-md5 271s 14:16:05.000269810 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 271s 14:16:05.109891311 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 271s 14:16:05.219372538 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 271s 14:16:05.333959920 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 271s 14:16:05.446606296 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 271s 14:16:05.552382582 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 271s 14:16:05.655324945 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 271s 14:16:05.756984203 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 271s 14:16:05.865155090 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 272s 14:16:05.978004932 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 272s 14:16:06.090629274 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 272s 14:16:06.207795607 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 272s 14:16:06.314427241 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 272s 14:16:06.425021093 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 272s 14:16:06.535004053 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 272s 14:16:06.651247411 O: test try ciphers: cipher aes192-ctr mac hmac-md5 272s 14:16:06.759855989 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 272s 14:16:06.863394267 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 273s 14:16:06.982518063 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 273s 14:16:07.095414684 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 273s 14:16:07.197995556 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 273s 14:16:07.303970433 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 273s 14:16:07.412826429 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 273s 14:16:07.531065106 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 273s 14:16:07.632274638 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 273s 14:16:07.747126766 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 273s 14:16:07.858366402 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 274s 14:16:07.977885429 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 274s 14:16:08.087046480 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 274s 14:16:08.200618026 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 274s 14:16:08.314184202 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 274s 14:16:08.429221591 O: test try ciphers: cipher aes256-ctr mac hmac-md5 274s 14:16:08.535923102 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 274s 14:16:08.646518349 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 274s 14:16:08.763257806 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 274s 14:16:08.871200760 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 275s 14:16:08.981695828 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 275s 14:16:09.092580504 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 275s 14:16:09.205137128 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 275s 14:16:09.318320390 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 275s 14:16:09.432636726 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 275s 14:16:09.549995798 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 275s 14:16:09.658756553 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 275s 14:16:09.782862562 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 275s 14:16:09.898645237 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 276s 14:16:10.029117920 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 276s 14:16:10.147046488 O: ok try ciphers 276s 14:16:10.148058827 E: run test yes-head.sh ... 279s 14:16:13.284126690 O: ok yes pipe head 279s 14:16:13.285471857 E: run test login-timeout.sh ... 294s 14:16:28.687186088 O: ok connect after login grace timeout 294s 14:16:28.688952022 E: run test agent.sh ... 298s 14:16:32.671265716 O: ok simple agent test 298s 14:16:32.672378745 E: run test agent-getpeereid.sh ... 298s 14:16:32.850712742 O: ok disallow agent attach from other uid 298s 14:16:32.851711840 E: run test agent-timeout.sh ... 319s 14:16:53.079880146 O: ok agent timeout test 319s 14:16:53.080848601 E: run test agent-ptrace.sh ... 319s 14:16:53.244898953 O: skipped (gdb not found) 319s 14:16:53.245982235 E: run test agent-subprocess.sh ... 329s 14:17:03.444685454 O: ok agent subprocess 329s 14:17:03.445716355 E: run test keyscan.sh ... 330s 14:17:04.120965948 O: ok keyscan 330s 14:17:04.122190798 E: run test keygen-change.sh ... 333s 14:17:07.947493398 O: ok change passphrase for key 333s 14:17:07.948688094 E: run test keygen-comment.sh ... 335s 14:17:09.406629024 E: run test keygen-convert.sh ... 335s 14:17:09.407824457 O: ok Comment extraction from private key 338s 14:17:12.407908569 O: ok convert keys 338s 14:17:12.409175016 E: run test keygen-knownhosts.sh ... 338s 14:17:12.684450415 O: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts updated. 338s 14:17:12.685698818 O: Original contents retained as /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts.old 338s 14:17:12.703091245 O: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts updated. 338s 14:17:12.704339938 O: Original contents retained as /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts.old 338s 14:17:12.710099339 O: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts updated. 338s 14:17:12.711280600 O: Original contents retained as /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts.old 338s 14:17:12.723053067 O: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts updated. 338s 14:17:12.724275649 O: Original contents retained as /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hosts.old 338s 14:17:12.746033320 O: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hashed updated. 338s 14:17:12.747227056 O: Original contents retained as /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/kh.hashed.old 338s 14:17:12.750610388 O: ok ssh-keygen known_hosts 338s 14:17:12.751867498 E: run test keygen-moduli.sh ... 340s 14:17:14.451458793 O: ok keygen moduli 340s 14:17:14.452698124 E: run test keygen-sshfp.sh ... 340s 14:17:14.613235570 O: ok keygen-sshfp 340s 14:17:14.614410918 E: run test key-options.sh ... 340s 14:17:14.746367432 O: key option command="echo bar" 340s 14:17:14.845044260 O: key option no-pty,command="echo bar" 340s 14:17:14.947991265 O: key option pty default 341s 14:17:15.053441919 O: key option pty no-pty 341s 14:17:15.151598302 O: key option pty restrict 341s 14:17:15.252115437 O: key option pty restrict,pty 341s 14:17:15.355046837 O: key option environment 341s 14:17:15.588595312 O: key option from="127.0.0.1" 342s 14:17:15.991983697 O: key option from="127.0.0.0/8" 342s 14:17:16.292300458 O: key option expiry-time default 342s 14:17:16.390515707 O: key option expiry-time invalid 342s 14:17:16.506173323 O: key option expiry-time expired 342s 14:17:16.622403603 O: key option expiry-time valid 342s 14:17:16.736095529 O: ok key options 342s 14:17:16.736787103 E: run test scp.sh ... 342s 14:17:16.869907106 O: scp: scp mode: simple copy local file to local file 342s 14:17:16.879338685 O: scp: scp mode: simple copy local file to remote file 342s 14:17:16.888558653 O: scp: scp mode: simple copy remote file to local file 342s 14:17:16.897985914 O: scp: scp mode: copy local file to remote file in place 342s 14:17:16.908656711 O: scp: scp mode: copy remote file to local file in place 342s 14:17:16.919962792 O: scp: scp mode: copy local file to remote file clobber 342s 14:17:16.930238011 O: -rw-r--r-- 1 openssh-tests openssh-tests 1125464 May 5 14:17 /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/copy 342s 14:17:16.931421161 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1125464 May 5 14:17 /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data 342s 14:17:16.932844256 O: scp: scp mode: copy remote file to local file clobber 342s 14:17:16.945105445 O: scp: scp mode: simple copy local file to remote dir 343s 14:17:16.956246877 O: scp: scp mode: simple copy local file to local dir 343s 14:17:16.967407656 O: scp: scp mode: simple copy remote file to local dir 343s 14:17:16.979102383 O: scp: scp mode: recursive local dir to remote dir 343s 14:17:17.001475652 O: scp: scp mode: recursive local dir to local dir 343s 14:17:17.024298569 O: scp: scp mode: recursive remote dir to local dir 343s 14:17:17.050061255 O: scp: scp mode: unmatched glob file local->remote 343s 14:17:17.060859608 O: scp: scp mode: unmatched glob file remote->local 343s 14:17:17.066681484 O: scp: scp mode: unmatched glob dir recursive local->remote 343s 14:17:17.082575312 O: scp: scp mode: unmatched glob dir recursive remote->local 343s 14:17:17.090990647 O: scp: scp mode: shell metacharacters 343s 14:17:17.100446613 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 343s 14:17:17.139641717 O: scp: scp mode: disallow bad server #0 343s 14:17:17.163095919 O: scp: scp mode: disallow bad server #1 343s 14:17:17.185614635 O: scp: scp mode: disallow bad server #2 343s 14:17:17.206986133 O: scp: scp mode: disallow bad server #3 343s 14:17:17.230301164 O: scp: scp mode: disallow bad server #4 343s 14:17:17.253069064 O: scp: scp mode: disallow bad server #5 343s 14:17:17.274060303 O: scp: scp mode: disallow bad server #6 343s 14:17:17.295924707 O: scp: scp mode: disallow bad server #7 343s 14:17:17.318779737 O: scp: scp mode: detect non-directory target 343s 14:17:17.323409839 E: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/copy2: Not a directory 343s 14:17:17.325533864 O: scp: sftp mode: simple copy local file to local file 343s 14:17:17.334660740 O: scp: sftp mode: simple copy local file to remote file 343s 14:17:17.347632043 O: scp: sftp mode: simple copy remote file to local file 343s 14:17:17.359122229 O: scp: sftp mode: copy local file to remote file in place 343s 14:17:17.372446106 O: scp: sftp mode: copy remote file to local file in place 343s 14:17:17.385170669 O: scp: sftp mode: copy local file to remote file clobber 343s 14:17:17.397410031 O: -rw-r--r-- 1 openssh-tests openssh-tests 1125464 May 5 14:17 /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/copy 343s 14:17:17.398707541 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1125464 May 5 14:17 /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data 343s 14:17:17.401372166 O: scp: sftp mode: copy remote file to local file clobber 343s 14:17:17.414953144 O: scp: sftp mode: simple copy local file to remote dir 343s 14:17:17.428684802 O: scp: sftp mode: simple copy local file to local dir 343s 14:17:17.440185158 O: scp: sftp mode: simple copy remote file to local dir 343s 14:17:17.453981072 O: scp: sftp mode: recursive local dir to remote dir 343s 14:17:17.481908773 O: scp: sftp mode: recursive local dir to local dir 343s 14:17:17.505549869 O: scp: sftp mode: recursive remote dir to local dir 343s 14:17:17.538510544 O: scp: sftp mode: unmatched glob file local->remote 343s 14:17:17.551911582 O: scp: sftp mode: unmatched glob file remote->local 343s 14:17:17.561157560 O: scp: sftp mode: unmatched glob dir recursive local->remote 343s 14:17:17.580449508 O: scp: sftp mode: unmatched glob dir recursive remote->local 343s 14:17:17.594148604 O: scp: sftp mode: shell metacharacters 343s 14:17:17.604102416 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 343s 14:17:17.645690108 O: scp: sftp mode: disallow bad server #0 343s 14:17:17.682659248 O: scp: sftp mode: disallow bad server #1 343s 14:17:17.719402641 O: scp: sftp mode: disallow bad server #2 343s 14:17:17.755592158 O: scp: sftp mode: disallow bad server #3 343s 14:17:17.791063400 O: scp: sftp mode: disallow bad server #4 343s 14:17:17.825580979 O: scp: sftp mode: disallow bad server #5 343s 14:17:17.860171671 O: scp: sftp mode: disallow bad server #6 343s 14:17:17.894878438 O: scp: sftp mode: disallow bad server #7 343s 14:17:17.929503546 O: scp: sftp mode: detect non-directory target 343s 14:17:17.934000800 E: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/copy2: Not a directory 343s 14:17:17.940024370 O: ok scp 343s 14:17:17.941596644 E: run test scp3.sh ... 344s 14:17:18.074814050 O: scp3: scp mode: simple copy remote file to remote file 344s 14:17:18.210848825 O: scp3: scp mode: simple copy remote file to remote dir 344s 14:17:18.345061223 O: scp3: scp mode: recursive remote dir to remote dir 344s 14:17:18.500979853 O: scp3: scp mode: detect non-directory target 344s 14:17:18.728016734 O: scp3: sftp mode: simple copy remote file to remote file 344s 14:17:18.740493824 O: scp3: sftp mode: simple copy remote file to remote dir 344s 14:17:18.754612878 O: scp3: sftp mode: recursive remote dir to remote dir 344s 14:17:18.788976168 O: scp3: sftp mode: detect non-directory target 344s 14:17:18.795981025 E: scp: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/copy2: destination is not a directory 344s 14:17:18.797188478 E: scp: /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/copy2: destination is not a directory 344s 14:17:18.802542951 O: ok scp3 344s 14:17:18.803624330 E: run test scp-uri.sh ... 344s 14:17:18.936518249 O: scp-uri: scp mode: simple copy local file to remote file 344s 14:17:18.944100036 O: scp-uri: scp mode: simple copy remote file to local file 344s 14:17:18.952010430 O: scp-uri: scp mode: simple copy local file to remote dir 344s 14:17:18.961879506 O: scp-uri: scp mode: simple copy remote file to local dir 345s 14:17:18.972091578 O: scp-uri: scp mode: recursive local dir to remote dir 345s 14:17:18.983512686 O: scp-uri: scp mode: recursive remote dir to local dir 345s 14:17:18.995097943 O: scp-uri: sftp mode: simple copy local file to remote file 345s 14:17:19.004830610 O: scp-uri: sftp mode: simple copy remote file to local file 345s 14:17:19.013944229 O: scp-uri: sftp mode: simple copy local file to remote dir 345s 14:17:19.025539132 O: scp-uri: sftp mode: simple copy remote file to local dir 345s 14:17:19.036889730 O: scp-uri: sftp mode: recursive local dir to remote dir 345s 14:17:19.049922761 O: scp-uri: sftp mode: recursive remote dir to local dir 345s 14:17:19.065970873 O: ok scp-uri 345s 14:17:19.067005520 E: run test sftp.sh ... 345s 14:17:19.195985923 O: test basic sftp put/get: buffer_size 5 num_requests 1 354s 14:17:28.846602939 O: test basic sftp put/get: buffer_size 5 num_requests 2 359s 14:17:33.695967745 O: test basic sftp put/get: buffer_size 5 num_requests 10 362s 14:17:36.277068008 O: test basic sftp put/get: buffer_size 1000 num_requests 1 362s 14:17:36.336708663 O: test basic sftp put/get: buffer_size 1000 num_requests 2 362s 14:17:36.373560109 O: test basic sftp put/get: buffer_size 1000 num_requests 10 362s 14:17:36.397474271 O: test basic sftp put/get: buffer_size 32000 num_requests 1 362s 14:17:36.409360766 O: test basic sftp put/get: buffer_size 32000 num_requests 2 362s 14:17:36.420146866 O: test basic sftp put/get: buffer_size 32000 num_requests 10 362s 14:17:36.431192804 O: test basic sftp put/get: buffer_size 64000 num_requests 1 362s 14:17:36.443440965 O: test basic sftp put/get: buffer_size 64000 num_requests 2 362s 14:17:36.454219670 O: test basic sftp put/get: buffer_size 64000 num_requests 10 362s 14:17:36.467186923 O: ok basic sftp put/get 362s 14:17:36.468170163 E: run test sftp-chroot.sh ... 362s 14:17:36.648663871 O: test sftp in chroot: get 362s 14:17:36.861077082 O: test sftp in chroot: match 363s 14:17:37.094563310 O: ok sftp in chroot 363s 14:17:37.102515133 E: run test sftp-cmds.sh ... 363s 14:17:37.246386900 O: sftp commands: lls 363s 14:17:37.254460206 O: sftp commands: lls w/path 363s 14:17:37.260294298 O: sftp commands: ls 363s 14:17:37.267185372 O: sftp commands: shell 363s 14:17:37.272462715 O: sftp commands: pwd 363s 14:17:37.275715005 O: sftp commands: lpwd 363s 14:17:37.279169114 O: sftp commands: quit 363s 14:17:37.282646908 O: sftp commands: help 363s 14:17:37.286977020 O: sftp commands: get 363s 14:17:37.296322020 O: sftp commands: get quoted 363s 14:17:37.308694288 O: sftp commands: get filename with quotes 363s 14:17:37.323031723 O: sftp commands: get filename with spaces 363s 14:17:37.334406394 O: sftp commands: get filename with glob metacharacters 363s 14:17:37.345635495 O: sftp commands: get to directory 363s 14:17:37.354677783 O: sftp commands: glob get to directory 363s 14:17:37.431071645 O: sftp commands: get to local dir 363s 14:17:37.439860316 O: sftp commands: glob get to local dir 363s 14:17:37.478279584 O: sftp commands: put 363s 14:17:37.487463244 O: sftp commands: put filename with quotes 363s 14:17:37.496884421 O: sftp commands: put filename with spaces 363s 14:17:37.508827333 O: sftp commands: put to directory 363s 14:17:37.517846056 O: sftp commands: glob put to directory 363s 14:17:37.528286793 O: sftp commands: put to local dir 363s 14:17:37.537461706 O: sftp commands: glob put to local dir 363s 14:17:37.563541468 O: sftp commands: rename 363s 14:17:37.568580924 O: sftp commands: rename directory 363s 14:17:37.571969256 O: sftp commands: ln 363s 14:17:37.576644652 O: sftp commands: ln -s 363s 14:17:37.580679256 O: sftp commands: cp 363s 14:17:37.587323264 O: sftp commands: mkdir 363s 14:17:37.590479150 O: sftp commands: chdir 363s 14:17:37.593717995 O: sftp commands: rmdir 363s 14:17:37.597124151 O: sftp commands: lmkdir 363s 14:17:37.600520589 O: sftp commands: lchdir 363s 14:17:37.609552297 O: ok sftp commands 363s 14:17:37.610704872 E: run test sftp-badcmds.sh ... 363s 14:17:37.745019812 O: sftp invalid commands: get nonexistent 363s 14:17:37.749185367 O: sftp invalid commands: glob get to nonexistent directory 363s 14:17:37.762911818 O: sftp invalid commands: put nonexistent 363s 14:17:37.767331663 O: sftp invalid commands: glob put to nonexistent directory 363s 14:17:37.772309840 O: sftp invalid commands: rename nonexistent 363s 14:17:37.779617434 O: sftp invalid commands: rename target exists (directory) 363s 14:17:37.787883471 O: sftp invalid commands: glob put files to local file 363s 14:17:37.794337665 O: ok sftp invalid commands 363s 14:17:37.795606574 E: run test sftp-batch.sh ... 363s 14:17:37.933384278 O: sftp batchfile: good commands 363s 14:17:37.942484709 O: sftp batchfile: bad commands 363s 14:17:37.953246131 O: sftp batchfile: comments and blanks 363s 14:17:37.959194383 O: sftp batchfile: junk command 363s 14:17:37.963520978 O: ok sftp batchfile 363s 14:17:37.964662343 E: run test sftp-glob.sh ... 364s 14:17:38.099970415 O: sftp glob: file glob 364s 14:17:38.107930126 O: sftp glob: dir glob 364s 14:17:38.114474128 O: sftp glob: quoted glob 364s 14:17:38.122317705 O: sftp glob: escaped glob 364s 14:17:38.130839577 O: sftp glob: escaped quote 364s 14:17:38.137863460 O: sftp glob: quoted quote 364s 14:17:38.144402130 O: sftp glob: single-quoted quote 364s 14:17:38.151690502 O: sftp glob: escaped space 364s 14:17:38.160519653 O: sftp glob: quoted space 364s 14:17:38.169557866 O: sftp glob: escaped slash 364s 14:17:38.176637095 O: sftp glob: quoted slash 364s 14:17:38.184541871 O: sftp glob: escaped slash at EOL 364s 14:17:38.191490300 O: sftp glob: quoted slash at EOL 364s 14:17:38.198315851 O: sftp glob: escaped slash+quote 364s 14:17:38.204893817 O: sftp glob: quoted slash+quote 364s 14:17:38.213973279 O: ok sftp glob 364s 14:17:38.215052583 E: run test sftp-perm.sh ... 364s 14:17:38.352431884 O: sftp permissions: read-only upload 364s 14:17:38.372960671 O: sftp permissions: read-only setstat 364s 14:17:38.392251470 O: sftp permissions: read-only rm 364s 14:17:38.408697773 O: sftp permissions: read-only mkdir 364s 14:17:38.423943792 O: sftp permissions: read-only rmdir 364s 14:17:38.441126110 O: sftp permissions: read-only posix-rename 364s 14:17:38.461435605 O: sftp permissions: read-only oldrename 364s 14:17:38.478720039 O: sftp permissions: read-only symlink 364s 14:17:38.495521124 O: sftp permissions: read-only hardlink 364s 14:17:38.510884739 O: sftp permissions: explicit open 364s 14:17:38.545763422 O: sftp permissions: explicit read 364s 14:17:38.583137299 O: sftp permissions: explicit write 364s 14:17:38.625446728 O: sftp permissions: explicit lstat 364s 14:17:38.663618598 O: sftp permissions: explicit opendir 364s 14:17:38.695720636 O: sftp permissions: explicit readdir 364s 14:17:38.729159905 O: sftp permissions: explicit setstat 364s 14:17:38.767351422 O: sftp permissions: explicit remove 364s 14:17:38.799175047 O: sftp permissions: explicit mkdir 364s 14:17:38.827360412 O: sftp permissions: explicit rmdir 364s 14:17:38.863254556 O: sftp permissions: explicit rename 364s 14:17:38.897283839 O: sftp permissions: explicit symlink 364s 14:17:38.930006720 O: sftp permissions: explicit hardlink 364s 14:17:38.962669136 O: sftp permissions: explicit statvfs 365s 14:17:38.985779682 O: ok sftp permissions 365s 14:17:38.987306335 E: run test sftp-uri.sh ... 365s 14:17:39.164652997 O: sftp-uri: non-interactive fetch to local file 365s 14:17:39.365819808 O: sftp-uri: non-interactive fetch to local dir 365s 14:17:39.567369102 O: sftp-uri: put to remote directory (trailing slash) 365s 14:17:39.771402166 O: sftp-uri: put to remote directory (no slash) 366s 14:17:39.995338919 O: ok sftp-uri 366s 14:17:39.995405567 E: run test reconfigure.sh ... 379s 14:17:52.982522331 O: ok simple connect after reconfigure 379s 14:17:52.983621841 E: run test dynamic-forward.sh ... 379s 14:17:53.150344588 O: test -D forwarding 379s 14:17:53.921591932 O: test -R forwarding 380s 14:17:54.899100069 O: PermitRemoteOpen=any 381s 14:17:55.874643055 O: PermitRemoteOpen=none 382s 14:17:56.202729336 O: PermitRemoteOpen=explicit 383s 14:17:57.209660472 O: PermitRemoteOpen=disallowed 383s 14:17:57.559695050 E: run test forwarding.sh ... 383s 14:17:57.560641044 O: ok dynamic forwarding 388s 14:18:02.707582587 O: ok local and remote forwarding 388s 14:18:02.708701778 E: run test multiplex.sh ... 389s 14:18:03.895668304 O: test connection multiplexing: setenv 389s 14:18:03.908766665 O: test connection multiplexing: envpass 389s 14:18:03.921651112 O: test connection multiplexing: transfer 389s 14:18:03.962304475 O: test connection multiplexing: transfer -Oproxy 390s 14:18:04.052620175 O: test connection multiplexing: forward 392s 14:18:06.103011783 O: test connection multiplexing: status 0 () 397s 14:18:11.131359137 O: test connection multiplexing: status 0 (-Oproxy) 402s 14:18:16.164843673 O: test connection multiplexing: status 1 () 407s 14:18:21.196006525 O: test connection multiplexing: status 1 (-Oproxy) 412s 14:18:26.236126406 O: test connection multiplexing: status 4 () 417s 14:18:31.273635446 O: test connection multiplexing: status 4 (-Oproxy) 422s 14:18:36.330927133 O: test connection multiplexing: status 5 () 427s 14:18:41.360290867 O: test connection multiplexing: status 5 (-Oproxy) 432s 14:18:46.390160077 O: test connection multiplexing: status 44 () 437s 14:18:51.418091377 O: test connection multiplexing: status 44 (-Oproxy) 442s 14:18:56.445790381 O: test connection multiplexing: cmd check 442s 14:18:56.454839910 O: test connection multiplexing: cmd forward local (TCP) 443s 14:18:57.674507940 O: test connection multiplexing: cmd forward remote (TCP) 444s 14:18:58.888138629 O: test connection multiplexing: cmd forward local (UNIX) 445s 14:18:59.918093286 O: test connection multiplexing: cmd forward remote (UNIX) 446s 14:19:00.947658838 O: test connection multiplexing: cmd exit 446s 14:19:00.957719011 O: test connection multiplexing: cmd stop 458s 14:19:12.185177171 O: ok connection multiplexing 458s 14:19:12.185789691 E: run test reexec.sh ... 458s 14:19:12.326115214 O: test config passing 458s 14:19:12.553252248 O: test reexec fallback 458s 14:19:12.554002028 E: ln: failed to create hard link '/tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 458s 14:19:12.823722081 O: ok reexec tests 458s 14:19:12.825883182 E: run test brokenkeys.sh ... 459s 14:19:13.204220770 O: ok broken keys 459s 14:19:13.204267578 E: run test sshcfgparse.sh ... 459s 14:19:13.342438586 O: reparse minimal config 459s 14:19:13.360583723 O: ssh -W opts 459s 14:19:13.411590815 O: user first match 459s 14:19:13.447658472 O: pubkeyacceptedalgorithms 459s 14:19:13.505262060 O: agentforwarding 459s 14:19:13.547612713 O: command line override 459s 14:19:13.570800317 O: ok ssh config parse 459s 14:19:13.572035167 E: run test cfgparse.sh ... 459s 14:19:13.713719949 O: reparse minimal config 459s 14:19:13.751052162 O: reparse regress config 459s 14:19:13.787931695 O: listenaddress order 459s 14:19:13.833204501 O: ok sshd config parse 459s 14:19:13.834583111 E: run test cfgmatch.sh ... 467s 14:19:20.969658125 O: ok sshd_config match 467s 14:19:20.970836759 E: run test cfgmatchlisten.sh ... 476s 14:19:30.106239231 O: ok sshd_config matchlisten 476s 14:19:30.107503083 E: run test percent.sh ... 476s 14:19:30.241548688 O: percent expansions matchexec percent 477s 14:19:31.549096671 O: percent expansions localcommand percent 478s 14:19:32.938160740 O: percent expansions remotecommand percent 479s 14:19:33.077965430 O: percent expansions controlpath percent 479s 14:19:33.214396081 O: percent expansions identityagent percent 479s 14:19:33.351543117 O: percent expansions forwardagent percent 479s 14:19:33.487864644 O: percent expansions localforward percent 479s 14:19:33.621675973 O: percent expansions remoteforward percent 479s 14:19:33.756652557 O: percent expansions revokedhostkeys percent 479s 14:19:33.894533639 O: percent expansions userknownhostsfile percent 480s 14:19:34.962262491 O: percent expansions controlpath dollar 481s 14:19:34.976123050 O: percent expansions identityagent dollar 481s 14:19:34.989268187 O: percent expansions forwardagent dollar 481s 14:19:35.002301164 O: percent expansions localforward dollar 481s 14:19:35.015172536 O: percent expansions remoteforward dollar 481s 14:19:35.028511245 O: percent expansions userknownhostsfile dollar 481s 14:19:35.138178441 O: percent expansions controlpath tilde 481s 14:19:35.163647331 O: percent expansions identityagent tilde 481s 14:19:35.189267566 O: percent expansions forwardagent tilde 481s 14:19:35.216288928 O: ok percent expansions 481s 14:19:35.217834737 E: run test addrmatch.sh ... 481s 14:19:35.361229093 O: test first entry for user 192.168.0.1 somehost 481s 14:19:35.373489870 O: test negative match for user 192.168.30.1 somehost 481s 14:19:35.385674864 O: test no match for user 19.0.0.1 somehost 481s 14:19:35.397800158 O: test list middle for user 10.255.255.254 somehost 481s 14:19:35.410203561 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 481s 14:19:35.422397402 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 481s 14:19:35.434529157 O: test localaddress for user 19.0.0.1 somehost 481s 14:19:35.447028933 O: test localport for user 19.0.0.1 somehost 481s 14:19:35.460553690 O: test bare IP6 address for user ::1 somehost.example.com 481s 14:19:35.475330365 O: test deny IPv6 for user ::2 somehost.example.com 481s 14:19:35.487174952 O: test IP6 negated for user ::3 somehost 481s 14:19:35.499515198 O: test IP6 no match for user ::4 somehost 481s 14:19:35.511654617 O: test IP6 network for user 2000::1 somehost 481s 14:19:35.524026092 O: test IP6 network for user 2001::1 somehost 481s 14:19:35.536679866 O: test IP6 localaddress for user ::5 somehost 481s 14:19:35.550713286 O: test IP6 localport for user ::5 somehost 481s 14:19:35.564661789 O: test invalid Match address 10.0.1.0/8 481s 14:19:35.575434344 O: test invalid Match localaddress 10.0.1.0/8 481s 14:19:35.585874566 O: test invalid Match address 10.0.0.1/24 481s 14:19:35.596055913 O: test invalid Match localaddress 10.0.0.1/24 481s 14:19:35.607477356 O: test invalid Match address 2000:aa:bb:01::/56 481s 14:19:35.618241094 O: test invalid Match localaddress 2000:aa:bb:01::/56 481s 14:19:35.631109470 O: ok address match 481s 14:19:35.632144161 E: run test localcommand.sh ... 481s 14:19:35.778021555 O: test localcommand: proto localcommand 481s 14:19:35.894529992 O: ok localcommand 481s 14:19:35.895698654 E: run test forcecommand.sh ... 482s 14:19:36.495725761 E: Connection closed 482s 14:19:36.498177320 E: Connection closed. 482s 14:19:36.697263957 E: Connection closed. 482s 14:19:36.698353511 E: Connection closed 482s 14:19:36.804077777 E: run test portnum.sh ... 482s 14:19:36.805423711 O: ok forced command 482s 14:19:36.948604146 O: port number parsing: invalid port 0 482s 14:19:36.956628136 O: port number parsing: invalid port 65536 482s 14:19:36.964881826 O: port number parsing: invalid port 131073 483s 14:19:36.973200267 O: port number parsing: invalid port 2000blah 483s 14:19:36.981313533 O: port number parsing: invalid port blah2000 483s 14:19:36.989472145 O: port number parsing: valid port 1 483s 14:19:37.094118587 O: port number parsing: valid port 22 483s 14:19:37.202915272 O: port number parsing: valid port 2222 483s 14:19:37.311033303 O: port number parsing: valid port 22222 483s 14:19:37.414111723 O: port number parsing: valid port 65535 483s 14:19:37.517316431 O: ok port number parsing 483s 14:19:37.518566365 E: run test keytype.sh ... 483s 14:19:37.657014577 O: keygen ed25519, 512 bits 483s 14:19:37.667515259 O: keygen ed25519-sk, n/a bits 483s 14:19:37.680999307 O: keygen ecdsa, 256 bits 483s 14:19:37.690472022 O: keygen ecdsa, 384 bits 483s 14:19:37.700164379 O: keygen ecdsa, 521 bits 483s 14:19:37.710189749 O: keygen ecdsa-sk, n/a bits 483s 14:19:37.723363295 O: keygen rsa, 2048 bits 483s 14:19:37.782971894 O: keygen rsa, 3072 bits 483s 14:19:37.849762270 O: userkey ed25519-512, hostkey ed25519-512 483s 14:19:37.929202079 O: userkey ed25519-512, hostkey ed25519-512 484s 14:19:38.014057333 O: userkey ed25519-512, hostkey ed25519-512 484s 14:19:38.105352664 O: userkey ed25519-sk, hostkey ed25519-sk 484s 14:19:38.190561411 O: userkey ed25519-sk, hostkey ed25519-sk 484s 14:19:38.278116057 O: userkey ed25519-sk, hostkey ed25519-sk 484s 14:19:38.370887506 O: userkey ecdsa-256, hostkey ecdsa-256 484s 14:19:38.456491164 O: userkey ecdsa-256, hostkey ecdsa-256 484s 14:19:38.541272250 O: userkey ecdsa-256, hostkey ecdsa-256 484s 14:19:38.633209334 O: userkey ecdsa-384, hostkey ecdsa-384 484s 14:19:38.720615461 O: userkey ecdsa-384, hostkey ecdsa-384 484s 14:19:38.809163531 O: userkey ecdsa-384, hostkey ecdsa-384 484s 14:19:38.902624443 O: userkey ecdsa-521, hostkey ecdsa-521 485s 14:19:38.985814894 O: userkey ecdsa-521, hostkey ecdsa-521 485s 14:19:39.069590215 O: userkey ecdsa-521, hostkey ecdsa-521 485s 14:19:39.158788093 O: userkey ecdsa-sk, hostkey ecdsa-sk 485s 14:19:39.244994148 O: userkey ecdsa-sk, hostkey ecdsa-sk 485s 14:19:39.332897628 O: userkey ecdsa-sk, hostkey ecdsa-sk 485s 14:19:39.426916253 O: userkey rsa-2048, hostkey rsa-2048 485s 14:19:39.510317250 O: userkey rsa-2048, hostkey rsa-2048 485s 14:19:39.595033062 O: userkey rsa-2048, hostkey rsa-2048 485s 14:19:39.684897237 O: userkey rsa-3072, hostkey rsa-3072 485s 14:19:39.767873768 O: userkey rsa-3072, hostkey rsa-3072 485s 14:19:39.851080651 O: userkey rsa-3072, hostkey rsa-3072 485s 14:19:39.937199243 O: ok login with different key types 485s 14:19:39.937820798 E: run test kextype.sh ... 486s 14:19:40.124380644 O: kex diffie-hellman-group1-sha1 486s 14:19:40.548204049 O: kex diffie-hellman-group14-sha1 486s 14:19:40.949485779 O: kex diffie-hellman-group14-sha256 487s 14:19:41.335620857 O: kex diffie-hellman-group16-sha512 487s 14:19:41.766625594 O: kex diffie-hellman-group18-sha512 488s 14:19:42.389953833 O: kex diffie-hellman-group-exchange-sha1 489s 14:19:43.033827088 O: kex diffie-hellman-group-exchange-sha256 489s 14:19:43.669966963 O: kex ecdh-sha2-nistp256 490s 14:19:44.048080378 O: kex ecdh-sha2-nistp384 490s 14:19:44.477509967 O: kex ecdh-sha2-nistp521 490s 14:19:44.887862056 O: kex curve25519-sha256 491s 14:19:45.279488680 O: kex curve25519-sha256@libssh.org 491s 14:19:45.665368030 O: kex sntrup761x25519-sha512 492s 14:19:46.066894440 O: kex sntrup761x25519-sha512@openssh.com 492s 14:19:46.474286566 O: kex mlkem768x25519-sha256 492s 14:19:46.863071668 O: ok login with different key exchange algorithms 492s 14:19:46.863115359 E: run test cert-hostkey.sh ... 493s 14:19:47.503423598 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/host_ca_key.pub 493s 14:19:47.505204697 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/host_ca_key2.pub 493s 14:19:47.506512018 O: certified host keys: sign host ed25519 cert 493s 14:19:47.512813607 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 493s 14:19:47.522598486 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 493s 14:19:47.526041850 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 493s 14:19:47.537412150 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 493s 14:19:47.546883200 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 493s 14:19:47.550339587 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 493s 14:19:47.558369476 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 493s 14:19:47.567801012 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 493s 14:19:47.571140762 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 493s 14:19:47.578913319 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 493s 14:19:47.589528994 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 493s 14:19:47.592792932 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 493s 14:19:47.602096518 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 493s 14:19:47.612894536 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 493s 14:19:47.616248341 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 493s 14:19:47.627297900 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 493s 14:19:47.637138814 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 493s 14:19:47.640374297 O: certified host keys: sign host rsa cert 493s 14:19:47.932332105 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 493s 14:19:47.942405444 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 493s 14:19:47.945719866 O: certified host keys: sign host rsa-sha2-256 cert 494s 14:19:48.190767445 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 494s 14:19:48.202542061 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 494s 14:19:48.205816768 O: certified host keys: sign host rsa-sha2-512 cert 494s 14:19:48.483623743 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 494s 14:19:48.494941593 O: Revoking from /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 494s 14:19:48.498264841 O: certified host keys: host ed25519 cert connect 494s 14:19:48.500320052 O: certified host keys: ed25519 basic connect expect success yes 494s 14:19:48.604164052 O: certified host keys: ed25519 empty KRL expect success yes 494s 14:19:48.703915823 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 494s 14:19:48.762846381 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 494s 14:19:48.821148714 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 494s 14:19:48.877892143 O: certified host keys: ed25519 empty plaintext revocation expect success yes 495s 14:19:48.974112806 O: certified host keys: ed25519 plain key plaintext revocation expect success no 495s 14:19:49.031667995 O: certified host keys: ed25519 cert plaintext revocation expect success no 495s 14:19:49.089808978 O: certified host keys: ed25519 CA plaintext revocation expect success no 495s 14:19:49.149685623 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 495s 14:19:49.151777043 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 495s 14:19:49.255647350 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 495s 14:19:49.361809172 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 495s 14:19:49.421213472 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 495s 14:19:49.481550260 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 495s 14:19:49.541497779 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 495s 14:19:49.645817247 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 495s 14:19:49.709316782 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 495s 14:19:49.777285322 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 495s 14:19:49.839282872 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 495s 14:19:49.841353211 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 495s 14:19:49.939090576 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 496s 14:19:50.038218513 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 496s 14:19:50.097531616 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 496s 14:19:50.156733051 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 496s 14:19:50.216191546 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 496s 14:19:50.313611696 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 496s 14:19:50.371858942 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 496s 14:19:50.432779346 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 496s 14:19:50.490693599 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 496s 14:19:50.492678939 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 496s 14:19:50.594364070 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 496s 14:19:50.697032754 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 496s 14:19:50.758507660 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 496s 14:19:50.820053627 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 496s 14:19:50.880532404 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 497s 14:19:50.983053081 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 497s 14:19:51.045614196 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 497s 14:19:51.115825343 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 497s 14:19:51.177913119 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 497s 14:19:51.179988978 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 497s 14:19:51.283059395 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 497s 14:19:51.387085853 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 497s 14:19:51.448857177 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 497s 14:19:51.517017100 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 497s 14:19:51.581139547 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 497s 14:19:51.690525949 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 497s 14:19:51.758808632 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 497s 14:19:51.840379614 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 497s 14:19:51.912325112 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 497s 14:19:51.914705912 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 498s 14:19:52.094800823 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 498s 14:19:52.228487102 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 498s 14:19:52.297291105 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 498s 14:19:52.365923637 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 498s 14:19:52.432819736 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 498s 14:19:52.544547211 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 498s 14:19:52.613383886 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 498s 14:19:52.687129378 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 498s 14:19:52.752684193 O: certified host keys: host rsa cert connect 498s 14:19:52.754886679 O: certified host keys: rsa basic connect expect success yes 498s 14:19:52.888491697 O: certified host keys: rsa empty KRL expect success yes 499s 14:19:52.994777556 O: certified host keys: rsa KRL w/ plain key revoked expect success no 499s 14:19:53.061829641 O: certified host keys: rsa KRL w/ cert revoked expect success no 499s 14:19:53.135683869 O: certified host keys: rsa KRL w/ CA revoked expect success no 499s 14:19:53.206989672 O: certified host keys: rsa empty plaintext revocation expect success yes 499s 14:19:53.330850509 O: certified host keys: rsa plain key plaintext revocation expect success no 499s 14:19:53.398242209 O: certified host keys: rsa cert plaintext revocation expect success no 499s 14:19:53.485669767 O: certified host keys: rsa CA plaintext revocation expect success no 499s 14:19:53.556709292 O: certified host keys: host rsa-sha2-256 cert connect 499s 14:19:53.558534802 O: certified host keys: rsa-sha2-256 basic connect expect success yes 499s 14:19:53.655121410 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 499s 14:19:53.752219406 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 499s 14:19:53.813938989 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 499s 14:19:53.876838312 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 499s 14:19:53.945156548 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 500s 14:19:54.053792426 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 500s 14:19:54.120044233 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 500s 14:19:54.196081704 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 500s 14:19:54.256114471 O: certified host keys: host rsa-sha2-512 cert connect 500s 14:19:54.258128474 O: certified host keys: rsa-sha2-512 basic connect expect success yes 500s 14:19:54.362288620 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 500s 14:19:54.465059785 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 500s 14:19:54.525019235 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 500s 14:19:54.586771613 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 500s 14:19:54.649162679 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 500s 14:19:54.755301452 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 500s 14:19:54.818499970 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 500s 14:19:54.890292207 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 500s 14:19:54.961931439 O: certified host keys: host ed25519 revoked cert 501s 14:19:55.023128294 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 501s 14:19:55.088273742 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 501s 14:19:55.149979498 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 501s 14:19:55.214856334 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 501s 14:19:55.279848995 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 501s 14:19:55.346149779 O: certified host keys: host rsa revoked cert 501s 14:19:55.411263237 O: certified host keys: host rsa-sha2-256 revoked cert 501s 14:19:55.490999421 O: certified host keys: host rsa-sha2-512 revoked cert 501s 14:19:55.582149824 O: certified host keys: host ed25519 revoked cert 501s 14:19:55.662860792 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 501s 14:19:55.748340193 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 501s 14:19:55.830538979 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 501s 14:19:55.914535924 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 502s 14:19:55.997826604 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 502s 14:19:56.078398483 O: certified host keys: host rsa revoked cert 502s 14:19:56.149479885 O: certified host keys: host rsa-sha2-256 revoked cert 502s 14:19:56.217006738 O: certified host keys: host rsa-sha2-512 revoked cert 507s 14:20:01.693146967 O: certified host keys: host ed25519 cert downgrade to raw key 508s 14:20:01.952239377 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 508s 14:20:02.174005547 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 508s 14:20:02.404370377 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 508s 14:20:02.652424602 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 508s 14:20:02.938770766 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 509s 14:20:03.223212955 O: certified host keys: host rsa cert downgrade to raw key 509s 14:20:03.764837606 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 510s 14:20:04.106731825 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 510s 14:20:04.462488971 O: certified host keys: host ed25519 connect wrong cert 510s 14:20:04.536794939 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 510s 14:20:04.648167445 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 510s 14:20:04.760557737 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 510s 14:20:04.856332705 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 510s 14:20:04.931143429 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 511s 14:20:05.008670548 O: certified host keys: host rsa connect wrong cert 511s 14:20:05.150487338 O: certified host keys: host rsa-sha2-256 connect wrong cert 511s 14:20:05.398361713 O: certified host keys: host rsa-sha2-512 connect wrong cert 511s 14:20:05.496798979 O: ok certified host keys 511s 14:20:05.496835618 E: run test cert-userkey.sh ... 511s 14:20:05.828992695 O: certified user keys: sign user ed25519 cert 511s 14:20:05.842014017 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 511s 14:20:05.856859794 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 511s 14:20:05.868148952 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 511s 14:20:05.880099358 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 511s 14:20:05.891891798 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 511s 14:20:05.906710235 O: certified user keys: sign user rsa cert 512s 14:20:06.277333475 O: certified user keys: sign user rsa-sha2-256 cert 512s 14:20:06.419779410 O: certified user keys: sign user rsa-sha2-512 cert 512s 14:20:06.643702841 O: certified user keys: ed25519 missing authorized_principals 512s 14:20:06.738488051 O: certified user keys: ed25519 empty authorized_principals 512s 14:20:06.825907208 O: certified user keys: ed25519 wrong authorized_principals 512s 14:20:06.915434945 O: certified user keys: ed25519 correct authorized_principals 513s 14:20:07.018323835 O: certified user keys: ed25519 authorized_principals bad key opt 513s 14:20:07.106711240 O: certified user keys: ed25519 authorized_principals command=false 513s 14:20:07.209298248 O: certified user keys: ed25519 authorized_principals command=true 513s 14:20:07.314565435 O: certified user keys: ed25519 wrong principals key option 513s 14:20:07.411820070 O: certified user keys: ed25519 correct principals key option 513s 14:20:07.525525346 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 513s 14:20:07.629946318 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 513s 14:20:07.734892154 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 513s 14:20:07.839179947 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 513s 14:20:07.942707888 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 514s 14:20:08.046001335 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 514s 14:20:08.158926321 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 514s 14:20:08.279299810 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 514s 14:20:08.377183276 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 514s 14:20:08.488561976 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 514s 14:20:08.580857018 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 514s 14:20:08.672916990 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 514s 14:20:08.767251031 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 514s 14:20:08.869293521 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 515s 14:20:08.964507879 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 515s 14:20:09.067472161 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 515s 14:20:09.177045203 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 515s 14:20:09.268586716 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 515s 14:20:09.378591255 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 515s 14:20:09.469693507 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 515s 14:20:09.567616969 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 515s 14:20:09.660050764 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 515s 14:20:09.762426231 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 515s 14:20:09.856097933 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 515s 14:20:09.957154470 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 516s 14:20:10.062778674 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 516s 14:20:10.166771160 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 516s 14:20:10.282073899 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 516s 14:20:10.381329496 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 516s 14:20:10.488888989 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 516s 14:20:10.591856356 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 516s 14:20:10.696777911 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 516s 14:20:10.793412733 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 516s 14:20:10.897723685 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 517s 14:20:11.003479430 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 517s 14:20:11.097701849 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 517s 14:20:11.198361628 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 517s 14:20:11.303013871 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 517s 14:20:11.426929944 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 517s 14:20:11.538265889 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 517s 14:20:11.645490456 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 517s 14:20:11.748107218 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 517s 14:20:11.856048066 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 517s 14:20:11.963279277 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 518s 14:20:12.058393651 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 518s 14:20:12.172648831 O: certified user keys: rsa missing authorized_principals 518s 14:20:12.270140433 O: certified user keys: rsa empty authorized_principals 518s 14:20:12.367676736 O: certified user keys: rsa wrong authorized_principals 518s 14:20:12.465133973 O: certified user keys: rsa correct authorized_principals 518s 14:20:12.570430524 O: certified user keys: rsa authorized_principals bad key opt 518s 14:20:12.664061038 O: certified user keys: rsa authorized_principals command=false 518s 14:20:12.769129251 O: certified user keys: rsa authorized_principals command=true 518s 14:20:12.876373778 O: certified user keys: rsa wrong principals key option 519s 14:20:12.968842046 O: certified user keys: rsa correct principals key option 519s 14:20:13.075912261 O: certified user keys: rsa-sha2-256 missing authorized_principals 519s 14:20:13.159900599 O: certified user keys: rsa-sha2-256 empty authorized_principals 519s 14:20:13.240544198 O: certified user keys: rsa-sha2-256 wrong authorized_principals 519s 14:20:13.321433706 O: certified user keys: rsa-sha2-256 correct authorized_principals 519s 14:20:13.414641100 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 519s 14:20:13.494933951 O: certified user keys: rsa-sha2-256 authorized_principals command=false 519s 14:20:13.590027877 O: certified user keys: rsa-sha2-256 authorized_principals command=true 519s 14:20:13.688405630 O: certified user keys: rsa-sha2-256 wrong principals key option 519s 14:20:13.771159978 O: certified user keys: rsa-sha2-256 correct principals key option 519s 14:20:13.871154487 O: certified user keys: rsa-sha2-512 missing authorized_principals 519s 14:20:13.950915963 O: certified user keys: rsa-sha2-512 empty authorized_principals 520s 14:20:14.029101008 O: certified user keys: rsa-sha2-512 wrong authorized_principals 520s 14:20:14.107458390 O: certified user keys: rsa-sha2-512 correct authorized_principals 520s 14:20:14.201639299 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 520s 14:20:14.280451313 O: certified user keys: rsa-sha2-512 authorized_principals command=false 520s 14:20:14.377047103 O: certified user keys: rsa-sha2-512 authorized_principals command=true 520s 14:20:14.480246593 O: certified user keys: rsa-sha2-512 wrong principals key option 520s 14:20:14.568266882 O: certified user keys: rsa-sha2-512 correct principals key option 520s 14:20:14.673618640 O: certified user keys: ed25519 authorized_keys connect 520s 14:20:14.782240459 O: certified user keys: ed25519 authorized_keys revoked key 520s 14:20:14.872348677 O: certified user keys: ed25519 authorized_keys revoked via KRL 520s 14:20:14.962842956 O: certified user keys: ed25519 authorized_keys empty KRL 521s 14:20:15.065437629 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 521s 14:20:15.169517269 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 521s 14:20:15.269063044 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 521s 14:20:15.369847206 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 521s 14:20:15.479573785 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 521s 14:20:15.590728898 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 521s 14:20:15.696267308 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 521s 14:20:15.802795591 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 521s 14:20:15.919835167 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 522s 14:20:16.026410306 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 522s 14:20:16.116316191 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 522s 14:20:16.208391156 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 522s 14:20:16.312670192 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 522s 14:20:16.412688042 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 522s 14:20:16.506377760 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 522s 14:20:16.600088095 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 522s 14:20:16.703974596 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 522s 14:20:16.813033838 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 522s 14:20:16.923468043 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 523s 14:20:17.035159139 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 523s 14:20:17.152597344 O: certified user keys: rsa authorized_keys connect 523s 14:20:17.271299026 O: certified user keys: rsa authorized_keys revoked key 523s 14:20:17.360899382 O: certified user keys: rsa authorized_keys revoked via KRL 523s 14:20:17.453085860 O: certified user keys: rsa authorized_keys empty KRL 523s 14:20:17.565008142 O: certified user keys: rsa-sha2-256 authorized_keys connect 523s 14:20:17.676798459 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 523s 14:20:17.778318803 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 523s 14:20:17.921079752 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 524s 14:20:18.039662677 O: certified user keys: rsa-sha2-512 authorized_keys connect 524s 14:20:18.153501198 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 524s 14:20:18.248122415 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 524s 14:20:18.344621727 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 524s 14:20:18.454607688 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 524s 14:20:18.553816832 O: certified user keys: authorized_keys CA does not authenticate 524s 14:20:18.555965843 O: certified user keys: ensure CA key does not authenticate user 524s 14:20:18.644443292 O: certified user keys: ed25519 TrustedUserCAKeys connect 524s 14:20:18.751076801 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 524s 14:20:18.838062308 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 524s 14:20:18.926990100 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 525s 14:20:19.030107987 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 525s 14:20:19.130877242 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 525s 14:20:19.238922264 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 525s 14:20:19.349957241 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 525s 14:20:19.464272547 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 525s 14:20:19.577255018 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 525s 14:20:19.669864314 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 525s 14:20:19.763550695 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 525s 14:20:19.870478797 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 526s 14:20:19.978952982 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 526s 14:20:20.075462942 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 526s 14:20:20.174463191 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 526s 14:20:20.283244604 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 526s 14:20:20.396705403 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 526s 14:20:20.494716131 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 526s 14:20:20.597071237 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 526s 14:20:20.716439721 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 526s 14:20:20.829673625 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 526s 14:20:20.937292783 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 527s 14:20:21.050571699 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 527s 14:20:21.169768424 O: certified user keys: rsa TrustedUserCAKeys connect 527s 14:20:21.272970659 O: certified user keys: rsa TrustedUserCAKeys revoked key 527s 14:20:21.361214063 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 527s 14:20:21.454613003 O: certified user keys: rsa TrustedUserCAKeys empty KRL 527s 14:20:21.565233932 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 527s 14:20:21.670007527 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 527s 14:20:21.766139352 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 527s 14:20:21.864046810 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 528s 14:20:21.975626671 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 528s 14:20:22.083937711 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 528s 14:20:22.183179610 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 528s 14:20:22.285873967 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 528s 14:20:22.402607326 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 528s 14:20:22.500866975 O: certified user keys: TrustedUserCAKeys CA does not authenticate 528s 14:20:22.503852012 O: certified user keys: ensure CA key does not authenticate user 528s 14:20:22.586311036 O: certified user keys: correct principal auth authorized_keys expect success rsa 528s 14:20:22.704676257 O: certified user keys: correct principal auth authorized_keys expect success ed25519 528s 14:20:22.821929187 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 528s 14:20:22.933596235 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 529s 14:20:23.047686244 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 529s 14:20:23.144518666 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 529s 14:20:23.241828584 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 529s 14:20:23.334829177 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 529s 14:20:23.427332792 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 529s 14:20:23.549179780 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 529s 14:20:23.670482010 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 529s 14:20:23.784262794 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 529s 14:20:23.899013204 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 530s 14:20:24.008859257 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 530s 14:20:24.131481248 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 530s 14:20:24.231662101 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 530s 14:20:24.332545478 O: certified user keys: cert expired auth authorized_keys expect failure rsa 530s 14:20:24.447312041 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 530s 14:20:24.553062395 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 530s 14:20:24.657963218 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 530s 14:20:24.782885704 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 530s 14:20:24.911699279 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 531s 14:20:25.031460806 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 531s 14:20:25.144902433 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 531s 14:20:25.258113519 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 531s 14:20:25.364432619 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 531s 14:20:25.468719808 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 531s 14:20:25.567924758 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 531s 14:20:25.669088895 O: certified user keys: force-command auth authorized_keys expect failure rsa 531s 14:20:25.788318832 O: certified user keys: force-command auth authorized_keys expect failure ed25519 531s 14:20:25.903757014 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 532s 14:20:26.018410687 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 532s 14:20:26.129108144 O: certified user keys: empty principals auth authorized_keys expect success rsa 532s 14:20:26.248520715 O: certified user keys: empty principals auth authorized_keys expect success ed25519 532s 14:20:26.363495373 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 532s 14:20:26.462399084 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 532s 14:20:26.564036008 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 532s 14:20:26.673337533 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 532s 14:20:26.780880727 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 532s 14:20:26.873182667 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 532s 14:20:26.963907446 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 533s 14:20:27.083565771 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 533s 14:20:27.204654762 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 533s 14:20:27.311910583 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 533s 14:20:27.418920593 O: certified user keys: force-command match true auth authorized_keys expect success rsa 533s 14:20:27.538374074 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 533s 14:20:27.658365651 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 533s 14:20:27.778581578 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 533s 14:20:27.903640606 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 534s 14:20:28.008405165 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 534s 14:20:28.114359656 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 534s 14:20:28.219513046 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 534s 14:20:28.332585842 O: certified user keys: user ed25519 connect wrong cert 534s 14:20:28.435331799 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 534s 14:20:28.519094733 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 534s 14:20:28.604598504 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 534s 14:20:28.688491101 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 534s 14:20:28.774007674 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 534s 14:20:28.859691362 O: certified user keys: user rsa connect wrong cert 534s 14:20:28.962020659 O: certified user keys: user rsa-sha2-256 connect wrong cert 535s 14:20:29.065714756 O: certified user keys: user rsa-sha2-512 connect wrong cert 535s 14:20:29.163546514 O: ok certified user keys 535s 14:20:29.164622167 E: run test host-expand.sh ... 535s 14:20:29.467925500 O: ok expand %h and %n 535s 14:20:29.471393307 E: run test keys-command.sh ... 535s 14:20:29.649905678 O: SKIPPED: /var/run/keycommand_openssh-tests.43373 not executable (/var/run mounted noexec?) 535s 14:20:29.656634375 E: run test forward-control.sh ... 536s 14:20:30.109539317 O: check_lfwd done (expecting Y): default configuration 537s 14:20:31.415332374 O: check_rfwd done (expecting Y): default configuration 537s 14:20:31.718050135 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 538s 14:20:32.031846562 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 538s 14:20:32.147146099 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 538s 14:20:32.451056946 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 538s 14:20:32.737549231 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 540s 14:20:34.038666550 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 541s 14:20:35.154003879 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 541s 14:20:35.469953836 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 542s 14:20:36.770789800 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 544s 14:20:38.078233343 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 544s 14:20:38.194819534 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 544s 14:20:38.308715168 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 545s 14:20:39.428223340 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 545s 14:20:39.543713841 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 545s 14:20:39.844967369 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 547s 14:20:41.159580423 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 547s 14:20:41.457949257 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 547s 14:20:41.568418888 O: check_rfwd done (expecting N): AllowTcpForwarding=local 548s 14:20:42.689143008 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 548s 14:20:42.801647738 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 550s 14:20:44.099492255 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 550s 14:20:44.230934987 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 550s 14:20:44.364147781 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 550s 14:20:44.486929940 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 551s 14:20:45.817706558 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 551s 14:20:45.923637543 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 552s 14:20:46.037674375 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 552s 14:20:46.145032712 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 553s 14:20:47.260718893 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 553s 14:20:47.368568333 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 553s 14:20:47.664111639 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 553s 14:20:47.769217709 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 554s 14:20:48.880494508 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 556s 14:20:50.187361669 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 557s 14:20:51.310826270 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 558s 14:20:52.617527040 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 558s 14:20:52.738957567 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 560s 14:20:54.055114492 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 561s 14:20:55.175530890 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 562s 14:20:56.480390818 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 563s 14:20:57.598021407 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 564s 14:20:58.901627643 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 566s 14:21:00.017426224 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 566s 14:21:00.127935222 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 566s 14:21:00.249276399 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 566s 14:21:00.362179273 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 566s 14:21:00.496950705 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 567s 14:21:01.825285927 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 567s 14:21:01.946342717 O: check_lfwd done (expecting N): AllowTcpForwarding=no 568s 14:21:02.060004372 O: check_rfwd done (expecting N): AllowTcpForwarding=no 569s 14:21:03.177418109 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 569s 14:21:03.288471162 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 570s 14:21:04.410828047 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 570s 14:21:04.526166188 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 571s 14:21:05.647239650 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 571s 14:21:05.754823232 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 572s 14:21:06.869645959 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 573s 14:21:06.983616646 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 573s 14:21:07.105662391 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 573s 14:21:07.219693567 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 573s 14:21:07.336399853 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 573s 14:21:07.441653492 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 574s 14:21:08.560114558 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 574s 14:21:08.665822093 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 576s 14:21:09.977722025 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 576s 14:21:10.091016771 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 577s 14:21:11.382639088 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 577s 14:21:11.685622028 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 579s 14:21:12.990285974 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 579s 14:21:13.099124053 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 580s 14:21:14.402784516 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 580s 14:21:14.512331074 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 580s 14:21:14.628228273 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 580s 14:21:14.736398362 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 580s 14:21:14.851033502 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 582s 14:21:16.160920798 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 583s 14:21:17.291855886 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 583s 14:21:17.401880624 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 584s 14:21:18.529916733 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 584s 14:21:18.649243867 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 584s 14:21:18.670214687 O: ok sshd control of local and remote forwarding 584s 14:21:18.670446480 E: run test integrity.sh ... 584s 14:21:18.829076374 O: test integrity: hmac-sha1 @2900 584s 14:21:18.942543880 O: test integrity: hmac-sha1 @2901 585s 14:21:19.061629694 O: test integrity: hmac-sha1 @2902 585s 14:21:19.172008454 O: test integrity: hmac-sha1 @2903 585s 14:21:19.288879928 O: test integrity: hmac-sha1 @2904 585s 14:21:19.403585745 O: test integrity: hmac-sha1 @2905 585s 14:21:19.526101907 O: test integrity: hmac-sha1 @2906 585s 14:21:19.642261651 O: test integrity: hmac-sha1 @2907 585s 14:21:19.751876383 O: test integrity: hmac-sha1 @2908 585s 14:21:19.860974220 O: test integrity: hmac-sha1 @2909 586s 14:21:19.975682351 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 586s 14:21:19.992716133 O: test integrity: hmac-sha1-96 @2900 586s 14:21:20.110705724 O: test integrity: hmac-sha1-96 @2901 586s 14:21:20.219879049 O: test integrity: hmac-sha1-96 @2902 586s 14:21:20.329471136 O: test integrity: hmac-sha1-96 @2903 586s 14:21:20.439492225 O: test integrity: hmac-sha1-96 @2904 586s 14:21:20.549606759 O: test integrity: hmac-sha1-96 @2905 586s 14:21:20.658338530 O: test integrity: hmac-sha1-96 @2906 586s 14:21:20.767215019 O: test integrity: hmac-sha1-96 @2907 586s 14:21:20.876479155 O: test integrity: hmac-sha1-96 @2908 587s 14:21:20.983883915 O: test integrity: hmac-sha1-96 @2909 587s 14:21:21.082101073 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 587s 14:21:21.096513025 O: test integrity: hmac-sha2-256 @2900 587s 14:21:21.207011464 O: test integrity: hmac-sha2-256 @2901 587s 14:21:21.319505221 O: test integrity: hmac-sha2-256 @2902 587s 14:21:21.438636562 O: test integrity: hmac-sha2-256 @2903 587s 14:21:21.558625304 O: test integrity: hmac-sha2-256 @2904 587s 14:21:21.681235276 O: test integrity: hmac-sha2-256 @2905 587s 14:21:21.810003416 O: test integrity: hmac-sha2-256 @2906 587s 14:21:21.929154092 O: test integrity: hmac-sha2-256 @2907 588s 14:21:22.054014888 O: test integrity: hmac-sha2-256 @2908 588s 14:21:22.169941187 O: test integrity: hmac-sha2-256 @2909 588s 14:21:22.281670734 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 588s 14:21:22.297222745 O: test integrity: hmac-sha2-512 @2900 588s 14:21:22.377579866 O: test integrity: hmac-sha2-512 @2901 588s 14:21:22.461439262 O: test integrity: hmac-sha2-512 @2902 588s 14:21:22.542324671 O: test integrity: hmac-sha2-512 @2903 588s 14:21:22.623082410 O: test integrity: hmac-sha2-512 @2904 588s 14:21:22.703948343 O: test integrity: hmac-sha2-512 @2905 588s 14:21:22.786052809 O: test integrity: hmac-sha2-512 @2906 588s 14:21:22.866477407 O: test integrity: hmac-sha2-512 @2907 588s 14:21:22.947626057 O: test integrity: hmac-sha2-512 @2908 589s 14:21:23.034296074 O: test integrity: hmac-sha2-512 @2909 589s 14:21:23.111294818 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 589s 14:21:23.127704413 O: test integrity: hmac-md5 @2900 589s 14:21:23.243795376 O: test integrity: hmac-md5 @2901 589s 14:21:23.377568491 O: test integrity: hmac-md5 @2902 589s 14:21:23.498347194 O: test integrity: hmac-md5 @2903 589s 14:21:23.614730564 O: test integrity: hmac-md5 @2904 589s 14:21:23.732111037 O: test integrity: hmac-md5 @2905 589s 14:21:23.851496657 O: test integrity: hmac-md5 @2906 590s 14:21:23.965360609 O: test integrity: hmac-md5 @2907 590s 14:21:24.079638042 O: test integrity: hmac-md5 @2908 590s 14:21:24.195353511 O: test integrity: hmac-md5 @2909 590s 14:21:24.308472738 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 590s 14:21:24.324680976 O: test integrity: hmac-md5-96 @2900 590s 14:21:24.445841500 O: test integrity: hmac-md5-96 @2901 590s 14:21:24.582343751 O: test integrity: hmac-md5-96 @2902 590s 14:21:24.709309362 O: test integrity: hmac-md5-96 @2903 590s 14:21:24.835949606 O: test integrity: hmac-md5-96 @2904 590s 14:21:24.954476629 O: test integrity: hmac-md5-96 @2905 591s 14:21:25.067591617 O: test integrity: hmac-md5-96 @2906 591s 14:21:25.190490115 O: test integrity: hmac-md5-96 @2907 591s 14:21:25.311515193 O: test integrity: hmac-md5-96 @2908 591s 14:21:25.430605154 O: test integrity: hmac-md5-96 @2909 591s 14:21:25.547112891 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 591s 14:21:25.566243328 O: test integrity: umac-64@openssh.com @2900 591s 14:21:25.679808888 O: test integrity: umac-64@openssh.com @2901 591s 14:21:25.795309051 O: test integrity: umac-64@openssh.com @2902 591s 14:21:25.908439817 O: test integrity: umac-64@openssh.com @2903 592s 14:21:26.023991327 O: test integrity: umac-64@openssh.com @2904 592s 14:21:26.139613216 O: test integrity: umac-64@openssh.com @2905 592s 14:21:26.255579427 O: test integrity: umac-64@openssh.com @2906 592s 14:21:26.366802036 O: test integrity: umac-64@openssh.com @2907 592s 14:21:26.488923389 O: test integrity: umac-64@openssh.com @2908 592s 14:21:26.598884460 O: test integrity: umac-64@openssh.com @2909 592s 14:21:26.699032223 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 592s 14:21:26.714508262 O: test integrity: umac-128@openssh.com @2900 592s 14:21:26.832704140 O: test integrity: umac-128@openssh.com @2901 592s 14:21:26.947712302 O: test integrity: umac-128@openssh.com @2902 593s 14:21:27.060310149 O: test integrity: umac-128@openssh.com @2903 593s 14:21:27.174036021 O: test integrity: umac-128@openssh.com @2904 593s 14:21:27.288201337 O: test integrity: umac-128@openssh.com @2905 593s 14:21:27.402680407 O: test integrity: umac-128@openssh.com @2906 593s 14:21:27.520705403 O: test integrity: umac-128@openssh.com @2907 593s 14:21:27.638764513 O: test integrity: umac-128@openssh.com @2908 593s 14:21:27.758700279 O: test integrity: umac-128@openssh.com @2909 593s 14:21:27.942800281 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 593s 14:21:27.958965036 O: test integrity: hmac-sha1-etm@openssh.com @2900 594s 14:21:28.073242387 O: test integrity: hmac-sha1-etm@openssh.com @2901 594s 14:21:28.191947983 O: test integrity: hmac-sha1-etm@openssh.com @2902 594s 14:21:28.310527913 O: test integrity: hmac-sha1-etm@openssh.com @2903 594s 14:21:28.427457800 O: test integrity: hmac-sha1-etm@openssh.com @2904 594s 14:21:28.542356532 O: test integrity: hmac-sha1-etm@openssh.com @2905 594s 14:21:28.657672755 O: test integrity: hmac-sha1-etm@openssh.com @2906 594s 14:21:28.776919691 O: test integrity: hmac-sha1-etm@openssh.com @2907 594s 14:21:28.897521560 O: test integrity: hmac-sha1-etm@openssh.com @2908 595s 14:21:29.014858869 O: test integrity: hmac-sha1-etm@openssh.com @2909 595s 14:21:29.122387997 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 595s 14:21:29.143193632 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 595s 14:21:29.282823272 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 595s 14:21:29.402894787 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 595s 14:21:29.520963770 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 595s 14:21:29.632430237 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 595s 14:21:29.746066157 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 595s 14:21:29.859640361 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 596s 14:21:29.974349386 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 596s 14:21:30.086499929 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 596s 14:21:30.205286169 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 596s 14:21:30.327068298 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 596s 14:21:30.347397713 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 596s 14:21:30.478564176 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 596s 14:21:30.609410611 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 596s 14:21:30.756045326 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 596s 14:21:30.878852499 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 597s 14:21:31.005531257 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 597s 14:21:31.117247545 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 597s 14:21:31.230680641 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 597s 14:21:31.349524426 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 597s 14:21:31.463551964 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 597s 14:21:31.566854130 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 597s 14:21:31.582728612 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 597s 14:21:31.666602267 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 597s 14:21:31.750957180 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 597s 14:21:31.832548481 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 597s 14:21:31.914495827 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 598s 14:21:31.996834414 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 598s 14:21:32.092249171 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 598s 14:21:32.205923144 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 598s 14:21:32.320174136 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 598s 14:21:32.428022395 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 598s 14:21:32.523901630 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 598s 14:21:32.543951020 O: test integrity: hmac-md5-etm@openssh.com @2900 598s 14:21:32.687213088 O: test integrity: hmac-md5-etm@openssh.com @2901 598s 14:21:32.830230418 O: test integrity: hmac-md5-etm@openssh.com @2902 598s 14:21:32.956156254 O: test integrity: hmac-md5-etm@openssh.com @2903 599s 14:21:33.090998998 O: test integrity: hmac-md5-etm@openssh.com @2904 599s 14:21:33.228451708 O: test integrity: hmac-md5-etm@openssh.com @2905 599s 14:21:33.352653179 O: test integrity: hmac-md5-etm@openssh.com @2906 599s 14:21:33.478957137 O: test integrity: hmac-md5-etm@openssh.com @2907 599s 14:21:33.602169090 O: test integrity: hmac-md5-etm@openssh.com @2908 599s 14:21:33.722271823 O: test integrity: hmac-md5-etm@openssh.com @2909 599s 14:21:33.835143825 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 599s 14:21:33.851197731 O: test integrity: hmac-md5-96-etm@openssh.com @2900 600s 14:21:33.970778158 O: test integrity: hmac-md5-96-etm@openssh.com @2901 600s 14:21:34.090462958 O: test integrity: hmac-md5-96-etm@openssh.com @2902 600s 14:21:34.211380631 O: test integrity: hmac-md5-96-etm@openssh.com @2903 600s 14:21:34.330237473 O: test integrity: hmac-md5-96-etm@openssh.com @2904 600s 14:21:34.458392088 O: test integrity: hmac-md5-96-etm@openssh.com @2905 600s 14:21:34.584051370 O: test integrity: hmac-md5-96-etm@openssh.com @2906 600s 14:21:34.709649798 O: test integrity: hmac-md5-96-etm@openssh.com @2907 600s 14:21:34.847451758 O: test integrity: hmac-md5-96-etm@openssh.com @2908 601s 14:21:34.970672654 O: test integrity: hmac-md5-96-etm@openssh.com @2909 601s 14:21:35.094462882 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 601s 14:21:35.110774649 O: test integrity: umac-64-etm@openssh.com @2900 601s 14:21:35.228992555 O: test integrity: umac-64-etm@openssh.com @2901 601s 14:21:35.371037769 O: test integrity: umac-64-etm@openssh.com @2902 601s 14:21:35.500243225 O: test integrity: umac-64-etm@openssh.com @2903 601s 14:21:35.636528078 O: test integrity: umac-64-etm@openssh.com @2904 601s 14:21:35.783161174 O: test integrity: umac-64-etm@openssh.com @2905 601s 14:21:35.929410473 O: test integrity: umac-64-etm@openssh.com @2906 602s 14:21:36.059844402 O: test integrity: umac-64-etm@openssh.com @2907 602s 14:21:36.182387938 O: test integrity: umac-64-etm@openssh.com @2908 602s 14:21:36.294925078 O: test integrity: umac-64-etm@openssh.com @2909 602s 14:21:36.398251890 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 602s 14:21:36.415971908 O: test integrity: umac-128-etm@openssh.com @2900 602s 14:21:36.537804595 O: test integrity: umac-128-etm@openssh.com @2901 602s 14:21:36.660182521 O: test integrity: umac-128-etm@openssh.com @2902 602s 14:21:36.779761338 O: test integrity: umac-128-etm@openssh.com @2903 602s 14:21:36.898247303 O: test integrity: umac-128-etm@openssh.com @2904 603s 14:21:37.027548294 O: test integrity: umac-128-etm@openssh.com @2905 603s 14:21:37.146067268 O: test integrity: umac-128-etm@openssh.com @2906 603s 14:21:37.255833919 O: test integrity: umac-128-etm@openssh.com @2907 603s 14:21:37.371047196 O: test integrity: umac-128-etm@openssh.com @2908 603s 14:21:37.480651995 O: test integrity: umac-128-etm@openssh.com @2909 603s 14:21:37.582286183 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 603s 14:21:37.598020571 O: test integrity: aes128-gcm@openssh.com @2900 603s 14:21:37.681577885 O: test integrity: aes128-gcm@openssh.com @2901 603s 14:21:37.761398797 O: test integrity: aes128-gcm@openssh.com @2902 603s 14:21:37.842344130 O: test integrity: aes128-gcm@openssh.com @2903 603s 14:21:37.923958094 O: test integrity: aes128-gcm@openssh.com @2904 604s 14:21:38.004587166 O: test integrity: aes128-gcm@openssh.com @2905 604s 14:21:38.083312861 O: test integrity: aes128-gcm@openssh.com @2906 604s 14:21:38.167260483 O: test integrity: aes128-gcm@openssh.com @2907 604s 14:21:38.251579166 O: test integrity: aes128-gcm@openssh.com @2908 604s 14:21:38.334557728 O: test integrity: aes128-gcm@openssh.com @2909 604s 14:21:38.407470021 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 604s 14:21:38.424390213 O: test integrity: aes256-gcm@openssh.com @2900 604s 14:21:38.507369125 O: test integrity: aes256-gcm@openssh.com @2901 604s 14:21:38.594128991 O: test integrity: aes256-gcm@openssh.com @2902 604s 14:21:38.678185456 O: test integrity: aes256-gcm@openssh.com @2903 604s 14:21:38.764618349 O: test integrity: aes256-gcm@openssh.com @2904 604s 14:21:38.853969346 O: test integrity: aes256-gcm@openssh.com @2905 604s 14:21:38.940698074 O: test integrity: aes256-gcm@openssh.com @2906 605s 14:21:39.031856627 O: test integrity: aes256-gcm@openssh.com @2907 605s 14:21:39.137089313 O: test integrity: aes256-gcm@openssh.com @2908 605s 14:21:39.241311842 O: test integrity: aes256-gcm@openssh.com @2909 605s 14:21:39.324859485 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 605s 14:21:39.343438105 O: test integrity: chacha20-poly1305@openssh.com @2900 605s 14:21:39.441094908 O: test integrity: chacha20-poly1305@openssh.com @2901 605s 14:21:39.539475012 O: test integrity: chacha20-poly1305@openssh.com @2902 605s 14:21:39.632841117 O: test integrity: chacha20-poly1305@openssh.com @2903 605s 14:21:39.720614014 O: test integrity: chacha20-poly1305@openssh.com @2904 605s 14:21:39.815626626 O: test integrity: chacha20-poly1305@openssh.com @2905 605s 14:21:39.907069570 O: test integrity: chacha20-poly1305@openssh.com @2906 606s 14:21:39.993482024 O: test integrity: chacha20-poly1305@openssh.com @2907 606s 14:21:40.077958560 O: test integrity: chacha20-poly1305@openssh.com @2908 606s 14:21:40.160307933 O: test integrity: chacha20-poly1305@openssh.com @2909 606s 14:21:40.234162323 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 606s 14:21:40.236374356 O: ok integrity 606s 14:21:40.237567325 E: run test krl.sh ... 606s 14:21:40.399491666 O: key revocation lists: generating test keys 607s 14:21:41.281604518 O: key revocation lists: generating KRLs 607s 14:21:41.369061702 O: key revocation lists: checking revocations for revoked keys 607s 14:21:41.824902754 O: key revocation lists: checking revocations for unrevoked keys 608s 14:21:42.287557861 O: key revocation lists: checking revocations for revoked certs 609s 14:21:43.128271720 O: key revocation lists: checking revocations for unrevoked certs 609s 14:21:43.924149162 O: key revocation lists: testing KRL update 610s 14:21:44.611095615 O: key revocation lists: checking revocations for revoked keys 611s 14:21:45.065196280 O: key revocation lists: checking revocations for unrevoked keys 611s 14:21:45.507727524 O: key revocation lists: checking revocations for revoked certs 612s 14:21:46.185441131 O: key revocation lists: checking revocations for unrevoked certs 613s 14:21:47.043389059 O: ok key revocation lists 613s 14:21:47.044563333 E: run test multipubkey.sh ... 614s 14:21:48.154681658 O: ok multiple pubkey 614s 14:21:48.156385090 E: run test limit-keytype.sh ... 615s 14:21:49.276620166 O: allow rsa,ed25519 615s 14:21:49.562618727 O: allow ed25519 615s 14:21:49.825028631 O: allow cert only 616s 14:21:50.146071818 O: match w/ no match 616s 14:21:50.474520728 O: match w/ matching 616s 14:21:50.775440389 O: ok restrict pubkey type 616s 14:21:50.775930905 E: run test hostkey-agent.sh ... 617s 14:21:51.701668894 O: key type ssh-ed25519 617s 14:21:51.804039443 O: key type sk-ssh-ed25519@openssh.com 617s 14:21:51.910224887 O: key type ecdsa-sha2-nistp256 618s 14:21:52.007975424 O: key type ecdsa-sha2-nistp384 618s 14:21:52.114539654 O: key type ecdsa-sha2-nistp521 618s 14:21:52.207410595 O: key type sk-ecdsa-sha2-nistp256@openssh.com 618s 14:21:52.307725481 O: key type ssh-rsa 618s 14:21:52.419905281 O: cert type ssh-ed25519-cert-v01@openssh.com 618s 14:21:52.540552851 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 618s 14:21:52.663498984 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 618s 14:21:52.783418934 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 618s 14:21:52.913262051 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 619s 14:21:53.033456534 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 619s 14:21:53.153201765 O: cert type ssh-rsa-cert-v01@openssh.com 619s 14:21:53.273234515 O: cert type rsa-sha2-256-cert-v01@openssh.com 619s 14:21:53.415985752 O: cert type rsa-sha2-512-cert-v01@openssh.com 619s 14:21:53.544005091 O: ok hostkey agent 619s 14:21:53.545019255 E: run test hostkey-rotate.sh ... 619s 14:21:53.890241172 O: learn hostkey with StrictHostKeyChecking=no 620s 14:21:54.001987371 O: learn additional hostkeys 620s 14:21:54.157538348 O: learn additional hostkeys, type=ssh-ed25519 620s 14:21:54.271379578 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 620s 14:21:54.387813402 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 620s 14:21:54.504639860 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 620s 14:21:54.624376333 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 620s 14:21:54.740063813 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 620s 14:21:54.867297602 O: learn additional hostkeys, type=ssh-rsa 621s 14:21:54.991419126 O: learn changed non-primary hostkey type=ssh-rsa 621s 14:21:55.557841432 O: learn new primary hostkey 621s 14:21:55.694933134 O: rotate primary hostkey 621s 14:21:55.828848924 O: check rotate primary hostkey 621s 14:21:55.945843333 O: ok hostkey rotate 621s 14:21:55.947058183 E: run test principals-command.sh ... 622s 14:21:56.348461632 O: SKIPPED: /var/run/principals_command_openssh-tests.59399 not executable (/var/run mounted noexec?) 622s 14:21:56.358611813 E: run test cert-file.sh ... 622s 14:21:56.633753482 O: identity cert with no plain public file 622s 14:21:56.758804851 O: CertificateFile with no plain public file 622s 14:21:56.871183556 O: plain keys 623s 14:21:57.013198392 O: untrusted cert 623s 14:21:57.140673797 O: good cert, bad key 623s 14:21:57.275872669 O: single trusted 623s 14:21:57.388729085 O: multiple trusted 623s 14:21:57.926156582 O: ok ssh with certificates 623s 14:21:57.927126964 E: run test cfginclude.sh ... 624s 14:21:58.103313540 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.116179975 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.127188638 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.140138369 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.151076932 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.164355486 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.177184871 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.189023866 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.200661345 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.228090071 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.237565672 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.246665099 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.261303073 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.271139808 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.281325645 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.293098025 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.305091479 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.317048855 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.329049943 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.341479843 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.353662348 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.379907034 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.392127201 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.404187380 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.424542643 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.438198231 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 624s 14:21:58.445779773 O: ok config include 624s 14:21:58.446852166 E: run test servcfginclude.sh ... 625s 14:21:59.024460672 E: run test allow-deny-users.sh ... 625s 14:21:59.026277877 O: ok server config include 626s 14:22:00.225095526 O: ok AllowUsers/DenyUsers 626s 14:22:00.225660653 E: run test authinfo.sh ... 626s 14:22:00.401008665 O: ExposeAuthInfo=no 626s 14:22:00.526486593 O: ExposeAuthInfo=yes 626s 14:22:00.656595903 O: ok authinfo 626s 14:22:00.656460540 E: run test sshsig.sh ... 626s 14:22:00.856642772 O: sshsig: make certificates 626s 14:22:00.895267763 O: sshsig: check signature for ssh-ed25519 627s 14:22:01.336286945 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 627s 14:22:01.750062938 O: sshsig: check signature for ecdsa-sha2-nistp256 628s 14:22:02.107850676 O: sshsig: check signature for ecdsa-sha2-nistp384 628s 14:22:02.571649291 O: sshsig: check signature for ecdsa-sha2-nistp521 629s 14:22:03.006333352 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 629s 14:22:03.387843654 O: sshsig: check signature for ssh-rsa 629s 14:22:03.700706206 O: sshsig: check signature for ssh-ed25519-cert.pub 630s 14:22:04.338346504 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 631s 14:22:05.020489664 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 631s 14:22:05.727247113 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 632s 14:22:06.417316577 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 633s 14:22:07.164308008 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 633s 14:22:07.866506584 O: sshsig: check signature for ssh-rsa-cert.pub 634s 14:22:08.468858344 O: sshsig: match principals 634s 14:22:08.492878249 O: sshsig: nomatch principals 634s 14:22:08.512218093 E: run test knownhosts.sh ... 634s 14:22:08.513200419 O: ok sshsig 635s 14:22:09.555231509 E: run test knownhosts-command.sh ... 635s 14:22:09.555408339 O: ok known hosts 635s 14:22:09.721927311 O: simple connection 635s 14:22:09.861626048 O: no keys 635s 14:22:09.928031513 O: bad exit status 636s 14:22:09.983047744 O: keytype ssh-ed25519 636s 14:22:10.095863426 O: keytype sk-ssh-ed25519@openssh.com 636s 14:22:10.209860725 O: keytype ecdsa-sha2-nistp256 636s 14:22:10.317967972 O: keytype ecdsa-sha2-nistp384 636s 14:22:10.426614185 O: keytype ecdsa-sha2-nistp521 636s 14:22:10.542292923 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 636s 14:22:10.661666023 O: keytype ssh-rsa 636s 14:22:10.783174039 O: ok known hosts command 636s 14:22:10.784289262 E: run test agent-restrict.sh ... 636s 14:22:10.948614214 O: generate keys 637s 14:22:11.017936947 O: prepare client config 637s 14:22:11.035925346 O: prepare known_hosts 637s 14:22:11.042893292 O: prepare server configs 637s 14:22:11.066429092 O: authentication w/o agent 638s 14:22:12.098406662 O: start agent 642s 14:22:16.106546004 O: authentication with agent (no restrict) 643s 14:22:17.123326422 O: unrestricted keylist 643s 14:22:17.708504092 O: authentication with agent (basic restrict) 644s 14:22:18.208935865 O: authentication with agent incorrect key (basic restrict) 644s 14:22:18.463294784 O: keylist (basic restrict) 645s 14:22:19.095703351 O: username 645s 14:22:19.712568132 O: username wildcard 646s 14:22:20.284847216 O: username incorrect 646s 14:22:20.388565354 O: agent restriction honours certificate principal 646s 14:22:20.444084338 O: multihop without agent 647s 14:22:21.046978840 O: multihop agent unrestricted 647s 14:22:21.650933295 O: multihop restricted 648s 14:22:22.368311100 O: multihop username 649s 14:22:23.030167693 O: multihop wildcard username 649s 14:22:23.654727633 O: multihop wrong username 650s 14:22:24.078239033 O: multihop cycle no agent 651s 14:22:25.025294126 O: multihop cycle agent unrestricted 651s 14:22:25.946444539 O: multihop cycle restricted deny 652s 14:22:26.257687879 O: multihop cycle restricted allow 653s 14:22:27.220386700 O: ok agent restrictions 653s 14:22:27.219521955 E: run test hostbased.sh ... 653s 14:22:27.376389580 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 653s 14:22:27.376329718 E: run test channel-timeout.sh ... 653s 14:22:27.545833313 O: no timeout 658s 14:22:32.660589157 O: command timeout 659s 14:22:33.320231544 O: command long timeout 659s 14:22:33.427615064 O: command wildcard timeout 660s 14:22:34.320163332 O: command irrelevant timeout 665s 14:22:39.429269791 O: multiplexed command timeout 676s 14:22:50.350161507 O: irrelevant multiplexed command timeout 691s 14:23:05.485973668 O: global command timeout 713s 14:23:27.366705124 O: sftp no timeout 718s 14:23:32.516215526 O: sftp timeout 719s 14:23:33.318808700 E: Connection closed 719s 14:23:33.321278222 O: sftp irrelevant timeout 724s 14:23:38.448384399 O: ok channel timeout 724s 14:23:38.448824782 E: run test connection-timeout.sh ... 724s 14:23:38.590118742 O: no timeout 729s 14:23:43.700479632 O: timeout 737s 14:23:51.791564321 O: session inhibits timeout 745s 14:23:59.901506021 O: timeout after session 753s 14:24:07.912471292 O: timeout with listeners 762s 14:24:16.029059942 O: ok unused connection timeout 762s 14:24:16.031060978 E: run test match-subsystem.sh ... 763s 14:24:17.319714317 O: ok sshd_config match subsystem 763s 14:24:17.321105465 E: run test agent-pkcs11-restrict.sh ... 763s 14:24:17.472867185 O: SKIPPED: No PKCS#11 library found 763s 14:24:17.474173685 E: run test agent-pkcs11-cert.sh ... 763s 14:24:17.642192921 O: SKIPPED: No PKCS#11 library found 763s 14:24:17.642844978 E: run test penalty.sh ... 763s 14:24:17.841820594 O: test connect 764s 14:24:18.039569358 O: penalty for authentication failure 770s 14:24:24.888308395 O: penalty for no authentication 772s 14:24:26.944384848 E: run test penalty-expire.sh ... 772s 14:24:26.945430721 O: ok penalties 773s 14:24:27.126483223 O: test connect 773s 14:24:27.327085086 O: penalty expiry 786s 14:24:40.769716684 O: ok penalties 786s 14:24:40.771476009 O: set -e ; if test -z "" ; then \ 786s 14:24:40.773034628 O: V="" ; \ 786s 14:24:40.774394067 O: test "x" = "x" || \ 786s 14:24:40.775835559 O: V=/tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 786s 14:24:40.777298390 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 786s 14:24:40.778834348 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 786s 14:24:40.780241856 O: -d /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 786s 14:24:40.781786410 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 786s 14:24:40.783235787 O: -d /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 786s 14:24:40.784701513 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 786s 14:24:40.786187147 O: -d /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 786s 14:24:40.787631323 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 786s 14:24:40.789117298 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 786s 14:24:40.790546376 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 786s 14:24:40.792019717 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 786s 14:24:40.793397340 O: -d /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 786s 14:24:40.794852406 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 786s 14:24:40.796267659 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 786s 14:24:40.797656613 O: if test "x" = "xyes" ; then \ 786s 14:24:40.799067617 O: $V /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 786s 14:24:40.800322660 O: fi \ 786s 14:24:40.801698229 O: fi 793s 14:24:47.136972531 O: test_sshbuf: ...................................................................................................... 103 tests ok 832s 14:25:26.446390311 O: test_sshkey: .................................................................................... 84 tests ok 832s 14:25:26.456629398 O: test_sshsig: ....... 7 tests ok 832s 14:25:26.652357146 O: test_authopt: .................................................................................................................................................. 146 tests ok 842s 14:25:36.153794140 O: test_bitmap: .. 2 tests ok 842s 14:25:36.158730376 O: test_conversion: . 1 tests ok 845s 14:25:39.618589996 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 845s 14:25:39.652112907 O: test_hostkeys: .................. 18 tests ok 845s 14:25:39.655569232 O: test_match: ...... 6 tests ok 845s 14:25:39.660469000 O: test_misc: ........................................... 43 tests ok 845s 14:25:39.662163183 E: run test putty-transfer.sh ... 846s 14:25:40.077359002 O: plink version 0.83 major 0 minor 83 846s 14:25:40.087474960 O: putty transfer data: compression 0 847s 14:25:41.116578348 O: putty transfer data: compression 1 848s 14:25:42.107955185 O: ok putty transfer data 848s 14:25:42.108586954 E: run test putty-ciphers.sh ... 849s 14:25:43.206620702 O: plink version 0.83 major 0 minor 83 849s 14:25:43.671733030 O: putty ciphers: cipher default mac default 849s 14:25:43.823073230 O: putty ciphers: cipher default mac hmac-sha1 849s 14:25:43.948620912 O: putty ciphers: cipher default mac hmac-sha1-96 850s 14:25:44.063166823 O: putty ciphers: cipher default mac hmac-sha2-256 850s 14:25:44.190990335 O: putty ciphers: cipher default mac hmac-sha2-512 850s 14:25:44.313951096 O: putty ciphers: cipher default mac hmac-md5 850s 14:25:44.440089841 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 850s 14:25:44.559003578 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 850s 14:25:44.676103667 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 850s 14:25:44.800504157 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 850s 14:25:44.926833188 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 851s 14:25:45.057437141 O: putty ciphers: cipher 3des-cbc mac default 851s 14:25:45.207218731 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 851s 14:25:45.327969937 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 851s 14:25:45.460005213 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 851s 14:25:45.591334870 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 851s 14:25:45.720849529 O: putty ciphers: cipher 3des-cbc mac hmac-md5 851s 14:25:45.840882816 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 852s 14:25:45.976865170 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 852s 14:25:46.108828341 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 852s 14:25:46.253407888 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 852s 14:25:46.386854829 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 852s 14:25:46.517038536 O: putty ciphers: cipher aes128-cbc mac default 852s 14:25:46.666900817 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 852s 14:25:46.801830625 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 852s 14:25:46.938712170 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 853s 14:25:47.058254799 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 853s 14:25:47.178893966 O: putty ciphers: cipher aes128-cbc mac hmac-md5 853s 14:25:47.298301735 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 853s 14:25:47.410316169 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 853s 14:25:47.521043470 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 853s 14:25:47.633838741 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 853s 14:25:47.747713298 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 853s 14:25:47.853893459 O: putty ciphers: cipher aes192-cbc mac default 854s 14:25:47.970710359 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 854s 14:25:48.108845633 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 854s 14:25:48.222420531 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 854s 14:25:48.343503466 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 854s 14:25:48.467453415 O: putty ciphers: cipher aes192-cbc mac hmac-md5 854s 14:25:48.580686814 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 854s 14:25:48.694046230 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 854s 14:25:48.808827840 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 854s 14:25:48.923076166 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 855s 14:25:49.032832383 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 855s 14:25:49.147498698 O: putty ciphers: cipher aes256-cbc mac default 855s 14:25:49.271072875 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 855s 14:25:49.387504796 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 855s 14:25:49.497922860 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 855s 14:25:49.613575567 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 855s 14:25:49.730171154 O: putty ciphers: cipher aes256-cbc mac hmac-md5 855s 14:25:49.878800282 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 856s 14:25:50.031570291 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 856s 14:25:50.210042579 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 856s 14:25:50.382973631 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 856s 14:25:50.529325216 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 856s 14:25:50.662806049 O: putty ciphers: cipher aes128-ctr mac default 856s 14:25:50.804860602 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 856s 14:25:50.939373632 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 857s 14:25:51.070274050 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 857s 14:25:51.220025863 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 857s 14:25:51.360396220 O: putty ciphers: cipher aes128-ctr mac hmac-md5 857s 14:25:51.504611286 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 857s 14:25:51.638313643 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 857s 14:25:51.766670350 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 857s 14:25:51.898222332 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 858s 14:25:52.007000464 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 858s 14:25:52.130280211 O: putty ciphers: cipher aes192-ctr mac default 858s 14:25:52.259730228 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 858s 14:25:52.394133905 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 858s 14:25:52.524596955 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 858s 14:25:52.684058918 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 858s 14:25:52.846707682 O: putty ciphers: cipher aes192-ctr mac hmac-md5 859s 14:25:52.996916008 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 859s 14:25:53.132962292 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 859s 14:25:53.251142278 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 859s 14:25:53.369863793 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 859s 14:25:53.482611285 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 859s 14:25:53.595892454 O: putty ciphers: cipher aes256-ctr mac default 859s 14:25:53.708915932 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 859s 14:25:53.829038263 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 859s 14:25:53.950068601 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 860s 14:25:54.070873108 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 860s 14:25:54.183160310 O: putty ciphers: cipher aes256-ctr mac hmac-md5 860s 14:25:54.295488179 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 860s 14:25:54.434248921 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 860s 14:25:54.552028479 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 860s 14:25:54.670695743 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 860s 14:25:54.786257660 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 860s 14:25:54.900333844 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 861s 14:25:55.014429944 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 861s 14:25:55.141856533 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 861s 14:25:55.281122549 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 861s 14:25:55.410645493 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 861s 14:25:55.568975333 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 861s 14:25:55.721080259 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 861s 14:25:55.877323634 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 862s 14:25:56.025979520 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 862s 14:25:56.180147039 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 862s 14:25:56.335424929 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 862s 14:25:56.469826603 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 862s 14:25:56.604697922 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 862s 14:25:56.734091395 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 862s 14:25:56.855648796 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 863s 14:25:56.997872023 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 863s 14:25:57.137179726 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 863s 14:25:57.263553049 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 863s 14:25:57.388651382 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 863s 14:25:57.518770719 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 863s 14:25:57.653189915 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 863s 14:25:57.806410094 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 863s 14:25:57.962080408 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 864s 14:25:58.116002848 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 864s 14:25:58.278316565 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 864s 14:25:58.410170171 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 864s 14:25:58.569160964 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 864s 14:25:58.731934592 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 864s 14:25:58.884505979 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 865s 14:25:59.037078969 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 865s 14:25:59.198658036 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 865s 14:25:59.333613823 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 865s 14:25:59.482880841 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 865s 14:25:59.631359848 O: ok putty ciphers 865s 14:25:59.632860349 E: run test putty-kex.sh ... 866s 14:26:00.099365977 O: plink version 0.83 major 0 minor 83 866s 14:26:00.288933621 O: putty KEX: kex diffie-hellman-group1-sha1 866s 14:26:00.378587195 O: putty KEX: kex diffie-hellman-group14-sha1 866s 14:26:00.484326682 O: putty KEX: kex diffie-hellman-group14-sha256 866s 14:26:00.594551133 O: putty KEX: kex diffie-hellman-group16-sha512 866s 14:26:00.798451197 O: putty KEX: kex diffie-hellman-group18-sha512 867s 14:26:01.688104027 O: putty KEX: kex diffie-hellman-group-exchange-sha1 867s 14:26:01.797046715 O: putty KEX: kex diffie-hellman-group-exchange-sha256 868s 14:26:02.107359142 O: putty KEX: kex ecdh-sha2-nistp256 868s 14:26:02.302418970 O: putty KEX: kex ecdh-sha2-nistp384 868s 14:26:02.414293251 O: putty KEX: kex ecdh-sha2-nistp521 868s 14:26:02.513394415 O: putty KEX: kex curve25519-sha256 868s 14:26:02.613128369 O: putty KEX: kex curve25519-sha256@libssh.org 868s 14:26:02.704027107 O: putty KEX: kex sntrup761x25519-sha512 868s 14:26:02.810449299 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 868s 14:26:02.912571211 O: putty KEX: kex mlkem768x25519-sha256 869s 14:26:02.994332719 O: ok putty KEX 869s 14:26:02.995578406 E: run test conch-ciphers.sh ... 869s 14:26:03.184554855 O: conch ciphers: cipher aes256-ctr 869s 14:26:03.897095707 O: conch ciphers: cipher aes256-cbc 870s 14:26:04.532976580 O: conch ciphers: cipher aes192-ctr 871s 14:26:05.132777601 O: conch ciphers: cipher aes192-cbc 871s 14:26:05.719381794 O: conch ciphers: cipher aes128-ctr 872s 14:26:06.309620253 O: conch ciphers: cipher aes128-cbc 872s 14:26:06.897507543 O: conch ciphers: cipher cast128-cbc 873s 14:26:07.475985644 O: conch ciphers: cipher blowfish 874s 14:26:08.065581957 O: conch ciphers: cipher 3des-cbc 874s 14:26:08.680240378 E: run test dropbear-ciphers.sh ... 874s 14:26:08.681335975 O: ok conch ciphers 874s 14:26:08.851872194 O: Create dropbear key type ed25519 874s 14:26:08.886351370 O: Create dropbear key type rsa 875s 14:26:09.133982935 O: Create dropbear key type ecdsa 875s 14:26:09.185847461 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 875s 14:26:09.366007089 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 875s 14:26:09.542545586 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 875s 14:26:09.713525333 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 875s 14:26:09.913010613 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 876s 14:26:10.095132224 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 876s 14:26:10.283896478 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 876s 14:26:10.460615963 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 876s 14:26:10.639673612 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 876s 14:26:10.824222034 O: ok dropbear ciphers 876s 14:26:10.824359400 E: run test dropbear-kex.sh ... 877s 14:26:11.015099773 O: diffie-hellman-group14-sha1 877s 14:26:11.016395703 O: dropbear kex: kex curve25519-sha256 877s 14:26:11.188946063 O: dropbear kex: kex curve25519-sha256@libssh.org 877s 14:26:11.364321658 O: dropbear kex: kex diffie-hellman-group14-sha256 877s 14:26:11.543559704 O: dropbear kex: kex diffie-hellman-group14-sha1 877s 14:26:11.621162317 O: ssh cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data failed 877s 14:26:11.622249126 E: cmp: EOF on /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/copy which is empty 877s 14:26:11.662728316 O: corrupted copy 877s 14:26:11.664614337 E: make: *** [Makefile:277: t-exec-interop] Error 1 877s 14:26:11.665131832 O: failed dropbear kex 877s 14:26:11.666520745 O: make: Leaving directory '/tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress' 877s 14:26:11.667859806 O: ==> /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/failed-regress.log <== 877s 14:26:11.669553538 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.849232.ssh.73816.log 877s 14:26:11.670709738 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.857063.ssh.73823.log 877s 14:26:11.671878480 O: trace: using cached key type ssh-ed25519 877s 14:26:11.672996777 O: trace: using cached key type sk-ssh-ed25519@openssh.com 877s 14:26:11.674115115 O: trace: using cached key type ecdsa-sha2-nistp256 877s 14:26:11.675196444 O: trace: using cached key type ecdsa-sha2-nistp384 877s 14:26:11.676451498 O: trace: using cached key type ecdsa-sha2-nistp521 877s 14:26:11.677591176 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com 877s 14:26:11.678706689 O: trace: using cached key type ssh-rsa 877s 14:26:11.679896811 O: trace: Create dropbear keys and add to authorized_keys 877s 14:26:11.681042430 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.949698.ssh.73869.log 877s 14:26:11.682193399 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.958014.ssh.73875.log 877s 14:26:11.683347563 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.965895.ssh.73880.log 877s 14:26:11.684588240 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.007888.ssh.73901.log 877s 14:26:11.685678886 O: trace: dropbear kex: kex curve25519-sha256 877s 14:26:11.686890990 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.031165.sshd.73915.log 877s 14:26:11.687986745 O: trace: dropbear kex: kex curve25519-sha256@libssh.org 877s 14:26:11.689475365 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.205423.sshd.73947.log 877s 14:26:11.690645329 O: trace: dropbear kex: kex diffie-hellman-group14-sha256 877s 14:26:11.691863053 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.379570.sshd.73979.log 877s 14:26:11.692976582 O: trace: dropbear kex: kex diffie-hellman-group14-sha1 877s 14:26:11.694131048 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.559388.sshd.74011.log 877s 14:26:11.695346828 O: FAIL: ssh cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data failed 877s 14:26:11.696562397 O: Saving debug logs to /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 877s 14:26:11.697601957 O: 877s 14:26:11.698700620 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.849232.ssh.73816.log 877s 14:26:11.699809459 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.857063.ssh.73823.log 877s 14:26:11.700901208 O: trace: using cached key type ssh-ed25519 877s 14:26:11.702065371 O: trace: using cached key type sk-ssh-ed25519@openssh.com 877s 14:26:11.703140429 O: trace: using cached key type ecdsa-sha2-nistp256 877s 14:26:11.704213032 O: trace: using cached key type ecdsa-sha2-nistp384 877s 14:26:11.705276447 O: trace: using cached key type ecdsa-sha2-nistp521 877s 14:26:11.706351063 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com 877s 14:26:11.707472006 O: trace: using cached key type ssh-rsa 877s 14:26:11.708522338 O: trace: Create dropbear keys and add to authorized_keys 877s 14:26:11.709552631 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.949698.ssh.73869.log 877s 14:26:11.710676760 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.958014.ssh.73875.log 877s 14:26:11.711820416 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142610.965895.ssh.73880.log 877s 14:26:11.712955845 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.007888.ssh.73901.log 877s 14:26:11.714028078 O: trace: dropbear kex: kex curve25519-sha256 877s 14:26:11.715227477 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.031165.sshd.73915.log 877s 14:26:11.716458585 O: trace: dropbear kex: kex curve25519-sha256@libssh.org 877s 14:26:11.717643388 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.205423.sshd.73947.log 877s 14:26:11.718817781 O: trace: dropbear kex: kex diffie-hellman-group14-sha256 877s 14:26:11.720065540 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.379570.sshd.73979.log 877s 14:26:11.721194097 O: trace: dropbear kex: kex diffie-hellman-group14-sha1 877s 14:26:11.722385591 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/log/20250505T142611.559388.sshd.74011.log 877s 14:26:11.723443437 O: FAIL: ssh cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data failed 877s 14:26:11.724596099 O: Saving debug logs to /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 877s 14:26:11.725753359 O: FAIL: corrupted copy 877s 14:26:11.727051142 O: Saving debug logs to /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 877s 14:26:11.728130017 O: 877s 14:26:11.729302737 O: ==> /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/failed-ssh.log <== 877s 14:26:11.730383275 O: FAIL: ssh cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data failed 877s 14:26:11.731535856 O: 877s 14:26:11.732690122 O: FAIL: ssh cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data failed 877s 14:26:11.733908696 O: FAIL: corrupted copy 877s 14:26:11.735056600 O: 877s 14:26:11.736176440 O: ==> /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/failed-sshd.log <== 877s 14:26:11.737420193 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy 877s 14:26:11.738603081 O: debug3: send_rexec_state: entering fd = 8 config len 1183 877s 14:26:11.739776512 O: debug3: ssh_msg_send: type 0 len 4942 877s 14:26:11.740930336 O: debug3: ssh_msg_send: done 877s 14:26:11.742066609 O: debug3: send_rexec_state: done 877s 14:26:11.743196087 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9 877s 14:26:11.744336155 O: FAIL: ssh cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data failed 877s 14:26:11.745513213 O: 877s 14:26:11.746662589 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/sshd_proxy 877s 14:26:11.747849405 O: debug3: send_rexec_state: entering fd = 8 config len 1183 877s 14:26:11.748985255 O: debug3: ssh_msg_send: type 0 len 4942 877s 14:26:11.750101941 O: debug3: ssh_msg_send: done 877s 14:26:11.751196095 O: debug3: send_rexec_state: done 877s 14:26:11.752373702 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9 877s 14:26:11.753634648 O: FAIL: ssh cat /tmp/autopkgtest.rl6GA9/autopkgtest_tmp/user/regress/data failed 877s 14:26:11.754767993 O: FAIL: corrupted copy 877s 14:26:11.755935483 O: 877s 14:26:11.757685841 I: Finished with exitcode 2 877s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 878s info: Looking for files to backup/remove ... 878s info: Removing files ... 878s info: Removing crontab ... 878s info: Removing user `openssh-tests' ... 878s autopkgtest [14:26:12]: test regress: -----------------------] 879s regress FAIL non-zero exit status 2 879s autopkgtest [14:26:13]: test regress: - - - - - - - - - - results - - - - - - - - - - 880s autopkgtest [14:26:14]: test ssh-gssapi: preparing testbed 958s autopkgtest [14:27:32]: testbed dpkg architecture: amd64 959s autopkgtest [14:27:33]: testbed apt version: 3.0.0 959s autopkgtest [14:27:33]: @@@@@@@@@@@@@@@@@@@@ test bed setup 959s autopkgtest [14:27:33]: testbed release detected to be: questing 960s autopkgtest [14:27:34]: updating testbed package index (apt update) 961s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 961s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 961s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 961s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 961s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 961s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 961s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 961s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [131 kB] 961s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [92.4 kB] 961s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [966 kB] 961s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [355 kB] 961s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [21.5 kB] 961s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [12.0 kB] 961s Fetched 2719 kB in 1s (2792 kB/s) 962s Reading package lists... 963s autopkgtest [14:27:37]: upgrading testbed (apt dist-upgrade and autopurge) 963s Reading package lists... 963s Building dependency tree... 963s Reading state information... 963s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 963s Starting 2 pkgProblemResolver with broken count: 0 963s Done 964s Entering ResolveByKeep 964s 964s Calculating upgrade... 964s The following packages will be upgraded: 964s groff-base libgpg-error-l10n libgpg-error0 libselinux1 netbase ucf 964s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 964s Need to get 1255 kB of archives. 964s After this operation, 225 kB disk space will be freed. 964s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libselinux1 amd64 3.8.1-1 [89.8 kB] 964s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error-l10n all 1.51-4 [8880 B] 964s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error0 amd64 1.51-4 [76.9 kB] 964s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 netbase all 6.5 [12.9 kB] 964s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB] 964s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 groff-base amd64 1.23.0-8 [1023 kB] 965s Preconfiguring packages ... 965s Fetched 1255 kB in 1s (2325 kB/s) 965s (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 ... 80527 files and directories currently installed.) 965s Preparing to unpack .../libselinux1_3.8.1-1_amd64.deb ... 965s Unpacking libselinux1:amd64 (3.8.1-1) over (3.7-3ubuntu3) ... 965s Setting up libselinux1:amd64 (3.8.1-1) ... 965s (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 ... 80527 files and directories currently installed.) 965s Preparing to unpack .../libgpg-error-l10n_1.51-4_all.deb ... 965s Unpacking libgpg-error-l10n (1.51-4) over (1.51-3) ... 965s Preparing to unpack .../libgpg-error0_1.51-4_amd64.deb ... 965s Unpacking libgpg-error0:amd64 (1.51-4) over (1.51-3) ... 965s Setting up libgpg-error0:amd64 (1.51-4) ... 965s (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 ... 80527 files and directories currently installed.) 965s Preparing to unpack .../archives/netbase_6.5_all.deb ... 965s Unpacking netbase (6.5) over (6.4) ... 965s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 965s Unpacking ucf (3.0051) over (3.0050) ... 965s Preparing to unpack .../groff-base_1.23.0-8_amd64.deb ... 965s Unpacking groff-base (1.23.0-8) over (1.23.0-7) ... 965s Setting up ucf (3.0051) ... 965s Setting up groff-base (1.23.0-8) ... 965s Setting up netbase (6.5) ... 965s Installing new version of config file /etc/ethertypes ... 965s Installing new version of config file /etc/services ... 965s Setting up libgpg-error-l10n (1.51-4) ... 965s Processing triggers for man-db (2.13.1-1) ... 966s Processing triggers for libc-bin (2.41-6ubuntu1) ... 967s Reading package lists... 967s Building dependency tree... 967s Reading state information... 967s Starting pkgProblemResolver with broken count: 0 967s Starting 2 pkgProblemResolver with broken count: 0 967s Done 968s Solving dependencies... 968s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 970s Reading package lists... 971s Building dependency tree... 971s Reading state information... 971s Starting pkgProblemResolver with broken count: 0 971s Starting 2 pkgProblemResolver with broken count: 0 971s Done 971s The following NEW packages will be installed: 971s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 971s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 971s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 971s openssh-server-gssapi 971s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 971s Need to get 799 kB of archives. 971s After this operation, 2807 kB of additional disk space will be used. 971s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 krb5-config all 2.7 [22.0 kB] 971s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libgssrpc4t64 amd64 1.21.3-4ubuntu2 [58.1 kB] 972s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 libkadm5clnt-mit12 amd64 1.21.3-4ubuntu2 [41.3 kB] 972s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 libkdb5-10t64 amd64 1.21.3-4ubuntu2 [42.0 kB] 972s Get:5 http://ftpmaster.internal/ubuntu questing/main amd64 libkadm5srv-mit12 amd64 1.21.3-4ubuntu2 [55.5 kB] 972s Get:6 http://ftpmaster.internal/ubuntu questing/universe amd64 krb5-user amd64 1.21.3-4ubuntu2 [111 kB] 972s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB] 972s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libverto1t64 amd64 0.3.1-1.2ubuntu3 [10.5 kB] 972s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libverto-libevent1t64 amd64 0.3.1-1.2ubuntu3 [6424 B] 972s Get:10 http://ftpmaster.internal/ubuntu questing/universe amd64 krb5-kdc amd64 1.21.3-4ubuntu2 [197 kB] 972s Get:11 http://ftpmaster.internal/ubuntu questing/universe amd64 krb5-admin-server amd64 1.21.3-4ubuntu2 [100 kB] 972s Get:12 http://ftpmaster.internal/ubuntu questing/universe amd64 openssh-client-gssapi all 1:9.9p1-3ubuntu3 [5022 B] 972s Get:13 http://ftpmaster.internal/ubuntu questing/universe amd64 openssh-server-gssapi all 1:9.9p1-3ubuntu3 [5026 B] 972s Preconfiguring packages ... 972s Fetched 799 kB in 1s (1419 kB/s) 972s Selecting previously unselected package krb5-config. 972s (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 ... 80527 files and directories currently installed.) 972s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 972s Unpacking krb5-config (2.7) ... 972s Selecting previously unselected package libgssrpc4t64:amd64. 972s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_amd64.deb ... 972s Unpacking libgssrpc4t64:amd64 (1.21.3-4ubuntu2) ... 972s Selecting previously unselected package libkadm5clnt-mit12:amd64. 972s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_amd64.deb ... 972s Unpacking libkadm5clnt-mit12:amd64 (1.21.3-4ubuntu2) ... 972s Selecting previously unselected package libkdb5-10t64:amd64. 972s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_amd64.deb ... 972s Unpacking libkdb5-10t64:amd64 (1.21.3-4ubuntu2) ... 972s Selecting previously unselected package libkadm5srv-mit12:amd64. 972s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_amd64.deb ... 972s Unpacking libkadm5srv-mit12:amd64 (1.21.3-4ubuntu2) ... 972s Selecting previously unselected package krb5-user. 972s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_amd64.deb ... 972s Unpacking krb5-user (1.21.3-4ubuntu2) ... 972s Selecting previously unselected package libevent-2.1-7t64:amd64. 972s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ... 972s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 972s Selecting previously unselected package libverto1t64:amd64. 972s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_amd64.deb ... 972s Unpacking libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 972s Selecting previously unselected package libverto-libevent1t64:amd64. 972s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_amd64.deb ... 972s Unpacking libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 972s Selecting previously unselected package krb5-kdc. 972s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_amd64.deb ... 972s Unpacking krb5-kdc (1.21.3-4ubuntu2) ... 972s Selecting previously unselected package krb5-admin-server. 972s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_amd64.deb ... 972s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ... 972s Selecting previously unselected package openssh-client-gssapi. 972s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 972s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 972s Selecting previously unselected package openssh-server-gssapi. 972s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 972s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 972s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 972s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 972s Setting up libgssrpc4t64:amd64 (1.21.3-4ubuntu2) ... 972s Setting up krb5-config (2.7) ... 973s Setting up libkadm5clnt-mit12:amd64 (1.21.3-4ubuntu2) ... 973s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 973s Setting up libkdb5-10t64:amd64 (1.21.3-4ubuntu2) ... 973s Setting up libkadm5srv-mit12:amd64 (1.21.3-4ubuntu2) ... 973s Setting up krb5-user (1.21.3-4ubuntu2) ... 973s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 973s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 973s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 973s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 973s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 973s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 973s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 973s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 973s Setting up libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 973s Setting up libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 973s Setting up krb5-kdc (1.21.3-4ubuntu2) ... 973s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 973s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 974s Setting up krb5-admin-server (1.21.3-4ubuntu2) ... 974s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 974s Processing triggers for libc-bin (2.41-6ubuntu1) ... 974s Processing triggers for man-db (2.13.1-1) ... 980s autopkgtest [14:27:54]: test ssh-gssapi: [----------------------- 981s Generating public/private ed25519 key pair. 981s Your identification has been saved in /root/.ssh/id_ed25519 981s Your public key has been saved in /root/.ssh/id_ed25519.pub 981s The key fingerprint is: 981s SHA256:VgzSOqmtOfaDKZKTQJ4f0r/4Aky2UAddRJbUP7wq3A8 root@autopkgtest 981s The key's randomart image is: 981s +--[ED25519 256]--+ 981s | .o =B+. | 981s | . o...oo | 981s | . . o oo | 981s |.+ + .+ | 981s |B + o .S o | 981s |.B o. .. . | 981s |.o+ o* .E. | 981s |= .oO.+ o. | 981s | o +o=oo .. | 981s +----[SHA256]-----+ 981s ## Setting up test environment 981s ## Creating Kerberos realm EXAMPLE.FAKE 981s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 981s master key name 'K/M@EXAMPLE.FAKE' 981s ## Creating principals 981s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 981s Principal "testuser2082@EXAMPLE.FAKE" created. 981s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 981s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 981s ## Extracting service principal host/sshd-gssapi.example.fake 981s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 981s 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. 981s 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. 981s ## Adjusting /etc/krb5.conf 981s ## TESTS 981s 981s ## TEST test_gssapi_login 981s ## Configuring sshd for gssapi-with-mic authentication 981s ## Restarting ssh 981s ## Obtaining TGT 981s Password for testuser2082@EXAMPLE.FAKE: 981s Ticket cache: FILE:/tmp/krb5cc_0 981s Default principal: testuser2082@EXAMPLE.FAKE 981s 981s Valid starting Expires Service principal 981s 05/05/25 14:27:55 05/06/25 00:27:55 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 981s renew until 05/06/25 14:27:55 981s 981s ## ssh'ing into localhost using gssapi-with-mic auth 981s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 981s Mon May 5 14:27:55 UTC 2025 981s 981s ## checking that we got a service ticket for ssh (host/) 981s 05/05/25 14:27:55 05/06/25 00:27:55 host/sshd-gssapi.example.fake@ 981s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 981s 981s ## Checking ssh logs to confirm gssapi-with-mic auth was used 981s May 05 14:27:55 sshd-gssapi.example.fake sshd-session[2185]: Accepted gssapi-with-mic for testuser2082 from 127.0.0.1 port 44412 ssh2: testuser2082@EXAMPLE.FAKE 981s ## PASS test_gssapi_login 981s 981s ## TEST test_gssapi_keyex_login 981s ## Configuring sshd for gssapi-keyex authentication 981s ## Restarting ssh 981s ## Obtaining TGT 981s Password for testuser2082@EXAMPLE.FAKE: 981s Ticket cache: FILE:/tmp/krb5cc_0 981s Default principal: testuser2082@EXAMPLE.FAKE 981s 981s Valid starting Expires Service principal 981s 05/05/25 14:27:55 05/06/25 00:27:55 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 981s renew until 05/06/25 14:27:55 981s 981s ## ssh'ing into localhost using gssapi-keyex auth 981s Mon May 5 14:27:55 UTC 2025 982s 982s ## checking that we got a service ticket for ssh (host/) 982s 05/05/25 14:27:55 05/06/25 00:27:55 host/sshd-gssapi.example.fake@ 982s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 982s 982s ## Checking ssh logs to confirm gssapi-keyex auth was used 982s May 05 14:27:55 sshd-gssapi.example.fake sshd-session[2240]: Accepted gssapi-keyex for testuser2082 from 127.0.0.1 port 44420 ssh2: testuser2082@EXAMPLE.FAKE 982s ## PASS test_gssapi_keyex_login 982s 982s ## TEST test_gssapi_keyex_pubkey_fallback 982s ## Configuring sshd for gssapi-keyex authentication 982s ## Restarting ssh 982s ## Obtaining TGT 982s Password for testuser2082@EXAMPLE.FAKE: 982s Ticket cache: FILE:/tmp/krb5cc_0 982s Default principal: testuser2082@EXAMPLE.FAKE 982s 982s Valid starting Expires Service principal 982s 05/05/25 14:27:56 05/06/25 00:27:56 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 982s renew until 05/06/25 14:27:56 982s 982s ## ssh'ing into localhost using gssapi-keyex auth 982s Mon May 5 14:27:56 UTC 2025 982s 982s ## checking that we got a service ticket for ssh (host/) 982s 05/05/25 14:27:56 05/06/25 00:27:56 host/sshd-gssapi.example.fake@ 982s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 982s 982s ## Checking ssh logs to confirm publickey auth was used 982s May 05 14:27:56 sshd-gssapi.example.fake sshd-session[2281]: Accepted publickey for testuser2082-2 from 127.0.0.1 port 44430 ssh2: ED25519 SHA256:VgzSOqmtOfaDKZKTQJ4f0r/4Aky2UAddRJbUP7wq3A8 982s ## PASS test_gssapi_keyex_pubkey_fallback 982s 982s ## ALL TESTS PASSED 982s ## Cleaning up 982s autopkgtest [14:27:56]: test ssh-gssapi: -----------------------] 983s autopkgtest [14:27:57]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 983s ssh-gssapi PASS 983s autopkgtest [14:27:57]: test socket-activation: preparing testbed 1055s autopkgtest [14:29:09]: testbed dpkg architecture: amd64 1055s autopkgtest [14:29:09]: testbed apt version: 3.0.0 1056s autopkgtest [14:29:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1056s autopkgtest [14:29:10]: testbed release detected to be: questing 1057s autopkgtest [14:29:11]: updating testbed package index (apt update) 1057s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1057s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1057s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1057s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1058s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 1058s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 1058s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 1058s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [92.4 kB] 1058s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [131 kB] 1058s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [966 kB] 1058s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [355 kB] 1058s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [12.0 kB] 1058s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [21.5 kB] 1058s Fetched 2719 kB in 1s (2600 kB/s) 1059s Reading package lists... 1060s autopkgtest [14:29:14]: upgrading testbed (apt dist-upgrade and autopurge) 1060s Reading package lists... 1060s Building dependency tree... 1060s Reading state information... 1060s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1060s Starting 2 pkgProblemResolver with broken count: 0 1060s Done 1061s Entering ResolveByKeep 1061s 1062s Calculating upgrade... 1062s The following packages will be upgraded: 1062s groff-base libgpg-error-l10n libgpg-error0 libselinux1 netbase ucf 1062s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1062s Need to get 1255 kB of archives. 1062s After this operation, 225 kB disk space will be freed. 1062s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libselinux1 amd64 3.8.1-1 [89.8 kB] 1062s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error-l10n all 1.51-4 [8880 B] 1062s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error0 amd64 1.51-4 [76.9 kB] 1062s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 netbase all 6.5 [12.9 kB] 1062s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB] 1062s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 groff-base amd64 1.23.0-8 [1023 kB] 1063s Preconfiguring packages ... 1063s Fetched 1255 kB in 1s (1990 kB/s) 1063s (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 ... 80527 files and directories currently installed.) 1063s Preparing to unpack .../libselinux1_3.8.1-1_amd64.deb ... 1063s Unpacking libselinux1:amd64 (3.8.1-1) over (3.7-3ubuntu3) ... 1063s Setting up libselinux1:amd64 (3.8.1-1) ... 1063s (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 ... 80527 files and directories currently installed.) 1063s Preparing to unpack .../libgpg-error-l10n_1.51-4_all.deb ... 1063s Unpacking libgpg-error-l10n (1.51-4) over (1.51-3) ... 1063s Preparing to unpack .../libgpg-error0_1.51-4_amd64.deb ... 1064s Unpacking libgpg-error0:amd64 (1.51-4) over (1.51-3) ... 1064s Setting up libgpg-error0:amd64 (1.51-4) ... 1064s (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 ... 80527 files and directories currently installed.) 1064s Preparing to unpack .../archives/netbase_6.5_all.deb ... 1064s Unpacking netbase (6.5) over (6.4) ... 1064s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 1064s Unpacking ucf (3.0051) over (3.0050) ... 1064s Preparing to unpack .../groff-base_1.23.0-8_amd64.deb ... 1064s Unpacking groff-base (1.23.0-8) over (1.23.0-7) ... 1064s Setting up ucf (3.0051) ... 1064s Setting up groff-base (1.23.0-8) ... 1064s Setting up netbase (6.5) ... 1064s Installing new version of config file /etc/ethertypes ... 1064s Installing new version of config file /etc/services ... 1064s Setting up libgpg-error-l10n (1.51-4) ... 1064s Processing triggers for man-db (2.13.1-1) ... 1065s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1065s Reading package lists... 1065s Building dependency tree... 1065s Reading state information... 1066s Starting pkgProblemResolver with broken count: 0 1066s Starting 2 pkgProblemResolver with broken count: 0 1066s Done 1066s Solving dependencies... 1067s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1070s Reading package lists... 1070s Building dependency tree... 1070s Reading state information... 1070s Starting pkgProblemResolver with broken count: 0 1070s Starting 2 pkgProblemResolver with broken count: 0 1070s Done 1071s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1073s autopkgtest [14:29:27]: test socket-activation: [----------------------- 1074s Generating public/private ed25519 key pair. 1074s Your identification has been saved in /home/testuser1667/.ssh/id_ed25519 1074s Your public key has been saved in /home/testuser1667/.ssh/id_ed25519.pub 1074s The key fingerprint is: 1074s SHA256:hy3sz3ZDhtvuNsGbvz41YzSzGstngXe3PqloELDGAnY testuser1667@autopkgtest 1074s The key's randomart image is: 1074s +--[ED25519 256]--+ 1074s | | 1074s | o E . | 1074s | . o . o | 1074s | . = + + | 1074s | o S +o o +| 1074s | . +. =o B+| 1074s | . .=.+*.O| 1074s | oo.X+.* | 1074s | .+*o*O+.| 1074s +----[SHA256]-----+ 1074s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1074s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1075s Disabling 'ssh.service', but its triggering units are still active: 1075s ssh.socket 1075s Stopping 'ssh.service', but its triggering units are still active: 1075s ssh.socket 1075s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1075s Mon May 5 14:29:29 UTC 2025 1076s autopkgtest [14:29:30]: test socket-activation: -----------------------] 1076s socket-activation PASS 1076s autopkgtest [14:29:30]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1077s autopkgtest [14:29:31]: test xinetd: preparing testbed 1077s Reading package lists... 1077s Building dependency tree... 1077s Reading state information... 1077s Starting pkgProblemResolver with broken count: 0 1077s Starting 2 pkgProblemResolver with broken count: 0 1077s Done 1078s The following NEW packages will be installed: 1078s xinetd 1078s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1078s Need to get 128 kB of archives. 1078s After this operation, 345 kB of additional disk space will be used. 1078s Get:1 http://ftpmaster.internal/ubuntu questing/universe amd64 xinetd amd64 1:2.3.15.4-5 [128 kB] 1078s Fetched 128 kB in 0s (353 kB/s) 1079s Selecting previously unselected package xinetd. 1079s (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 ... 80527 files and directories currently installed.) 1079s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_amd64.deb ... 1079s Unpacking xinetd (1:2.3.15.4-5) ... 1079s Setting up xinetd (1:2.3.15.4-5) ... 1079s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1079s Processing triggers for man-db (2.13.1-1) ... 1081s autopkgtest [14:29:35]: test xinetd: [----------------------- 1081s Generating public/private ed25519 key pair. 1081s Your identification has been saved in /home/testuser2195/.ssh/id_ed25519 1081s Your public key has been saved in /home/testuser2195/.ssh/id_ed25519.pub 1081s The key fingerprint is: 1081s SHA256:V5fZWoR/W1aRTngrO9YswADldLZE48iJxSS/Ergy3FI testuser2195@autopkgtest 1081s The key's randomart image is: 1081s +--[ED25519 256]--+ 1081s | o==.* .o+| 1081s | . OoB o..O.| 1081s | E o *oo. B.=| 1081s | . o . . .+ o ==| 1081s | = o .S.. . *.+| 1081s | + .. = + | 1081s | . o | 1081s | | 1081s | | 1081s +----[SHA256]-----+ 1082s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1082s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1082s Disabling 'ssh.service', but its triggering units are still active: 1082s ssh.socket 1082s Stopping 'ssh.service', but its triggering units are still active: 1082s ssh.socket 1082s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1083s Mon May 5 14:29:37 UTC 2025 1083s autopkgtest [14:29:37]: test xinetd: -----------------------] 1084s xinetd PASS 1084s autopkgtest [14:29:38]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1084s autopkgtest [14:29:38]: test systemd-socket-activation: preparing testbed 1153s autopkgtest [14:30:47]: testbed dpkg architecture: amd64 1153s autopkgtest [14:30:47]: testbed apt version: 3.0.0 1154s autopkgtest [14:30:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1154s autopkgtest [14:30:48]: testbed release detected to be: questing 1155s autopkgtest [14:30:49]: updating testbed package index (apt update) 1155s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1155s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1156s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1156s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1156s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 1156s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 1156s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 1156s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [131 kB] 1156s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [92.4 kB] 1156s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [355 kB] 1156s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [966 kB] 1156s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [12.0 kB] 1156s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [21.5 kB] 1156s Fetched 2719 kB in 1s (2877 kB/s) 1157s Reading package lists... 1157s autopkgtest [14:30:51]: upgrading testbed (apt dist-upgrade and autopurge) 1158s Reading package lists... 1158s Building dependency tree... 1158s Reading state information... 1158s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1158s Starting 2 pkgProblemResolver with broken count: 0 1158s Done 1159s Entering ResolveByKeep 1159s 1159s Calculating upgrade... 1159s The following packages will be upgraded: 1159s groff-base libgpg-error-l10n libgpg-error0 libselinux1 netbase ucf 1159s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1159s Need to get 1255 kB of archives. 1159s After this operation, 225 kB disk space will be freed. 1159s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libselinux1 amd64 3.8.1-1 [89.8 kB] 1159s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error-l10n all 1.51-4 [8880 B] 1159s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 libgpg-error0 amd64 1.51-4 [76.9 kB] 1159s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 netbase all 6.5 [12.9 kB] 1159s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB] 1159s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 groff-base amd64 1.23.0-8 [1023 kB] 1160s Preconfiguring packages ... 1160s Fetched 1255 kB in 1s (2320 kB/s) 1160s (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 ... 80527 files and directories currently installed.) 1160s Preparing to unpack .../libselinux1_3.8.1-1_amd64.deb ... 1160s Unpacking libselinux1:amd64 (3.8.1-1) over (3.7-3ubuntu3) ... 1160s Setting up libselinux1:amd64 (3.8.1-1) ... 1160s (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 ... 80527 files and directories currently installed.) 1160s Preparing to unpack .../libgpg-error-l10n_1.51-4_all.deb ... 1160s Unpacking libgpg-error-l10n (1.51-4) over (1.51-3) ... 1160s Preparing to unpack .../libgpg-error0_1.51-4_amd64.deb ... 1160s Unpacking libgpg-error0:amd64 (1.51-4) over (1.51-3) ... 1160s Setting up libgpg-error0:amd64 (1.51-4) ... 1160s (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 ... 80527 files and directories currently installed.) 1160s Preparing to unpack .../archives/netbase_6.5_all.deb ... 1160s Unpacking netbase (6.5) over (6.4) ... 1160s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 1160s Unpacking ucf (3.0051) over (3.0050) ... 1160s Preparing to unpack .../groff-base_1.23.0-8_amd64.deb ... 1160s Unpacking groff-base (1.23.0-8) over (1.23.0-7) ... 1160s Setting up ucf (3.0051) ... 1160s Setting up groff-base (1.23.0-8) ... 1160s Setting up netbase (6.5) ... 1160s Installing new version of config file /etc/ethertypes ... 1160s Installing new version of config file /etc/services ... 1160s Setting up libgpg-error-l10n (1.51-4) ... 1160s Processing triggers for man-db (2.13.1-1) ... 1161s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1161s Reading package lists... 1162s Building dependency tree... 1162s Reading state information... 1162s Starting pkgProblemResolver with broken count: 0 1162s Starting 2 pkgProblemResolver with broken count: 0 1162s Done 1162s Solving dependencies... 1162s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1165s Reading package lists... 1165s Building dependency tree... 1165s Reading state information... 1165s Starting pkgProblemResolver with broken count: 0 1166s Starting 2 pkgProblemResolver with broken count: 0 1166s Done 1166s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1169s autopkgtest [14:31:03]: test systemd-socket-activation: [----------------------- 1169s Stopping ssh.service... 1169s Checking that ssh.socket is active and listening... 1169s Checking that ssh.service is inactive/dead... 1169s Checking that a connection attempt activates ssh.service... 1169s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1169s Checking that sshd can be re-executed... 1170s Checking sshd can run in debug mode... 1170s debug1: PAM: establishing credentials 1170s debug1: permanently_set_uid: 0/0 1170s debug3: Copy environment: XDG_SESSION_ID=7 1170s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1170s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1170s debug3: Copy environment: XDG_SESSION_TYPE=tty 1170s debug3: Copy environment: XDG_SESSION_CLASS=user 1170s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1170s debug3: Copy environment: http_proxy=http://squid.internal:3128/ 1170s debug3: Copy environment: https_proxy=http://squid.internal:3128/ 1170s debug3: Copy environment: no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net 1170s debug3: Copy environment: TERM=linux 1170s debug3: Copy environment: LANG=C.UTF-8 1170s Environment: 1170s LANG=C.UTF-8 1170s USER=root 1170s LOGNAME=root 1170s HOME=/root 1170s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1170s SHELL=/bin/bash 1170s XDG_SESSION_ID=7 1170s XDG_RUNTIME_DIR=/run/user/0 1170s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1170s XDG_SESSION_TYPE=tty 1170s XDG_SESSION_CLASS=user 1170s http_proxy=http://squid.internal:3128/ 1170s https_proxy=http://squid.internal:3128/ 1170s no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchp 1170s TERM=linux 1170s SSH_CLIENT=::1 60434 22 1170s SSH_CONNECTION=::1 60434 ::1 22 1170s Done. 1170s autopkgtest [14:31:04]: test systemd-socket-activation: -----------------------] 1171s autopkgtest [14:31:05]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1171s systemd-socket-activation PASS 1171s autopkgtest [14:31:05]: test sshd-socket-generator: preparing testbed 1171s Reading package lists... 1171s Building dependency tree... 1171s Reading state information... 1171s Starting pkgProblemResolver with broken count: 0 1172s Starting 2 pkgProblemResolver with broken count: 0 1172s Done 1172s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1173s autopkgtest [14:31:07]: test sshd-socket-generator: [----------------------- 1173s test_default...PASS 1173s test_custom_port...PASS 1173s test_default_and_custom_port...PASS 1173s test_mutiple_custom_ports...PASS 1173s test_custom_listenaddress...PASS 1173s test_custom_listenaddress_and_port...PASS 1173s test_custom_ipv6_listenaddress...PASS 1173s test_custom_family_ipv4...PASS 1173s test_custom_family_ipv6...PASS 1173s test_custom_port_and_family_ipv4...PASS 1173s test_custom_port_and_family_ipv6...PASS 1173s test_match_on_port...PASS 1173s autopkgtest [14:31:07]: test sshd-socket-generator: -----------------------] 1174s autopkgtest [14:31:08]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1174s sshd-socket-generator PASS 1174s autopkgtest [14:31:08]: @@@@@@@@@@@@@@@@@@@@ summary 1174s regress FAIL non-zero exit status 2 1174s ssh-gssapi PASS 1174s socket-activation PASS 1174s xinetd PASS 1174s systemd-socket-activation PASS 1174s sshd-socket-generator PASS 1179s nova [W] Skipping flock for amd64 1179s Creating nova instance adt-questing-amd64-openssh-20250505-141134-juju-7f2275-prod-proposed-migration-environment-2-080cc25d-1899-4bbc-aa48-20e8e3156f79 from image adt/ubuntu-questing-amd64-server-20250505.img (UUID 1da2e1b3-5892-44f7-bdef-fb8deb9e2fcb)... 1179s nova [W] Timed out waiting for 8d066fb6-d16f-447d-8bf1-0500742dd336 to get deleted. 1179s nova [W] Skipping flock for amd64 1179s Creating nova instance adt-questing-amd64-openssh-20250505-141134-juju-7f2275-prod-proposed-migration-environment-2-080cc25d-1899-4bbc-aa48-20e8e3156f79 from image adt/ubuntu-questing-amd64-server-20250505.img (UUID 1da2e1b3-5892-44f7-bdef-fb8deb9e2fcb)... 1179s nova [W] Timed out waiting for 2cf66b42-6c32-41fe-9ce4-de6c294a5838 to get deleted. 1179s nova [W] Skipping flock for amd64 1179s Creating nova instance adt-questing-amd64-openssh-20250505-141134-juju-7f2275-prod-proposed-migration-environment-2-080cc25d-1899-4bbc-aa48-20e8e3156f79 from image adt/ubuntu-questing-amd64-server-20250505.img (UUID 1da2e1b3-5892-44f7-bdef-fb8deb9e2fcb)... 1179s nova [W] Timed out waiting for 0034941b-691a-4008-a508-6dde5c699387 to get deleted. 1179s nova [W] Skipping flock for amd64 1179s Creating nova instance adt-questing-amd64-openssh-20250505-141134-juju-7f2275-prod-proposed-migration-environment-2-080cc25d-1899-4bbc-aa48-20e8e3156f79 from image adt/ubuntu-questing-amd64-server-20250505.img (UUID 1da2e1b3-5892-44f7-bdef-fb8deb9e2fcb)... 1179s nova [W] Timed out waiting for 45963da2-c017-4217-b63d-76bcd3f7960a to get deleted.