0s autopkgtest [15:35:47]: starting date and time: 2025-05-06 15:35:47+0000
  0s autopkgtest [15:35:47]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production'
  0s autopkgtest [15:35:47]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3pb36inj/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-17.secgroup --name adt-questing-amd64-openssh-20250506-153547-juju-7f2275-prod-proposed-migration-environment-2-e245af80-4795-4f4e-95ca-c77e839ad412 --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/
 49s autopkgtest [15:36:36]: testbed dpkg architecture: amd64
 49s autopkgtest [15:36:36]: testbed apt version: 3.0.0
 49s autopkgtest [15:36:36]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 49s autopkgtest [15:36:36]: testbed release detected to be: None
 50s autopkgtest [15:36:37]: updating testbed package index (apt update)
 50s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB]
 51s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease
 51s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease
 51s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease
 51s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [605 kB]
 51s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [27.3 kB]
 51s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [71.6 kB]
 51s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [104 kB]
 51s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [148 kB]
 51s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [670 kB]
 52s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [264 kB]
 52s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [5028 B]
 52s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [18.4 kB]
 52s Fetched 2023 kB in 1s (1483 kB/s)
 53s Reading package lists...
 53s autopkgtest [15:36:40]: upgrading testbed (apt dist-upgrade and autopurge)
 53s Reading package lists...
 54s Building dependency tree...
 54s Reading state information...
 54s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
 54s Starting 2 pkgProblemResolver with broken count: 0
 54s Done
 55s Entering ResolveByKeep
 55s 
 55s Calculating upgrade...
 55s The following packages will be upgraded:
 55s   libdebuginfod-common libdebuginfod1t64 libdw1t64 libelf1t64 libperl5.40
 55s   lto-disabled-list perl perl-base perl-modules-5.40 python3-dbus ucf
 55s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 55s Need to get 10.7 MB of archives.
 55s After this operation, 23.6 kB of additional disk space will be used.
 55s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libperl5.40 amd64 5.40.1-3 [4877 kB]
 57s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 perl amd64 5.40.1-3 [262 kB]
 57s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 perl-base amd64 5.40.1-3 [1840 kB]
 57s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 perl-modules-5.40 all 5.40.1-3 [3217 kB]
 57s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB]
 57s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod-common all 0.193-1 [15.6 kB]
 57s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod1t64 amd64 0.193-1 [21.3 kB]
 57s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libdw1t64 amd64 0.193-1 [283 kB]
 57s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libelf1t64 amd64 0.193-1 [58.6 kB]
 57s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 python3-dbus amd64 1.4.0-1 [102 kB]
 57s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 lto-disabled-list all 58 [12.4 kB]
 58s Preconfiguring packages ...
 58s Fetched 10.7 MB in 3s (4281 kB/s)
 58s (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.)
 58s Preparing to unpack .../libperl5.40_5.40.1-3_amd64.deb ...
 58s Unpacking libperl5.40:amd64 (5.40.1-3) over (5.40.1-2) ...
 58s Preparing to unpack .../perl_5.40.1-3_amd64.deb ...
 58s Unpacking perl (5.40.1-3) over (5.40.1-2) ...
 59s Preparing to unpack .../perl-base_5.40.1-3_amd64.deb ...
 59s Unpacking perl-base (5.40.1-3) over (5.40.1-2) ...
 59s Setting up perl-base (5.40.1-3) ...
 59s (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 80527 files and directories currently installed.)
 59s Preparing to unpack .../0-perl-modules-5.40_5.40.1-3_all.deb ...
 59s Unpacking perl-modules-5.40 (5.40.1-3) over (5.40.1-2) ...
 59s Preparing to unpack .../1-ucf_3.0051_all.deb ...
 59s Unpacking ucf (3.0051) over (3.0050) ...
 59s Preparing to unpack .../2-libdebuginfod-common_0.193-1_all.deb ...
 59s Unpacking libdebuginfod-common (0.193-1) over (0.192-4ubuntu1) ...
 59s Preparing to unpack .../3-libdebuginfod1t64_0.193-1_amd64.deb ...
 59s Unpacking libdebuginfod1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
 59s Preparing to unpack .../4-libdw1t64_0.193-1_amd64.deb ...
 59s Unpacking libdw1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
 59s Preparing to unpack .../5-libelf1t64_0.193-1_amd64.deb ...
 59s Unpacking libelf1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
 59s Preparing to unpack .../6-python3-dbus_1.4.0-1_amd64.deb ...
 59s Unpacking python3-dbus (1.4.0-1) over (1.3.2-5build5) ...
 59s Preparing to unpack .../7-lto-disabled-list_58_all.deb ...
 59s Unpacking lto-disabled-list (58) over (57) ...
 59s Setting up python3-dbus (1.4.0-1) ...
 60s Setting up lto-disabled-list (58) ...
 60s Setting up libelf1t64:amd64 (0.193-1) ...
 60s Setting up libdw1t64:amd64 (0.193-1) ...
 60s Setting up ucf (3.0051) ...
 60s Setting up perl-modules-5.40 (5.40.1-3) ...
 60s Setting up libdebuginfod-common (0.193-1) ...
 60s Setting up libperl5.40:amd64 (5.40.1-3) ...
 60s Setting up perl (5.40.1-3) ...
 60s Setting up libdebuginfod1t64:amd64 (0.193-1) ...
 60s Processing triggers for man-db (2.13.1-1) ...
 60s Processing triggers for libc-bin (2.41-6ubuntu1) ...
 61s Reading package lists...
 61s Building dependency tree...
 61s Reading state information...
 62s Starting pkgProblemResolver with broken count: 0
 62s Starting 2 pkgProblemResolver with broken count: 0
 62s Done
 62s Solving dependencies...
 62s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 65s autopkgtest [15:36:52]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr  6 15:05:05 UTC 2025
 65s autopkgtest [15:36:52]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh
 71s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (dsc) [3488 B]
 71s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (tar) [1965 kB]
 71s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (asc) [833 B]
 71s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (diff) [212 kB]
 71s gpgv: Signature made Fri Apr 11 11:58:21 2025 UTC
 71s gpgv:                using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93
 71s gpgv: Can't check signature: No public key
 71s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.1.dsc: no acceptable signature found
 71s autopkgtest [15:36:58]: testing package openssh version 1:9.9p1-3ubuntu3.1
 73s autopkgtest [15:37:00]: build not needed
 83s autopkgtest [15:37:10]: test regress: preparing testbed
 83s Reading package lists...
 84s Building dependency tree...
 84s Reading state information...
 84s Starting pkgProblemResolver with broken count: 0
 84s Starting 2 pkgProblemResolver with broken count: 0
 84s Done
 84s The following NEW packages will be installed:
 84s   devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl
 84s   libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl
 84s   libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl
 84s   libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl
 84s   libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl
 84s   libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl
 84s   libhttp-message-perl libhttp-negotiate-perl libimport-into-perl
 84s   libio-html-perl libio-socket-ssl-perl liblwp-mediatypes-perl
 84s   liblwp-protocol-https-perl libmodule-runtime-perl libmoo-perl
 84s   libnet-http-perl libnet-ssleay-perl libparams-classify-perl
 84s   librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1
 84s   libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl
 84s   openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat
 84s   python3-constantly python3-hamcrest python3-hyperlink python3-incremental
 84s   python3-pyasn1 python3-pyasn1-modules python3-service-identity
 84s   python3-twisted python3-zope.interface wdiff
 84s 0 upgraded, 58 newly installed, 0 to remove and 0 not upgraded.
 84s Need to get 8330 kB of archives.
 84s After this operation, 38.6 MB of additional disk space will be used.
 84s Get:1 http://ftpmaster.internal/ubuntu questing/universe amd64 libtommath1 amd64 1.3.0-1 [67.2 kB]
 85s Get:2 http://ftpmaster.internal/ubuntu questing/universe amd64 libtomcrypt1 amd64 1.18.2+dfsg-7build1 [384 kB]
 85s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 dropbear-bin amd64 2025.87-1 [178 kB]
 85s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 dropbear all 2025.87-1 [8668 B]
 85s Get:5 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-dirlist-perl all 0.05-3 [7286 B]
 85s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-which-perl all 1.27-2 [12.5 kB]
 85s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-homedir-perl all 1.006-2 [37.0 kB]
 85s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-touch-perl all 0.12-2 [7498 B]
 85s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libclass-method-modifiers-perl all 2.15-1 [16.1 kB]
 85s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 libclass-xsaccessor-perl amd64 1.19-4build6 [33.8 kB]
 85s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 libb-hooks-op-check-perl amd64 0.22-3build2 [9624 B]
 85s Get:12 http://ftpmaster.internal/ubuntu questing/main amd64 libdynaloader-functions-perl all 0.004-2 [11.5 kB]
 85s Get:13 http://ftpmaster.internal/ubuntu questing/main amd64 libdevel-callchecker-perl amd64 0.009-2 [14.2 kB]
 85s Get:14 http://ftpmaster.internal/ubuntu questing/main amd64 libparams-classify-perl amd64 0.015-2build6 [20.1 kB]
 85s Get:15 http://ftpmaster.internal/ubuntu questing/main amd64 libmodule-runtime-perl all 0.018-1 [15.2 kB]
 85s Get:16 http://ftpmaster.internal/ubuntu questing/main amd64 libimport-into-perl all 1.002005-2 [10.7 kB]
 85s Get:17 http://ftpmaster.internal/ubuntu questing/main amd64 librole-tiny-perl all 2.002004-1 [16.3 kB]
 85s Get:18 http://ftpmaster.internal/ubuntu questing/main amd64 libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB]
 85s Get:19 http://ftpmaster.internal/ubuntu questing/main amd64 libmoo-perl all 2.005005-1 [47.4 kB]
 85s Get:20 http://ftpmaster.internal/ubuntu questing/main amd64 libencode-locale-perl all 1.05-3 [11.6 kB]
 85s Get:21 http://ftpmaster.internal/ubuntu questing/main amd64 libtimedate-perl all 2.3300-2 [34.0 kB]
 85s Get:22 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-date-perl all 6.06-1 [10.2 kB]
 85s Get:23 http://ftpmaster.internal/ubuntu questing/main amd64 libfile-listing-perl all 6.16-1 [11.3 kB]
 85s Get:24 http://ftpmaster.internal/ubuntu questing/main amd64 libhtml-tagset-perl all 3.24-1 [14.1 kB]
 85s Get:25 http://ftpmaster.internal/ubuntu questing/main amd64 liburi-perl all 5.30-1 [94.4 kB]
 85s Get:26 http://ftpmaster.internal/ubuntu questing/main amd64 libhtml-parser-perl amd64 3.83-1build1 [86.2 kB]
 85s Get:27 http://ftpmaster.internal/ubuntu questing/main amd64 libhtml-tree-perl all 5.07-3 [200 kB]
 85s Get:28 http://ftpmaster.internal/ubuntu questing/main amd64 libclone-perl amd64 0.47-1 [10.7 kB]
 85s Get:29 http://ftpmaster.internal/ubuntu questing/main amd64 libio-html-perl all 1.004-3 [15.9 kB]
 85s Get:30 http://ftpmaster.internal/ubuntu questing/main amd64 liblwp-mediatypes-perl all 6.04-2 [20.1 kB]
 85s Get:31 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB]
 85s Get:32 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-cookies-perl all 6.11-1 [18.2 kB]
 85s Get:33 http://ftpmaster.internal/ubuntu questing/main amd64 libhttp-negotiate-perl all 6.01-2 [12.4 kB]
 85s Get:34 http://ftpmaster.internal/ubuntu questing/main amd64 perl-openssl-defaults amd64 7build3 [6626 B]
 85s Get:35 http://ftpmaster.internal/ubuntu questing/main amd64 libnet-ssleay-perl amd64 1.94-3 [318 kB]
 85s Get:36 http://ftpmaster.internal/ubuntu questing/main amd64 libio-socket-ssl-perl all 2.089-1 [200 kB]
 85s Get:37 http://ftpmaster.internal/ubuntu questing/main amd64 libnet-http-perl all 6.23-1 [22.3 kB]
 85s Get:38 http://ftpmaster.internal/ubuntu questing/main amd64 liblwp-protocol-https-perl all 6.14-1 [9040 B]
 85s Get:39 http://ftpmaster.internal/ubuntu questing/main amd64 libtry-tiny-perl all 0.32-1 [21.2 kB]
 85s Get:40 http://ftpmaster.internal/ubuntu questing/main amd64 libwww-robotrules-perl all 6.02-1 [12.6 kB]
 85s Get:41 http://ftpmaster.internal/ubuntu questing/main amd64 libwww-perl all 6.78-1 [139 kB]
 85s Get:42 http://ftpmaster.internal/ubuntu questing/main amd64 patchutils amd64 0.4.2-1build3 [77.0 kB]
 85s Get:43 http://ftpmaster.internal/ubuntu questing/main amd64 wdiff amd64 1.2.2-9 [29.5 kB]
 85s Get:44 http://ftpmaster.internal/ubuntu questing/main amd64 devscripts all 2.25.11 [1066 kB]
 85s Get:45 http://ftpmaster.internal/ubuntu questing/universe amd64 libhavege2 amd64 1.9.19-11ubuntu1 [28.0 kB]
 85s Get:46 http://ftpmaster.internal/ubuntu questing/universe amd64 putty-tools amd64 0.83-2 [801 kB]
 86s Get:47 http://ftpmaster.internal/ubuntu questing/main amd64 python3-hamcrest all 2.1.0-1 [28.1 kB]
 86s Get:48 http://ftpmaster.internal/ubuntu questing/main amd64 python3-pyasn1 all 0.6.1-1 [56.4 kB]
 86s Get:49 http://ftpmaster.internal/ubuntu questing/main amd64 python3-pyasn1-modules all 0.4.1-2 [80.3 kB]
 86s Get:50 http://ftpmaster.internal/ubuntu questing/main amd64 python3-service-identity all 24.2.0-1 [11.0 kB]
 86s Get:51 http://ftpmaster.internal/ubuntu questing/main amd64 python3-automat all 25.4.16-1 [35.7 kB]
 86s Get:52 http://ftpmaster.internal/ubuntu questing/main amd64 python3-constantly all 23.10.4-2 [13.9 kB]
 86s Get:53 http://ftpmaster.internal/ubuntu questing/main amd64 python3-hyperlink all 21.0.0-6 [68.0 kB]
 86s Get:54 http://ftpmaster.internal/ubuntu questing/main amd64 python3-incremental all 24.7.2-3 [18.6 kB]
 86s Get:55 http://ftpmaster.internal/ubuntu questing/main amd64 python3-zope.interface amd64 7.2-1build1 [142 kB]
 86s Get:56 http://ftpmaster.internal/ubuntu questing/main amd64 python3-twisted all 24.11.0-1 [2066 kB]
 86s Get:57 http://ftpmaster.internal/ubuntu questing/universe amd64 openssh-tests amd64 1:9.9p1-3ubuntu3.1 [1514 kB]
 86s Get:58 http://ftpmaster.internal/ubuntu questing/universe amd64 haveged amd64 1.9.19-11ubuntu1 [32.9 kB]
 86s Fetched 8330 kB in 2s (4792 kB/s)
 86s Selecting previously unselected package libtommath1:amd64.
 86s (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.)
 86s Preparing to unpack .../00-libtommath1_1.3.0-1_amd64.deb ...
 86s Unpacking libtommath1:amd64 (1.3.0-1) ...
 86s Selecting previously unselected package libtomcrypt1:amd64.
 86s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_amd64.deb ...
 86s Unpacking libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ...
 86s Selecting previously unselected package dropbear-bin.
 86s Preparing to unpack .../02-dropbear-bin_2025.87-1_amd64.deb ...
 86s Unpacking dropbear-bin (2025.87-1) ...
 87s Selecting previously unselected package dropbear.
 87s Preparing to unpack .../03-dropbear_2025.87-1_all.deb ...
 87s Unpacking dropbear (2025.87-1) ...
 87s Selecting previously unselected package libfile-dirlist-perl.
 87s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ...
 87s Unpacking libfile-dirlist-perl (0.05-3) ...
 87s Selecting previously unselected package libfile-which-perl.
 87s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ...
 87s Unpacking libfile-which-perl (1.27-2) ...
 87s Selecting previously unselected package libfile-homedir-perl.
 87s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ...
 87s Unpacking libfile-homedir-perl (1.006-2) ...
 87s Selecting previously unselected package libfile-touch-perl.
 87s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ...
 87s Unpacking libfile-touch-perl (0.12-2) ...
 87s Selecting previously unselected package libclass-method-modifiers-perl.
 87s Preparing to unpack .../08-libclass-method-modifiers-perl_2.15-1_all.deb ...
 87s Unpacking libclass-method-modifiers-perl (2.15-1) ...
 87s Selecting previously unselected package libclass-xsaccessor-perl.
 87s Preparing to unpack .../09-libclass-xsaccessor-perl_1.19-4build6_amd64.deb ...
 87s Unpacking libclass-xsaccessor-perl (1.19-4build6) ...
 87s Selecting previously unselected package libb-hooks-op-check-perl:amd64.
 87s Preparing to unpack .../10-libb-hooks-op-check-perl_0.22-3build2_amd64.deb ...
 87s Unpacking libb-hooks-op-check-perl:amd64 (0.22-3build2) ...
 87s Selecting previously unselected package libdynaloader-functions-perl.
 87s Preparing to unpack .../11-libdynaloader-functions-perl_0.004-2_all.deb ...
 87s Unpacking libdynaloader-functions-perl (0.004-2) ...
 87s Selecting previously unselected package libdevel-callchecker-perl:amd64.
 87s Preparing to unpack .../12-libdevel-callchecker-perl_0.009-2_amd64.deb ...
 87s Unpacking libdevel-callchecker-perl:amd64 (0.009-2) ...
 87s Selecting previously unselected package libparams-classify-perl:amd64.
 87s Preparing to unpack .../13-libparams-classify-perl_0.015-2build6_amd64.deb ...
 87s Unpacking libparams-classify-perl:amd64 (0.015-2build6) ...
 87s Selecting previously unselected package libmodule-runtime-perl.
 87s Preparing to unpack .../14-libmodule-runtime-perl_0.018-1_all.deb ...
 87s Unpacking libmodule-runtime-perl (0.018-1) ...
 87s Selecting previously unselected package libimport-into-perl.
 87s Preparing to unpack .../15-libimport-into-perl_1.002005-2_all.deb ...
 87s Unpacking libimport-into-perl (1.002005-2) ...
 87s Selecting previously unselected package librole-tiny-perl.
 87s Preparing to unpack .../16-librole-tiny-perl_2.002004-1_all.deb ...
 87s Unpacking librole-tiny-perl (2.002004-1) ...
 87s Selecting previously unselected package libsub-quote-perl.
 87s Preparing to unpack .../17-libsub-quote-perl_2.006008-1ubuntu1_all.deb ...
 87s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ...
 87s Selecting previously unselected package libmoo-perl.
 87s Preparing to unpack .../18-libmoo-perl_2.005005-1_all.deb ...
 87s Unpacking libmoo-perl (2.005005-1) ...
 87s Selecting previously unselected package libencode-locale-perl.
 87s Preparing to unpack .../19-libencode-locale-perl_1.05-3_all.deb ...
 87s Unpacking libencode-locale-perl (1.05-3) ...
 87s Selecting previously unselected package libtimedate-perl.
 87s Preparing to unpack .../20-libtimedate-perl_2.3300-2_all.deb ...
 87s Unpacking libtimedate-perl (2.3300-2) ...
 87s Selecting previously unselected package libhttp-date-perl.
 87s Preparing to unpack .../21-libhttp-date-perl_6.06-1_all.deb ...
 87s Unpacking libhttp-date-perl (6.06-1) ...
 87s Selecting previously unselected package libfile-listing-perl.
 87s Preparing to unpack .../22-libfile-listing-perl_6.16-1_all.deb ...
 87s Unpacking libfile-listing-perl (6.16-1) ...
 87s Selecting previously unselected package libhtml-tagset-perl.
 87s Preparing to unpack .../23-libhtml-tagset-perl_3.24-1_all.deb ...
 87s Unpacking libhtml-tagset-perl (3.24-1) ...
 87s Selecting previously unselected package liburi-perl.
 87s Preparing to unpack .../24-liburi-perl_5.30-1_all.deb ...
 87s Unpacking liburi-perl (5.30-1) ...
 87s Selecting previously unselected package libhtml-parser-perl:amd64.
 87s Preparing to unpack .../25-libhtml-parser-perl_3.83-1build1_amd64.deb ...
 87s Unpacking libhtml-parser-perl:amd64 (3.83-1build1) ...
 87s Selecting previously unselected package libhtml-tree-perl.
 87s Preparing to unpack .../26-libhtml-tree-perl_5.07-3_all.deb ...
 87s Unpacking libhtml-tree-perl (5.07-3) ...
 87s Selecting previously unselected package libclone-perl:amd64.
 87s Preparing to unpack .../27-libclone-perl_0.47-1_amd64.deb ...
 87s Unpacking libclone-perl:amd64 (0.47-1) ...
 87s Selecting previously unselected package libio-html-perl.
 87s Preparing to unpack .../28-libio-html-perl_1.004-3_all.deb ...
 87s Unpacking libio-html-perl (1.004-3) ...
 87s Selecting previously unselected package liblwp-mediatypes-perl.
 87s Preparing to unpack .../29-liblwp-mediatypes-perl_6.04-2_all.deb ...
 87s Unpacking liblwp-mediatypes-perl (6.04-2) ...
 87s Selecting previously unselected package libhttp-message-perl.
 87s Preparing to unpack .../30-libhttp-message-perl_7.00-2ubuntu1_all.deb ...
 87s Unpacking libhttp-message-perl (7.00-2ubuntu1) ...
 87s Selecting previously unselected package libhttp-cookies-perl.
 87s Preparing to unpack .../31-libhttp-cookies-perl_6.11-1_all.deb ...
 87s Unpacking libhttp-cookies-perl (6.11-1) ...
 87s Selecting previously unselected package libhttp-negotiate-perl.
 87s Preparing to unpack .../32-libhttp-negotiate-perl_6.01-2_all.deb ...
 87s Unpacking libhttp-negotiate-perl (6.01-2) ...
 87s Selecting previously unselected package perl-openssl-defaults:amd64.
 87s Preparing to unpack .../33-perl-openssl-defaults_7build3_amd64.deb ...
 87s Unpacking perl-openssl-defaults:amd64 (7build3) ...
 87s Selecting previously unselected package libnet-ssleay-perl:amd64.
 87s Preparing to unpack .../34-libnet-ssleay-perl_1.94-3_amd64.deb ...
 87s Unpacking libnet-ssleay-perl:amd64 (1.94-3) ...
 87s Selecting previously unselected package libio-socket-ssl-perl.
 87s Preparing to unpack .../35-libio-socket-ssl-perl_2.089-1_all.deb ...
 87s Unpacking libio-socket-ssl-perl (2.089-1) ...
 87s Selecting previously unselected package libnet-http-perl.
 87s Preparing to unpack .../36-libnet-http-perl_6.23-1_all.deb ...
 87s Unpacking libnet-http-perl (6.23-1) ...
 87s Selecting previously unselected package liblwp-protocol-https-perl.
 87s Preparing to unpack .../37-liblwp-protocol-https-perl_6.14-1_all.deb ...
 87s Unpacking liblwp-protocol-https-perl (6.14-1) ...
 87s Selecting previously unselected package libtry-tiny-perl.
 87s Preparing to unpack .../38-libtry-tiny-perl_0.32-1_all.deb ...
 87s Unpacking libtry-tiny-perl (0.32-1) ...
 87s Selecting previously unselected package libwww-robotrules-perl.
 87s Preparing to unpack .../39-libwww-robotrules-perl_6.02-1_all.deb ...
 87s Unpacking libwww-robotrules-perl (6.02-1) ...
 87s Selecting previously unselected package libwww-perl.
 87s Preparing to unpack .../40-libwww-perl_6.78-1_all.deb ...
 87s Unpacking libwww-perl (6.78-1) ...
 88s Selecting previously unselected package patchutils.
 88s Preparing to unpack .../41-patchutils_0.4.2-1build3_amd64.deb ...
 88s Unpacking patchutils (0.4.2-1build3) ...
 88s Selecting previously unselected package wdiff.
 88s Preparing to unpack .../42-wdiff_1.2.2-9_amd64.deb ...
 88s Unpacking wdiff (1.2.2-9) ...
 88s Selecting previously unselected package devscripts.
 88s Preparing to unpack .../43-devscripts_2.25.11_all.deb ...
 88s Unpacking devscripts (2.25.11) ...
 88s Selecting previously unselected package libhavege2:amd64.
 88s Preparing to unpack .../44-libhavege2_1.9.19-11ubuntu1_amd64.deb ...
 88s Unpacking libhavege2:amd64 (1.9.19-11ubuntu1) ...
 88s Selecting previously unselected package putty-tools.
 88s Preparing to unpack .../45-putty-tools_0.83-2_amd64.deb ...
 88s Unpacking putty-tools (0.83-2) ...
 88s Selecting previously unselected package python3-hamcrest.
 88s Preparing to unpack .../46-python3-hamcrest_2.1.0-1_all.deb ...
 88s Unpacking python3-hamcrest (2.1.0-1) ...
 88s Selecting previously unselected package python3-pyasn1.
 88s Preparing to unpack .../47-python3-pyasn1_0.6.1-1_all.deb ...
 88s Unpacking python3-pyasn1 (0.6.1-1) ...
 88s Selecting previously unselected package python3-pyasn1-modules.
 88s Preparing to unpack .../48-python3-pyasn1-modules_0.4.1-2_all.deb ...
 88s Unpacking python3-pyasn1-modules (0.4.1-2) ...
 88s Selecting previously unselected package python3-service-identity.
 88s Preparing to unpack .../49-python3-service-identity_24.2.0-1_all.deb ...
 88s Unpacking python3-service-identity (24.2.0-1) ...
 88s Selecting previously unselected package python3-automat.
 88s Preparing to unpack .../50-python3-automat_25.4.16-1_all.deb ...
 88s Unpacking python3-automat (25.4.16-1) ...
 88s Selecting previously unselected package python3-constantly.
 88s Preparing to unpack .../51-python3-constantly_23.10.4-2_all.deb ...
 88s Unpacking python3-constantly (23.10.4-2) ...
 88s Selecting previously unselected package python3-hyperlink.
 88s Preparing to unpack .../52-python3-hyperlink_21.0.0-6_all.deb ...
 88s Unpacking python3-hyperlink (21.0.0-6) ...
 88s Selecting previously unselected package python3-incremental.
 88s Preparing to unpack .../53-python3-incremental_24.7.2-3_all.deb ...
 88s Unpacking python3-incremental (24.7.2-3) ...
 88s Selecting previously unselected package python3-zope.interface.
 88s Preparing to unpack .../54-python3-zope.interface_7.2-1build1_amd64.deb ...
 88s Unpacking python3-zope.interface (7.2-1build1) ...
 88s Selecting previously unselected package python3-twisted.
 88s Preparing to unpack .../55-python3-twisted_24.11.0-1_all.deb ...
 88s Unpacking python3-twisted (24.11.0-1) ...
 88s Selecting previously unselected package openssh-tests.
 88s Preparing to unpack .../56-openssh-tests_1%3a9.9p1-3ubuntu3.1_amd64.deb ...
 88s Unpacking openssh-tests (1:9.9p1-3ubuntu3.1) ...
 88s Selecting previously unselected package haveged.
 88s Preparing to unpack .../57-haveged_1.9.19-11ubuntu1_amd64.deb ...
 88s Unpacking haveged (1.9.19-11ubuntu1) ...
 88s Setting up wdiff (1.2.2-9) ...
 88s Setting up libfile-which-perl (1.27-2) ...
 88s Setting up libdynaloader-functions-perl (0.004-2) ...
 88s Setting up libclass-method-modifiers-perl (2.15-1) ...
 88s Setting up python3-zope.interface (7.2-1build1) ...
 89s Setting up libclone-perl:amd64 (0.47-1) ...
 89s Setting up libtommath1:amd64 (1.3.0-1) ...
 89s Setting up libhtml-tagset-perl (3.24-1) ...
 89s Setting up python3-automat (25.4.16-1) ...
 89s Setting up liblwp-mediatypes-perl (6.04-2) ...
 89s Setting up libtry-tiny-perl (0.32-1) ...
 89s Setting up perl-openssl-defaults:amd64 (7build3) ...
 89s Setting up libencode-locale-perl (1.05-3) ...
 89s Setting up python3-hamcrest (2.1.0-1) ...
 89s Setting up putty-tools (0.83-2) ...
 89s Setting up libhavege2:amd64 (1.9.19-11ubuntu1) ...
 89s Setting up patchutils (0.4.2-1build3) ...
 89s Setting up python3-incremental (24.7.2-3) ...
 89s Setting up python3-hyperlink (21.0.0-6) ...
 89s Setting up libio-html-perl (1.004-3) ...
 89s Setting up libb-hooks-op-check-perl:amd64 (0.22-3build2) ...
 89s Setting up libtimedate-perl (2.3300-2) ...
 89s Setting up librole-tiny-perl (2.002004-1) ...
 89s Setting up python3-pyasn1 (0.6.1-1) ...
 89s Setting up python3-constantly (23.10.4-2) ...
 89s Setting up libsub-quote-perl (2.006008-1ubuntu1) ...
 89s Setting up libclass-xsaccessor-perl (1.19-4build6) ...
 89s Setting up libfile-dirlist-perl (0.05-3) ...
 89s Setting up libfile-homedir-perl (1.006-2) ...
 89s Setting up liburi-perl (5.30-1) ...
 89s Setting up libfile-touch-perl (0.12-2) ...
 89s Setting up libnet-ssleay-perl:amd64 (1.94-3) ...
 89s Setting up libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ...
 89s Setting up libhttp-date-perl (6.06-1) ...
 89s Setting up haveged (1.9.19-11ubuntu1) ...
 89s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'.

 90s Setting up dropbear-bin (2025.87-1) ...
 90s Setting up libfile-listing-perl (6.16-1) ...
 90s Setting up libnet-http-perl (6.23-1) ...
 90s Setting up libdevel-callchecker-perl:amd64 (0.009-2) ...
 90s Setting up dropbear (2025.87-1) ...
 90s Converting existing OpenSSH RSA host key to Dropbear format.
 90s Key is a ssh-rsa key
 90s Wrote key to '/etc/dropbear/dropbear_rsa_host_key'
 90s 3072 SHA256:V4by+S5xWnuSgkUaseSY04WhFlIiOUa/Lz1N9oHW2mA /etc/dropbear/dropbear_rsa_host_key (RSA)
 90s +---[RSA 3072]----+
 90s | .o.o.o .o       |
 90s |  +o o o+ ..     |
 90s | . .. o*.+. o    |
 90s |     o+ ==.+     |
 90s |    .  .E+*      |
 90s |     o *.*ooo    |
 90s |    . + ooo=.o   |
 90s |     . .. +.+ .  |
 90s |           o.o   |
 90s +----[SHA256]-----+
 90s Converting existing OpenSSH ECDSA host key to Dropbear format.
 90s Key is a ecdsa-sha2-nistp256 key
 90s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key'
 90s 256 SHA256:3xxHcqSA8+UKkwhb0r0iDW3r0dMSrdwTnYYqyKif/1Q /etc/dropbear/dropbear_ecdsa_host_key (ECDSA)
 90s +---[ECDSA 256]---+
 90s |     o . o.o ..  |
 90s |    + = = +.=o   |
 90s |   o X = @ =o o  |
 90s |  . = B % = .+   |
 90s | .   o +E= o. .  |
 90s |.     .. ..o o   |
 90s | . .  .   . o    |
 90s |  o  .           |
 90s |   ....          |
 90s +----[SHA256]-----+
 90s Converting existing OpenSSH ED25519 host key to Dropbear format.
 90s Key is a ssh-ed25519 key
 90s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key'
 90s 256 SHA256:gvnyw59PJ5+eSdKGAmIpiMJmIxhJpsDFBbG26hyWFto /etc/dropbear/dropbear_ed25519_host_key (ED25519)
 90s +--[ED25519 256]--+
 90s |ooo++.           |
 90s |=o ..            |
 90s |+  o             |
 90s |+o. .+           |
 90s |**..* o S        |
 90s |*.=o o o   o     |
 90s |.*E ... . = =    |
 90s |= .  oo  + B +   |
 90s | o    .oo...*    |
 90s +----[SHA256]-----+
 90s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'.

 90s Setting up python3-pyasn1-modules (0.4.1-2) ...
 91s Setting up python3-service-identity (24.2.0-1) ...
 91s Setting up libwww-robotrules-perl (6.02-1) ...
 91s Setting up libhtml-parser-perl:amd64 (3.83-1build1) ...
 91s Setting up libio-socket-ssl-perl (2.089-1) ...
 91s Setting up libhttp-message-perl (7.00-2ubuntu1) ...
 91s Setting up libhttp-negotiate-perl (6.01-2) ...
 91s Setting up libhttp-cookies-perl (6.11-1) ...
 91s Setting up libhtml-tree-perl (5.07-3) ...
 91s Setting up libparams-classify-perl:amd64 (0.015-2build6) ...
 91s Setting up libmodule-runtime-perl (0.018-1) ...
 91s Setting up python3-twisted (24.11.0-1) ...
 93s Setting up libimport-into-perl (1.002005-2) ...
 93s Setting up libmoo-perl (2.005005-1) ...
 93s Setting up openssh-tests (1:9.9p1-3ubuntu3.1) ...
 93s Setting up liblwp-protocol-https-perl (6.14-1) ...
 93s Setting up libwww-perl (6.78-1) ...
 93s Setting up devscripts (2.25.11) ...
 93s Processing triggers for install-info (7.1.1-1) ...
 93s Processing triggers for libc-bin (2.41-6ubuntu1) ...
 93s Processing triggers for man-db (2.13.1-1) ...
 96s autopkgtest [15:37:23]: test regress: [-----------------------
 96s info: Adding user `openssh-tests' ...
 96s info: Selecting UID/GID from range 1000 to 59999 ...
 96s info: Adding new group `openssh-tests' (1001) ...
 96s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ...
 96s info: Creating home directory `/home/openssh-tests' ...
 96s info: Copying files from `/etc/skel' ...
 96s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ...
 96s info: Adding user `openssh-tests' to group `users' ...
 96s I: annotate-output 2.25.11
 96s I: prefix='%H:%M:%S.%N '
 96s 15:37:23.737598042 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.68bocB/autopkgtest_tmp/user
 96s 15:37:23.771183543 O: make: Entering directory '/tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress'
 96s 15:37:23.772652600 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/valgrind-out
 96s 15:37:23.774031861 E: + /usr/bin/ssh -Q key
 96s 15:37:23.774903252 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \
 96s 15:37:23.775377109 E: + grep -q ^ssh-rsa
 96s 15:37:23.776512899 O: 	ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \
 96s 15:37:23.778935519 O: 	tr '\n' '\r' </tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \
 96s 15:37:23.780187505 O: 	ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \
 96s 15:37:23.781344765 O: 	awk '{print $0 "\r"}' /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \
 96s 15:37:23.782481377 O: 	ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \
 96s 15:37:23.783961494 E: + ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2.prv
 96s 15:37:23.784607379 O: fi
 96s 15:37:23.785915968 E: + diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv
 96s 15:37:23.790709484 E: + tr \n \r
 96s 15:37:23.793105395 E: + ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv
 96s 15:37:23.794336893 E: + diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv
 96s 15:37:23.796501225 E: + awk {print $0 "\r"} /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2.prv
 96s 15:37:23.799616576 E: + ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv
 96s 15:37:23.800836361 E: + diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv
 96s 15:37:23.803976319 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \
 96s 15:37:23.805257959 E: + + grep -q ^ssh-rsa
 96s 15:37:23.806466073 E: /usr/bin/ssh -Q key
 96s 15:37:23.807261163 O: 	cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t2.out ; \
 96s 15:37:23.808524260 O: 	chmod 600 /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t2.out ; \
 96s 15:37:23.809344316 E: + cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.prv
 96s 15:37:23.810994027 E: + chmod 600 /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t2.out
 96s 15:37:23.811426827 O: 	ssh-keygen -yf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \
 96s 15:37:23.814397331 E: + ssh-keygen -yf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t2.out
 96s 15:37:23.813448065 O: fi
 96s 15:37:23.815534404 E: + diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub
 96s 15:37:23.817197078 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \
 96s 15:37:23.819118422 E: + /usr/bin/ssh -Q key
 96s 15:37:23.820967431 O: 	ssh-keygen -ef /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t3.out ; \
 96s 15:37:23.821360198 E: + grep -q ^ssh-rsa
 96s 15:37:23.822769945 O: 	ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \
 96s 15:37:23.824443420 O: fi
 96s 15:37:23.824706637 E: + ssh-keygen -ef /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub
 96s 15:37:23.826615627 E: + ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t3.out
 96s 15:37:23.828833750 E: + diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub
 96s 15:37:23.830637294 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \
 96s 15:37:23.832521960 O: 	ssh-keygen -E md5 -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub |\
 96s 15:37:23.832620993 E: + /usr/bin/ssh -Q+  key
 96s 15:37:23.833981249 O: 		awk '{print $2}' | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t4.ok ; \
 96s 15:37:23.835925625 O: fi
 96s 15:37:23.835058030 E: grep -q ^ssh-rsa
 96s 15:37:23.837690098 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub
 96s 15:37:23.839938196 E: + awk {print $2}
 96s 15:37:23.841024855 E: + diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t4.ok
 96s 15:37:23.843737181 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \
 96s 15:37:23.844627979 E: + /usr/bin/ssh -Q key
 96s 15:37:23.846882077 O: 	ssh-keygen -Bf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub |\
 96s 15:37:23.847845458 E: + grep -q ^ssh-rsa
 96s 15:37:23.848201397 O: 		awk '{print $2}' | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t5.ok ; \
 96s 15:37:23.849315517 O: fi
 96s 15:37:23.850600935 E: + ssh-keygen -Bf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub
 96s 15:37:23.851937637 E: + awk {print $2}
 96s 15:37:23.853122457 E: + diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t5.ok
 96s 15:37:23.857539747 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \
 96s 15:37:23.858618622 E: + /usr/bin/ssh -Q key
 96s 15:37:23.860101183 O: 	ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t6.out1 ; \
 96s 15:37:23.861123334 E: + grep -q ^ssh-dss
 96s 15:37:23.862445760 O: 	ssh-keygen -if /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t6.out2 ; \
 96s 15:37:23.863574197 O: 	chmod 600 /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t6.out1 ; \
 96s 15:37:23.865650397 O: 	ssh-keygen -yf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t6.out2 ; \
 96s 15:37:23.865593372 E: + /usr/bin/ssh -Q key
 96s 15:37:23.866718321 O: fi
 96s 15:37:23.868859131 E: + grep -q ^ssh-dss
 96s 15:37:23.869191526 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \
 96s 15:37:23.870317267 O: 	ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t7.out ; \
 96s 15:37:23.871308921 O: fi
 96s 15:37:23.873246715 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \
 96s 15:37:23.874218402 E: + /usr/bin/ssh -Q key
 96s 15:37:23.875300433 O: 	ssh-keygen -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \
 96s 15:37:23.876379259 E: + grep -q ^ssh-dss
 96s 15:37:23.877479954 O: 	ssh-keygen -Bf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \
 96s 15:37:23.878732871 O: fi
 96s 15:37:23.882643423 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \
 96s 15:37:23.883121587 E: + ssh -Q key
 96s 15:37:23.884152975 O: 	ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t8.out ; \
 96s 15:37:23.885230387 E: + grep -q ^ssh-dss
 96s 15:37:23.886522746 O: fi
 96s 15:37:23.888469237 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \
 96s 15:37:23.889403054 E: + ssh -Q key
 96s 15:37:23.890573809 O: 	ssh-keygen -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \
 96s 15:37:23.891637827 E: + grep -q ^ssh-dss
 96s 15:37:23.892976572 O: 	ssh-keygen -Bf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \
 96s 15:37:23.894090322 O: fi
 96s 15:37:23.895182982 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \
 96s 15:37:23.896251699 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t9.out
 96s 15:37:23.904632478 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \
 96s 15:37:23.905890074 O: ssh-keygen -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t9.out > /dev/null
 96s 15:37:23.914112421 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \
 96s 15:37:23.915311438 O: ssh-keygen -Bf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t9.out > /dev/null
 96s 15:37:23.923640351 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t10.out
 96s 15:37:23.927636090 O: ssh-keygen -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t10.out > /dev/null
 96s 15:37:23.931814117 O: ssh-keygen -Bf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t10.out > /dev/null
 96s 15:37:23.936589529 E: + /usr/bin/ssh -Q key
 96s 15:37:23.937928615 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \
 96s 15:37:23.938815896 E: + grep -q ^ssh-dss
 96s 15:37:23.939296495 O: 	ssh-keygen -E sha256 -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/rsa_openssh.pub |\
 96s 15:37:23.940395006 O: 		awk '{print $2}' | diff - /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t11.ok ; \
 96s 15:37:23.941503697 O: fi
 96s 15:37:23.942642162 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t12.out
 96s 15:37:23.945978933 O: ssh-keygen -lf /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null
 96s 15:37:23.952011198 E: run test connect.sh ...
 97s 15:37:24.675871254 O: ok simple connect
 97s 15:37:24.675983682 E: run test proxy-connect.sh ...
 97s 15:37:24.824108032 O: plain username comp=no
 97s 15:37:24.927334104 O: plain username comp=yes
 98s 15:37:25.028419786 O: username with style
 98s 15:37:25.128562274 O: ok proxy connect
 98s 15:37:25.129470587 E: run test sshfp-connect.sh ...
 98s 15:37:25.267231105 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set.
 98s 15:37:25.268120502 E: run test connect-privsep.sh ...
 99s 15:37:26.787403958 O: ok proxy connect with privsep
 99s 15:37:26.787382619 E: run test connect-uri.sh ...
100s 15:37:27.011489514 O: uri connect: no trailing slash
100s 15:37:27.213828042 O: uri connect: trailing slash
100s 15:37:27.408427831 O: uri connect: with path name
100s 15:37:27.435397328 E: run test proto-version.sh ...
100s 15:37:27.436516976 O: ok uri connect
100s 15:37:27.634205847 E: run test proto-mismatch.sh ...
100s 15:37:27.635490200 O: ok sshd version with different protocol combinations
100s 15:37:27.843169325 O: ok protocol version mismatch
100s 15:37:27.844208684 E: run test exit-status.sh ...
101s 15:37:27.982158739 O: test remote exit status: status 0
106s 15:37:33.204524929 O: test remote exit status: status 1
111s 15:37:38.452032597 O: test remote exit status: status 4
116s 15:37:43.716260343 O: test remote exit status: status 5
122s 15:37:48.997018829 O: test remote exit status: status 44
127s 15:37:54.248444926 O: ok remote exit status
127s 15:37:54.248404110 E: run test exit-status-signal.sh ...
128s 15:37:55.385026220 O: ok exit status on signal
128s 15:37:55.386515108 E: run test envpass.sh ...
128s 15:37:55.526976867 O: test environment passing: pass env, don't accept
128s 15:37:55.632688783 O: test environment passing: setenv, don't accept
128s 15:37:55.735668766 O: test environment passing: don't pass env, accept
128s 15:37:55.846181000 O: test environment passing: pass single env, accept single env
128s 15:37:55.946556245 O: test environment passing: pass multiple env, accept multiple env
129s 15:37:56.047659904 O: test environment passing: setenv, accept
129s 15:37:56.153249072 O: test environment passing: setenv, first match wins
129s 15:37:56.261731237 O: test environment passing: server setenv wins
129s 15:37:56.364279502 O: test environment passing: server setenv wins
129s 15:37:56.467924370 O: ok environment passing
129s 15:37:56.469130408 E: run test transfer.sh ...
130s 15:37:57.596188802 O: ok transfer data
130s 15:37:57.596656949 E: run test banner.sh ...
130s 15:37:57.748436620 O: test banner: missing banner file
130s 15:37:57.854178415 O: test banner: size 0
130s 15:37:57.954040440 O: test banner: size 10
131s 15:37:58.067401104 O: test banner: size 100
131s 15:37:58.183720379 O: test banner: size 1000
131s 15:37:58.298567807 O: test banner: size 10000
131s 15:37:58.410387625 O: test banner: size 100000
131s 15:37:58.517017623 O: test banner: suppress banner (-q)
131s 15:37:58.618149264 O: ok banner
131s 15:37:58.619370921 E: run test rekey.sh ...
131s 15:37:58.782638196 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1
131s 15:37:58.901684760 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1
132s 15:37:59.028536084 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256
132s 15:37:59.159433065 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512
132s 15:37:59.328484672 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512
132s 15:37:59.636499367 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1
133s 15:37:59.976649094 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256
133s 15:38:00.309861626 O: client rekey KexAlgorithms=ecdh-sha2-nistp256
133s 15:38:00.425916494 O: client rekey KexAlgorithms=ecdh-sha2-nistp384
133s 15:38:00.570942783 O: client rekey KexAlgorithms=ecdh-sha2-nistp521
133s 15:38:00.705688323 O: client rekey KexAlgorithms=curve25519-sha256
133s 15:38:00.859410132 O: client rekey KexAlgorithms=sntrup761x25519-sha512
134s 15:38:01.023793173 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com
134s 15:38:01.175545394 O: client rekey KexAlgorithms=mlkem768x25519-sha256
134s 15:38:01.305998854 O: client rekey Ciphers=3des-cbc
134s 15:38:01.453219125 O: client rekey Ciphers=aes128-cbc
134s 15:38:01.607010726 O: client rekey Ciphers=aes192-cbc
134s 15:38:01.755023102 O: client rekey Ciphers=aes256-cbc
134s 15:38:01.888966389 O: client rekey Ciphers=aes128-ctr
135s 15:38:02.025853436 O: client rekey Ciphers=aes192-ctr
135s 15:38:02.160926319 O: client rekey Ciphers=aes256-ctr
135s 15:38:02.293928422 O: client rekey Ciphers=aes128-gcm@openssh.com
137s 15:38:04.201640638 O: client rekey Ciphers=aes256-gcm@openssh.com
139s 15:38:06.334663665 O: client rekey Ciphers=chacha20-poly1305@openssh.com
141s 15:38:08.762608178 O: client rekey MACs=hmac-sha1
141s 15:38:08.902274746 O: client rekey MACs=hmac-sha1-96
142s 15:38:09.046029426 O: client rekey MACs=hmac-sha2-256
142s 15:38:09.194712289 O: client rekey MACs=hmac-sha2-512
142s 15:38:09.326468758 O: client rekey MACs=hmac-md5
142s 15:38:09.463078433 O: client rekey MACs=hmac-md5-96
142s 15:38:09.597533045 O: client rekey MACs=umac-64@openssh.com
142s 15:38:09.731378408 O: client rekey MACs=umac-128@openssh.com
142s 15:38:09.863281960 O: client rekey MACs=hmac-sha1-etm@openssh.com
143s 15:38:09.990707396 O: client rekey MACs=hmac-sha1-96-etm@openssh.com
143s 15:38:10.119959075 O: client rekey MACs=hmac-sha2-256-etm@openssh.com
143s 15:38:10.259164164 O: client rekey MACs=hmac-sha2-512-etm@openssh.com
143s 15:38:10.388105723 O: client rekey MACs=hmac-md5-etm@openssh.com
143s 15:38:10.527333184 O: client rekey MACs=hmac-md5-96-etm@openssh.com
143s 15:38:10.657695021 O: client rekey MACs=umac-64-etm@openssh.com
143s 15:38:10.788290626 O: client rekey MACs=umac-128-etm@openssh.com
143s 15:38:10.915659064 O: client rekeylimit 16
144s 15:38:11.054044175 O: client rekeylimit 1k
144s 15:38:11.183451112 O: client rekeylimit 128k
144s 15:38:11.340645094 O: client rekeylimit 256k
144s 15:38:11.522089684 O: client rekeylimit default 5
159s 15:38:26.657205808 O: client rekeylimit default 10
179s 15:38:46.784479020 O: client rekeylimit default 5 no data
194s 15:39:01.896361716 O: client rekeylimit default 10 no data
215s 15:39:22.023853220 O: server rekeylimit 16
215s 15:39:22.182850867 O: server rekeylimit 1k
215s 15:39:22.333904443 O: server rekeylimit 128k
215s 15:39:22.483537942 O: server rekeylimit 256k
215s 15:39:22.679415338 O: server rekeylimit default 5 no data
230s 15:39:37.798289676 O: server rekeylimit default 10 no data
250s 15:39:57.919955822 O: rekeylimit parsing: bytes
251s 15:39:57.996357374 O: rekeylimit parsing: time
251s 15:39:58.081094858 O: ok rekey
251s 15:39:58.081981648 E: run test dhgex.sh ...
251s 15:39:58.282343384 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc
251s 15:39:58.437293704 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc
251s 15:39:58.561134617 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc
251s 15:39:58.693509749 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc
251s 15:39:58.850124012 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr
252s 15:39:59.004062977 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr
252s 15:39:59.123807232 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com
252s 15:39:59.267084546 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com
252s 15:39:59.428863358 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc
252s 15:39:59.605038675 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc
252s 15:39:59.836034464 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr
253s 15:40:00.047644289 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr
253s 15:40:00.273008245 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc
253s 15:40:00.420296537 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc
253s 15:40:00.650716173 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr
253s 15:40:00.878122223 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr
254s 15:40:01.074354035 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com
254s 15:40:01.259543542 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com
254s 15:40:01.395947354 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com
254s 15:40:01.581632825 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com
254s 15:40:01.749262520 O: ok dhgex
254s 15:40:01.749896785 E: run test stderr-data.sh ...
254s 15:40:01.889560806 O: test stderr data transfer: ()
261s 15:40:08.117253855 O: test stderr data transfer: (-n)
267s 15:40:14.349080398 O: ok stderr data transfer
267s 15:40:14.350230031 E: run test stderr-after-eof.sh ...
269s 15:40:16.632268347 O: ok stderr data after eof
269s 15:40:16.633575118 E: run test broken-pipe.sh ...
269s 15:40:16.811392120 O: ok broken pipe test
269s 15:40:16.812029930 E: run test try-ciphers.sh ...
270s 15:40:16.977001460 O: test try ciphers: cipher 3des-cbc mac hmac-sha1
270s 15:40:17.095069647 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96
270s 15:40:17.211466828 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256
270s 15:40:17.331427226 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512
270s 15:40:17.444787692 O: test try ciphers: cipher 3des-cbc mac hmac-md5
270s 15:40:17.562194563 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96
270s 15:40:17.671844464 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com
270s 15:40:17.781732922 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com
270s 15:40:17.888678002 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com
271s 15:40:18.010295971 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com
271s 15:40:18.121783507 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com
271s 15:40:18.228529700 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com
271s 15:40:18.330671184 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com
271s 15:40:18.432116920 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com
271s 15:40:18.534854396 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com
271s 15:40:18.637090229 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com
271s 15:40:18.752402806 O: test try ciphers: cipher aes128-cbc mac hmac-sha1
271s 15:40:18.858279050 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96
271s 15:40:18.963340250 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256
272s 15:40:19.077412582 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512
272s 15:40:19.182369935 O: test try ciphers: cipher aes128-cbc mac hmac-md5
272s 15:40:19.286159620 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96
272s 15:40:19.389321382 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com
272s 15:40:19.502450999 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com
272s 15:40:19.614277511 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com
272s 15:40:19.725183601 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com
272s 15:40:19.837286925 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com
272s 15:40:19.953940133 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com
273s 15:40:20.068663848 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com
273s 15:40:20.169889755 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com
273s 15:40:20.282677936 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com
273s 15:40:20.387848689 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com
273s 15:40:20.515220690 O: test try ciphers: cipher aes192-cbc mac hmac-sha1
273s 15:40:20.627196091 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96
273s 15:40:20.757628894 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256
273s 15:40:20.920268180 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512
274s 15:40:21.057436640 O: test try ciphers: cipher aes192-cbc mac hmac-md5
274s 15:40:21.215395553 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96
274s 15:40:21.377245749 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com
274s 15:40:21.502668069 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com
274s 15:40:21.612418389 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com
274s 15:40:21.715403907 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com
274s 15:40:21.826201899 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com
274s 15:40:21.939381214 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com
275s 15:40:22.049036484 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com
275s 15:40:22.158515321 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com
275s 15:40:22.272958323 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com
275s 15:40:22.382002462 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com
275s 15:40:22.496790924 O: test try ciphers: cipher aes256-cbc mac hmac-sha1
275s 15:40:22.612826953 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96
275s 15:40:22.735457455 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256
275s 15:40:22.858461400 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512
276s 15:40:22.969390488 O: test try ciphers: cipher aes256-cbc mac hmac-md5
276s 15:40:23.079121478 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96
276s 15:40:23.190621307 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com
276s 15:40:23.302665411 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com
276s 15:40:23.419523666 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com
276s 15:40:23.536492019 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com
276s 15:40:23.647270220 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com
276s 15:40:23.765680476 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com
276s 15:40:23.875205548 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com
277s 15:40:23.980223798 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com
277s 15:40:24.087075998 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com
277s 15:40:24.197096261 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com
277s 15:40:24.312970051 O: test try ciphers: cipher aes128-ctr mac hmac-sha1
277s 15:40:24.426653028 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96
277s 15:40:24.546938752 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256
277s 15:40:24.664537609 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512
277s 15:40:24.777356609 O: test try ciphers: cipher aes128-ctr mac hmac-md5
277s 15:40:24.882603748 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96
278s 15:40:24.994793725 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com
278s 15:40:25.103273276 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com
278s 15:40:25.219062935 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com
278s 15:40:25.338936081 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com
278s 15:40:25.455060881 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com
278s 15:40:25.571853830 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com
278s 15:40:25.697168097 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com
278s 15:40:25.829702213 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com
278s 15:40:25.961389876 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com
279s 15:40:26.095240519 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com
279s 15:40:26.246734629 O: test try ciphers: cipher aes192-ctr mac hmac-sha1
279s 15:40:26.373180223 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96
279s 15:40:26.506426148 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256
279s 15:40:26.639136143 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512
279s 15:40:26.761941937 O: test try ciphers: cipher aes192-ctr mac hmac-md5
279s 15:40:26.873236985 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96
280s 15:40:26.986607479 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com
280s 15:40:27.107847844 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com
280s 15:40:27.230533921 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com
280s 15:40:27.351499189 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com
280s 15:40:27.456855898 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com
280s 15:40:27.565912651 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com
280s 15:40:27.691814641 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com
280s 15:40:27.863371019 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com
281s 15:40:27.974956703 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com
281s 15:40:28.087190004 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com
281s 15:40:28.208706306 O: test try ciphers: cipher aes256-ctr mac hmac-sha1
281s 15:40:28.319478227 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96
281s 15:40:28.433878065 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256
281s 15:40:28.548526591 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512
281s 15:40:28.658046535 O: test try ciphers: cipher aes256-ctr mac hmac-md5
281s 15:40:28.764243244 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96
281s 15:40:28.875178742 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com
282s 15:40:28.990568593 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com
282s 15:40:29.111422077 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com
282s 15:40:29.226701509 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com
282s 15:40:29.343736896 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com
282s 15:40:29.469607231 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com
282s 15:40:29.588914756 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com
282s 15:40:29.698905984 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com
282s 15:40:29.809883811 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com
282s 15:40:29.919634918 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com
283s 15:40:30.045409693 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1
283s 15:40:30.175873722 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1
283s 15:40:30.313383593 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1
283s 15:40:30.436656308 E: run test yes-head.sh ...
283s 15:40:30.438285986 O: ok try ciphers
286s 15:40:33.609968744 O: ok yes pipe head
286s 15:40:33.610136540 E: run test login-timeout.sh ...
302s 15:40:49.008070104 E: run test agent.sh ...
302s 15:40:49.008459106 O: ok connect after login grace timeout
305s 15:40:52.532662626 O: ok simple agent test
305s 15:40:52.532713872 E: run test agent-getpeereid.sh ...
305s 15:40:52.700907897 O: ok disallow agent attach from other uid
305s 15:40:52.702214645 E: run test agent-timeout.sh ...
325s 15:41:12.891861694 O: ok agent timeout test
325s 15:41:12.893323776 E: run test agent-ptrace.sh ...
326s 15:41:13.024032469 O: skipped (gdb not found)
326s 15:41:13.024678655 E: run test agent-subprocess.sh ...
336s 15:41:23.178888240 E: run test keyscan.sh ...
336s 15:41:23.179342845 O: ok agent subprocess
336s 15:41:23.956451222 O: ok keyscan
336s 15:41:23.956513640 E: run test keygen-change.sh ...
340s 15:41:27.839933222 O: ok change passphrase for key
340s 15:41:27.841156635 E: run test keygen-comment.sh ...
342s 15:41:29.244320882 O: ok Comment extraction from private key
342s 15:41:29.245270600 E: run test keygen-convert.sh ...
345s 15:41:32.636447062 O: ok convert keys
345s 15:41:32.638007690 E: run test keygen-knownhosts.sh ...
346s 15:41:33.021589971 O: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts updated.
346s 15:41:33.023252500 O: Original contents retained as /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts.old
346s 15:41:33.047802276 O: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts updated.
346s 15:41:33.049678647 O: Original contents retained as /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts.old
346s 15:41:33.058877326 O: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts updated.
346s 15:41:33.060311704 O: Original contents retained as /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts.old
346s 15:41:33.077472857 O: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts updated.
346s 15:41:33.079506865 O: Original contents retained as /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hosts.old
346s 15:41:33.114332388 O: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hashed updated.
346s 15:41:33.116185555 O: Original contents retained as /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/kh.hashed.old
346s 15:41:33.120441613 O: ok ssh-keygen known_hosts
346s 15:41:33.120526904 E: run test keygen-moduli.sh ...
347s 15:41:34.941120097 O: ok keygen moduli
347s 15:41:34.942595142 E: run test keygen-sshfp.sh ...
348s 15:41:35.150099429 O: ok keygen-sshfp
348s 15:41:35.151024801 E: run test key-options.sh ...
348s 15:41:35.334022763 O: key option command="echo bar"
348s 15:41:35.461989974 O: key option no-pty,command="echo bar"
348s 15:41:35.593511683 O: key option pty default
348s 15:41:35.733023586 O: key option pty no-pty
348s 15:41:35.841895875 O: key option pty restrict
349s 15:41:35.961578576 O: key option pty restrict,pty
349s 15:41:36.093622357 O: key option environment
349s 15:41:36.365300642 O: key option from="127.0.0.1"
349s 15:41:36.794989029 O: key option from="127.0.0.0/8"
350s 15:41:37.097671595 O: key option expiry-time default
350s 15:41:37.196716120 O: key option expiry-time invalid
350s 15:41:37.312580365 O: key option expiry-time expired
350s 15:41:37.427822870 O: key option expiry-time valid
350s 15:41:37.553190698 O: ok key options
350s 15:41:37.554454315 E: run test scp.sh ...
350s 15:41:37.733204185 O: scp: scp mode: simple copy local file to local file
350s 15:41:37.749530857 O: scp: scp mode: simple copy local file to remote file
350s 15:41:37.760876296 O: scp: scp mode: simple copy remote file to local file
350s 15:41:37.771646904 O: scp: scp mode: copy local file to remote file in place
350s 15:41:37.783723338 O: scp: scp mode: copy remote file to local file in place
350s 15:41:37.795824359 O: scp: scp mode: copy local file to remote file clobber
350s 15:41:37.808636037 O: -rw-r--r-- 1 openssh-tests openssh-tests 1125464 May  6 15:41 /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/copy
350s 15:41:37.810078100 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1125464 May  6 15:41 /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data
350s 15:41:37.811479818 O: scp: scp mode: copy remote file to local file clobber
350s 15:41:37.825389001 O: scp: scp mode: simple copy local file to remote dir
350s 15:41:37.838507918 O: scp: scp mode: simple copy local file to local dir
350s 15:41:37.850099730 O: scp: scp mode: simple copy remote file to local dir
350s 15:41:37.862330887 O: scp: scp mode: recursive local dir to remote dir
350s 15:41:37.889555425 O: scp: scp mode: recursive local dir to local dir
350s 15:41:37.927509835 O: scp: scp mode: recursive remote dir to local dir
350s 15:41:37.966119648 O: scp: scp mode: unmatched glob file local->remote
351s 15:41:37.985194631 O: scp: scp mode: unmatched glob file remote->local
351s 15:41:37.994580584 O: scp: scp mode: unmatched glob dir recursive local->remote
351s 15:41:38.020808748 O: scp: scp mode: unmatched glob dir recursive remote->local
351s 15:41:38.034730796 O: scp: scp mode: shell metacharacters
351s 15:41:38.050932212 O: scp: scp mode: skipped file after scp -p with failed chown+utimes
351s 15:41:38.098266703 O: scp: scp mode: disallow bad server #0
351s 15:41:38.124965192 O: scp: scp mode: disallow bad server #1
351s 15:41:38.151543265 O: scp: scp mode: disallow bad server #2
351s 15:41:38.182632316 O: scp: scp mode: disallow bad server #3
351s 15:41:38.217276677 O: scp: scp mode: disallow bad server #4
351s 15:41:38.284975978 O: scp: scp mode: disallow bad server #5
351s 15:41:38.349514943 O: scp: scp mode: disallow bad server #6
351s 15:41:38.411306649 O: scp: scp mode: disallow bad server #7
351s 15:41:38.441706874 O: scp: scp mode: detect non-directory target
351s 15:41:38.446468083 E: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/copy2: Not a directory
351s 15:41:38.448611576 O: scp: sftp mode: simple copy local file to local file
351s 15:41:38.458422267 O: scp: sftp mode: simple copy local file to remote file
351s 15:41:38.472580248 O: scp: sftp mode: simple copy remote file to local file
351s 15:41:38.484717196 O: scp: sftp mode: copy local file to remote file in place
351s 15:41:38.498825415 O: scp: sftp mode: copy remote file to local file in place
351s 15:41:38.512235258 O: scp: sftp mode: copy local file to remote file clobber
351s 15:41:38.526077646 O: -rw-r--r-- 1 openssh-tests openssh-tests 1125464 May  6 15:41 /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/copy
351s 15:41:38.527187695 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1125464 May  6 15:41 /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data
351s 15:41:38.528920735 O: scp: sftp mode: copy remote file to local file clobber
351s 15:41:38.542876336 O: scp: sftp mode: simple copy local file to remote dir
351s 15:41:38.556778576 O: scp: sftp mode: simple copy local file to local dir
351s 15:41:38.569416458 O: scp: sftp mode: simple copy remote file to local dir
351s 15:41:38.583796737 O: scp: sftp mode: recursive local dir to remote dir
351s 15:41:38.611874121 O: scp: sftp mode: recursive local dir to local dir
351s 15:41:38.638175674 O: scp: sftp mode: recursive remote dir to local dir
351s 15:41:38.672155234 O: scp: sftp mode: unmatched glob file local->remote
351s 15:41:38.685405668 O: scp: sftp mode: unmatched glob file remote->local
351s 15:41:38.695131349 O: scp: sftp mode: unmatched glob dir recursive local->remote
351s 15:41:38.713608567 O: scp: sftp mode: unmatched glob dir recursive remote->local
351s 15:41:38.727106477 O: scp: sftp mode: shell metacharacters
351s 15:41:38.736310857 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes
351s 15:41:38.777589974 O: scp: sftp mode: disallow bad server #0
351s 15:41:38.813789881 O: scp: sftp mode: disallow bad server #1
351s 15:41:38.848814748 O: scp: sftp mode: disallow bad server #2
351s 15:41:38.883451817 O: scp: sftp mode: disallow bad server #3
351s 15:41:38.918958921 O: scp: sftp mode: disallow bad server #4
351s 15:41:38.954046045 O: scp: sftp mode: disallow bad server #5
352s 15:41:38.989177443 O: scp: sftp mode: disallow bad server #6
352s 15:41:39.024259618 O: scp: sftp mode: disallow bad server #7
352s 15:41:39.059231625 O: scp: sftp mode: detect non-directory target
352s 15:41:39.063650751 E: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/copy2: Not a directory
352s 15:41:39.069625224 O: ok scp
352s 15:41:39.071405143 E: run test scp3.sh ...
352s 15:41:39.210351091 O: scp3: scp mode: simple copy remote file to remote file
352s 15:41:39.354909770 O: scp3: scp mode: simple copy remote file to remote dir
352s 15:41:39.491212715 O: scp3: scp mode: recursive remote dir to remote dir
352s 15:41:39.670425525 O: scp3: scp mode: detect non-directory target
352s 15:41:39.917434648 O: scp3: sftp mode: simple copy remote file to remote file
352s 15:41:39.931821529 O: scp3: sftp mode: simple copy remote file to remote dir
352s 15:41:39.948457624 O: scp3: sftp mode: recursive remote dir to remote dir
353s 15:41:39.986422904 O: scp3: sftp mode: detect non-directory target
353s 15:41:39.995025823 E: scp: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/copy2: destination is not a directory
353s 15:41:39.997008724 E: scp: /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/copy2: destination is not a directory
353s 15:41:40.003708661 E: run test scp-uri.sh ...
353s 15:41:40.004835361 O: ok scp3
353s 15:41:40.157491629 O: scp-uri: scp mode: simple copy local file to remote file
353s 15:41:40.167132650 O: scp-uri: scp mode: simple copy remote file to local file
353s 15:41:40.177090187 O: scp-uri: scp mode: simple copy local file to remote dir
353s 15:41:40.191817990 O: scp-uri: scp mode: simple copy remote file to local dir
353s 15:41:40.204373908 O: scp-uri: scp mode: recursive local dir to remote dir
353s 15:41:40.217504055 O: scp-uri: scp mode: recursive remote dir to local dir
353s 15:41:40.230947793 O: scp-uri: sftp mode: simple copy local file to remote file
353s 15:41:40.243667156 O: scp-uri: sftp mode: simple copy remote file to local file
353s 15:41:40.255439569 O: scp-uri: sftp mode: simple copy local file to remote dir
353s 15:41:40.270700585 O: scp-uri: sftp mode: simple copy remote file to local dir
353s 15:41:40.284177175 O: scp-uri: sftp mode: recursive local dir to remote dir
353s 15:41:40.299120244 O: scp-uri: sftp mode: recursive remote dir to local dir
353s 15:41:40.317291297 O: ok scp-uri
353s 15:41:40.318649743 E: run test sftp.sh ...
353s 15:41:40.462238847 O: test basic sftp put/get: buffer_size 5 num_requests 1
365s 15:41:52.602589845 O: test basic sftp put/get: buffer_size 5 num_requests 2
371s 15:41:57.956620259 O: test basic sftp put/get: buffer_size 5 num_requests 10
373s 15:42:00.912528564 O: test basic sftp put/get: buffer_size 1000 num_requests 1
374s 15:42:00.977692373 O: test basic sftp put/get: buffer_size 1000 num_requests 2
374s 15:42:01.019520210 O: test basic sftp put/get: buffer_size 1000 num_requests 10
374s 15:42:01.047498416 O: test basic sftp put/get: buffer_size 32000 num_requests 1
374s 15:42:01.061631521 O: test basic sftp put/get: buffer_size 32000 num_requests 2
374s 15:42:01.073382933 O: test basic sftp put/get: buffer_size 32000 num_requests 10
374s 15:42:01.086674113 O: test basic sftp put/get: buffer_size 64000 num_requests 1
374s 15:42:01.100007974 O: test basic sftp put/get: buffer_size 64000 num_requests 2
374s 15:42:01.113381409 O: test basic sftp put/get: buffer_size 64000 num_requests 10
374s 15:42:01.129659118 O: ok basic sftp put/get
374s 15:42:01.130716027 E: run test sftp-chroot.sh ...
374s 15:42:01.354104126 O: test sftp in chroot: get
374s 15:42:01.542671281 O: test sftp in chroot: match
374s 15:42:01.826482501 O: ok sftp in chroot
374s 15:42:01.838239072 E: run test sftp-cmds.sh ...
375s 15:42:02.003688068 O: sftp commands: lls
375s 15:42:02.011241130 O: sftp commands: lls w/path
375s 15:42:02.018053098 O: sftp commands: ls
375s 15:42:02.026203996 O: sftp commands: shell
375s 15:42:02.031157337 O: sftp commands: pwd
375s 15:42:02.035659217 O: sftp commands: lpwd
375s 15:42:02.040592251 O: sftp commands: quit
375s 15:42:02.044509702 O: sftp commands: help
375s 15:42:02.048619125 O: sftp commands: get
375s 15:42:02.060231255 O: sftp commands: get quoted
375s 15:42:02.072494370 O: sftp commands: get filename with quotes
375s 15:42:02.089966698 O: sftp commands: get filename with spaces
375s 15:42:02.101311215 O: sftp commands: get filename with glob metacharacters
375s 15:42:02.111131103 O: sftp commands: get to directory
375s 15:42:02.121328882 O: sftp commands: glob get to directory
375s 15:42:02.199536235 O: sftp commands: get to local dir
375s 15:42:02.208806328 O: sftp commands: glob get to local dir
375s 15:42:02.254872511 O: sftp commands: put
375s 15:42:02.265969472 O: sftp commands: put filename with quotes
375s 15:42:02.277322085 O: sftp commands: put filename with spaces
375s 15:42:02.289802017 O: sftp commands: put to directory
375s 15:42:02.300281577 O: sftp commands: glob put to directory
375s 15:42:02.311924196 O: sftp commands: put to local dir
375s 15:42:02.321867145 O: sftp commands: glob put to local dir
375s 15:42:02.353973088 O: sftp commands: rename
375s 15:42:02.360120746 O: sftp commands: rename directory
375s 15:42:02.365050913 O: sftp commands: ln
375s 15:42:02.369541043 O: sftp commands: ln -s
375s 15:42:02.375400548 O: sftp commands: cp
375s 15:42:02.384298482 O: sftp commands: mkdir
375s 15:42:02.389007863 O: sftp commands: chdir
375s 15:42:02.394088333 O: sftp commands: rmdir
375s 15:42:02.398166598 O: sftp commands: lmkdir
375s 15:42:02.402467720 O: sftp commands: lchdir
375s 15:42:02.415775653 O: ok sftp commands
375s 15:42:02.417008902 E: run test sftp-badcmds.sh ...
375s 15:42:02.598095813 O: sftp invalid commands: get nonexistent
375s 15:42:02.603412608 O: sftp invalid commands: glob get to nonexistent directory
375s 15:42:02.620865760 O: sftp invalid commands: put nonexistent
375s 15:42:02.625832656 O: sftp invalid commands: glob put to nonexistent directory
375s 15:42:02.631965997 O: sftp invalid commands: rename nonexistent
375s 15:42:02.643319862 O: sftp invalid commands: rename target exists (directory)
375s 15:42:02.651238632 O: sftp invalid commands: glob put files to local file
375s 15:42:02.660000951 O: ok sftp invalid commands
375s 15:42:02.661358164 E: run test sftp-batch.sh ...
375s 15:42:02.805625372 O: sftp batchfile: good commands
375s 15:42:02.814578519 O: sftp batchfile: bad commands
375s 15:42:02.825881708 O: sftp batchfile: comments and blanks
375s 15:42:02.831800987 O: sftp batchfile: junk command
375s 15:42:02.836335940 O: ok sftp batchfile
375s 15:42:02.836705996 E: run test sftp-glob.sh ...
376s 15:42:02.983381184 O: sftp glob: file glob
376s 15:42:02.991990755 O: sftp glob: dir glob
376s 15:42:02.999284720 O: sftp glob: quoted glob
376s 15:42:03.007620015 O: sftp glob: escaped glob
376s 15:42:03.015472661 O: sftp glob: escaped quote
376s 15:42:03.023689623 O: sftp glob: quoted quote
376s 15:42:03.031084808 O: sftp glob: single-quoted quote
376s 15:42:03.040233864 O: sftp glob: escaped space
376s 15:42:03.049009136 O: sftp glob: quoted space
376s 15:42:03.056011383 O: sftp glob: escaped slash
376s 15:42:03.065268502 O: sftp glob: quoted slash
376s 15:42:03.073450106 O: sftp glob: escaped slash at EOL
376s 15:42:03.080097947 O: sftp glob: quoted slash at EOL
376s 15:42:03.089015908 O: sftp glob: escaped slash+quote
376s 15:42:03.096493568 O: sftp glob: quoted slash+quote
376s 15:42:03.106452988 O: ok sftp glob
376s 15:42:03.107661423 E: run test sftp-perm.sh ...
376s 15:42:03.267264386 O: sftp permissions: read-only upload
376s 15:42:03.290888480 O: sftp permissions: read-only setstat
376s 15:42:03.313408967 O: sftp permissions: read-only rm
376s 15:42:03.332294914 O: sftp permissions: read-only mkdir
376s 15:42:03.348152273 O: sftp permissions: read-only rmdir
376s 15:42:03.365435716 O: sftp permissions: read-only posix-rename
376s 15:42:03.384200155 O: sftp permissions: read-only oldrename
376s 15:42:03.400658113 O: sftp permissions: read-only symlink
376s 15:42:03.416840334 O: sftp permissions: read-only hardlink
376s 15:42:03.432121188 O: sftp permissions: explicit open
376s 15:42:03.467870737 O: sftp permissions: explicit read
376s 15:42:03.502525247 O: sftp permissions: explicit write
376s 15:42:03.540499623 O: sftp permissions: explicit lstat
376s 15:42:03.579593927 O: sftp permissions: explicit opendir
376s 15:42:03.613819769 O: sftp permissions: explicit readdir
376s 15:42:03.649514687 O: sftp permissions: explicit setstat
376s 15:42:03.686045898 O: sftp permissions: explicit remove
376s 15:42:03.716386900 O: sftp permissions: explicit mkdir
376s 15:42:03.745178186 O: sftp permissions: explicit rmdir
376s 15:42:03.777904837 O: sftp permissions: explicit rename
376s 15:42:03.810297731 O: sftp permissions: explicit symlink
376s 15:42:03.844468290 O: sftp permissions: explicit hardlink
376s 15:42:03.878948041 O: sftp permissions: explicit statvfs
376s 15:42:03.904781632 O: ok sftp permissions
376s 15:42:03.904696321 E: run test sftp-uri.sh ...
377s 15:42:04.114612890 O: sftp-uri: non-interactive fetch to local file
377s 15:42:04.311712040 O: sftp-uri: non-interactive fetch to local dir
377s 15:42:04.518057320 O: sftp-uri: put to remote directory (trailing slash)
377s 15:42:04.723315655 O: sftp-uri: put to remote directory (no slash)
377s 15:42:04.959578228 O: ok sftp-uri
377s 15:42:04.961004884 E: run test reconfigure.sh ...
391s 15:42:17.987994541 O: ok simple connect after reconfigure
391s 15:42:17.987741115 E: run test dynamic-forward.sh ...
391s 15:42:18.194224342 O: test -D forwarding
391s 15:42:18.934651936 O: test -R forwarding
392s 15:42:19.945484186 O: PermitRemoteOpen=any
394s 15:42:21.016349200 O: PermitRemoteOpen=none
394s 15:42:21.332953109 O: PermitRemoteOpen=explicit
395s 15:42:22.347533753 O: PermitRemoteOpen=disallowed
395s 15:42:22.693611754 O: ok dynamic forwarding
395s 15:42:22.694651390 E: run test forwarding.sh ...
399s 15:42:26.894107999 O: ok local and remote forwarding
399s 15:42:26.894818155 E: run test multiplex.sh ...
401s 15:42:28.101402377 O: test connection multiplexing: setenv
401s 15:42:28.115933819 O: test connection multiplexing: envpass
401s 15:42:28.130561955 O: test connection multiplexing: transfer 
401s 15:42:28.179949076 O: test connection multiplexing: transfer -Oproxy
401s 15:42:28.290449593 O: test connection multiplexing: forward
403s 15:42:30.352132921 O: test connection multiplexing: status 0 ()
408s 15:42:35.381965389 O: test connection multiplexing: status 0 (-Oproxy)
413s 15:42:40.413515459 O: test connection multiplexing: status 1 ()
418s 15:42:45.444137026 O: test connection multiplexing: status 1 (-Oproxy)
423s 15:42:50.476543719 O: test connection multiplexing: status 4 ()
428s 15:42:55.506908278 O: test connection multiplexing: status 4 (-Oproxy)
433s 15:43:00.537818024 O: test connection multiplexing: status 5 ()
438s 15:43:05.567201239 O: test connection multiplexing: status 5 (-Oproxy)
443s 15:43:10.596861962 O: test connection multiplexing: status 44 ()
448s 15:43:15.629545701 O: test connection multiplexing: status 44 (-Oproxy)
453s 15:43:20.662680148 O: test connection multiplexing: cmd check
453s 15:43:20.671818274 O: test connection multiplexing: cmd forward local (TCP)
454s 15:43:21.889059477 O: test connection multiplexing: cmd forward remote (TCP)
456s 15:43:23.112590155 O: test connection multiplexing: cmd forward local (UNIX)
457s 15:43:24.144591436 O: test connection multiplexing: cmd forward remote (UNIX)
458s 15:43:25.175326336 O: test connection multiplexing: cmd exit
458s 15:43:25.185151223 O: test connection multiplexing: cmd stop
469s 15:43:36.237685312 O: ok connection multiplexing
469s 15:43:36.238633857 E: run test reexec.sh ...
469s 15:43:36.390523006 O: test config passing
469s 15:43:36.659634115 O: test reexec fallback
469s 15:43:36.661004254 E: ln: failed to create hard link '/tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted
469s 15:43:36.929238392 O: ok reexec tests
469s 15:43:36.930280033 E: run test brokenkeys.sh ...
470s 15:43:37.357800667 O: ok broken keys
470s 15:43:37.358974587 E: run test sshcfgparse.sh ...
470s 15:43:37.507274649 O: reparse minimal config
470s 15:43:37.524091764 O: ssh -W opts
470s 15:43:37.572106402 O: user first match
470s 15:43:37.605823686 O: pubkeyacceptedalgorithms
470s 15:43:37.657303586 O: agentforwarding
470s 15:43:37.698004961 O: command line override
470s 15:43:37.720045575 O: ok ssh config parse
470s 15:43:37.721456610 E: run test cfgparse.sh ...
470s 15:43:37.857039934 O: reparse minimal config
470s 15:43:37.893030316 O: reparse regress config
470s 15:43:37.932243503 O: listenaddress order
471s 15:43:37.979562181 O: ok sshd config parse
471s 15:43:37.979722252 E: run test cfgmatch.sh ...
478s 15:43:45.175018742 O: ok sshd_config match
478s 15:43:45.176238547 E: run test cfgmatchlisten.sh ...
487s 15:43:54.394182920 O: ok sshd_config matchlisten
487s 15:43:54.394683592 E: run test percent.sh ...
487s 15:43:54.551995271 O: percent expansions matchexec percent
488s 15:43:55.927242069 O: percent expansions localcommand percent
490s 15:43:57.273241060 O: percent expansions remotecommand percent
490s 15:43:57.416223086 O: percent expansions controlpath percent
490s 15:43:57.555679287 O: percent expansions identityagent percent
490s 15:43:57.702570760 O: percent expansions forwardagent percent
490s 15:43:57.918161871 O: percent expansions localforward percent
491s 15:43:58.059889137 O: percent expansions remoteforward percent
491s 15:43:58.203083261 O: percent expansions revokedhostkeys percent
491s 15:43:58.341085586 O: percent expansions userknownhostsfile percent
492s 15:43:59.488560921 O: percent expansions controlpath dollar
492s 15:43:59.504023637 O: percent expansions identityagent dollar
492s 15:43:59.517632615 O: percent expansions forwardagent dollar
492s 15:43:59.531132546 O: percent expansions localforward dollar
492s 15:43:59.546973354 O: percent expansions remoteforward dollar
492s 15:43:59.561031497 O: percent expansions userknownhostsfile dollar
492s 15:43:59.676426222 O: percent expansions controlpath tilde
492s 15:43:59.705380024 O: percent expansions identityagent tilde
492s 15:43:59.730554124 O: percent expansions forwardagent tilde
492s 15:43:59.754792963 O: ok percent expansions
492s 15:43:59.756013740 E: run test addrmatch.sh ...
492s 15:43:59.895269054 O: test first entry for user 192.168.0.1 somehost
492s 15:43:59.908324479 O: test negative match for user 192.168.30.1 somehost
492s 15:43:59.921135205 O: test no match for user 19.0.0.1 somehost
492s 15:43:59.933634715 O: test list middle for user 10.255.255.254 somehost
492s 15:43:59.947125821 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1
492s 15:43:59.965030982 O: test bare IP4 address for user 1.1.1.1 somehost.example.com
493s 15:43:59.982886460 O: test localaddress for user 19.0.0.1 somehost
493s 15:44:00.000888165 O: test localport for user 19.0.0.1 somehost
493s 15:44:00.018720098 O: test bare IP6 address for user ::1 somehost.example.com
493s 15:44:00.036431094 O: test deny IPv6 for user ::2 somehost.example.com
493s 15:44:00.054448878 O: test IP6 negated for user ::3 somehost
493s 15:44:00.072951945 O: test IP6 no match for user ::4 somehost
493s 15:44:00.086627227 O: test IP6 network for user 2000::1 somehost
493s 15:44:00.099372099 O: test IP6 network for user 2001::1 somehost
493s 15:44:00.112053923 O: test IP6 localaddress for user ::5 somehost
493s 15:44:00.124414450 O: test IP6 localport for user ::5 somehost
493s 15:44:00.136959977 O: test invalid Match address 10.0.1.0/8
493s 15:44:00.147900213 O: test invalid Match localaddress 10.0.1.0/8
493s 15:44:00.158671742 O: test invalid Match address 10.0.0.1/24
493s 15:44:00.170228558 O: test invalid Match localaddress 10.0.0.1/24
493s 15:44:00.181624160 O: test invalid Match address 2000:aa:bb:01::/56
493s 15:44:00.193979039 O: test invalid Match localaddress 2000:aa:bb:01::/56
493s 15:44:00.207932083 O: ok address match
493s 15:44:00.209327949 E: run test localcommand.sh ...
493s 15:44:00.356097272 O: test localcommand: proto  localcommand
493s 15:44:00.465721168 O: ok localcommand
493s 15:44:00.467319817 E: run test forcecommand.sh ...
494s 15:44:01.102068648 E: Connection closed
494s 15:44:01.318290187 E: Connection closed
494s 15:44:01.320434762 E: 
Connection closed.  
494s 15:44:01.430080409 O: ok forced command
494s 15:44:01.431148189 E: run test portnum.sh ...
494s 15:44:01.588125489 O: port number parsing: invalid port 0
494s 15:44:01.596815821 O: port number parsing: invalid port 65536
494s 15:44:01.604714754 O: port number parsing: invalid port 131073
494s 15:44:01.613222302 O: port number parsing: invalid port 2000blah
494s 15:44:01.621011830 O: port number parsing: invalid port blah2000
494s 15:44:01.628951951 O: port number parsing: valid port 1
494s 15:44:01.746426870 O: port number parsing: valid port 22
494s 15:44:01.860804972 O: port number parsing: valid port 2222
495s 15:44:01.979062474 O: port number parsing: valid port 22222
495s 15:44:02.083246434 O: port number parsing: valid port 65535
495s 15:44:02.188794842 E: run test keytype.sh ...
495s 15:44:02.190005600 O: ok port number parsing
495s 15:44:02.365546571 O: keygen ed25519, 512 bits
495s 15:44:02.376294215 O: keygen ed25519-sk, n/a bits
495s 15:44:02.391165658 O: keygen ecdsa, 256 bits
495s 15:44:02.402475209 O: keygen ecdsa, 384 bits
495s 15:44:02.413122024 O: keygen ecdsa, 521 bits
495s 15:44:02.423665713 O: keygen ecdsa-sk, n/a bits
495s 15:44:02.438509023 O: keygen rsa, 2048 bits
495s 15:44:02.584214223 O: keygen rsa, 3072 bits
495s 15:44:02.713963679 O: userkey ed25519-512, hostkey ed25519-512
495s 15:44:02.803688102 O: userkey ed25519-512, hostkey ed25519-512
495s 15:44:02.894688725 O: userkey ed25519-512, hostkey ed25519-512
496s 15:44:02.998789348 O: userkey ed25519-sk, hostkey ed25519-sk
496s 15:44:03.088292073 O: userkey ed25519-sk, hostkey ed25519-sk
496s 15:44:03.181708783 O: userkey ed25519-sk, hostkey ed25519-sk
496s 15:44:03.290576196 O: userkey ecdsa-256, hostkey ecdsa-256
496s 15:44:03.380307281 O: userkey ecdsa-256, hostkey ecdsa-256
496s 15:44:03.470924373 O: userkey ecdsa-256, hostkey ecdsa-256
496s 15:44:03.563968341 O: userkey ecdsa-384, hostkey ecdsa-384
496s 15:44:03.650372987 O: userkey ecdsa-384, hostkey ecdsa-384
496s 15:44:03.739252138 O: userkey ecdsa-384, hostkey ecdsa-384
496s 15:44:03.834964688 O: userkey ecdsa-521, hostkey ecdsa-521
496s 15:44:03.917041471 O: userkey ecdsa-521, hostkey ecdsa-521
497s 15:44:03.998730231 O: userkey ecdsa-521, hostkey ecdsa-521
497s 15:44:04.086892905 O: userkey ecdsa-sk, hostkey ecdsa-sk
497s 15:44:04.168512767 O: userkey ecdsa-sk, hostkey ecdsa-sk
497s 15:44:04.249283651 O: userkey ecdsa-sk, hostkey ecdsa-sk
497s 15:44:04.348209638 O: userkey rsa-2048, hostkey rsa-2048
497s 15:44:04.439874962 O: userkey rsa-2048, hostkey rsa-2048
497s 15:44:04.520891760 O: userkey rsa-2048, hostkey rsa-2048
497s 15:44:04.610163541 O: userkey rsa-3072, hostkey rsa-3072
497s 15:44:04.695325958 O: userkey rsa-3072, hostkey rsa-3072
497s 15:44:04.781515530 O: userkey rsa-3072, hostkey rsa-3072
497s 15:44:04.865657566 O: ok login with different key types
497s 15:44:04.866591364 E: run test kextype.sh ...
498s 15:44:05.058907330 O: kex diffie-hellman-group1-sha1
498s 15:44:05.461259807 O: kex diffie-hellman-group14-sha1
498s 15:44:05.845296729 O: kex diffie-hellman-group14-sha256
499s 15:44:06.248607358 O: kex diffie-hellman-group16-sha512
499s 15:44:06.664970187 O: kex diffie-hellman-group18-sha512
500s 15:44:07.300535133 O: kex diffie-hellman-group-exchange-sha1
500s 15:44:07.958203521 O: kex diffie-hellman-group-exchange-sha256
501s 15:44:08.620844786 O: kex ecdh-sha2-nistp256
502s 15:44:09.041021052 O: kex ecdh-sha2-nistp384
502s 15:44:09.418313964 O: kex ecdh-sha2-nistp521
502s 15:44:09.819701595 O: kex curve25519-sha256
503s 15:44:10.243643125 O: kex curve25519-sha256@libssh.org
503s 15:44:10.660046199 O: kex sntrup761x25519-sha512
504s 15:44:11.068564969 O: kex sntrup761x25519-sha512@openssh.com
504s 15:44:11.484598308 O: kex mlkem768x25519-sha256
504s 15:44:11.895129775 O: ok login with different key exchange algorithms
504s 15:44:11.896321467 E: run test cert-hostkey.sh ...
505s 15:44:12.463941483 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/host_ca_key.pub
505s 15:44:12.465147042 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/host_ca_key2.pub
505s 15:44:12.466354353 O: certified host keys: sign host ed25519 cert
505s 15:44:12.472988165 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub
505s 15:44:12.482702204 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub
505s 15:44:12.485887789 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert
505s 15:44:12.496269233 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub
505s 15:44:12.505963835 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub
505s 15:44:12.509092562 O: certified host keys: sign host ecdsa-sha2-nistp256 cert
505s 15:44:12.516270579 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub
505s 15:44:12.526038188 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub
505s 15:44:12.529044325 O: certified host keys: sign host ecdsa-sha2-nistp384 cert
505s 15:44:12.536645438 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub
505s 15:44:12.547595001 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub
505s 15:44:12.550410519 O: certified host keys: sign host ecdsa-sha2-nistp521 cert
505s 15:44:12.557554201 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub
505s 15:44:12.569572877 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub
505s 15:44:12.572595774 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert
505s 15:44:12.583575785 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub
505s 15:44:12.593252274 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub
505s 15:44:12.596672690 O: certified host keys: sign host rsa cert
506s 15:44:13.094252934 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub
506s 15:44:13.104918613 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub
506s 15:44:13.109134365 O: certified host keys: sign host rsa-sha2-256 cert
506s 15:44:13.306728173 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub
506s 15:44:13.318431214 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub
506s 15:44:13.321962409 O: certified host keys: sign host rsa-sha2-512 cert
506s 15:44:13.450544889 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub
506s 15:44:13.463061231 O: Revoking from /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub
506s 15:44:13.466619155 O: certified host keys: host ed25519 cert connect
506s 15:44:13.469105805 O: certified host keys: ed25519 basic connect expect success yes
506s 15:44:13.574208574 O: certified host keys: ed25519 empty KRL expect success yes
506s 15:44:13.687063079 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no
506s 15:44:13.751988027 O: certified host keys: ed25519 KRL w/ cert revoked expect success no
506s 15:44:13.820798095 O: certified host keys: ed25519 KRL w/ CA revoked expect success no
506s 15:44:13.883627199 O: certified host keys: ed25519 empty plaintext revocation expect success yes
507s 15:44:13.987906749 O: certified host keys: ed25519 plain key plaintext revocation expect success no
507s 15:44:14.047882684 O: certified host keys: ed25519 cert plaintext revocation expect success no
507s 15:44:14.116170620 O: certified host keys: ed25519 CA plaintext revocation expect success no
507s 15:44:14.192631886 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect
507s 15:44:14.195098127 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes
507s 15:44:14.307085880 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes
507s 15:44:14.474186328 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no
507s 15:44:14.556923804 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no
507s 15:44:14.628350235 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no
507s 15:44:14.691615951 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes
507s 15:44:14.804123924 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no
507s 15:44:14.870802431 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no
507s 15:44:14.937977091 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no
508s 15:44:15.007522093 O: certified host keys: host ecdsa-sha2-nistp256 cert connect
508s 15:44:15.009546141 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes
508s 15:44:15.116788306 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes
508s 15:44:15.234107563 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no
508s 15:44:15.296927620 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no
508s 15:44:15.360588468 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no
508s 15:44:15.423452209 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes
508s 15:44:15.533081495 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no
508s 15:44:15.593433227 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no
508s 15:44:15.660648274 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no
508s 15:44:15.723944707 O: certified host keys: host ecdsa-sha2-nistp384 cert connect
508s 15:44:15.726091786 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes
508s 15:44:15.829226784 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes
508s 15:44:15.932167915 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no
509s 15:44:15.994970520 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no
509s 15:44:16.058511873 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no
509s 15:44:16.123033141 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes
509s 15:44:16.224147015 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no
509s 15:44:16.288399867 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no
509s 15:44:16.358006655 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no
509s 15:44:16.420516318 O: certified host keys: host ecdsa-sha2-nistp521 cert connect
509s 15:44:16.422176872 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes
509s 15:44:16.524299824 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes
509s 15:44:16.629698611 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no
509s 15:44:16.692874396 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no
509s 15:44:16.756785416 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no
509s 15:44:16.820049630 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes
509s 15:44:16.930529907 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no
510s 15:44:16.998141240 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no
510s 15:44:17.070680776 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no
510s 15:44:17.134943317 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect
510s 15:44:17.136878498 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes
510s 15:44:17.240295014 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes
510s 15:44:17.357144076 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no
510s 15:44:17.423048437 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no
510s 15:44:17.491142668 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no
510s 15:44:17.558547722 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes
510s 15:44:17.669171340 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no
510s 15:44:17.749620809 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no
510s 15:44:17.838450658 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no
510s 15:44:17.909786750 O: certified host keys: host rsa cert connect
510s 15:44:17.911807151 O: certified host keys: rsa basic connect expect success yes
511s 15:44:18.022327315 O: certified host keys: rsa empty KRL expect success yes
511s 15:44:18.156196162 O: certified host keys: rsa KRL w/ plain key revoked expect success no
511s 15:44:18.226266271 O: certified host keys: rsa KRL w/ cert revoked expect success no
511s 15:44:18.297292389 O: certified host keys: rsa KRL w/ CA revoked expect success no
511s 15:44:18.379475561 O: certified host keys: rsa empty plaintext revocation expect success yes
511s 15:44:18.486909337 O: certified host keys: rsa plain key plaintext revocation expect success no
511s 15:44:18.551927409 O: certified host keys: rsa cert plaintext revocation expect success no
511s 15:44:18.627528957 O: certified host keys: rsa CA plaintext revocation expect success no
511s 15:44:18.696678062 O: certified host keys: host rsa-sha2-256 cert connect
511s 15:44:18.698701540 O: certified host keys: rsa-sha2-256 basic connect expect success yes
511s 15:44:18.803231051 O: certified host keys: rsa-sha2-256 empty KRL expect success yes
511s 15:44:18.911115384 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no
512s 15:44:18.976015075 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no
512s 15:44:19.041162160 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no
512s 15:44:19.102232303 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes
512s 15:44:19.203296825 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no
512s 15:44:19.268276885 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no
512s 15:44:19.339737221 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no
512s 15:44:19.420804953 O: certified host keys: host rsa-sha2-512 cert connect
512s 15:44:19.423472403 O: certified host keys: rsa-sha2-512 basic connect expect success yes
512s 15:44:19.525283078 O: certified host keys: rsa-sha2-512 empty KRL expect success yes
512s 15:44:19.621939614 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no
512s 15:44:19.684363034 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no
512s 15:44:19.744314172 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no
512s 15:44:19.804619227 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes
512s 15:44:19.906583481 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no
513s 15:44:19.973598771 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no
513s 15:44:20.051411610 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no
513s 15:44:20.131341271 O: certified host keys: host ed25519 revoked cert
513s 15:44:20.196738107 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert
513s 15:44:20.265425975 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert
513s 15:44:20.336307732 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert
513s 15:44:20.404433342 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert
513s 15:44:20.476473027 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert
513s 15:44:20.551356062 O: certified host keys: host rsa revoked cert
513s 15:44:20.629789448 O: certified host keys: host rsa-sha2-256 revoked cert
513s 15:44:20.700121902 O: certified host keys: host rsa-sha2-512 revoked cert
513s 15:44:20.772536713 O: certified host keys: host ed25519 revoked cert
513s 15:44:20.836976778 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert
513s 15:44:20.906245338 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert
514s 15:44:20.974251835 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert
514s 15:44:21.047990868 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert
514s 15:44:21.114569005 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert
514s 15:44:21.180999136 O: certified host keys: host rsa revoked cert
514s 15:44:21.248404070 O: certified host keys: host rsa-sha2-256 revoked cert
514s 15:44:21.313213170 O: certified host keys: host rsa-sha2-512 revoked cert
519s 15:44:26.624631688 O: certified host keys: host ed25519  cert downgrade to raw key
519s 15:44:26.834094309 O: certified host keys: host sk-ssh-ed25519@openssh.com  cert downgrade to raw key
520s 15:44:27.060577347 O: certified host keys: host ecdsa-sha2-nistp256  cert downgrade to raw key
520s 15:44:27.259271554 O: certified host keys: host ecdsa-sha2-nistp384  cert downgrade to raw key
520s 15:44:27.494726946 O: certified host keys: host ecdsa-sha2-nistp521  cert downgrade to raw key
520s 15:44:27.857335236 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com  cert downgrade to raw key
521s 15:44:28.092014609 O: certified host keys: host rsa  cert downgrade to raw key
521s 15:44:28.373414076 O: certified host keys: host rsa-sha2-256  cert downgrade to raw key
521s 15:44:28.838192853 O: certified host keys: host rsa-sha2-512  cert downgrade to raw key
522s 15:44:29.188227801 O: certified host keys: host ed25519 connect wrong cert
522s 15:44:29.274864977 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert
522s 15:44:29.378948596 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert
522s 15:44:29.465259526 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert
522s 15:44:29.544884312 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert
522s 15:44:29.620833092 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert
522s 15:44:29.699313562 O: certified host keys: host rsa connect wrong cert
522s 15:44:29.963568925 O: certified host keys: host rsa-sha2-256 connect wrong cert
523s 15:44:30.180874282 O: certified host keys: host rsa-sha2-512 connect wrong cert
523s 15:44:30.426773344 O: ok certified host keys
523s 15:44:30.428251099 E: run test cert-userkey.sh ...
523s 15:44:30.944581466 O: certified user keys: sign user ed25519 cert
523s 15:44:30.956412246 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert
524s 15:44:30.970630919 O: certified user keys: sign user ecdsa-sha2-nistp256 cert
524s 15:44:30.985885555 O: certified user keys: sign user ecdsa-sha2-nistp384 cert
524s 15:44:31.001377063 O: certified user keys: sign user ecdsa-sha2-nistp521 cert
524s 15:44:31.012826586 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert
524s 15:44:31.028288511 O: certified user keys: sign user rsa cert
524s 15:44:31.110517961 O: certified user keys: sign user rsa-sha2-256 cert
524s 15:44:31.243819306 O: certified user keys: sign user rsa-sha2-512 cert
524s 15:44:31.317513446 O: certified user keys: ed25519 missing authorized_principals
524s 15:44:31.403559694 O: certified user keys: ed25519 empty authorized_principals
524s 15:44:31.491111287 O: certified user keys: ed25519 wrong authorized_principals
524s 15:44:31.576571758 O: certified user keys: ed25519 correct authorized_principals
524s 15:44:31.676858652 O: certified user keys: ed25519 authorized_principals bad key opt
524s 15:44:31.763493374 O: certified user keys: ed25519 authorized_principals command=false
524s 15:44:31.866659806 O: certified user keys: ed25519 authorized_principals command=true
525s 15:44:31.989505294 O: certified user keys: ed25519 wrong principals key option
525s 15:44:32.087990838 O: certified user keys: ed25519 correct principals key option
525s 15:44:32.196409884 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals
525s 15:44:32.287370193 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals
525s 15:44:32.376588107 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals
525s 15:44:32.465502296 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals
525s 15:44:32.566440057 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt
525s 15:44:32.655638064 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false
525s 15:44:32.756711085 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true
525s 15:44:32.863073841 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option
525s 15:44:32.958619006 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option
526s 15:44:33.067665548 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals
526s 15:44:33.173471439 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals
526s 15:44:33.289448000 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals
526s 15:44:33.395347504 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals
526s 15:44:33.512950576 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt
526s 15:44:33.626653254 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false
526s 15:44:33.734877717 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true
526s 15:44:33.845588828 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option
526s 15:44:33.941296140 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option
527s 15:44:34.048264898 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals
527s 15:44:34.145652016 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals
527s 15:44:34.241805804 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals
527s 15:44:34.337869074 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals
527s 15:44:34.439179731 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt
527s 15:44:34.535188298 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false
527s 15:44:34.637216058 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true
527s 15:44:34.746232440 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option
527s 15:44:34.849077817 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option
527s 15:44:34.967243465 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals
528s 15:44:35.064298298 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals
528s 15:44:35.166723031 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals
528s 15:44:35.267986047 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals
528s 15:44:35.377289049 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt
528s 15:44:35.475539647 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false
528s 15:44:35.584574110 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true
528s 15:44:35.698487658 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option
528s 15:44:35.810611560 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option
528s 15:44:35.945961312 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals
529s 15:44:36.060075292 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals
529s 15:44:36.173273780 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals
529s 15:44:36.288448449 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals
529s 15:44:36.410095784 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt
529s 15:44:36.519816240 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false
529s 15:44:36.647709271 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true
529s 15:44:36.765134692 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option
529s 15:44:36.857553221 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option
530s 15:44:36.973657327 O: certified user keys: rsa missing authorized_principals
530s 15:44:37.073203110 O: certified user keys: rsa empty authorized_principals
530s 15:44:37.168223911 O: certified user keys: rsa wrong authorized_principals
530s 15:44:37.268331573 O: certified user keys: rsa correct authorized_principals
530s 15:44:37.381091093 O: certified user keys: rsa authorized_principals bad key opt
530s 15:44:37.478111479 O: certified user keys: rsa authorized_principals command=false
530s 15:44:37.593699244 O: certified user keys: rsa authorized_principals command=true
530s 15:44:37.726060787 O: certified user keys: rsa wrong principals key option
530s 15:44:37.822664530 O: certified user keys: rsa correct principals key option
530s 15:44:37.935481176 O: certified user keys: rsa-sha2-256 missing authorized_principals
531s 15:44:38.019553255 O: certified user keys: rsa-sha2-256 empty authorized_principals
531s 15:44:38.102818235 O: certified user keys: rsa-sha2-256 wrong authorized_principals
531s 15:44:38.191067637 O: certified user keys: rsa-sha2-256 correct authorized_principals
531s 15:44:38.294309669 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt
531s 15:44:38.379416933 O: certified user keys: rsa-sha2-256 authorized_principals command=false
531s 15:44:38.491696129 O: certified user keys: rsa-sha2-256 authorized_principals command=true
531s 15:44:38.606483858 O: certified user keys: rsa-sha2-256 wrong principals key option
531s 15:44:38.704592230 O: certified user keys: rsa-sha2-256 correct principals key option
531s 15:44:38.823211178 O: certified user keys: rsa-sha2-512 missing authorized_principals
531s 15:44:38.915852792 O: certified user keys: rsa-sha2-512 empty authorized_principals
532s 15:44:39.004668336 O: certified user keys: rsa-sha2-512 wrong authorized_principals
532s 15:44:39.093055098 O: certified user keys: rsa-sha2-512 correct authorized_principals
532s 15:44:39.206983969 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt
532s 15:44:39.314927245 O: certified user keys: rsa-sha2-512 authorized_principals command=false
532s 15:44:39.419605974 O: certified user keys: rsa-sha2-512 authorized_principals command=true
532s 15:44:39.558473422 O: certified user keys: rsa-sha2-512 wrong principals key option
532s 15:44:39.682187338 O: certified user keys: rsa-sha2-512 correct principals key option
532s 15:44:39.827563250 O: certified user keys: ed25519 authorized_keys connect
532s 15:44:39.951367833 O: certified user keys: ed25519 authorized_keys revoked key
533s 15:44:40.047168601 O: certified user keys: ed25519 authorized_keys revoked via KRL
533s 15:44:40.157035760 O: certified user keys: ed25519 authorized_keys empty KRL
533s 15:44:40.290051634 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect
533s 15:44:40.401688763 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key
533s 15:44:40.512475743 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL
533s 15:44:40.628556310 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL
533s 15:44:40.752094749 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect
533s 15:44:40.876723704 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key
534s 15:44:40.977564998 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL
534s 15:44:41.091179016 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL
534s 15:44:41.204196898 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect
534s 15:44:41.311927805 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key
534s 15:44:41.422084450 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL
534s 15:44:41.562179613 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL
534s 15:44:41.699089095 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect
534s 15:44:41.803542478 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key
534s 15:44:41.922084981 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL
535s 15:44:42.051593373 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL
535s 15:44:42.181496093 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect
535s 15:44:42.294634708 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key
535s 15:44:42.413688579 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL
535s 15:44:42.537032105 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL
535s 15:44:42.655340440 O: certified user keys: rsa authorized_keys connect
535s 15:44:42.771315037 O: certified user keys: rsa authorized_keys revoked key
535s 15:44:42.873235550 O: certified user keys: rsa authorized_keys revoked via KRL
536s 15:44:42.978322146 O: certified user keys: rsa authorized_keys empty KRL
536s 15:44:43.092486783 O: certified user keys: rsa-sha2-256 authorized_keys connect
536s 15:44:43.206973674 O: certified user keys: rsa-sha2-256 authorized_keys revoked key
536s 15:44:43.310209877 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL
536s 15:44:43.416029954 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL
536s 15:44:43.545078865 O: certified user keys: rsa-sha2-512 authorized_keys connect
536s 15:44:43.652048991 O: certified user keys: rsa-sha2-512 authorized_keys revoked key
536s 15:44:43.740958391 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL
536s 15:44:43.834247562 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL
536s 15:44:43.950029689 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key
537s 15:44:44.050703538 O: certified user keys: authorized_keys CA does not authenticate
537s 15:44:44.053665925 O: certified user keys: ensure CA key does not authenticate user
537s 15:44:44.150658296 O: certified user keys: ed25519 TrustedUserCAKeys connect
537s 15:44:44.270897298 O: certified user keys: ed25519 TrustedUserCAKeys revoked key
537s 15:44:44.362314593 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL
537s 15:44:44.458435414 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL
537s 15:44:44.564003349 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect
537s 15:44:44.673030796 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key
537s 15:44:44.788487827 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL
537s 15:44:44.908084424 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL
538s 15:44:45.032727730 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect
538s 15:44:45.141812721 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key
538s 15:44:45.238703578 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL
538s 15:44:45.332434008 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL
538s 15:44:45.437341431 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect
538s 15:44:45.542891599 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key
538s 15:44:45.645264751 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL
538s 15:44:45.755981576 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL
538s 15:44:45.872408327 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect
539s 15:44:45.987006388 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key
539s 15:44:46.092206460 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL
539s 15:44:46.202153700 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL
539s 15:44:46.317994370 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect
539s 15:44:46.432901905 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key
539s 15:44:46.540828408 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL
539s 15:44:46.664308012 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL
539s 15:44:46.796504636 O: certified user keys: rsa TrustedUserCAKeys connect
539s 15:44:46.921086491 O: certified user keys: rsa TrustedUserCAKeys revoked key
540s 15:44:47.019652681 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL
540s 15:44:47.129028305 O: certified user keys: rsa TrustedUserCAKeys empty KRL
540s 15:44:47.256374038 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect
540s 15:44:47.384562720 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key
540s 15:44:47.491299746 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL
540s 15:44:47.592543779 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL
540s 15:44:47.718992954 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect
540s 15:44:47.834682580 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key
540s 15:44:47.934302212 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL
541s 15:44:48.029367579 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL
541s 15:44:48.144252154 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key
541s 15:44:48.247894432 O: certified user keys: TrustedUserCAKeys CA does not authenticate
541s 15:44:48.249860609 O: certified user keys: ensure CA key does not authenticate user
541s 15:44:48.336103697 O: certified user keys: correct principal auth authorized_keys expect success rsa
541s 15:44:48.451330179 O: certified user keys: correct principal auth authorized_keys expect success ed25519
541s 15:44:48.574217349 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa
541s 15:44:48.679680684 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519
541s 15:44:48.786837157 O: certified user keys: host-certificate auth authorized_keys expect failure rsa
541s 15:44:48.897737805 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519
542s 15:44:48.991135750 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa
542s 15:44:49.087639563 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519
542s 15:44:49.176644961 O: certified user keys: wrong principals auth authorized_keys expect failure rsa
542s 15:44:49.283448285 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519
542s 15:44:49.389629941 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa
542s 15:44:49.482646470 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519
542s 15:44:49.577394941 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa
542s 15:44:49.683723551 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519
542s 15:44:49.794965159 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa
542s 15:44:49.892483350 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519
543s 15:44:49.990544553 O: certified user keys: cert expired auth authorized_keys expect failure rsa
543s 15:44:50.099206126 O: certified user keys: cert expired auth authorized_keys expect failure ed25519
543s 15:44:50.207174602 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa
543s 15:44:50.302707616 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519
543s 15:44:50.399233386 O: certified user keys: cert valid interval auth authorized_keys expect success rsa
543s 15:44:50.520931932 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519
543s 15:44:50.639603316 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa
543s 15:44:50.761227143 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519
543s 15:44:50.869718822 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa
544s 15:44:50.975370748 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519
544s 15:44:51.092910621 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa
544s 15:44:51.228470992 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519
544s 15:44:51.325225522 O: certified user keys: force-command auth authorized_keys expect failure rsa
544s 15:44:51.445460914 O: certified user keys: force-command auth authorized_keys expect failure ed25519
544s 15:44:51.575246561 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa
544s 15:44:51.683806032 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519
544s 15:44:51.793635375 O: certified user keys: empty principals auth authorized_keys expect success rsa
544s 15:44:51.910227399 O: certified user keys: empty principals auth authorized_keys expect success ed25519
545s 15:44:52.027341859 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa
545s 15:44:52.122503219 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519
545s 15:44:52.218423210 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa
545s 15:44:52.342499847 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519
545s 15:44:52.450418180 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa
545s 15:44:52.545332962 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519
545s 15:44:52.641467843 O: certified user keys: principals key option principals auth authorized_keys expect success rsa
545s 15:44:52.758410176 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519
545s 15:44:52.872403040 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa
546s 15:44:52.976008661 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519
546s 15:44:53.079694702 O: certified user keys: force-command match true auth authorized_keys expect success rsa
546s 15:44:53.198423566 O: certified user keys: force-command match true auth authorized_keys expect success ed25519
546s 15:44:53.315246754 O: certified user keys: force-command match true auth authorized_keys expect failure rsa
546s 15:44:53.433243203 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519
546s 15:44:53.554879386 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa
546s 15:44:53.661263892 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519
546s 15:44:53.767202258 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa
546s 15:44:53.874594809 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519
547s 15:44:53.990191234 O: certified user keys: user ed25519 connect wrong cert
547s 15:44:54.094543792 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert
547s 15:44:54.179881341 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert
547s 15:44:54.266055807 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert
547s 15:44:54.356424367 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert
547s 15:44:54.454494206 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert
547s 15:44:54.559240498 O: certified user keys: user rsa connect wrong cert
547s 15:44:54.683626145 O: certified user keys: user rsa-sha2-256 connect wrong cert
547s 15:44:54.808283327 O: certified user keys: user rsa-sha2-512 connect wrong cert
547s 15:44:54.914314810 O: ok certified user keys
547s 15:44:54.915092047 E: run test host-expand.sh ...
548s 15:44:55.153731778 O: ok expand %h and %n
548s 15:44:55.154793313 E: run test keys-command.sh ...
548s 15:44:55.321905414 O: SKIPPED: /var/run/keycommand_openssh-tests.43392 not executable (/var/run mounted noexec?)
548s 15:44:55.329463238 E: run test forward-control.sh ...
549s 15:44:56.808007382 O: check_lfwd done (expecting Y): default configuration
551s 15:44:58.082724076 O: check_rfwd done (expecting Y): default configuration
551s 15:44:58.350686277 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes
552s 15:44:59.625809649 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes
553s 15:45:00.753938683 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen
554s 15:45:01.085004038 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen
555s 15:45:02.403956286 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen
556s 15:45:03.686967144 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen
557s 15:45:04.804121239 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen
559s 15:45:06.082609647 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen
560s 15:45:07.394017198 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen
561s 15:45:08.719109810 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen
562s 15:45:09.844981564 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding
562s 15:45:09.960717338 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding
563s 15:45:10.084980948 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict
563s 15:45:10.200937678 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict
564s 15:45:11.507105774 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding
564s 15:45:11.824336529 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding
566s 15:45:13.119343735 O: check_lfwd done (expecting Y): AllowTcpForwarding=local
566s 15:45:13.227278330 O: check_rfwd done (expecting N): AllowTcpForwarding=local
567s 15:45:14.349016519 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen
567s 15:45:14.475355208 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen
568s 15:45:15.767278522 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen
568s 15:45:15.878455799 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen
569s 15:45:15.994545470 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen
569s 15:45:16.102385169 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen
570s 15:45:17.404511189 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen
570s 15:45:17.509831814 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen
571s 15:45:18.634335510 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding
571s 15:45:18.740391427 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding
572s 15:45:19.856148187 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict
573s 15:45:19.968950594 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict
574s 15:45:21.265556763 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding
574s 15:45:21.376073087 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding
574s 15:45:21.502402899 O: check_lfwd done (expecting N): AllowTcpForwarding=remote
575s 15:45:22.841583773 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote
576s 15:45:23.960152877 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen
578s 15:45:25.272294457 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen
579s 15:45:26.390677161 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen
580s 15:45:27.710141143 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen
581s 15:45:28.829147545 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen
582s 15:45:29.139292242 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen
583s 15:45:30.260963818 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen
583s 15:45:30.535354216 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen
583s 15:45:30.647304975 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding
583s 15:45:30.751139745 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding
583s 15:45:30.869277605 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict
584s 15:45:30.975602941 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict
584s 15:45:31.098716190 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding
585s 15:45:32.431100333 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding
585s 15:45:32.549394661 O: check_lfwd done (expecting N): AllowTcpForwarding=no
585s 15:45:32.657294560 O: check_rfwd done (expecting N): AllowTcpForwarding=no
586s 15:45:33.775334602 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen
586s 15:45:33.897221998 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen
588s 15:45:35.016057695 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen
588s 15:45:35.137336626 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen
588s 15:45:35.256062534 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen
588s 15:45:35.372101539 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen
588s 15:45:35.487843540 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen
588s 15:45:35.595930148 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen
589s 15:45:36.720483339 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding
589s 15:45:36.837208271 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding
589s 15:45:36.955894415 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict
590s 15:45:37.085981563 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict
590s 15:45:37.200549657 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding
590s 15:45:37.318506454 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding
591s 15:45:38.622064842 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen
591s 15:45:38.736228875 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen
593s 15:45:40.035013598 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen
594s 15:45:41.375533950 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen
595s 15:45:42.684161353 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen
595s 15:45:42.804551837 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen
597s 15:45:44.105051991 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen
597s 15:45:44.212817617 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen
598s 15:45:45.327176360 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen
598s 15:45:45.439205908 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen
599s 15:45:46.556844679 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen
600s 15:45:47.904403602 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen
602s 15:45:49.036246363 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen
602s 15:45:49.141950208 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen
603s 15:45:50.264208788 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen
603s 15:45:50.375791690 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen
603s 15:45:50.394010828 O: ok sshd control of local and remote forwarding
603s 15:45:50.394892173 E: run test integrity.sh ...
603s 15:45:50.563405838 O: test integrity: hmac-sha1 @2900
603s 15:45:50.709660490 O: test integrity: hmac-sha1 @2901
603s 15:45:50.836082752 O: test integrity: hmac-sha1 @2902
604s 15:45:50.976227637 O: test integrity: hmac-sha1 @2903
604s 15:45:51.118359953 O: test integrity: hmac-sha1 @2904
604s 15:45:51.250731506 O: test integrity: hmac-sha1 @2905
604s 15:45:51.390556737 O: test integrity: hmac-sha1 @2906
604s 15:45:51.525932599 O: test integrity: hmac-sha1 @2907
604s 15:45:51.646454756 O: test integrity: hmac-sha1 @2908
604s 15:45:51.763138575 O: test integrity: hmac-sha1 @2909
604s 15:45:51.889420647 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
604s 15:45:51.908705196 O: test integrity: hmac-sha1-96 @2900
605s 15:45:52.042542440 O: test integrity: hmac-sha1-96 @2901
605s 15:45:52.167353998 O: test integrity: hmac-sha1-96 @2902
605s 15:45:52.282472034 O: test integrity: hmac-sha1-96 @2903
605s 15:45:52.395863336 O: test integrity: hmac-sha1-96 @2904
605s 15:45:52.520975033 O: test integrity: hmac-sha1-96 @2905
605s 15:45:52.657628995 O: test integrity: hmac-sha1-96 @2906
605s 15:45:52.788516007 O: test integrity: hmac-sha1-96 @2907
605s 15:45:52.917852916 O: test integrity: hmac-sha1-96 @2908
606s 15:45:53.026858760 O: test integrity: hmac-sha1-96 @2909
606s 15:45:53.128466788 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
606s 15:45:53.145057922 O: test integrity: hmac-sha2-256 @2900
606s 15:45:53.259871800 O: test integrity: hmac-sha2-256 @2901
606s 15:45:53.380733673 O: test integrity: hmac-sha2-256 @2902
606s 15:45:53.491331017 O: test integrity: hmac-sha2-256 @2903
606s 15:45:53.605939132 O: test integrity: hmac-sha2-256 @2904
606s 15:45:53.714969590 O: test integrity: hmac-sha2-256 @2905
606s 15:45:53.823072747 O: test integrity: hmac-sha2-256 @2906
606s 15:45:53.945278309 O: test integrity: hmac-sha2-256 @2907
607s 15:45:54.061626238 O: test integrity: hmac-sha2-256 @2908
607s 15:45:54.168472067 O: test integrity: hmac-sha2-256 @2909
607s 15:45:54.269680907 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
607s 15:45:54.285316797 O: test integrity: hmac-sha2-512 @2900
607s 15:45:54.367083760 O: test integrity: hmac-sha2-512 @2901
607s 15:45:54.444464521 O: test integrity: hmac-sha2-512 @2902
607s 15:45:54.524808006 O: test integrity: hmac-sha2-512 @2903
607s 15:45:54.601672592 O: test integrity: hmac-sha2-512 @2904
607s 15:45:54.679446859 O: test integrity: hmac-sha2-512 @2905
607s 15:45:54.757798614 O: test integrity: hmac-sha2-512 @2906
607s 15:45:54.836845947 O: test integrity: hmac-sha2-512 @2907
607s 15:45:54.922108838 O: test integrity: hmac-sha2-512 @2908
608s 15:45:55.005945457 O: test integrity: hmac-sha2-512 @2909
608s 15:45:55.080561341 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
608s 15:45:55.096571943 O: test integrity: hmac-md5 @2900
608s 15:45:55.209122135 O: test integrity: hmac-md5 @2901
608s 15:45:55.321136425 O: test integrity: hmac-md5 @2902
608s 15:45:55.434000973 O: test integrity: hmac-md5 @2903
608s 15:45:55.545719621 O: test integrity: hmac-md5 @2904
608s 15:45:55.675700408 O: test integrity: hmac-md5 @2905
608s 15:45:55.837824564 O: test integrity: hmac-md5 @2906
608s 15:45:55.950668945 O: test integrity: hmac-md5 @2907
609s 15:45:56.057436421 O: test integrity: hmac-md5 @2908
609s 15:45:56.163247035 O: test integrity: hmac-md5 @2909
609s 15:45:56.261665332 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
609s 15:45:56.276599404 O: test integrity: hmac-md5-96 @2900
609s 15:45:56.383164217 O: test integrity: hmac-md5-96 @2901
609s 15:45:56.490936604 O: test integrity: hmac-md5-96 @2902
609s 15:45:56.602501636 O: test integrity: hmac-md5-96 @2903
609s 15:45:56.728855588 O: test integrity: hmac-md5-96 @2904
609s 15:45:56.843203518 O: test integrity: hmac-md5-96 @2905
609s 15:45:56.953259800 O: test integrity: hmac-md5-96 @2906
610s 15:45:57.062303909 O: test integrity: hmac-md5-96 @2907
610s 15:45:57.189040407 O: test integrity: hmac-md5-96 @2908
610s 15:45:57.317853804 O: test integrity: hmac-md5-96 @2909
610s 15:45:57.430096802 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
610s 15:45:57.449919868 O: test integrity: umac-64@openssh.com @2900
610s 15:45:57.575504785 O: test integrity: umac-64@openssh.com @2901
610s 15:45:57.704191315 O: test integrity: umac-64@openssh.com @2902
610s 15:45:57.849860696 O: test integrity: umac-64@openssh.com @2903
611s 15:45:57.996810409 O: test integrity: umac-64@openssh.com @2904
611s 15:45:58.119739726 O: test integrity: umac-64@openssh.com @2905
611s 15:45:58.248977407 O: test integrity: umac-64@openssh.com @2906
611s 15:45:58.369078503 O: test integrity: umac-64@openssh.com @2907
611s 15:45:58.503370765 O: test integrity: umac-64@openssh.com @2908
611s 15:45:58.649169380 O: test integrity: umac-64@openssh.com @2909
611s 15:45:58.786198756 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
611s 15:45:58.808227563 O: test integrity: umac-128@openssh.com @2900
611s 15:45:58.953158026 O: test integrity: umac-128@openssh.com @2901
612s 15:45:59.073881420 O: test integrity: umac-128@openssh.com @2902
612s 15:45:59.188821093 O: test integrity: umac-128@openssh.com @2903
612s 15:45:59.319632266 O: test integrity: umac-128@openssh.com @2904
612s 15:45:59.461430190 O: test integrity: umac-128@openssh.com @2905
612s 15:45:59.617132056 O: test integrity: umac-128@openssh.com @2906
612s 15:45:59.764098595 O: test integrity: umac-128@openssh.com @2907
612s 15:45:59.905215397 O: test integrity: umac-128@openssh.com @2908
613s 15:46:00.043572599 O: test integrity: umac-128@openssh.com @2909
613s 15:46:00.185707095 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
613s 15:46:00.213159487 O: test integrity: hmac-sha1-etm@openssh.com @2900
613s 15:46:00.385678221 O: test integrity: hmac-sha1-etm@openssh.com @2901
613s 15:46:00.569492908 O: test integrity: hmac-sha1-etm@openssh.com @2902
613s 15:46:00.756025250 O: test integrity: hmac-sha1-etm@openssh.com @2903
613s 15:46:00.921132530 O: test integrity: hmac-sha1-etm@openssh.com @2904
614s 15:46:01.086009387 O: test integrity: hmac-sha1-etm@openssh.com @2905
614s 15:46:01.241464479 O: test integrity: hmac-sha1-etm@openssh.com @2906
614s 15:46:01.374027064 O: test integrity: hmac-sha1-etm@openssh.com @2907
614s 15:46:01.492512982 O: test integrity: hmac-sha1-etm@openssh.com @2908
614s 15:46:01.688256129 O: test integrity: hmac-sha1-etm@openssh.com @2909
614s 15:46:01.861145660 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
614s 15:46:01.891029749 O: test integrity: hmac-sha1-96-etm@openssh.com @2900
615s 15:46:02.052817325 O: test integrity: hmac-sha1-96-etm@openssh.com @2901
615s 15:46:02.243473424 O: test integrity: hmac-sha1-96-etm@openssh.com @2902
615s 15:46:02.438129594 O: test integrity: hmac-sha1-96-etm@openssh.com @2903
615s 15:46:02.600551105 O: test integrity: hmac-sha1-96-etm@openssh.com @2904
615s 15:46:02.743303820 O: test integrity: hmac-sha1-96-etm@openssh.com @2905
615s 15:46:02.853430981 O: test integrity: hmac-sha1-96-etm@openssh.com @2906
615s 15:46:02.960345131 O: test integrity: hmac-sha1-96-etm@openssh.com @2907
616s 15:46:03.087116064 O: test integrity: hmac-sha1-96-etm@openssh.com @2908
616s 15:46:03.261020762 O: test integrity: hmac-sha1-96-etm@openssh.com @2909
616s 15:46:03.371427015 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
616s 15:46:03.387124543 O: test integrity: hmac-sha2-256-etm@openssh.com @2900
616s 15:46:03.503067199 O: test integrity: hmac-sha2-256-etm@openssh.com @2901
616s 15:46:03.620157180 O: test integrity: hmac-sha2-256-etm@openssh.com @2902
616s 15:46:03.734727393 O: test integrity: hmac-sha2-256-etm@openssh.com @2903
616s 15:46:03.847357119 O: test integrity: hmac-sha2-256-etm@openssh.com @2904
616s 15:46:03.959932245 O: test integrity: hmac-sha2-256-etm@openssh.com @2905
617s 15:46:04.072545126 O: test integrity: hmac-sha2-256-etm@openssh.com @2906
617s 15:46:04.185497495 O: test integrity: hmac-sha2-256-etm@openssh.com @2907
617s 15:46:04.296563871 O: test integrity: hmac-sha2-256-etm@openssh.com @2908
617s 15:46:04.413522856 O: test integrity: hmac-sha2-256-etm@openssh.com @2909
617s 15:46:04.530759340 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
617s 15:46:04.546890669 O: test integrity: hmac-sha2-512-etm@openssh.com @2900
617s 15:46:04.624087403 O: test integrity: hmac-sha2-512-etm@openssh.com @2901
617s 15:46:04.704606203 O: test integrity: hmac-sha2-512-etm@openssh.com @2902
617s 15:46:04.781208006 O: test integrity: hmac-sha2-512-etm@openssh.com @2903
617s 15:46:04.858168508 O: test integrity: hmac-sha2-512-etm@openssh.com @2904
617s 15:46:04.935230399 O: test integrity: hmac-sha2-512-etm@openssh.com @2905
618s 15:46:05.012046172 O: test integrity: hmac-sha2-512-etm@openssh.com @2906
618s 15:46:05.092904664 O: test integrity: hmac-sha2-512-etm@openssh.com @2907
618s 15:46:05.172473827 O: test integrity: hmac-sha2-512-etm@openssh.com @2908
618s 15:46:05.253034853 O: test integrity: hmac-sha2-512-etm@openssh.com @2909
618s 15:46:05.322278894 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
618s 15:46:05.338286384 O: test integrity: hmac-md5-etm@openssh.com @2900
618s 15:46:05.450672033 O: test integrity: hmac-md5-etm@openssh.com @2901
618s 15:46:05.568079927 O: test integrity: hmac-md5-etm@openssh.com @2902
618s 15:46:05.689370084 O: test integrity: hmac-md5-etm@openssh.com @2903
618s 15:46:05.799522700 O: test integrity: hmac-md5-etm@openssh.com @2904
618s 15:46:05.915278278 O: test integrity: hmac-md5-etm@openssh.com @2905
619s 15:46:06.027138717 O: test integrity: hmac-md5-etm@openssh.com @2906
619s 15:46:06.138581765 O: test integrity: hmac-md5-etm@openssh.com @2907
619s 15:46:06.248562350 O: test integrity: hmac-md5-etm@openssh.com @2908
619s 15:46:06.356295886 O: test integrity: hmac-md5-etm@openssh.com @2909
619s 15:46:06.461438320 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
619s 15:46:06.478214568 O: test integrity: hmac-md5-96-etm@openssh.com @2900
619s 15:46:06.597170584 O: test integrity: hmac-md5-96-etm@openssh.com @2901
619s 15:46:06.722456126 O: test integrity: hmac-md5-96-etm@openssh.com @2902
619s 15:46:06.834897258 O: test integrity: hmac-md5-96-etm@openssh.com @2903
619s 15:46:06.941996429 O: test integrity: hmac-md5-96-etm@openssh.com @2904
620s 15:46:07.054177740 O: test integrity: hmac-md5-96-etm@openssh.com @2905
620s 15:46:07.165503347 O: test integrity: hmac-md5-96-etm@openssh.com @2906
620s 15:46:07.278538359 O: test integrity: hmac-md5-96-etm@openssh.com @2907
620s 15:46:07.390763857 O: test integrity: hmac-md5-96-etm@openssh.com @2908
620s 15:46:07.505695753 O: test integrity: hmac-md5-96-etm@openssh.com @2909
620s 15:46:07.608928417 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
620s 15:46:07.625412489 O: test integrity: umac-64-etm@openssh.com @2900
620s 15:46:07.804137552 O: test integrity: umac-64-etm@openssh.com @2901
620s 15:46:07.942347625 O: test integrity: umac-64-etm@openssh.com @2902
621s 15:46:08.058740346 O: test integrity: umac-64-etm@openssh.com @2903
621s 15:46:08.185320238 O: test integrity: umac-64-etm@openssh.com @2904
621s 15:46:08.304426443 O: test integrity: umac-64-etm@openssh.com @2905
621s 15:46:08.435872218 O: test integrity: umac-64-etm@openssh.com @2906
621s 15:46:08.552940535 O: test integrity: umac-64-etm@openssh.com @2907
621s 15:46:08.673148680 O: test integrity: umac-64-etm@openssh.com @2908
621s 15:46:08.799006403 O: test integrity: umac-64-etm@openssh.com @2909
621s 15:46:08.900101701 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
621s 15:46:08.915633595 O: test integrity: umac-128-etm@openssh.com @2900
622s 15:46:09.032784550 O: test integrity: umac-128-etm@openssh.com @2901
622s 15:46:09.147010504 O: test integrity: umac-128-etm@openssh.com @2902
622s 15:46:09.267395359 O: test integrity: umac-128-etm@openssh.com @2903
622s 15:46:09.397706042 O: test integrity: umac-128-etm@openssh.com @2904
622s 15:46:09.529866094 O: test integrity: umac-128-etm@openssh.com @2905
622s 15:46:09.675906662 O: test integrity: umac-128-etm@openssh.com @2906
622s 15:46:09.797131807 O: test integrity: umac-128-etm@openssh.com @2907
622s 15:46:09.911413937 O: test integrity: umac-128-etm@openssh.com @2908
623s 15:46:10.030828401 O: test integrity: umac-128-etm@openssh.com @2909
623s 15:46:10.142469534 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
623s 15:46:10.160855405 O: test integrity: aes128-gcm@openssh.com @2900
623s 15:46:10.246319487 O: test integrity: aes128-gcm@openssh.com @2901
623s 15:46:10.333009279 O: test integrity: aes128-gcm@openssh.com @2902
623s 15:46:10.418668114 O: test integrity: aes128-gcm@openssh.com @2903
623s 15:46:10.506356383 O: test integrity: aes128-gcm@openssh.com @2904
623s 15:46:10.594913735 O: test integrity: aes128-gcm@openssh.com @2905
623s 15:46:10.681856610 O: test integrity: aes128-gcm@openssh.com @2906
623s 15:46:10.764087549 O: test integrity: aes128-gcm@openssh.com @2907
623s 15:46:10.846572834 O: test integrity: aes128-gcm@openssh.com @2908
623s 15:46:10.926625403 O: test integrity: aes128-gcm@openssh.com @2909
624s 15:46:10.997936594 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
624s 15:46:11.014131341 O: test integrity: aes256-gcm@openssh.com @2900
624s 15:46:11.096346988 O: test integrity: aes256-gcm@openssh.com @2901
624s 15:46:11.177359836 O: test integrity: aes256-gcm@openssh.com @2902
624s 15:46:11.255646902 O: test integrity: aes256-gcm@openssh.com @2903
624s 15:46:11.335152383 O: test integrity: aes256-gcm@openssh.com @2904
624s 15:46:11.415553208 O: test integrity: aes256-gcm@openssh.com @2905
624s 15:46:11.505958343 O: test integrity: aes256-gcm@openssh.com @2906
624s 15:46:11.600385171 O: test integrity: aes256-gcm@openssh.com @2907
624s 15:46:11.684682910 O: test integrity: aes256-gcm@openssh.com @2908
624s 15:46:11.769525265 O: test integrity: aes256-gcm@openssh.com @2909
624s 15:46:11.840236622 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
624s 15:46:11.857038023 O: test integrity: chacha20-poly1305@openssh.com @2900
624s 15:46:11.938395346 O: test integrity: chacha20-poly1305@openssh.com @2901
625s 15:46:12.019535965 O: test integrity: chacha20-poly1305@openssh.com @2902
625s 15:46:12.095681944 O: test integrity: chacha20-poly1305@openssh.com @2903
625s 15:46:12.193163399 O: test integrity: chacha20-poly1305@openssh.com @2904
625s 15:46:12.272022129 O: test integrity: chacha20-poly1305@openssh.com @2905
625s 15:46:12.355828746 O: test integrity: chacha20-poly1305@openssh.com @2906
625s 15:46:12.441463591 O: test integrity: chacha20-poly1305@openssh.com @2907
625s 15:46:12.529333331 O: test integrity: chacha20-poly1305@openssh.com @2908
625s 15:46:12.613522954 O: test integrity: chacha20-poly1305@openssh.com @2909
625s 15:46:12.687290609 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0
625s 15:46:12.689707175 O: ok integrity
625s 15:46:12.690667890 E: run test krl.sh ...
625s 15:46:12.848330156 O: key revocation lists: generating test keys
626s 15:46:13.263694735 O: key revocation lists: generating KRLs
626s 15:46:13.347144561 O: key revocation lists: checking revocations for revoked keys
626s 15:46:13.787354921 O: key revocation lists: checking revocations for unrevoked keys
627s 15:46:14.233026236 O: key revocation lists: checking revocations for revoked certs
627s 15:46:14.889352210 O: key revocation lists: checking revocations for unrevoked certs
628s 15:46:15.550732984 O: key revocation lists: testing KRL update
629s 15:46:16.302301972 O: key revocation lists: checking revocations for revoked keys
629s 15:46:16.756428046 O: key revocation lists: checking revocations for unrevoked keys
630s 15:46:17.203595847 O: key revocation lists: checking revocations for revoked certs
630s 15:46:17.857601510 O: key revocation lists: checking revocations for unrevoked certs
631s 15:46:18.499025522 O: ok key revocation lists
631s 15:46:18.499849843 E: run test multipubkey.sh ...
632s 15:46:19.587985546 O: ok multiple pubkey
632s 15:46:19.588699350 E: run test limit-keytype.sh ...
633s 15:46:20.512127558 O: allow rsa,ed25519
633s 15:46:20.787628835 O: allow ed25519
634s 15:46:21.006812667 O: allow cert only
634s 15:46:21.244278998 O: match w/ no match
634s 15:46:21.487683180 O: match w/ matching
634s 15:46:21.743847261 O: ok restrict pubkey type
634s 15:46:21.743889210 E: run test hostkey-agent.sh ...
635s 15:46:22.098249254 O: key type ssh-ed25519
635s 15:46:22.189533274 O: key type sk-ssh-ed25519@openssh.com
635s 15:46:22.282041574 O: key type ecdsa-sha2-nistp256
635s 15:46:22.366677163 O: key type ecdsa-sha2-nistp384
635s 15:46:22.462419710 O: key type ecdsa-sha2-nistp521
635s 15:46:22.543378294 O: key type sk-ecdsa-sha2-nistp256@openssh.com
635s 15:46:22.625309788 O: key type ssh-rsa
635s 15:46:22.718424891 O: cert type ssh-ed25519-cert-v01@openssh.com
635s 15:46:22.820968275 O: cert type sk-ssh-ed25519-cert-v01@openssh.com
635s 15:46:22.927114153 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com
636s 15:46:23.032160599 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com
636s 15:46:23.140858287 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com
636s 15:46:23.250482607 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com
636s 15:46:23.364179182 O: cert type ssh-rsa-cert-v01@openssh.com
636s 15:46:23.471393346 O: cert type rsa-sha2-256-cert-v01@openssh.com
636s 15:46:23.576271282 O: cert type rsa-sha2-512-cert-v01@openssh.com
636s 15:46:23.691332929 O: ok hostkey agent
636s 15:46:23.692496945 E: run test hostkey-rotate.sh ...
637s 15:46:24.015544916 O: learn hostkey with StrictHostKeyChecking=no
637s 15:46:24.113477291 O: learn additional hostkeys
637s 15:46:24.271048736 O: learn additional hostkeys, type=ssh-ed25519
637s 15:46:24.376133365 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com
637s 15:46:24.486007160 O: learn additional hostkeys, type=ecdsa-sha2-nistp256
637s 15:46:24.585484384 O: learn additional hostkeys, type=ecdsa-sha2-nistp384
637s 15:46:24.690235360 O: learn additional hostkeys, type=ecdsa-sha2-nistp521
637s 15:46:24.788832077 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com
637s 15:46:24.894924441 O: learn additional hostkeys, type=ssh-rsa
638s 15:46:24.994070756 O: learn changed non-primary hostkey type=ssh-rsa
638s 15:46:25.212171941 O: learn new primary hostkey
638s 15:46:25.379474304 O: rotate primary hostkey
638s 15:46:25.522334380 O: check rotate primary hostkey
638s 15:46:25.629505098 O: ok hostkey rotate
638s 15:46:25.630206530 E: run test principals-command.sh ...
639s 15:46:25.974937592 O: SKIPPED: /var/run/principals_command_openssh-tests.59437 not executable  (/var/run mounted noexec?)
639s 15:46:25.984583569 E: run test cert-file.sh ...
639s 15:46:26.197346116 O: identity cert with no plain public file
639s 15:46:26.333488169 O: CertificateFile with no plain public file
639s 15:46:26.451905606 O: plain keys
639s 15:46:26.569661035 O: untrusted cert
639s 15:46:26.685063263 O: good cert, bad key
639s 15:46:26.814406934 O: single trusted
639s 15:46:26.916901604 O: multiple trusted
640s 15:46:27.398690854 O: ok ssh with certificates
640s 15:46:27.398761576 E: run test cfginclude.sh ...
640s 15:46:27.547738190 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.555649767 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.564620593 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.572512031 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.580295206 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.588422045 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.596213085 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.604352597 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.612757124 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.630728183 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.638824584 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.647531768 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.662732654 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.672406476 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.682691350 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.691518287 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.700499523 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.709518740 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.717661258 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.726531567 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.736786454 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.754474613 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.763128036 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.771021148 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.788727301 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.797769610 E: Pseudo-terminal will not be allocated because stdin is not a terminal.
640s 15:46:27.804112051 O: ok config include
640s 15:46:27.805112893 E: run test servcfginclude.sh ...
641s 15:46:28.205137753 O: ok server config include
641s 15:46:28.206354919 E: run test allow-deny-users.sh ...
642s 15:46:29.240037631 O: ok AllowUsers/DenyUsers
642s 15:46:29.241290474 E: run test authinfo.sh ...
642s 15:46:29.389391311 O: ExposeAuthInfo=no
642s 15:46:29.491827073 O: ExposeAuthInfo=yes
642s 15:46:29.592442640 O: ok authinfo
642s 15:46:29.592456736 E: run test sshsig.sh ...
642s 15:46:29.750088359 O: sshsig: make certificates
642s 15:46:29.779080241 O: sshsig: check signature for ssh-ed25519
643s 15:46:30.124901074 O: sshsig: check signature for sk-ssh-ed25519@openssh.com
643s 15:46:30.515600380 O: sshsig: check signature for ecdsa-sha2-nistp256
643s 15:46:30.827841242 O: sshsig: check signature for ecdsa-sha2-nistp384
644s 15:46:31.160800386 O: sshsig: check signature for ecdsa-sha2-nistp521
644s 15:46:31.519020379 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com
644s 15:46:31.829930833 O: sshsig: check signature for ssh-rsa
645s 15:46:32.155962121 O: sshsig: check signature for ssh-ed25519-cert.pub
645s 15:46:32.776233785 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub
646s 15:46:33.422025651 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub
647s 15:46:33.982221933 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub
647s 15:46:34.598402534 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub
648s 15:46:35.306188833 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub
648s 15:46:35.888208882 O: sshsig: check signature for ssh-rsa-cert.pub
649s 15:46:36.517121215 O: sshsig: match principals
649s 15:46:36.566826110 O: sshsig: nomatch principals
649s 15:46:36.584331647 E: run test knownhosts.sh ...
649s 15:46:36.584803049 O: ok sshsig
650s 15:46:37.416139894 O: ok known hosts
650s 15:46:37.415864490 E: run test knownhosts-command.sh ...
650s 15:46:37.554356178 O: simple connection
650s 15:46:37.655173035 O: no keys
650s 15:46:37.710644529 O: bad exit status
650s 15:46:37.757470241 O: keytype ssh-ed25519
650s 15:46:37.865346985 O: keytype sk-ssh-ed25519@openssh.com
651s 15:46:37.972046897 O: keytype ecdsa-sha2-nistp256
651s 15:46:38.072177023 O: keytype ecdsa-sha2-nistp384
651s 15:46:38.173901826 O: keytype ecdsa-sha2-nistp521
651s 15:46:38.276336325 O: keytype sk-ecdsa-sha2-nistp256@openssh.com
651s 15:46:38.383117746 O: keytype ssh-rsa
651s 15:46:38.498878448 O: ok known hosts command
651s 15:46:38.500546208 E: run test agent-restrict.sh ...
651s 15:46:38.635616233 O: generate keys
651s 15:46:38.700398591 O: prepare client config
651s 15:46:38.716925009 O: prepare known_hosts
651s 15:46:38.722639210 O: prepare server configs
651s 15:46:38.742491580 O: authentication w/o agent
652s 15:46:39.634785827 O: start agent
656s 15:46:43.641269270 O: authentication with agent (no restrict)
657s 15:46:44.515255286 O: unrestricted keylist
658s 15:46:44.992824952 O: authentication with agent (basic restrict)
658s 15:46:45.492954232 O: authentication with agent incorrect key (basic restrict)
658s 15:46:45.777331712 O: keylist (basic restrict)
659s 15:46:46.303501670 O: username
659s 15:46:46.826998228 O: username wildcard
660s 15:46:47.337186021 O: username incorrect
660s 15:46:47.421628637 O: agent restriction honours certificate principal
660s 15:46:47.462543574 O: multihop without agent
661s 15:46:48.104847206 O: multihop agent unrestricted
661s 15:46:48.693810263 O: multihop restricted
662s 15:46:49.254472182 O: multihop username
662s 15:46:49.801272125 O: multihop wildcard username
663s 15:46:50.373411152 O: multihop wrong username
663s 15:46:50.758263249 O: multihop cycle no agent
664s 15:46:51.570180963 O: multihop cycle agent unrestricted
665s 15:46:52.399217382 O: multihop cycle restricted deny
665s 15:46:52.726144717 O: multihop cycle restricted allow
666s 15:46:53.615618075 O: ok agent restrictions
666s 15:46:53.616708367 E: run test hostbased.sh ...
666s 15:46:53.759261879 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set.
666s 15:46:53.759713935 E: run test channel-timeout.sh ...
666s 15:46:53.902401449 O: no timeout
672s 15:46:59.011418257 O: command timeout
672s 15:46:59.252674185 O: command long timeout
672s 15:46:59.356468345 O: command wildcard timeout
673s 15:47:00.254452687 O: command irrelevant timeout
678s 15:47:05.360030926 O: multiplexed command timeout
689s 15:47:16.276591551 O: irrelevant multiplexed command timeout
704s 15:47:31.398662208 O: global command timeout
726s 15:47:53.288135758 O: sftp no timeout
731s 15:47:58.402063208 O: sftp timeout
732s 15:47:59.253455703 E: Connection closed
732s 15:47:59.253414175 O: sftp irrelevant timeout
737s 15:48:04.369671789 O: ok channel timeout
737s 15:48:04.370686952 E: run test connection-timeout.sh ...
737s 15:48:04.507358205 O: no timeout
742s 15:48:09.622732114 O: timeout
750s 15:48:17.708920511 O: session inhibits timeout
758s 15:48:25.872772261 O: timeout after session
766s 15:48:33.879992407 O: timeout with listeners
775s 15:48:41.977547516 E: run test match-subsystem.sh ...
775s 15:48:41.978653620 O: ok unused connection timeout
776s 15:48:43.031052659 O: ok sshd_config match subsystem
776s 15:48:43.032375621 E: run test agent-pkcs11-restrict.sh ...
776s 15:48:43.168969163 O: SKIPPED: No PKCS#11 library found
776s 15:48:43.169672623 E: run test agent-pkcs11-cert.sh ...
776s 15:48:43.300668504 O: SKIPPED: No PKCS#11 library found
776s 15:48:43.301606904 E: run test penalty.sh ...
776s 15:48:43.490883173 O: test connect
776s 15:48:43.681567761 O: penalty for authentication failure
783s 15:48:50.506375813 O: penalty for no authentication
785s 15:48:52.554457070 O: ok penalties
785s 15:48:52.554481536 E: run test penalty-expire.sh ...
785s 15:48:52.735045191 O: test connect
785s 15:48:52.933624651 O: penalty expiry
799s 15:49:06.337681337 O: ok penalties
799s 15:49:06.338796099 O: set -e ; if test -z "" ; then \
799s 15:49:06.339854414 O: 	V="" ; \
799s 15:49:06.340941023 O: 	test "x" = "x" || \
799s 15:49:06.342108924 O: 	    V=/tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \
799s 15:49:06.343206544 O: 	 $V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \
799s 15:49:06.344416695 O: 	 $V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \
799s 15:49:06.345500840 O: 		-d /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \
799s 15:49:06.346593289 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \
799s 15:49:06.347682542 O: 		-d /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \
799s 15:49:06.348800310 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \
799s 15:49:06.350058360 O: 		-d /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \
799s 15:49:06.351245188 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \
799s 15:49:06.352364357 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \
799s 15:49:06.353412153 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \
799s 15:49:06.354511636 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \
799s 15:49:06.355556548 O: 		-d /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \
799s 15:49:06.356628829 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/match/test_match ; \
799s 15:49:06.357736798 O: 	$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \
799s 15:49:06.358859675 O: 	if test "x" = "xyes"  ; then \
799s 15:49:06.359925185 O: 		$V /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \
799s 15:49:06.360973281 O: 	fi \
799s 15:49:06.361993094 O: fi
804s 15:49:11.181865499 O: test_sshbuf: ...................................................................................................... 103 tests ok
837s 15:49:44.617970345 O: test_sshkey: .................................................................................... 84 tests ok
837s 15:49:44.625236114 O: test_sshsig: ....... 7 tests ok
837s 15:49:44.787075248 O: test_authopt: .................................................................................................................................................. 146 tests ok
846s 15:49:53.281718153 O: test_bitmap: .. 2 tests ok
846s 15:49:53.284896831 O: test_conversion: . 1 tests ok
849s 15:49:56.689688471 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok
849s 15:49:56.721500277 O: test_hostkeys: .................. 18 tests ok
849s 15:49:56.724454282 O: test_match: ...... 6 tests ok
849s 15:49:56.727415512 O: test_misc: ........................................... 43 tests ok
849s 15:49:56.730019790 E: run test putty-transfer.sh ...
850s 15:49:57.187593804 O: plink version 0.83 major 0 minor 83
850s 15:49:57.199177981 O: putty transfer data: compression 0
851s 15:49:58.185640841 O: putty transfer data: compression 1
852s 15:49:59.257628464 O: ok putty transfer data
852s 15:49:59.256419133 E: run test putty-ciphers.sh ...
853s 15:50:00.004046539 O: plink version 0.83 major 0 minor 83
853s 15:50:00.348235764 O: putty ciphers: cipher default mac default
853s 15:50:00.465061875 O: putty ciphers: cipher default mac hmac-sha1
853s 15:50:00.584303410 O: putty ciphers: cipher default mac hmac-sha1-96
853s 15:50:00.703230244 O: putty ciphers: cipher default mac hmac-sha2-256
853s 15:50:00.815227360 O: putty ciphers: cipher default mac hmac-sha2-512
853s 15:50:00.926432428 O: putty ciphers: cipher default mac hmac-md5
854s 15:50:01.034647704 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com
854s 15:50:01.140326351 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com
854s 15:50:01.251318270 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com
854s 15:50:01.359006688 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com
854s 15:50:01.486908272 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com
854s 15:50:01.595080518 O: putty ciphers: cipher 3des-cbc mac default
854s 15:50:01.704311062 O: putty ciphers: cipher 3des-cbc mac hmac-sha1
854s 15:50:01.819860808 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96
854s 15:50:01.936730101 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256
855s 15:50:02.065603179 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512
855s 15:50:02.175434131 O: putty ciphers: cipher 3des-cbc mac hmac-md5
855s 15:50:02.283961695 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com
855s 15:50:02.412135141 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com
855s 15:50:02.549057150 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com
855s 15:50:02.666028596 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com
855s 15:50:02.776189126 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com
855s 15:50:02.892987638 O: putty ciphers: cipher aes128-cbc mac default
856s 15:50:03.017206721 O: putty ciphers: cipher aes128-cbc mac hmac-sha1
856s 15:50:03.149638231 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96
856s 15:50:03.259359117 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256
856s 15:50:03.375891942 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512
856s 15:50:03.488851919 O: putty ciphers: cipher aes128-cbc mac hmac-md5
856s 15:50:03.606900228 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com
856s 15:50:03.713636863 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com
856s 15:50:03.821547581 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com
856s 15:50:03.927149706 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com
857s 15:50:04.040287146 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com
857s 15:50:04.144562402 O: putty ciphers: cipher aes192-cbc mac default
857s 15:50:04.258010798 O: putty ciphers: cipher aes192-cbc mac hmac-sha1
857s 15:50:04.382674347 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96
857s 15:50:04.499098478 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256
857s 15:50:04.638138396 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512
857s 15:50:04.786293397 O: putty ciphers: cipher aes192-cbc mac hmac-md5
857s 15:50:04.895154060 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com
858s 15:50:05.012290148 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com
858s 15:50:05.132512221 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com
858s 15:50:05.259637453 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com
858s 15:50:05.369328237 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com
858s 15:50:05.482333472 O: putty ciphers: cipher aes256-cbc mac default
858s 15:50:05.598365228 O: putty ciphers: cipher aes256-cbc mac hmac-sha1
858s 15:50:05.722938941 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96
858s 15:50:05.855927691 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256
859s 15:50:05.976890164 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512
859s 15:50:06.116429073 O: putty ciphers: cipher aes256-cbc mac hmac-md5
859s 15:50:06.230112562 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com
859s 15:50:06.354236773 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com
859s 15:50:06.461507704 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com
859s 15:50:06.572477269 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com
859s 15:50:06.687628043 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com
859s 15:50:06.802107827 O: putty ciphers: cipher aes128-ctr mac default
859s 15:50:06.919343986 O: putty ciphers: cipher aes128-ctr mac hmac-sha1
860s 15:50:07.035122521 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96
860s 15:50:07.150498489 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256
860s 15:50:07.264194343 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512
860s 15:50:07.383070202 O: putty ciphers: cipher aes128-ctr mac hmac-md5
860s 15:50:07.498069443 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com
860s 15:50:07.616093352 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com
860s 15:50:07.741399994 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com
860s 15:50:07.881544841 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com
861s 15:50:07.984258699 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com
861s 15:50:08.095841648 O: putty ciphers: cipher aes192-ctr mac default
861s 15:50:08.211095298 O: putty ciphers: cipher aes192-ctr mac hmac-sha1
861s 15:50:08.331219964 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96
861s 15:50:08.449367065 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256
861s 15:50:08.565041426 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512
861s 15:50:08.687995973 O: putty ciphers: cipher aes192-ctr mac hmac-md5
861s 15:50:08.818982228 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com
861s 15:50:08.947159961 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com
862s 15:50:09.053549190 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com
862s 15:50:09.177160352 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com
862s 15:50:09.292041605 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com
862s 15:50:09.402278799 O: putty ciphers: cipher aes256-ctr mac default
862s 15:50:09.518524122 O: putty ciphers: cipher aes256-ctr mac hmac-sha1
862s 15:50:09.632136032 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96
862s 15:50:09.741031527 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256
862s 15:50:09.843588132 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512
862s 15:50:09.962871228 O: putty ciphers: cipher aes256-ctr mac hmac-md5
863s 15:50:10.065015489 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com
863s 15:50:10.193420303 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com
863s 15:50:10.317426056 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com
863s 15:50:10.440401836 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com
863s 15:50:10.553083590 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com
863s 15:50:10.669729176 O: putty ciphers: cipher aes128-gcm@openssh.com mac default
863s 15:50:10.785234482 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1
863s 15:50:10.900084617 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96
864s 15:50:11.018036656 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256
864s 15:50:11.132659146 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512
864s 15:50:11.241670712 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5
864s 15:50:11.338067685 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com
864s 15:50:11.445389048 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com
864s 15:50:11.563655257 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com
864s 15:50:11.677671809 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com
864s 15:50:11.791522740 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com
864s 15:50:11.905206478 O: putty ciphers: cipher aes256-gcm@openssh.com mac default
865s 15:50:12.033283275 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1
865s 15:50:12.143711061 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96
865s 15:50:12.245364134 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256
865s 15:50:12.352870074 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512
865s 15:50:12.477085936 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5
865s 15:50:12.585356722 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com
865s 15:50:12.687384969 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com
865s 15:50:12.792816003 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com
865s 15:50:12.911138971 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com
866s 15:50:13.024994202 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com
866s 15:50:13.142702768 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default
866s 15:50:13.258263612 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1
866s 15:50:13.391443733 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96
866s 15:50:13.550933840 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256
866s 15:50:13.651602254 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512
866s 15:50:13.772859573 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5
866s 15:50:13.888485920 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com
867s 15:50:13.999844204 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com
867s 15:50:14.143705200 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com
867s 15:50:14.291180091 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com
867s 15:50:14.407056028 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com
867s 15:50:14.551140644 O: ok putty ciphers
867s 15:50:14.551615314 E: run test putty-kex.sh ...
867s 15:50:14.799344774 O: plink version 0.83 major 0 minor 83
868s 15:50:14.983151515 O: putty KEX: kex diffie-hellman-group1-sha1
868s 15:50:15.069459631 O: putty KEX: kex diffie-hellman-group14-sha1
868s 15:50:15.169465806 O: putty KEX: kex diffie-hellman-group14-sha256
868s 15:50:15.287563224 O: putty KEX: kex diffie-hellman-group16-sha512
868s 15:50:15.483466097 O: putty KEX: kex diffie-hellman-group18-sha512
869s 15:50:16.336921407 O: putty KEX: kex diffie-hellman-group-exchange-sha1
869s 15:50:16.442230257 O: putty KEX: kex diffie-hellman-group-exchange-sha256
869s 15:50:16.655862686 O: putty KEX: kex ecdh-sha2-nistp256
869s 15:50:16.759145293 O: putty KEX: kex ecdh-sha2-nistp384
869s 15:50:16.855165415 O: putty KEX: kex ecdh-sha2-nistp521
870s 15:50:16.965030019 O: putty KEX: kex curve25519-sha256
870s 15:50:17.055293343 O: putty KEX: kex curve25519-sha256@libssh.org
870s 15:50:17.135497572 O: putty KEX: kex sntrup761x25519-sha512
870s 15:50:17.224691178 O: putty KEX: kex sntrup761x25519-sha512@openssh.com
870s 15:50:17.334339166 O: putty KEX: kex mlkem768x25519-sha256
870s 15:50:17.414606964 E: run test conch-ciphers.sh ...
870s 15:50:17.415730092 O: ok putty KEX
870s 15:50:17.610268468 O: conch ciphers: cipher aes256-ctr
871s 15:50:18.254820512 O: conch ciphers: cipher aes256-cbc
871s 15:50:18.861494327 O: conch ciphers: cipher aes192-ctr
872s 15:50:19.475133622 O: conch ciphers: cipher aes192-cbc
873s 15:50:20.080739457 O: conch ciphers: cipher aes128-ctr
873s 15:50:20.760359169 O: conch ciphers: cipher aes128-cbc
874s 15:50:21.370995169 O: conch ciphers: cipher cast128-cbc
875s 15:50:21.987009179 O: conch ciphers: cipher blowfish
875s 15:50:22.589287076 O: conch ciphers: cipher 3des-cbc
876s 15:50:23.212383511 O: ok conch ciphers
876s 15:50:23.212683405 E: run test dropbear-ciphers.sh ...
876s 15:50:23.361351050 O: Create dropbear key type ed25519
876s 15:50:23.380445170 O: Create dropbear key type rsa
876s 15:50:23.493028005 O: Create dropbear key type ecdsa
876s 15:50:23.538012884 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa
876s 15:50:23.706357584 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519
876s 15:50:23.873538818 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa
877s 15:50:24.041738767 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa
877s 15:50:24.212704548 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519
877s 15:50:24.382736617 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa
877s 15:50:24.551673850 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa
877s 15:50:24.721569353 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519
877s 15:50:24.899699476 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa
878s 15:50:25.078713891 O: ok dropbear ciphers
878s 15:50:25.079948469 E: run test dropbear-kex.sh ...
878s 15:50:25.267259440 O: diffie-hellman-group14-sha1
878s 15:50:25.268588827 O: dropbear kex: kex curve25519-sha256
878s 15:50:25.470135748 O: dropbear kex: kex curve25519-sha256@libssh.org
878s 15:50:25.664451642 O: dropbear kex: kex diffie-hellman-group14-sha256
878s 15:50:25.844249349 O: dropbear kex: kex diffie-hellman-group14-sha1
878s 15:50:25.919301090 O: ssh cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data failed
878s 15:50:25.920011595 E: cmp: EOF on /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/copy which is empty
879s 15:50:25.962875964 O: corrupted copy
879s 15:50:25.964149044 O: failed dropbear kex
879s 15:50:25.965381058 O: make: Leaving directory '/tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress'
879s 15:50:25.963479146 E: make: *** [Makefile:277: t-exec-interop] Error 1
879s 15:50:25.967155689 O: ==> /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/failed-regress.log <==
879s 15:50:25.968401127 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.103097.ssh.73851.log
879s 15:50:25.969699265 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.110968.ssh.73858.log
879s 15:50:25.971030574 O: trace: using cached key type ssh-ed25519
879s 15:50:25.972233934 O: trace: using cached key type sk-ssh-ed25519@openssh.com
879s 15:50:25.973393261 O: trace: using cached key type ecdsa-sha2-nistp256
879s 15:50:25.974680527 O: trace: using cached key type ecdsa-sha2-nistp384
879s 15:50:25.975899627 O: trace: using cached key type ecdsa-sha2-nistp521
879s 15:50:25.977136369 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com
879s 15:50:25.978391875 O: trace: using cached key type ssh-rsa
879s 15:50:25.979728225 O: trace: Create dropbear keys and add to authorized_keys
879s 15:50:25.980979914 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.199024.ssh.73904.log
879s 15:50:25.982210936 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.208141.ssh.73910.log
879s 15:50:25.983407824 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.216088.ssh.73915.log
879s 15:50:25.984637272 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.259124.ssh.73936.log
879s 15:50:25.985898900 O: trace: dropbear kex: kex curve25519-sha256
879s 15:50:25.987157704 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.284709.sshd.73950.log
879s 15:50:25.988383214 O: trace: dropbear kex: kex curve25519-sha256@libssh.org
879s 15:50:25.989652427 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.490156.sshd.73982.log
879s 15:50:25.990848534 O: trace: dropbear kex: kex diffie-hellman-group14-sha256
879s 15:50:25.992067872 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.679059.sshd.74014.log
879s 15:50:25.993181403 O: trace: dropbear kex: kex diffie-hellman-group14-sha1
879s 15:50:25.994370406 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.859635.sshd.74046.log
879s 15:50:25.995492051 O: FAIL: ssh cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data failed
879s 15:50:25.996722442 O: Saving debug logs to /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar
879s 15:50:25.997904381 O: 
879s 15:50:25.999061614 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.103097.ssh.73851.log
879s 15:50:26.000235467 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.110968.ssh.73858.log
879s 15:50:26.001411847 O: trace: using cached key type ssh-ed25519
879s 15:50:26.002820210 O: trace: using cached key type sk-ssh-ed25519@openssh.com
879s 15:50:26.003986050 O: trace: using cached key type ecdsa-sha2-nistp256
879s 15:50:26.005113296 O: trace: using cached key type ecdsa-sha2-nistp384
879s 15:50:26.006229552 O: trace: using cached key type ecdsa-sha2-nistp521
879s 15:50:26.007410118 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com
879s 15:50:26.008532416 O: trace: using cached key type ssh-rsa
879s 15:50:26.009640616 O: trace: Create dropbear keys and add to authorized_keys
879s 15:50:26.010863422 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.199024.ssh.73904.log
879s 15:50:26.012043177 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.208141.ssh.73910.log
879s 15:50:26.013227771 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.216088.ssh.73915.log
879s 15:50:26.014419598 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.259124.ssh.73936.log
879s 15:50:26.015663194 O: trace: dropbear kex: kex curve25519-sha256
879s 15:50:26.016939329 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.284709.sshd.73950.log
879s 15:50:26.018083598 O: trace: dropbear kex: kex curve25519-sha256@libssh.org
879s 15:50:26.019314990 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.490156.sshd.73982.log
879s 15:50:26.020426468 O: trace: dropbear kex: kex diffie-hellman-group14-sha256
879s 15:50:26.021578591 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.679059.sshd.74014.log
879s 15:50:26.022721105 O: trace: dropbear kex: kex diffie-hellman-group14-sha1
879s 15:50:26.024057505 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/log/20250506T155025.859635.sshd.74046.log
879s 15:50:26.025180723 O: FAIL: ssh cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data failed
879s 15:50:26.026364035 O: Saving debug logs to /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar
879s 15:50:26.027702678 O: FAIL: corrupted copy
879s 15:50:26.029011767 O: Saving debug logs to /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar
879s 15:50:26.030130667 O: 
879s 15:50:26.031437221 O: ==> /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/failed-ssh.log <==
879s 15:50:26.032632324 O: FAIL: ssh cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data failed
879s 15:50:26.033875538 O: 
879s 15:50:26.035136325 O: FAIL: ssh cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data failed
879s 15:50:26.036280113 O: FAIL: corrupted copy
879s 15:50:26.037418140 O: 
879s 15:50:26.038590451 O: ==> /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/failed-sshd.log <==
879s 15:50:26.039858140 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy
879s 15:50:26.041054808 O: debug3: send_rexec_state: entering fd = 8 config len 1183
879s 15:50:26.042257175 O: debug3: ssh_msg_send: type 0 len 4944
879s 15:50:26.043442941 O: debug3: ssh_msg_send: done
879s 15:50:26.044598240 O: debug3: send_rexec_state: done
879s 15:50:26.045729696 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9
879s 15:50:26.046921302 O: FAIL: ssh cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data failed
879s 15:50:26.047987675 O: 
879s 15:50:26.049174532 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/sshd_proxy
879s 15:50:26.050343437 O: debug3: send_rexec_state: entering fd = 8 config len 1183
879s 15:50:26.052156972 O: debug3: ssh_msg_send: type 0 len 4944
879s 15:50:26.053337780 O: debug3: ssh_msg_send: done
879s 15:50:26.054479824 O: debug3: send_rexec_state: done
879s 15:50:26.055720723 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9
879s 15:50:26.056922090 O: FAIL: ssh cat /tmp/autopkgtest.68bocB/autopkgtest_tmp/user/regress/data failed
879s 15:50:26.058063302 O: FAIL: corrupted copy
879s 15:50:26.059181561 O: 
879s 15:50:26.061527647 I: Finished with exitcode 2
879s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'.
879s info: Looking for files to backup/remove ...
879s info: Removing files ...
879s info: Removing crontab ...
879s info: Removing user `openssh-tests' ...
879s autopkgtest [15:50:26]: test regress: -----------------------]
880s autopkgtest [15:50:27]: test regress:  - - - - - - - - - - results - - - - - - - - - -
880s regress              FAIL non-zero exit status 2
880s autopkgtest [15:50:27]: test ssh-gssapi: preparing testbed
940s autopkgtest [15:51:27]: testbed dpkg architecture: amd64
940s autopkgtest [15:51:27]: testbed apt version: 3.0.0
941s autopkgtest [15:51:28]: @@@@@@@@@@@@@@@@@@@@ test bed setup
941s autopkgtest [15:51:28]: testbed release detected to be: questing
942s autopkgtest [15:51:29]: updating testbed package index (apt update)
942s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB]
942s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease
942s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease
943s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease
943s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [605 kB]
943s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [71.6 kB]
943s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [27.3 kB]
943s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [148 kB]
943s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [104 kB]
943s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [264 kB]
943s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [670 kB]
943s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [5028 B]
943s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [18.4 kB]
943s Fetched 2023 kB in 1s (2206 kB/s)
944s Reading package lists...
944s autopkgtest [15:51:31]: upgrading testbed (apt dist-upgrade and autopurge)
945s Reading package lists...
945s Building dependency tree...
945s Reading state information...
945s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
945s Starting 2 pkgProblemResolver with broken count: 0
945s Done
946s Entering ResolveByKeep
946s 
946s Calculating upgrade...
946s The following packages will be upgraded:
946s   libdebuginfod-common libdebuginfod1t64 libdw1t64 libelf1t64 libperl5.40
946s   lto-disabled-list perl perl-base perl-modules-5.40 python3-dbus ucf
946s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
946s Need to get 10.7 MB of archives.
946s After this operation, 23.6 kB of additional disk space will be used.
946s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libperl5.40 amd64 5.40.1-3 [4877 kB]
947s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 perl amd64 5.40.1-3 [262 kB]
947s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 perl-base amd64 5.40.1-3 [1840 kB]
947s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 perl-modules-5.40 all 5.40.1-3 [3217 kB]
948s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB]
948s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod-common all 0.193-1 [15.6 kB]
948s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod1t64 amd64 0.193-1 [21.3 kB]
948s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libdw1t64 amd64 0.193-1 [283 kB]
948s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libelf1t64 amd64 0.193-1 [58.6 kB]
948s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 python3-dbus amd64 1.4.0-1 [102 kB]
948s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 lto-disabled-list all 58 [12.4 kB]
948s Preconfiguring packages ...
948s Fetched 10.7 MB in 1s (7601 kB/s)
948s (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.)
948s Preparing to unpack .../libperl5.40_5.40.1-3_amd64.deb ...
948s Unpacking libperl5.40:amd64 (5.40.1-3) over (5.40.1-2) ...
949s Preparing to unpack .../perl_5.40.1-3_amd64.deb ...
949s Unpacking perl (5.40.1-3) over (5.40.1-2) ...
949s Preparing to unpack .../perl-base_5.40.1-3_amd64.deb ...
949s Unpacking perl-base (5.40.1-3) over (5.40.1-2) ...
949s Setting up perl-base (5.40.1-3) ...
949s (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.)
949s Preparing to unpack .../0-perl-modules-5.40_5.40.1-3_all.deb ...
949s Unpacking perl-modules-5.40 (5.40.1-3) over (5.40.1-2) ...
949s Preparing to unpack .../1-ucf_3.0051_all.deb ...
949s Unpacking ucf (3.0051) over (3.0050) ...
949s Preparing to unpack .../2-libdebuginfod-common_0.193-1_all.deb ...
949s Unpacking libdebuginfod-common (0.193-1) over (0.192-4ubuntu1) ...
949s Preparing to unpack .../3-libdebuginfod1t64_0.193-1_amd64.deb ...
949s Unpacking libdebuginfod1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
949s Preparing to unpack .../4-libdw1t64_0.193-1_amd64.deb ...
949s Unpacking libdw1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
949s Preparing to unpack .../5-libelf1t64_0.193-1_amd64.deb ...
949s Unpacking libelf1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
949s Preparing to unpack .../6-python3-dbus_1.4.0-1_amd64.deb ...
950s Unpacking python3-dbus (1.4.0-1) over (1.3.2-5build5) ...
950s Preparing to unpack .../7-lto-disabled-list_58_all.deb ...
950s Unpacking lto-disabled-list (58) over (57) ...
950s Setting up python3-dbus (1.4.0-1) ...
950s Setting up lto-disabled-list (58) ...
950s Setting up libelf1t64:amd64 (0.193-1) ...
950s Setting up libdw1t64:amd64 (0.193-1) ...
950s Setting up ucf (3.0051) ...
950s Setting up perl-modules-5.40 (5.40.1-3) ...
950s Setting up libdebuginfod-common (0.193-1) ...
950s Setting up libperl5.40:amd64 (5.40.1-3) ...
950s Setting up perl (5.40.1-3) ...
950s Setting up libdebuginfod1t64:amd64 (0.193-1) ...
950s Processing triggers for man-db (2.13.1-1) ...
951s Processing triggers for libc-bin (2.41-6ubuntu1) ...
951s Reading package lists...
951s Building dependency tree...
951s Reading state information...
952s Starting pkgProblemResolver with broken count: 0
952s Starting 2 pkgProblemResolver with broken count: 0
952s Done
952s Solving dependencies...
952s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
955s Reading package lists...
955s Building dependency tree...
955s Reading state information...
955s Starting pkgProblemResolver with broken count: 0
955s Starting 2 pkgProblemResolver with broken count: 0
955s Done
956s The following NEW packages will be installed:
956s   krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64
956s   libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64
956s   libverto-libevent1t64 libverto1t64 openssh-client-gssapi
956s   openssh-server-gssapi
956s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded.
956s Need to get 799 kB of archives.
956s After this operation, 2807 kB of additional disk space will be used.
956s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 krb5-config all 2.7 [22.0 kB]
956s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libgssrpc4t64 amd64 1.21.3-4ubuntu2 [58.1 kB]
956s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 libkadm5clnt-mit12 amd64 1.21.3-4ubuntu2 [41.3 kB]
956s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 libkdb5-10t64 amd64 1.21.3-4ubuntu2 [42.0 kB]
956s Get:5 http://ftpmaster.internal/ubuntu questing/main amd64 libkadm5srv-mit12 amd64 1.21.3-4ubuntu2 [55.5 kB]
956s Get:6 http://ftpmaster.internal/ubuntu questing/universe amd64 krb5-user amd64 1.21.3-4ubuntu2 [111 kB]
956s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB]
956s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libverto1t64 amd64 0.3.1-1.2ubuntu3 [10.5 kB]
956s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libverto-libevent1t64 amd64 0.3.1-1.2ubuntu3 [6424 B]
956s Get:10 http://ftpmaster.internal/ubuntu questing/universe amd64 krb5-kdc amd64 1.21.3-4ubuntu2 [197 kB]
956s Get:11 http://ftpmaster.internal/ubuntu questing/universe amd64 krb5-admin-server amd64 1.21.3-4ubuntu2 [100 kB]
956s Get:12 http://ftpmaster.internal/ubuntu questing/universe amd64 openssh-client-gssapi all 1:9.9p1-3ubuntu3.1 [5034 B]
956s Get:13 http://ftpmaster.internal/ubuntu questing/universe amd64 openssh-server-gssapi all 1:9.9p1-3ubuntu3.1 [5038 B]
957s Preconfiguring packages ...
957s Fetched 799 kB in 1s (1408 kB/s)
957s Selecting previously unselected package krb5-config.
957s (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.)
957s Preparing to unpack .../00-krb5-config_2.7_all.deb ...
957s Unpacking krb5-config (2.7) ...
957s Selecting previously unselected package libgssrpc4t64:amd64.
957s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_amd64.deb ...
957s Unpacking libgssrpc4t64:amd64 (1.21.3-4ubuntu2) ...
957s Selecting previously unselected package libkadm5clnt-mit12:amd64.
957s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_amd64.deb ...
957s Unpacking libkadm5clnt-mit12:amd64 (1.21.3-4ubuntu2) ...
957s Selecting previously unselected package libkdb5-10t64:amd64.
957s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_amd64.deb ...
957s Unpacking libkdb5-10t64:amd64 (1.21.3-4ubuntu2) ...
957s Selecting previously unselected package libkadm5srv-mit12:amd64.
957s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_amd64.deb ...
957s Unpacking libkadm5srv-mit12:amd64 (1.21.3-4ubuntu2) ...
957s Selecting previously unselected package krb5-user.
957s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_amd64.deb ...
957s Unpacking krb5-user (1.21.3-4ubuntu2) ...
957s Selecting previously unselected package libevent-2.1-7t64:amd64.
957s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ...
957s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ...
957s Selecting previously unselected package libverto1t64:amd64.
957s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_amd64.deb ...
957s Unpacking libverto1t64:amd64 (0.3.1-1.2ubuntu3) ...
957s Selecting previously unselected package libverto-libevent1t64:amd64.
957s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_amd64.deb ...
957s Unpacking libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ...
957s Selecting previously unselected package krb5-kdc.
957s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_amd64.deb ...
957s Unpacking krb5-kdc (1.21.3-4ubuntu2) ...
957s Selecting previously unselected package krb5-admin-server.
957s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_amd64.deb ...
957s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ...
957s Selecting previously unselected package openssh-client-gssapi.
957s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ...
957s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ...
957s Selecting previously unselected package openssh-server-gssapi.
957s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ...
957s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ...
957s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ...
957s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ...
957s Setting up libgssrpc4t64:amd64 (1.21.3-4ubuntu2) ...
957s Setting up krb5-config (2.7) ...
957s Setting up libkadm5clnt-mit12:amd64 (1.21.3-4ubuntu2) ...
957s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ...
957s Setting up libkdb5-10t64:amd64 (1.21.3-4ubuntu2) ...
957s Setting up libkadm5srv-mit12:amd64 (1.21.3-4ubuntu2) ...
957s Setting up krb5-user (1.21.3-4ubuntu2) ...
957s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode
957s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode
957s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode
958s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode
958s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode
958s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode
958s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode
958s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode
958s Setting up libverto1t64:amd64 (0.3.1-1.2ubuntu3) ...
958s Setting up libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ...
958s Setting up krb5-kdc (1.21.3-4ubuntu2) ...
958s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'.

958s Could not execute systemctl:  at /usr/bin/deb-systemd-invoke line 148.
958s Setting up krb5-admin-server (1.21.3-4ubuntu2) ...
959s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'.

959s Processing triggers for libc-bin (2.41-6ubuntu1) ...
959s Processing triggers for man-db (2.13.1-1) ...
964s autopkgtest [15:51:51]: test ssh-gssapi: [-----------------------
965s Generating public/private ed25519 key pair.
965s Your identification has been saved in /root/.ssh/id_ed25519
965s Your public key has been saved in /root/.ssh/id_ed25519.pub
965s The key fingerprint is:
965s SHA256:c0oGyp2e4EMGDHND4ujBX1gj7eappeSVdA/TzxmHQHQ root@autopkgtest
965s The key's randomart image is:
965s +--[ED25519 256]--+
965s |+.+..o  oo E     |
965s |+* .+..  ..      |
965s |.+o.... . . .    |
965s |. oo.* * . o .   |
965s | . .X * S + +    |
965s |   = O + = +     |
965s |  o B o .        |
965s |   + .           |
965s |                 |
965s +----[SHA256]-----+
965s ## Setting up test environment
965s ## Creating Kerberos realm EXAMPLE.FAKE
965s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE',
965s master key name 'K/M@EXAMPLE.FAKE'
965s ## Creating principals
965s Authenticating as principal root/admin@EXAMPLE.FAKE with password.
965s Principal "testuser2172@EXAMPLE.FAKE" created.
965s Authenticating as principal root/admin@EXAMPLE.FAKE with password.
965s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created.
965s ## Extracting service principal host/sshd-gssapi.example.fake
965s Authenticating as principal root/admin@EXAMPLE.FAKE with password.
965s 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.
965s 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.
965s ## Adjusting /etc/krb5.conf
965s ## TESTS
965s 
965s ## TEST test_gssapi_login
965s ## Configuring sshd for gssapi-with-mic authentication
965s ## Restarting ssh
965s ## Obtaining TGT
965s Password for testuser2172@EXAMPLE.FAKE: 
965s Ticket cache: FILE:/tmp/krb5cc_0
965s Default principal: testuser2172@EXAMPLE.FAKE
965s 
965s Valid starting     Expires            Service principal
965s 05/06/25 15:51:52  05/07/25 01:51:52  krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE
965s 	renew until 05/07/25 15:51:52
965s 
965s ## ssh'ing into localhost using gssapi-with-mic auth
965s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts.
965s Tue May  6 15:51:52 UTC 2025
965s 
965s ## checking that we got a service ticket for ssh (host/)
965s 05/06/25 15:51:52  05/07/25 01:51:52  host/sshd-gssapi.example.fake@
965s 	Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE
965s 
965s ## Checking ssh logs to confirm gssapi-with-mic auth was used
965s May 06 15:51:52 sshd-gssapi.example.fake sshd-session[2275]: Accepted gssapi-with-mic for testuser2172 from 127.0.0.1 port 57448 ssh2: testuser2172@EXAMPLE.FAKE
965s ## PASS test_gssapi_login
965s 
965s ## TEST test_gssapi_keyex_login
965s ## Configuring sshd for gssapi-keyex authentication
965s ## Restarting ssh
965s ## Obtaining TGT
965s Password for testuser2172@EXAMPLE.FAKE: 
965s Ticket cache: FILE:/tmp/krb5cc_0
965s Default principal: testuser2172@EXAMPLE.FAKE
965s 
965s Valid starting     Expires            Service principal
965s 05/06/25 15:51:52  05/07/25 01:51:52  krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE
965s 	renew until 05/07/25 15:51:52
965s 
965s ## ssh'ing into localhost using gssapi-keyex auth
966s Tue May  6 15:51:53 UTC 2025
966s 
966s ## checking that we got a service ticket for ssh (host/)
966s 05/06/25 15:51:52  05/07/25 01:51:52  host/sshd-gssapi.example.fake@
966s 	Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE
966s 
966s ## Checking ssh logs to confirm gssapi-keyex auth was used
966s May 06 15:51:53 sshd-gssapi.example.fake sshd-session[2330]: Accepted gssapi-keyex for testuser2172 from 127.0.0.1 port 57456 ssh2: testuser2172@EXAMPLE.FAKE
966s ## PASS test_gssapi_keyex_login
966s 
966s ## TEST test_gssapi_keyex_pubkey_fallback
966s ## Configuring sshd for gssapi-keyex authentication
966s ## Restarting ssh
966s ## Obtaining TGT
966s Password for testuser2172@EXAMPLE.FAKE: 
966s Ticket cache: FILE:/tmp/krb5cc_0
966s Default principal: testuser2172@EXAMPLE.FAKE
966s 
966s Valid starting     Expires            Service principal
966s 05/06/25 15:51:53  05/07/25 01:51:53  krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE
966s 	renew until 05/07/25 15:51:53
966s 
966s ## ssh'ing into localhost using gssapi-keyex auth
966s Tue May  6 15:51:53 UTC 2025
966s 
966s ## checking that we got a service ticket for ssh (host/)
966s 05/06/25 15:51:53  05/07/25 01:51:53  host/sshd-gssapi.example.fake@
966s 	Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE
966s 
966s ## Checking ssh logs to confirm publickey auth was used
966s May 06 15:51:53 sshd-gssapi.example.fake sshd-session[2371]: Accepted publickey for testuser2172-2 from 127.0.0.1 port 57464 ssh2: ED25519 SHA256:c0oGyp2e4EMGDHND4ujBX1gj7eappeSVdA/TzxmHQHQ
966s ## PASS test_gssapi_keyex_pubkey_fallback
966s 
966s ## ALL TESTS PASSED
966s ## Cleaning up
966s autopkgtest [15:51:53]: test ssh-gssapi: -----------------------]
967s autopkgtest [15:51:54]: test ssh-gssapi:  - - - - - - - - - - results - - - - - - - - - -
967s ssh-gssapi           PASS
967s autopkgtest [15:51:54]: test socket-activation: preparing testbed
1032s autopkgtest [15:52:59]: testbed dpkg architecture: amd64
1032s autopkgtest [15:52:59]: testbed apt version: 3.0.0
1032s autopkgtest [15:52:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup
1032s autopkgtest [15:52:59]: testbed release detected to be: questing
1033s autopkgtest [15:53:00]: updating testbed package index (apt update)
1033s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB]
1034s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease
1034s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease
1034s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease
1034s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [27.3 kB]
1034s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [71.6 kB]
1034s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [605 kB]
1034s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [104 kB]
1034s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [148 kB]
1034s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [670 kB]
1034s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [264 kB]
1034s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [18.4 kB]
1034s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [5028 B]
1034s Fetched 2023 kB in 1s (2343 kB/s)
1035s Reading package lists...
1036s autopkgtest [15:53:03]: upgrading testbed (apt dist-upgrade and autopurge)
1036s Reading package lists...
1036s Building dependency tree...
1036s Reading state information...
1037s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
1037s Starting 2 pkgProblemResolver with broken count: 0
1037s Done
1037s Entering ResolveByKeep
1037s 
1037s Calculating upgrade...
1038s The following packages will be upgraded:
1038s   libdebuginfod-common libdebuginfod1t64 libdw1t64 libelf1t64 libperl5.40
1038s   lto-disabled-list perl perl-base perl-modules-5.40 python3-dbus ucf
1038s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
1038s Need to get 10.7 MB of archives.
1038s After this operation, 23.6 kB of additional disk space will be used.
1038s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libperl5.40 amd64 5.40.1-3 [4877 kB]
1038s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 perl amd64 5.40.1-3 [262 kB]
1038s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 perl-base amd64 5.40.1-3 [1840 kB]
1038s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 perl-modules-5.40 all 5.40.1-3 [3217 kB]
1039s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB]
1039s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod-common all 0.193-1 [15.6 kB]
1039s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod1t64 amd64 0.193-1 [21.3 kB]
1039s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libdw1t64 amd64 0.193-1 [283 kB]
1039s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libelf1t64 amd64 0.193-1 [58.6 kB]
1039s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 python3-dbus amd64 1.4.0-1 [102 kB]
1039s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 lto-disabled-list all 58 [12.4 kB]
1039s Preconfiguring packages ...
1039s Fetched 10.7 MB in 1s (10.7 MB/s)
1039s (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.)
1039s Preparing to unpack .../libperl5.40_5.40.1-3_amd64.deb ...
1039s Unpacking libperl5.40:amd64 (5.40.1-3) over (5.40.1-2) ...
1040s Preparing to unpack .../perl_5.40.1-3_amd64.deb ...
1040s Unpacking perl (5.40.1-3) over (5.40.1-2) ...
1040s Preparing to unpack .../perl-base_5.40.1-3_amd64.deb ...
1040s Unpacking perl-base (5.40.1-3) over (5.40.1-2) ...
1040s Setting up perl-base (5.40.1-3) ...
1040s (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.)
1040s Preparing to unpack .../0-perl-modules-5.40_5.40.1-3_all.deb ...
1040s Unpacking perl-modules-5.40 (5.40.1-3) over (5.40.1-2) ...
1040s Preparing to unpack .../1-ucf_3.0051_all.deb ...
1040s Unpacking ucf (3.0051) over (3.0050) ...
1040s Preparing to unpack .../2-libdebuginfod-common_0.193-1_all.deb ...
1040s Unpacking libdebuginfod-common (0.193-1) over (0.192-4ubuntu1) ...
1040s Preparing to unpack .../3-libdebuginfod1t64_0.193-1_amd64.deb ...
1040s Unpacking libdebuginfod1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
1040s Preparing to unpack .../4-libdw1t64_0.193-1_amd64.deb ...
1040s Unpacking libdw1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
1041s Preparing to unpack .../5-libelf1t64_0.193-1_amd64.deb ...
1041s Unpacking libelf1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
1041s Preparing to unpack .../6-python3-dbus_1.4.0-1_amd64.deb ...
1041s Unpacking python3-dbus (1.4.0-1) over (1.3.2-5build5) ...
1041s Preparing to unpack .../7-lto-disabled-list_58_all.deb ...
1041s Unpacking lto-disabled-list (58) over (57) ...
1041s Setting up python3-dbus (1.4.0-1) ...
1041s Setting up lto-disabled-list (58) ...
1041s Setting up libelf1t64:amd64 (0.193-1) ...
1041s Setting up libdw1t64:amd64 (0.193-1) ...
1041s Setting up ucf (3.0051) ...
1041s Setting up perl-modules-5.40 (5.40.1-3) ...
1041s Setting up libdebuginfod-common (0.193-1) ...
1041s Setting up libperl5.40:amd64 (5.40.1-3) ...
1041s Setting up perl (5.40.1-3) ...
1041s Setting up libdebuginfod1t64:amd64 (0.193-1) ...
1041s Processing triggers for man-db (2.13.1-1) ...
1042s Processing triggers for libc-bin (2.41-6ubuntu1) ...
1042s Reading package lists...
1042s Building dependency tree...
1042s Reading state information...
1043s Starting pkgProblemResolver with broken count: 0
1043s Starting 2 pkgProblemResolver with broken count: 0
1043s Done
1043s Solving dependencies...
1043s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
1046s Reading package lists...
1046s Building dependency tree...
1046s Reading state information...
1046s Starting pkgProblemResolver with broken count: 0
1047s Starting 2 pkgProblemResolver with broken count: 0
1047s Done
1047s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
1051s autopkgtest [15:53:18]: test socket-activation: [-----------------------
1052s Generating public/private ed25519 key pair.
1052s Your identification has been saved in /home/testuser1745/.ssh/id_ed25519
1052s Your public key has been saved in /home/testuser1745/.ssh/id_ed25519.pub
1052s The key fingerprint is:
1052s SHA256:Vzm/b27krzADYeeZPXH1S600+tYgql0lkknOVZYUaeM testuser1745@autopkgtest
1052s The key's randomart image is:
1052s +--[ED25519 256]--+
1052s |             .=+.|
1052s |             += +|
1052s |          + *o+o+|
1052s |         = O OE=.|
1052s |        S O B B. |
1052s |         . + = =.|
1052s |          . = +o.|
1052s |         o . = .+|
1052s |        . .   .==|
1052s +----[SHA256]-----+
1052s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
1052s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh
1053s Disabling 'ssh.service', but its triggering units are still active:
1053s ssh.socket
1053s Stopping 'ssh.service', but its triggering units are still active:
1053s ssh.socket
1053s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
1053s Tue May  6 15:53:20 UTC 2025
1054s autopkgtest [15:53:21]: test socket-activation: -----------------------]
1054s autopkgtest [15:53:21]: test socket-activation:  - - - - - - - - - - results - - - - - - - - - -
1054s socket-activation    PASS
1054s autopkgtest [15:53:21]: test xinetd: preparing testbed
1055s Reading package lists...
1055s Building dependency tree...
1055s Reading state information...
1055s Starting pkgProblemResolver with broken count: 0
1055s Starting 2 pkgProblemResolver with broken count: 0
1055s Done
1056s The following NEW packages will be installed:
1056s   xinetd
1056s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
1056s Need to get 128 kB of archives.
1056s After this operation, 345 kB of additional disk space will be used.
1056s Get:1 http://ftpmaster.internal/ubuntu questing/universe amd64 xinetd amd64 1:2.3.15.4-5 [128 kB]
1056s Fetched 128 kB in 0s (362 kB/s)
1056s Selecting previously unselected package xinetd.
1056s (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.)
1056s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_amd64.deb ...
1056s Unpacking xinetd (1:2.3.15.4-5) ...
1057s Setting up xinetd (1:2.3.15.4-5) ...
1057s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'.

1057s Processing triggers for man-db (2.13.1-1) ...
1059s autopkgtest [15:53:26]: test xinetd: [-----------------------
1059s Generating public/private ed25519 key pair.
1059s Your identification has been saved in /home/testuser2277/.ssh/id_ed25519
1059s Your public key has been saved in /home/testuser2277/.ssh/id_ed25519.pub
1059s The key fingerprint is:
1059s SHA256:BnxSBUEAlpFG0bzSGPFTiha10qft+0LHGWjLHvZBPMU testuser2277@autopkgtest
1059s The key's randomart image is:
1059s +--[ED25519 256]--+
1059s |   .O@oo*+..     |
1059s |   .=*o=    E    |
1059s |   .++X.oo .     |
1059s |   .o.oOo =      |
1059s |     ..oS+ +     |
1059s |       o* =      |
1059s |       +.+ .     |
1059s |        o..      |
1059s |        .o.      |
1059s +----[SHA256]-----+
1059s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
1059s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh
1060s Disabling 'ssh.service', but its triggering units are still active:
1060s ssh.socket
1060s Stopping 'ssh.service', but its triggering units are still active:
1060s ssh.socket
1060s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
1061s Tue May  6 15:53:28 UTC 2025
1061s autopkgtest [15:53:28]: test xinetd: -----------------------]
1061s xinetd               PASS
1061s autopkgtest [15:53:28]: test xinetd:  - - - - - - - - - - results - - - - - - - - - -
1062s autopkgtest [15:53:29]: test systemd-socket-activation: preparing testbed
1130s autopkgtest [15:54:37]: testbed dpkg architecture: amd64
1131s autopkgtest [15:54:38]: testbed apt version: 3.0.0
1131s autopkgtest [15:54:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup
1131s autopkgtest [15:54:38]: testbed release detected to be: questing
1132s autopkgtest [15:54:39]: updating testbed package index (apt update)
1132s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB]
1133s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease
1133s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease
1133s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease
1133s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [27.3 kB]
1133s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [605 kB]
1133s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [71.6 kB]
1133s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [148 kB]
1133s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [104 kB]
1133s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [264 kB]
1133s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [670 kB]
1133s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [5028 B]
1133s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [18.4 kB]
1133s Fetched 2023 kB in 1s (2211 kB/s)
1134s Reading package lists...
1135s autopkgtest [15:54:42]: upgrading testbed (apt dist-upgrade and autopurge)
1135s Reading package lists...
1135s Building dependency tree...
1135s Reading state information...
1135s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
1135s Starting 2 pkgProblemResolver with broken count: 0
1135s Done
1136s Entering ResolveByKeep
1136s 
1136s Calculating upgrade...
1136s The following packages will be upgraded:
1136s   libdebuginfod-common libdebuginfod1t64 libdw1t64 libelf1t64 libperl5.40
1136s   lto-disabled-list perl perl-base perl-modules-5.40 python3-dbus ucf
1136s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
1136s Need to get 10.7 MB of archives.
1136s After this operation, 23.6 kB of additional disk space will be used.
1136s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libperl5.40 amd64 5.40.1-3 [4877 kB]
1137s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 perl amd64 5.40.1-3 [262 kB]
1137s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 perl-base amd64 5.40.1-3 [1840 kB]
1137s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 perl-modules-5.40 all 5.40.1-3 [3217 kB]
1137s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 ucf all 3.0051 [43.6 kB]
1137s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod-common all 0.193-1 [15.6 kB]
1137s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libdebuginfod1t64 amd64 0.193-1 [21.3 kB]
1137s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libdw1t64 amd64 0.193-1 [283 kB]
1137s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libelf1t64 amd64 0.193-1 [58.6 kB]
1137s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 python3-dbus amd64 1.4.0-1 [102 kB]
1137s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 lto-disabled-list all 58 [12.4 kB]
1137s Preconfiguring packages ...
1137s Fetched 10.7 MB in 1s (11.3 MB/s)
1138s (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.)
1138s Preparing to unpack .../libperl5.40_5.40.1-3_amd64.deb ...
1138s Unpacking libperl5.40:amd64 (5.40.1-3) over (5.40.1-2) ...
1138s Preparing to unpack .../perl_5.40.1-3_amd64.deb ...
1138s Unpacking perl (5.40.1-3) over (5.40.1-2) ...
1138s Preparing to unpack .../perl-base_5.40.1-3_amd64.deb ...
1138s Unpacking perl-base (5.40.1-3) over (5.40.1-2) ...
1138s Setting up perl-base (5.40.1-3) ...
1138s (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.)
1138s Preparing to unpack .../0-perl-modules-5.40_5.40.1-3_all.deb ...
1138s Unpacking perl-modules-5.40 (5.40.1-3) over (5.40.1-2) ...
1139s Preparing to unpack .../1-ucf_3.0051_all.deb ...
1139s Unpacking ucf (3.0051) over (3.0050) ...
1139s Preparing to unpack .../2-libdebuginfod-common_0.193-1_all.deb ...
1139s Unpacking libdebuginfod-common (0.193-1) over (0.192-4ubuntu1) ...
1139s Preparing to unpack .../3-libdebuginfod1t64_0.193-1_amd64.deb ...
1139s Unpacking libdebuginfod1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
1139s Preparing to unpack .../4-libdw1t64_0.193-1_amd64.deb ...
1139s Unpacking libdw1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
1139s Preparing to unpack .../5-libelf1t64_0.193-1_amd64.deb ...
1139s Unpacking libelf1t64:amd64 (0.193-1) over (0.192-4ubuntu1) ...
1139s Preparing to unpack .../6-python3-dbus_1.4.0-1_amd64.deb ...
1139s Unpacking python3-dbus (1.4.0-1) over (1.3.2-5build5) ...
1139s Preparing to unpack .../7-lto-disabled-list_58_all.deb ...
1139s Unpacking lto-disabled-list (58) over (57) ...
1139s Setting up python3-dbus (1.4.0-1) ...
1139s Setting up lto-disabled-list (58) ...
1139s Setting up libelf1t64:amd64 (0.193-1) ...
1139s Setting up libdw1t64:amd64 (0.193-1) ...
1139s Setting up ucf (3.0051) ...
1139s Setting up perl-modules-5.40 (5.40.1-3) ...
1139s Setting up libdebuginfod-common (0.193-1) ...
1139s Setting up libperl5.40:amd64 (5.40.1-3) ...
1139s Setting up perl (5.40.1-3) ...
1139s Setting up libdebuginfod1t64:amd64 (0.193-1) ...
1139s Processing triggers for man-db (2.13.1-1) ...
1140s Processing triggers for libc-bin (2.41-6ubuntu1) ...
1140s Reading package lists...
1140s Building dependency tree...
1140s Reading state information...
1141s Starting pkgProblemResolver with broken count: 0
1141s Starting 2 pkgProblemResolver with broken count: 0
1141s Done
1141s Solving dependencies...
1141s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
1144s Reading package lists...
1144s Building dependency tree...
1144s Reading state information...
1144s Starting pkgProblemResolver with broken count: 0
1144s Starting 2 pkgProblemResolver with broken count: 0
1144s Done
1145s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
1148s autopkgtest [15:54:55]: test systemd-socket-activation: [-----------------------
1148s Stopping ssh.service...
1148s Checking that ssh.socket is active and listening...
1148s Checking that ssh.service is inactive/dead...
1148s Checking that a connection attempt activates ssh.service...
1148s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
1149s Checking that sshd can be re-executed...
1149s Checking sshd can run in debug mode...
1149s debug1: PAM: establishing credentials
1149s debug1: permanently_set_uid: 0/0
1149s debug3: Copy environment: XDG_SESSION_ID=7
1149s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0
1149s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus
1149s debug3: Copy environment: XDG_SESSION_TYPE=tty
1149s debug3: Copy environment: XDG_SESSION_CLASS=user
1149s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin
1149s debug3: Copy environment: http_proxy=http://squid.internal:3128/
1149s debug3: Copy environment: https_proxy=http://squid.internal:3128/
1149s 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
1149s debug3: Copy environment: TERM=linux
1149s debug3: Copy environment: LANG=C.UTF-8
1149s Environment:
1149s   LANG=C.UTF-8
1149s   USER=root
1149s   LOGNAME=root
1149s   HOME=/root
1149s   PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin
1149s   SHELL=/bin/bash
1149s   XDG_SESSION_ID=7
1149s   XDG_RUNTIME_DIR=/run/user/0
1149s   DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus
1149s   XDG_SESSION_TYPE=tty
1149s   XDG_SESSION_CLASS=user
1149s   http_proxy=http://squid.internal:3128/
1149s   https_proxy=http://squid.internal:3128/
1149s   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
1149s   TERM=linux
1149s   SSH_CLIENT=::1 45678 22
1149s   SSH_CONNECTION=::1 45678 ::1 22
1149s Done.
1149s autopkgtest [15:54:56]: test systemd-socket-activation: -----------------------]
1150s systemd-socket-activation PASS
1150s autopkgtest [15:54:57]: test systemd-socket-activation:  - - - - - - - - - - results - - - - - - - - - -
1150s autopkgtest [15:54:57]: test sshd-socket-generator: preparing testbed
1150s Reading package lists...
1151s Building dependency tree...
1151s Reading state information...
1151s Starting pkgProblemResolver with broken count: 0
1151s Starting 2 pkgProblemResolver with broken count: 0
1151s Done
1151s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
1152s autopkgtest [15:54:59]: test sshd-socket-generator: [-----------------------
1152s test_default...PASS
1152s test_custom_port...PASS
1152s test_default_and_custom_port...PASS
1152s test_mutiple_custom_ports...PASS
1152s test_custom_listenaddress...PASS
1152s test_custom_listenaddress_and_port...PASS
1152s test_custom_ipv6_listenaddress...PASS
1152s test_custom_family_ipv4...PASS
1152s test_custom_family_ipv6...PASS
1152s test_custom_port_and_family_ipv4...PASS
1152s test_custom_port_and_family_ipv6...PASS
1152s test_match_on_port...PASS
1153s autopkgtest [15:55:00]: test sshd-socket-generator: -----------------------]
1153s autopkgtest [15:55:00]: test sshd-socket-generator:  - - - - - - - - - - results - - - - - - - - - -
1153s sshd-socket-generator PASS
1154s autopkgtest [15:55:01]: @@@@@@@@@@@@@@@@@@@@ summary
1154s regress              FAIL non-zero exit status 2
1154s ssh-gssapi           PASS
1154s socket-activation    PASS
1154s xinetd               PASS
1154s systemd-socket-activation PASS
1154s sshd-socket-generator PASS
1171s nova [W] Skipping flock for amd64
1171s Creating nova instance adt-questing-amd64-openssh-20250506-153547-juju-7f2275-prod-proposed-migration-environment-2-e245af80-4795-4f4e-95ca-c77e839ad412 from image adt/ubuntu-questing-amd64-server-20250506.img (UUID f1864fa2-149c-4e5d-800d-c5107d8a2f35)...
1171s nova [W] Timed out waiting for 1c53c551-0f1f-40f0-b72b-ea5ff4ab288f to get deleted.
1171s nova [W] Skipping flock for amd64
1171s Creating nova instance adt-questing-amd64-openssh-20250506-153547-juju-7f2275-prod-proposed-migration-environment-2-e245af80-4795-4f4e-95ca-c77e839ad412 from image adt/ubuntu-questing-amd64-server-20250506.img (UUID f1864fa2-149c-4e5d-800d-c5107d8a2f35)...
1171s nova [W] Timed out waiting for dfe07808-b8a0-490d-b766-387733da6565 to get deleted.
1171s nova [W] Skipping flock for amd64
1171s Creating nova instance adt-questing-amd64-openssh-20250506-153547-juju-7f2275-prod-proposed-migration-environment-2-e245af80-4795-4f4e-95ca-c77e839ad412 from image adt/ubuntu-questing-amd64-server-20250506.img (UUID f1864fa2-149c-4e5d-800d-c5107d8a2f35)...
1171s nova [W] Timed out waiting for c631d36f-619b-4651-ad78-7e874c122117 to get deleted.
1171s nova [W] Skipping flock for amd64
1171s Creating nova instance adt-questing-amd64-openssh-20250506-153547-juju-7f2275-prod-proposed-migration-environment-2-e245af80-4795-4f4e-95ca-c77e839ad412 from image adt/ubuntu-questing-amd64-server-20250506.img (UUID f1864fa2-149c-4e5d-800d-c5107d8a2f35)...
1171s nova [W] Timed out waiting for 2636b873-23a3-42e4-ba61-6bf360d16613 to get deleted.