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.