0s autopkgtest [14:27:39]: starting date and time: 2026-01-24 14:27:39+0000 0s autopkgtest [14:27:39]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [14:27:39]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.f4vyjr0f/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:zlib --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=zlib/1:1.3.dfsg+really1.3.1-1ubuntu3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-s390x-14.secgroup --name adt-resolute-s390x-openssh-20260124-120636-juju-7f2275-prod-proposed-migration-environment-20-bfadab52-d732-4450-9fff-b8dbd5c4d67c --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-s390x-openssh-20260124-120636-juju-7f2275-prod-proposed-migration-environment-20-bfadab52-d732-4450-9fff-b8dbd5c4d67c from image adt/ubuntu-resolute-s390x-server-20260124.img (UUID cb5f7561-b2f6-4cdf-8333-83c53514cd6a)... 66s autopkgtest [14:28:45]: testbed dpkg architecture: s390x 66s autopkgtest [14:28:45]: testbed apt version: 3.1.13 67s autopkgtest [14:28:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 67s autopkgtest [14:28:46]: testbed release detected to be: None 68s autopkgtest [14:28:47]: updating testbed package index (apt update) 68s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 68s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 68s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 68s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 68s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 68s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 68s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [91.5 kB] 68s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [994 kB] 69s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [100 kB] 69s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [791 kB] 69s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [9916 B] 70s Fetched 2138 kB in 2s (1351 kB/s) 73s Reading package lists... 74s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 74s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 74s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 74s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 78s Reading package lists... 78s Reading package lists... 79s Building dependency tree... 79s Reading state information... 80s Calculating upgrade... 80s The following packages will be upgraded: 80s cloud-initramfs-copymods cloud-initramfs-dyn-netconf libgpg-error-l10n 80s libgpg-error0 liblzo2-2 rust-coreutils zlib1g 80s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 80s Need to get 3818 kB of archives. 80s After this operation, 1334 kB of additional disk space will be used. 80s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x rust-coreutils s390x 0.5.0-0ubuntu1 [3576 kB] 81s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x zlib1g s390x 1:1.3.dfsg+really1.3.1-1ubuntu3 [70.4 kB] 81s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x libgpg-error-l10n all 1.58-1build1 [9192 B] 81s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x libgpg-error0 s390x 1.58-1build1 [88.1 kB] 81s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x liblzo2-2 s390x 2.10-3build2 [62.8 kB] 81s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x cloud-initramfs-copymods all 0.50 [4528 B] 81s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x cloud-initramfs-dyn-netconf all 0.50 [6514 B] 82s dpkg-preconfigure: unable to re-open stdin: No such file or directory 82s Fetched 3818 kB in 0s (11.4 MB/s) 82s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 87682 files and directories currently installed.) 82s Preparing to unpack .../rust-coreutils_0.5.0-0ubuntu1_s390x.deb ... 82s Unpacking rust-coreutils (0.5.0-0ubuntu1) over (0.2.2-0ubuntu2) ... 83s Setting up rust-coreutils (0.5.0-0ubuntu1) ... 83s (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 ... 87711 files and directories currently installed.) 83s Preparing to unpack .../zlib1g_1%3a1.3.dfsg+really1.3.1-1ubuntu3_s390x.deb ... 84s Unpacking zlib1g:s390x (1:1.3.dfsg+really1.3.1-1ubuntu3) over (1:1.3.dfsg+really1.3.1-1ubuntu2) ... 84s Setting up zlib1g:s390x (1:1.3.dfsg+really1.3.1-1ubuntu3) ... 84s (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 ... 87711 files and directories currently installed.) 84s Preparing to unpack .../libgpg-error-l10n_1.58-1build1_all.deb ... 84s Unpacking libgpg-error-l10n (1.58-1build1) over (1.58-1) ... 85s Preparing to unpack .../libgpg-error0_1.58-1build1_s390x.deb ... 85s Unpacking libgpg-error0:s390x (1.58-1build1) over (1.58-1) ... 85s Preparing to unpack .../liblzo2-2_2.10-3build2_s390x.deb ... 85s Unpacking liblzo2-2:s390x (2.10-3build2) over (2.10-3build1) ... 85s Preparing to unpack .../cloud-initramfs-copymods_0.50_all.deb ... 86s Unpacking cloud-initramfs-copymods (0.50) over (0.49build1) ... 86s Preparing to unpack .../cloud-initramfs-dyn-netconf_0.50_all.deb ... 86s Unpacking cloud-initramfs-dyn-netconf (0.50) over (0.49build1) ... 86s Setting up libgpg-error0:s390x (1.58-1build1) ... 86s Setting up liblzo2-2:s390x (2.10-3build2) ... 86s Setting up cloud-initramfs-copymods (0.50) ... 86s Setting up cloud-initramfs-dyn-netconf (0.50) ... 86s Setting up libgpg-error-l10n (1.58-1build1) ... 86s Processing triggers for libc-bin (2.42-2ubuntu4) ... 87s Processing triggers for man-db (2.13.1-1) ... 89s Processing triggers for initramfs-tools (0.150ubuntu7) ... 89s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 101s Using config file '/etc/zipl.conf' 101s Building bootmap in '/boot' 101s Adding IPL section 'ubuntu' (default) 101s Preparing boot device: vda. 101s Done. 102s autopkgtest [14:29:21]: upgrading testbed (apt dist-upgrade and autopurge) 102s Reading package lists... 103s Building dependency tree... 103s Reading state information... 103s Calculating upgrade... 103s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 103s Reading package lists... 104s Building dependency tree... 104s Reading state information... 104s Solving dependencies... 104s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 104s autopkgtest [14:29:23]: rebooting testbed after setup commands that affected boot 139s autopkgtest [14:29:58]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP Mon Jan 12 15:39:23 UTC 2026 141s autopkgtest [14:30:00]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 146s Get:1 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (dsc) [3499 B] 146s Get:2 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (tar) [1975 kB] 146s Get:3 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (asc) [833 B] 146s Get:4 http://ftpmaster.internal/ubuntu resolute/main openssh 1:10.2p1-2ubuntu1 (diff) [215 kB] 147s gpgv: Signature made Wed Dec 3 14:30:09 2025 UTC 147s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 147s gpgv: Can't check signature: No public key 147s dpkg-source: warning: cannot verify inline signature for ./openssh_10.2p1-2ubuntu1.dsc: no acceptable signature found 148s autopkgtest [14:30:07]: testing package openssh version 1:10.2p1-2ubuntu1 149s autopkgtest [14:30:08]: build not needed 152s autopkgtest [14:30:11]: test regress: preparing testbed 152s Reading package lists... 153s Building dependency tree... 153s Reading state information... 153s Solving dependencies... 153s The following NEW packages will be installed: 153s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 153s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 153s libconfig-inifiles-perl libdevel-callchecker-perl 153s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 153s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 153s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 153s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 153s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 153s libio-html-perl libio-socket-ssl-perl libio-string-perl 153s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 153s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 153s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 153s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 153s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 153s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 153s python3-pyasn1 python3-pyasn1-modules python3-service-identity 153s python3-twisted python3-zope.interface wdiff 153s 0 upgraded, 60 newly installed, 0 to remove and 0 not upgraded. 153s Need to get 8449 kB of archives. 153s After this operation, 38.5 MB of additional disk space will be used. 153s Get:1 http://ftpmaster.internal/ubuntu resolute/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 153s Get:2 http://ftpmaster.internal/ubuntu resolute/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build2 [432 kB] 153s Get:3 http://ftpmaster.internal/ubuntu resolute/universe s390x dropbear-bin s390x 2025.89-1 [188 kB] 153s Get:4 http://ftpmaster.internal/ubuntu resolute/universe s390x dropbear all 2025.89-1 [8666 B] 153s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x libconfig-inifiles-perl all 3.000003-4 [38.5 kB] 153s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 153s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 153s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 153s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-touch-perl all 0.12-2 [7498 B] 153s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x libio-string-perl all 1.08-4 [11.1 kB] 153s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 153s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x libclass-xsaccessor-perl s390x 1.19-4build6 [34.2 kB] 153s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 153s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x libdynaloader-functions-perl all 0.004-2 [11.5 kB] 153s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x libdevel-callchecker-perl s390x 0.009-2 [14.1 kB] 153s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 153s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x libmodule-runtime-perl all 0.018-1 [15.2 kB] 153s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 153s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 153s Get:20 http://ftpmaster.internal/ubuntu resolute/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 153s Get:21 http://ftpmaster.internal/ubuntu resolute/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 153s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 153s Get:23 http://ftpmaster.internal/ubuntu resolute/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 153s Get:24 http://ftpmaster.internal/ubuntu resolute/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 153s Get:25 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 154s Get:26 http://ftpmaster.internal/ubuntu resolute/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 154s Get:27 http://ftpmaster.internal/ubuntu resolute/main s390x liburi-perl all 5.34-2build1 [100 kB] 154s Get:28 http://ftpmaster.internal/ubuntu resolute/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 154s Get:29 http://ftpmaster.internal/ubuntu resolute/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 154s Get:30 http://ftpmaster.internal/ubuntu resolute/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 154s Get:31 http://ftpmaster.internal/ubuntu resolute/main s390x libio-html-perl all 1.004-3 [15.9 kB] 154s Get:32 http://ftpmaster.internal/ubuntu resolute/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 154s Get:33 http://ftpmaster.internal/ubuntu resolute/main s390x libhttp-message-perl all 7.01-1ubuntu1 [76.1 kB] 154s Get:34 http://ftpmaster.internal/ubuntu resolute/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 154s Get:35 http://ftpmaster.internal/ubuntu resolute/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 154s Get:36 http://ftpmaster.internal/ubuntu resolute/main s390x perl-openssl-defaults s390x 7build4 [6710 B] 154s Get:37 http://ftpmaster.internal/ubuntu resolute/main s390x libnet-ssleay-perl s390x 1.94-3 [320 kB] 154s Get:38 http://ftpmaster.internal/ubuntu resolute/main s390x libio-socket-ssl-perl all 2.098-1 [205 kB] 154s Get:39 http://ftpmaster.internal/ubuntu resolute/main s390x libnet-http-perl all 6.24-1build1 [21.7 kB] 154s Get:40 http://ftpmaster.internal/ubuntu resolute/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 154s Get:41 http://ftpmaster.internal/ubuntu resolute/main s390x libtry-tiny-perl all 0.32-1 [21.2 kB] 154s Get:42 http://ftpmaster.internal/ubuntu resolute/main s390x libwww-robotrules-perl all 6.02-1build1 [12.4 kB] 154s Get:43 http://ftpmaster.internal/ubuntu resolute/main s390x libwww-perl all 6.81-1build1 [141 kB] 154s Get:44 http://ftpmaster.internal/ubuntu resolute/main s390x patchutils s390x 0.4.3-1 [84.5 kB] 154s Get:45 http://ftpmaster.internal/ubuntu resolute/main s390x wdiff s390x 1.2.2-9 [29.7 kB] 154s Get:46 http://ftpmaster.internal/ubuntu resolute/main s390x devscripts all 2.26.5 [1094 kB] 154s Get:47 http://ftpmaster.internal/ubuntu resolute/universe s390x libhavege2 s390x 1.9.19-13 [26.5 kB] 154s Get:48 http://ftpmaster.internal/ubuntu resolute/universe s390x putty-tools s390x 0.83-3 [741 kB] 154s Get:49 http://ftpmaster.internal/ubuntu resolute/main s390x python3-hamcrest all 2.1.0-3 [28.5 kB] 154s Get:50 http://ftpmaster.internal/ubuntu resolute/main s390x python3-pyasn1 all 0.6.2-1 [56.7 kB] 154s Get:51 http://ftpmaster.internal/ubuntu resolute/main s390x python3-pyasn1-modules all 0.4.1-2build1 [80.5 kB] 154s Get:52 http://ftpmaster.internal/ubuntu resolute/main s390x python3-service-identity all 24.2.0-1build1 [11.2 kB] 154s Get:53 http://ftpmaster.internal/ubuntu resolute/main s390x python3-automat all 25.4.16-1 [35.7 kB] 154s Get:54 http://ftpmaster.internal/ubuntu resolute/main s390x python3-constantly all 23.10.4-2build1 [14.1 kB] 154s Get:55 http://ftpmaster.internal/ubuntu resolute/main s390x python3-hyperlink all 21.0.0-6build1 [68.2 kB] 154s Get:56 http://ftpmaster.internal/ubuntu resolute/main s390x python3-incremental all 24.7.2-3build1 [18.7 kB] 154s Get:57 http://ftpmaster.internal/ubuntu resolute/main s390x python3-zope.interface s390x 8.2-1 [146 kB] 154s Get:58 http://ftpmaster.internal/ubuntu resolute/main s390x python3-twisted all 25.5.0-5 [2076 kB] 154s Get:59 http://ftpmaster.internal/ubuntu resolute/universe s390x openssh-tests s390x 1:10.2p1-2ubuntu1 [1529 kB] 154s Get:60 http://ftpmaster.internal/ubuntu resolute/universe s390x haveged s390x 1.9.19-13 [32.6 kB] 156s Fetched 8449 kB in 1s (7163 kB/s) 156s Selecting previously unselected package libtommath1:s390x. 156s (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 ... 87715 files and directories currently installed.) 156s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 156s Unpacking libtommath1:s390x (1.3.0-1) ... 156s Selecting previously unselected package libtomcrypt1:s390x. 156s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build2_s390x.deb ... 156s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build2) ... 156s Selecting previously unselected package dropbear-bin. 156s Preparing to unpack .../02-dropbear-bin_2025.89-1_s390x.deb ... 156s Unpacking dropbear-bin (2025.89-1) ... 156s Selecting previously unselected package dropbear. 156s Preparing to unpack .../03-dropbear_2025.89-1_all.deb ... 156s Unpacking dropbear (2025.89-1) ... 156s Selecting previously unselected package libconfig-inifiles-perl. 156s Preparing to unpack .../04-libconfig-inifiles-perl_3.000003-4_all.deb ... 156s Unpacking libconfig-inifiles-perl (3.000003-4) ... 156s Selecting previously unselected package libfile-dirlist-perl. 156s Preparing to unpack .../05-libfile-dirlist-perl_0.05-3_all.deb ... 156s Unpacking libfile-dirlist-perl (0.05-3) ... 156s Selecting previously unselected package libfile-which-perl. 156s Preparing to unpack .../06-libfile-which-perl_1.27-2_all.deb ... 156s Unpacking libfile-which-perl (1.27-2) ... 156s Selecting previously unselected package libfile-homedir-perl. 156s Preparing to unpack .../07-libfile-homedir-perl_1.006-2_all.deb ... 156s Unpacking libfile-homedir-perl (1.006-2) ... 156s Selecting previously unselected package libfile-touch-perl. 156s Preparing to unpack .../08-libfile-touch-perl_0.12-2_all.deb ... 156s Unpacking libfile-touch-perl (0.12-2) ... 156s Selecting previously unselected package libio-string-perl. 156s Preparing to unpack .../09-libio-string-perl_1.08-4_all.deb ... 156s Unpacking libio-string-perl (1.08-4) ... 156s Selecting previously unselected package libclass-method-modifiers-perl. 156s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 156s Unpacking libclass-method-modifiers-perl (2.15-1) ... 156s Selecting previously unselected package libclass-xsaccessor-perl. 156s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_s390x.deb ... 156s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 156s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 156s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 156s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 156s Selecting previously unselected package libdynaloader-functions-perl. 156s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-2_all.deb ... 156s Unpacking libdynaloader-functions-perl (0.004-2) ... 156s Selecting previously unselected package libdevel-callchecker-perl:s390x. 157s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-2_s390x.deb ... 157s Unpacking libdevel-callchecker-perl:s390x (0.009-2) ... 157s Selecting previously unselected package libparams-classify-perl:s390x. 157s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_s390x.deb ... 157s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 157s Selecting previously unselected package libmodule-runtime-perl. 157s Preparing to unpack .../16-libmodule-runtime-perl_0.018-1_all.deb ... 157s Unpacking libmodule-runtime-perl (0.018-1) ... 157s Selecting previously unselected package libimport-into-perl. 157s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 157s Unpacking libimport-into-perl (1.002005-2) ... 157s Selecting previously unselected package librole-tiny-perl. 157s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 157s Unpacking librole-tiny-perl (2.002004-1) ... 157s Selecting previously unselected package libsub-quote-perl. 157s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 157s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 157s Selecting previously unselected package libmoo-perl. 157s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 157s Unpacking libmoo-perl (2.005005-1) ... 157s Selecting previously unselected package libencode-locale-perl. 157s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 157s Unpacking libencode-locale-perl (1.05-3) ... 157s Selecting previously unselected package libtimedate-perl. 157s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 157s Unpacking libtimedate-perl (2.3300-2) ... 157s Selecting previously unselected package libhttp-date-perl. 157s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 157s Unpacking libhttp-date-perl (6.06-1) ... 157s Selecting previously unselected package libfile-listing-perl. 157s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 157s Unpacking libfile-listing-perl (6.16-1) ... 157s Selecting previously unselected package libhtml-tagset-perl. 157s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 157s Unpacking libhtml-tagset-perl (3.24-1) ... 157s Selecting previously unselected package liburi-perl. 157s Preparing to unpack .../26-liburi-perl_5.34-2build1_all.deb ... 157s Unpacking liburi-perl (5.34-2build1) ... 157s Selecting previously unselected package libhtml-parser-perl:s390x. 157s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_s390x.deb ... 157s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 157s Selecting previously unselected package libhtml-tree-perl. 157s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 157s Unpacking libhtml-tree-perl (5.07-3) ... 157s Selecting previously unselected package libclone-perl:s390x. 157s Preparing to unpack .../29-libclone-perl_0.47-1_s390x.deb ... 157s Unpacking libclone-perl:s390x (0.47-1) ... 157s Selecting previously unselected package libio-html-perl. 157s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 157s Unpacking libio-html-perl (1.004-3) ... 157s Selecting previously unselected package liblwp-mediatypes-perl. 157s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 157s Unpacking liblwp-mediatypes-perl (6.04-2) ... 158s Selecting previously unselected package libhttp-message-perl. 158s Preparing to unpack .../32-libhttp-message-perl_7.01-1ubuntu1_all.deb ... 158s Unpacking libhttp-message-perl (7.01-1ubuntu1) ... 158s Selecting previously unselected package libhttp-cookies-perl. 158s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 158s Unpacking libhttp-cookies-perl (6.11-1) ... 158s Selecting previously unselected package libhttp-negotiate-perl. 158s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 158s Unpacking libhttp-negotiate-perl (6.01-2) ... 158s Selecting previously unselected package perl-openssl-defaults:s390x. 158s Preparing to unpack .../35-perl-openssl-defaults_7build4_s390x.deb ... 158s Unpacking perl-openssl-defaults:s390x (7build4) ... 158s Selecting previously unselected package libnet-ssleay-perl:s390x. 158s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_s390x.deb ... 158s Unpacking libnet-ssleay-perl:s390x (1.94-3) ... 158s Selecting previously unselected package libio-socket-ssl-perl. 158s Preparing to unpack .../37-libio-socket-ssl-perl_2.098-1_all.deb ... 158s Unpacking libio-socket-ssl-perl (2.098-1) ... 158s Selecting previously unselected package libnet-http-perl. 158s Preparing to unpack .../38-libnet-http-perl_6.24-1build1_all.deb ... 158s Unpacking libnet-http-perl (6.24-1build1) ... 158s Selecting previously unselected package liblwp-protocol-https-perl. 158s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 158s Unpacking liblwp-protocol-https-perl (6.14-1) ... 158s Selecting previously unselected package libtry-tiny-perl. 158s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 158s Unpacking libtry-tiny-perl (0.32-1) ... 159s Selecting previously unselected package libwww-robotrules-perl. 159s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1build1_all.deb ... 159s Unpacking libwww-robotrules-perl (6.02-1build1) ... 159s Selecting previously unselected package libwww-perl. 159s Preparing to unpack .../42-libwww-perl_6.81-1build1_all.deb ... 159s Unpacking libwww-perl (6.81-1build1) ... 159s Selecting previously unselected package patchutils. 159s Preparing to unpack .../43-patchutils_0.4.3-1_s390x.deb ... 159s Unpacking patchutils (0.4.3-1) ... 159s Selecting previously unselected package wdiff. 159s Preparing to unpack .../44-wdiff_1.2.2-9_s390x.deb ... 159s Unpacking wdiff (1.2.2-9) ... 159s Selecting previously unselected package devscripts. 159s Preparing to unpack .../45-devscripts_2.26.5_all.deb ... 159s Unpacking devscripts (2.26.5) ... 159s Selecting previously unselected package libhavege2:s390x. 159s Preparing to unpack .../46-libhavege2_1.9.19-13_s390x.deb ... 159s Unpacking libhavege2:s390x (1.9.19-13) ... 159s Selecting previously unselected package putty-tools. 159s Preparing to unpack .../47-putty-tools_0.83-3_s390x.deb ... 159s Unpacking putty-tools (0.83-3) ... 159s Selecting previously unselected package python3-hamcrest. 159s Preparing to unpack .../48-python3-hamcrest_2.1.0-3_all.deb ... 159s Unpacking python3-hamcrest (2.1.0-3) ... 159s Selecting previously unselected package python3-pyasn1. 159s Preparing to unpack .../49-python3-pyasn1_0.6.2-1_all.deb ... 159s Unpacking python3-pyasn1 (0.6.2-1) ... 159s Selecting previously unselected package python3-pyasn1-modules. 159s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-2build1_all.deb ... 159s Unpacking python3-pyasn1-modules (0.4.1-2build1) ... 159s Selecting previously unselected package python3-service-identity. 159s Preparing to unpack .../51-python3-service-identity_24.2.0-1build1_all.deb ... 160s Unpacking python3-service-identity (24.2.0-1build1) ... 160s Selecting previously unselected package python3-automat. 160s Preparing to unpack .../52-python3-automat_25.4.16-1_all.deb ... 160s Unpacking python3-automat (25.4.16-1) ... 160s Selecting previously unselected package python3-constantly. 160s Preparing to unpack .../53-python3-constantly_23.10.4-2build1_all.deb ... 160s Unpacking python3-constantly (23.10.4-2build1) ... 160s Selecting previously unselected package python3-hyperlink. 160s Preparing to unpack .../54-python3-hyperlink_21.0.0-6build1_all.deb ... 160s Unpacking python3-hyperlink (21.0.0-6build1) ... 160s Selecting previously unselected package python3-incremental. 160s Preparing to unpack .../55-python3-incremental_24.7.2-3build1_all.deb ... 160s Unpacking python3-incremental (24.7.2-3build1) ... 160s Selecting previously unselected package python3-zope.interface. 160s Preparing to unpack .../56-python3-zope.interface_8.2-1_s390x.deb ... 160s Unpacking python3-zope.interface (8.2-1) ... 160s Selecting previously unselected package python3-twisted. 160s Preparing to unpack .../57-python3-twisted_25.5.0-5_all.deb ... 160s Unpacking python3-twisted (25.5.0-5) ... 161s Selecting previously unselected package openssh-tests. 161s Preparing to unpack .../58-openssh-tests_1%3a10.2p1-2ubuntu1_s390x.deb ... 161s Unpacking openssh-tests (1:10.2p1-2ubuntu1) ... 161s Selecting previously unselected package haveged. 161s Preparing to unpack .../59-haveged_1.9.19-13_s390x.deb ... 161s Unpacking haveged (1.9.19-13) ... 161s Setting up libconfig-inifiles-perl (3.000003-4) ... 161s Setting up wdiff (1.2.2-9) ... 161s Setting up libfile-which-perl (1.27-2) ... 161s Setting up libdynaloader-functions-perl (0.004-2) ... 161s Setting up libclass-method-modifiers-perl (2.15-1) ... 161s Setting up python3-zope.interface (8.2-1) ... 162s Setting up libclone-perl:s390x (0.47-1) ... 162s Setting up libtommath1:s390x (1.3.0-1) ... 162s Setting up libhtml-tagset-perl (3.24-1) ... 162s Setting up python3-automat (25.4.16-1) ... 162s Setting up liblwp-mediatypes-perl (6.04-2) ... 162s Setting up libtry-tiny-perl (0.32-1) ... 162s Setting up perl-openssl-defaults:s390x (7build4) ... 162s Setting up libencode-locale-perl (1.05-3) ... 162s Setting up python3-hamcrest (2.1.0-3) ... 163s Setting up putty-tools (0.83-3) ... 163s Setting up libhavege2:s390x (1.9.19-13) ... 163s Setting up patchutils (0.4.3-1) ... 163s Setting up libio-string-perl (1.08-4) ... 163s Setting up python3-incremental (24.7.2-3build1) ... 163s Setting up python3-hyperlink (21.0.0-6build1) ... 163s Setting up libio-html-perl (1.004-3) ... 163s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 163s Setting up libtimedate-perl (2.3300-2) ... 163s Setting up librole-tiny-perl (2.002004-1) ... 163s Setting up python3-pyasn1 (0.6.2-1) ... 163s Setting up python3-constantly (23.10.4-2build1) ... 164s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 164s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 164s Setting up libfile-dirlist-perl (0.05-3) ... 164s Setting up libfile-homedir-perl (1.006-2) ... 164s Setting up liburi-perl (5.34-2build1) ... 164s Setting up libfile-touch-perl (0.12-2) ... 164s Setting up libnet-ssleay-perl:s390x (1.94-3) ... 164s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build2) ... 164s Setting up libhttp-date-perl (6.06-1) ... 164s Setting up haveged (1.9.19-13) ... 164s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 165s Setting up dropbear-bin (2025.89-1) ... 165s Setting up libfile-listing-perl (6.16-1) ... 165s Setting up libnet-http-perl (6.24-1build1) ... 165s Setting up libdevel-callchecker-perl:s390x (0.009-2) ... 165s Setting up dropbear (2025.89-1) ... 165s Converting existing OpenSSH RSA host key to Dropbear format. 165s Key is a ssh-rsa key 165s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 165s 3072 SHA256:is5x1c1cc2CL6BgMKmN9RE2nNSErDcRpraf8MMLk6WE /etc/dropbear/dropbear_rsa_host_key (RSA) 165s +---[RSA 3072]----+ 165s | +*=o =. o | 165s | . o+=o* o o o | 165s | + o.o.* . . + .| 165s | . o.....= + . o | 165s | + o oS o + | 165s | E.=o | 165s | oooo+ | 165s | o.o . | 165s | o | 165s +----[SHA256]-----+ 165s Converting existing OpenSSH ECDSA host key to Dropbear format. 165s Key is a ecdsa-sha2-nistp256 key 165s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 165s 256 SHA256:+IW7hIMFppHhQS1yS9IJM3LU+tsWC/TA1LclUU0L7wE /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 165s +---[ECDSA 256]---+ 165s |==*+ . .oEo. | 165s |+=*++ . o .+.. | 165s | =+*o . + + | 165s | o++. ... . . | 165s | .o oo S . . | 165s | oooo o | 165s | .+oo+ | 165s | . +o . | 165s | . . | 165s +----[SHA256]-----+ 165s Converting existing OpenSSH ED25519 host key to Dropbear format. 165s Key is a ssh-ed25519 key 165s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 165s 256 SHA256:8vcJ1AjT7V7V5+02KXYT7Kky1ok2v/ZEDqUMysZejsw /etc/dropbear/dropbear_ed25519_host_key (ED25519) 165s +--[ED25519 256]--+ 165s | | 165s | . . .| 165s | o ... .+| 165s | oo.+o +oo| 165s | . S=o.o+.+o| 165s | o=.+. .=.+| 165s | .Eo.+o.Xo| 165s | . X.==.o| 165s | o O+o. | 165s +----[SHA256]-----+ 166s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 167s Setting up python3-pyasn1-modules (0.4.1-2build1) ... 168s Setting up python3-service-identity (24.2.0-1build1) ... 168s Setting up libwww-robotrules-perl (6.02-1build1) ... 168s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 168s Setting up libio-socket-ssl-perl (2.098-1) ... 168s Setting up libhttp-message-perl (7.01-1ubuntu1) ... 168s Setting up libhttp-negotiate-perl (6.01-2) ... 168s Setting up libhttp-cookies-perl (6.11-1) ... 168s Setting up libhtml-tree-perl (5.07-3) ... 168s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 168s Setting up libmodule-runtime-perl (0.018-1) ... 168s Setting up python3-twisted (25.5.0-5) ... 178s Setting up libimport-into-perl (1.002005-2) ... 178s Setting up libmoo-perl (2.005005-1) ... 178s Setting up openssh-tests (1:10.2p1-2ubuntu1) ... 178s Setting up liblwp-protocol-https-perl (6.14-1) ... 178s Setting up libwww-perl (6.81-1build1) ... 178s Setting up devscripts (2.26.5) ... 178s Processing triggers for install-info (7.2-5) ... 178s Processing triggers for libc-bin (2.42-2ubuntu4) ... 178s Processing triggers for man-db (2.13.1-1) ... 183s autopkgtest [14:30:42]: test regress: [----------------------- 183s I: annotate-output 2.26.5 183s I: prefix='%H:%M:%S.%N ' 183s 14:32:25.392391527 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.koxaBD/autopkgtest_tmp 183s 14:32:25.462746933 O: make: Entering directory '/tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress' 183s 14:32:25.465271663 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/valgrind-out 183s 14:32:25.467266587 E: + /usr/bin/ssh -Q key 183s 14:32:25.467342199 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 183s 14:32:25.469234148 E: + grep -q ^ssh-rsa 183s 14:32:25.471098957 O: ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv ; \ 183s 14:32:25.473111555 E: + ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2.prv 183s 14:32:25.475036463 O: tr '\n' '\r' /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2_cr.prv ; \ 183s 14:32:25.476929660 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv 183s 14:32:25.478839837 O: ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv ; \ 183s 14:32:25.480707465 E: + tr \n \r 183s 14:32:25.482591597 O: awk '{print $0 "\r"}' /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2.prv > /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2_crnl.prv ; \ 183s 14:32:25.484467324 E: + ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2_cr.prv 183s 14:32:25.486499823 O: ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv ; \ 183s 14:32:25.488693509 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv 183s 14:32:25.490403844 O: fi 183s 14:32:25.492293886 E: + awk {print $0 "\r"} /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2.prv 183s 14:32:25.494196117 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 183s 14:32:25.496111799 E: + ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_ssh2_crnl.prv 183s 14:32:25.504303368 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv 183s 14:32:25.498033502 O: cat /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv > /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t2.out ; \ 183s 14:32:25.506993782 E: + /usr/bin/ssh -Q key 183s 14:32:25.506882691 O: chmod 600 /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t2.out ; \ 183s 14:32:25.512284925 O: ssh-keygen -yf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t2.out | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub ; \ 183s 14:32:25.509444408 E: + grep -q ^ssh-rsa 183s 14:32:25.514403652 O: fi 183s 14:32:25.537012183 E: + cat /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.prv 183s 14:32:25.539403046 E: + chmod 600 /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t2.out 183s 14:32:25.576839376 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 183s 14:32:25.631903462 E: + ssh-keygen -yf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t2.out 183s 14:32:25.632726030 O: ssh-keygen -ef /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub >/tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t3.out ; \ 183s 14:32:25.635809343 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub 183s 14:32:25.638974718 E: + /usr/bin/ssh -Q key 183s 14:32:25.636059289 O: ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t3.out | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub ; \ 183s 14:32:25.641734322 E: + grep -q ^ssh-rsa 183s 14:32:25.642053547 O: fi 183s 14:32:25.643815399 E: + ssh-keygen -ef /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub 183s 14:32:25.645104631 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 183s 14:32:25.645997298 E: + ssh-keygen -if /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t3.out 183s 14:32:25.647984045 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub 183s 14:32:25.648284496 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub |\ 183s 14:32:25.650002441 E: + /usr/bin/ssh -Q key 183s 14:32:25.652023399 E: + grep -q ^ssh-rsa 183s 14:32:25.653928993 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub 183s 14:32:25.651016745 O: awk '{print $2}' | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t4.ok ; \ 183s 14:32:25.655812582 E: + awk {print $2} 183s 14:32:25.657861627 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t4.ok 183s 14:32:25.658044767 O: fi 183s 14:32:25.659909160 E: + /usr/bin/ssh -Q key 183s 14:32:25.661108054 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 183s 14:32:25.661827449 E: + grep -q ^ssh-rsa 183s 14:32:25.663775515 E: + ssh-keygen -Bf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub 183s 14:32:25.664943461 O: ssh-keygen -Bf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub |\ 183s 14:32:25.665762634 E: + awk {print $2} 183s 14:32:25.667829751 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t5.ok 183s 14:32:25.668236011 O: awk '{print $2}' | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t5.ok ; \ 184s 14:32:25.669974406 E: + /usr/bin/ssh -Q key 184s 14:32:25.681399803 E: + grep -q ^ssh-rsa 184s 14:32:25.683370829 E: + ssh-keygen -q -t rsa -N -f /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t7.out 184s 14:32:25.671786398 O: fi 184s 14:32:25.698748504 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 184s 14:32:25.701798022 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t7.out ; \ 184s 14:32:25.742743621 O: fi 184s 14:32:26.435362781 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 184s 14:32:26.437432968 O: ssh-keygen -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t7.out > /dev/null ; \ 184s 14:32:26.433902027 E: + /usr/bin/ssh -Q key 184s 14:32:26.441772845 E: + grep -q ^ssh-rsa 184s 14:32:26.440125698 O: ssh-keygen -Bf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t7.out > /dev/null ; \ 184s 14:32:26.444088190 O: fi 184s 14:32:26.444173067 E: + ssh-keygen -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t7.out 184s 14:32:26.460293090 E: + ssh-keygen -Bf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t7.out 184s 14:32:26.468015790 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 184s 14:32:26.470010348 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t9.out 184s 14:32:26.626923424 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 184s 14:32:26.629204600 O: ssh-keygen -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t9.out > /dev/null 184s 14:32:26.645831499 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 184s 14:32:26.649028196 O: ssh-keygen -Bf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t9.out > /dev/null 185s 14:32:26.698053252 E: + /usr/bin/ssh -Q key 185s 14:32:26.701145081 E: + grep -q ^ssh-rsa 185s 14:32:26.703745111 E: + ssh-keygen -E sha256 -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub 185s 14:32:26.706285101 E: + awk {print $2} 185s 14:32:26.663090695 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t10.out 185s 14:32:26.708890535 E: + diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t11.ok 185s 14:32:26.710713359 O: ssh-keygen -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t10.out > /dev/null 185s 14:32:26.713831494 O: ssh-keygen -Bf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t10.out > /dev/null 185s 14:32:26.717478059 E: run test connect.sh ... 185s 14:32:26.715950260 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 185s 14:32:26.737037414 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/rsa_openssh.pub |\ 185s 14:32:26.739298988 O: awk '{print $2}' | diff - /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t11.ok ; \ 185s 14:32:26.741918734 O: fi 185s 14:32:26.743910756 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t12.out 185s 14:32:26.758754300 O: ssh-keygen -lf /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/t12.out.pub | grep test-comment-1234 >/dev/null 190s 14:32:32.288871317 O: ok simple connect 190s 14:32:32.288812895 E: run test proxy-connect.sh ... 191s 14:32:33.007489557 O: plain username comp=no 192s 14:32:34.052024499 O: plain username comp=yes 193s 14:32:35.033041422 O: username with style 194s 14:32:36.390653832 O: ok proxy connect 194s 14:32:36.396055301 E: run test sshfp-connect.sh ... 195s 14:32:37.638564812 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 195s 14:32:37.652851681 E: run test connect-privsep.sh ... 210s 14:32:52.153514189 E: run test connect-uri.sh ... 210s 14:32:52.156453038 O: ok proxy connect with privsep 212s 14:32:53.920817471 O: uri connect: no trailing slash 213s 14:32:54.775098382 O: uri connect: trailing slash 213s 14:32:55.356835222 O: uri connect: with path name 213s 14:32:55.420888126 E: run test proto-version.sh ... 213s 14:32:55.421726745 O: ok uri connect 215s 14:32:56.841652768 E: run test proto-mismatch.sh ... 215s 14:32:56.848552203 O: ok sshd version with different protocol combinations 216s 14:32:57.771744075 O: ok protocol version mismatch 216s 14:32:57.772255867 E: run test exit-status.sh ... 216s 14:32:58.539630650 O: test remote exit status: status 0 224s 14:33:06.307199237 O: test remote exit status: status 1 230s 14:33:12.653864652 O: test remote exit status: status 4 237s 14:33:19.439755883 O: test remote exit status: status 5 244s 14:33:26.513761286 O: test remote exit status: status 44 252s 14:33:34.024660160 O: ok remote exit status 252s 14:33:34.047871507 E: run test exit-status-signal.sh ... 255s 14:33:36.777519034 O: ok exit status on signal 255s 14:33:36.782187601 E: run test envpass.sh ... 255s 14:33:37.395573556 O: test environment passing: pass env, don't accept 256s 14:33:38.411487890 O: test environment passing: setenv, don't accept 258s 14:33:39.741314187 O: test environment passing: don't pass env, accept 259s 14:33:40.777674587 O: test environment passing: pass single env, accept single env 260s 14:33:41.765487746 O: test environment passing: pass multiple env, accept multiple env 260s 14:33:42.376714774 O: test environment passing: setenv, accept 261s 14:33:42.769709086 O: test environment passing: setenv, first match wins 261s 14:33:43.413142533 O: test environment passing: server setenv wins 262s 14:33:44.121788462 O: test environment passing: server setenv wins 263s 14:33:45.371375822 E: run test transfer.sh ... 263s 14:33:45.375321244 O: ok environment passing 271s 14:33:53.003652764 O: ok transfer data 271s 14:33:53.005797572 E: run test banner.sh ... 272s 14:33:53.842291082 O: test banner: missing banner file 273s 14:33:54.951705219 O: test banner: size 0 274s 14:33:55.669263142 O: test banner: size 10 274s 14:33:56.367269274 O: test banner: size 100 276s 14:33:57.745561391 O: test banner: size 1000 277s 14:33:59.111492821 O: test banner: size 10000 278s 14:34:00.420104008 O: test banner: size 100000 279s 14:34:01.546088664 O: test banner: suppress banner (-q) 280s 14:34:02.583225073 E: run test rekey.sh ... 280s 14:34:02.581958215 O: ok banner 282s 14:34:03.711353580 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 283s 14:34:04.806847975 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 284s 14:34:06.186792579 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 285s 14:34:07.416583098 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 287s 14:34:09.634303181 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 291s 14:34:13.456332284 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 294s 14:34:16.547274488 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 297s 14:34:19.177344819 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 298s 14:34:20.575304995 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 300s 14:34:22.079136930 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 301s 14:34:23.511213283 O: client rekey KexAlgorithms=curve25519-sha256 303s 14:34:24.861971414 O: client rekey KexAlgorithms=sntrup761x25519-sha512 304s 14:34:26.456879075 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 306s 14:34:27.706312198 O: client rekey KexAlgorithms=mlkem768x25519-sha256 307s 14:34:28.982534858 O: client rekey Ciphers=3des-cbc 308s 14:34:30.096613471 O: client rekey Ciphers=aes128-cbc 309s 14:34:31.063304200 O: client rekey Ciphers=aes192-cbc 310s 14:34:32.522422348 O: client rekey Ciphers=aes256-cbc 311s 14:34:33.598947045 O: client rekey Ciphers=aes128-ctr 313s 14:34:35.000604301 O: client rekey Ciphers=aes192-ctr 314s 14:34:36.479871627 O: client rekey Ciphers=aes256-ctr 316s 14:34:37.698401124 O: client rekey Ciphers=aes128-gcm@openssh.com 334s 14:34:56.566463476 O: client rekey Ciphers=aes256-gcm@openssh.com 352s 14:35:14.611984815 O: client rekey Ciphers=chacha20-poly1305@openssh.com 382s 14:35:44.421722015 O: client rekey MACs=hmac-sha1 384s 14:35:46.173587065 O: client rekey MACs=hmac-sha1-96 385s 14:35:47.299312207 O: client rekey MACs=hmac-sha2-256 386s 14:35:48.214921956 O: client rekey MACs=hmac-sha2-512 388s 14:35:49.837942207 O: client rekey MACs=hmac-md5 388s 14:35:50.437893843 O: client rekey MACs=hmac-md5-96 389s 14:35:51.429700627 O: client rekey MACs=umac-64@openssh.com 391s 14:35:52.799209580 O: client rekey MACs=umac-128@openssh.com 393s 14:35:54.680922547 O: client rekey MACs=hmac-sha1-etm@openssh.com 394s 14:35:56.019563034 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 396s 14:35:57.787053879 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 397s 14:35:58.872855718 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 397s 14:35:59.413673507 O: client rekey MACs=hmac-md5-etm@openssh.com 398s 14:36:00.424180830 O: client rekey MACs=hmac-md5-96-etm@openssh.com 399s 14:36:01.435520116 O: client rekey MACs=umac-64-etm@openssh.com 400s 14:36:02.178146097 O: client rekey MACs=umac-128-etm@openssh.com 401s 14:36:03.475130953 O: client rekeylimit 16 402s 14:36:04.150718062 O: client rekeylimit 1k 403s 14:36:05.485802564 O: client rekeylimit 128k 405s 14:36:07.611802018 O: client rekeylimit 256k 408s 14:36:10.545057656 O: client rekeylimit default 5 425s 14:36:27.284543471 O: client rekeylimit default 10 446s 14:36:48.454808687 O: client rekeylimit default 5 no data 462s 14:37:03.739450965 O: client rekeylimit default 10 no data 483s 14:37:25.187768987 O: server rekeylimit 16 484s 14:37:26.501129447 O: server rekeylimit 1k 486s 14:37:27.872511330 O: server rekeylimit 128k 488s 14:37:29.934780159 O: server rekeylimit 256k 489s 14:37:31.227219269 O: server rekeylimit default 5 no data 505s 14:37:47.164638655 O: server rekeylimit default 10 no data 525s 14:38:07.568902960 O: rekeylimit parsing: bytes 526s 14:38:07.767635246 O: rekeylimit parsing: time 526s 14:38:07.865700378 O: ok rekey 526s 14:38:07.867663409 E: run test dhgex.sh ... 526s 14:38:08.279416822 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 528s 14:38:09.939591510 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 529s 14:38:11.180509257 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 530s 14:38:12.584657147 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 532s 14:38:14.580156797 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 533s 14:38:15.065773743 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 534s 14:38:16.166135248 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 535s 14:38:17.221233027 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 536s 14:38:18.401928588 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 538s 14:38:20.078762527 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 539s 14:38:21.376294405 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 541s 14:38:23.621447204 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 544s 14:38:25.801788634 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 545s 14:38:27.613870598 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 547s 14:38:28.712039596 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 549s 14:38:30.712031234 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 550s 14:38:31.786207929 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 551s 14:38:32.919024298 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 552s 14:38:34.096832298 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 553s 14:38:35.653315845 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 555s 14:38:37.186130297 O: ok dhgex 555s 14:38:37.194541921 E: run test stderr-data.sh ... 556s 14:38:37.842795557 O: test stderr data transfer: () 565s 14:38:47.160702900 O: test stderr data transfer: (-n) 574s 14:38:55.750815206 E: run test stderr-after-eof.sh ... 574s 14:38:55.753240393 O: ok stderr data transfer 578s 14:39:00.163160936 E: run test broken-pipe.sh ... 578s 14:39:00.164869527 O: ok stderr data after eof 578s 14:39:00.343319001 E: run test try-ciphers.sh ... 578s 14:39:00.345038500 O: ok broken pipe test 580s 14:39:01.894657015 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 581s 14:39:03.176210470 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 583s 14:39:05.021856508 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 585s 14:39:06.720002069 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 586s 14:39:08.225157090 O: test try ciphers: cipher 3des-cbc mac hmac-md5 588s 14:39:09.736836155 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 589s 14:39:11.573642697 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 591s 14:39:13.189423762 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 593s 14:39:14.928672774 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 594s 14:39:16.365482499 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 595s 14:39:17.563891350 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 597s 14:39:18.856958394 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 598s 14:39:20.298045963 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 600s 14:39:21.987793743 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 601s 14:39:23.354961577 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 602s 14:39:24.249916842 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 603s 14:39:25.502537624 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 605s 14:39:27.052290782 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 606s 14:39:28.395617319 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 608s 14:39:29.868231859 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 609s 14:39:31.293365586 O: test try ciphers: cipher aes128-cbc mac hmac-md5 610s 14:39:32.665639598 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 612s 14:39:34.062546650 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 613s 14:39:35.445516761 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 614s 14:39:36.582984768 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 616s 14:39:37.749175745 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 617s 14:39:38.925551753 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 618s 14:39:39.742357140 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 619s 14:39:40.631593515 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 619s 14:39:41.335338307 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 620s 14:39:42.102959476 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 621s 14:39:42.889732877 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 623s 14:39:44.591859327 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 625s 14:39:46.830505758 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 626s 14:39:47.962434212 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 627s 14:39:49.470840579 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 629s 14:39:50.971684503 O: test try ciphers: cipher aes192-cbc mac hmac-md5 630s 14:39:52.428219718 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 631s 14:39:53.207869410 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 632s 14:39:54.041177057 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 633s 14:39:54.901442736 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 634s 14:39:56.448361903 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 636s 14:39:57.703335526 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 637s 14:39:59.017785788 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 638s 14:40:00.235209768 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 639s 14:40:01.561729212 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 641s 14:40:03.332902807 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 642s 14:40:04.609684377 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 644s 14:40:06.462744065 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 647s 14:40:08.839144441 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 648s 14:40:10.606978184 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 650s 14:40:12.479039797 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 652s 14:40:13.812742887 O: test try ciphers: cipher aes256-cbc mac hmac-md5 653s 14:40:15.059764253 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 654s 14:40:15.834529285 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 655s 14:40:16.798134039 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 655s 14:40:17.328420611 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 657s 14:40:18.890308870 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 658s 14:40:20.630818401 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 659s 14:40:21.550326508 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 660s 14:40:22.612349010 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 661s 14:40:23.482614939 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 663s 14:40:25.016270397 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 665s 14:40:26.690578133 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 666s 14:40:27.883344387 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 667s 14:40:28.689835616 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 667s 14:40:29.498487272 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 669s 14:40:30.708288814 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 670s 14:40:32.663229527 O: test try ciphers: cipher aes128-ctr mac hmac-md5 671s 14:40:33.652745421 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 673s 14:40:34.830410393 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 674s 14:40:36.141170823 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 675s 14:40:37.119361539 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 677s 14:40:39.174043057 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 678s 14:40:40.227617783 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 680s 14:40:41.739765809 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 681s 14:40:43.122904879 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 682s 14:40:44.280565967 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 683s 14:40:45.429816745 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 684s 14:40:46.227236777 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 685s 14:40:47.480203555 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 687s 14:40:48.867988809 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 688s 14:40:50.169790009 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 689s 14:40:51.048921883 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 690s 14:40:52.189386618 O: test try ciphers: cipher aes192-ctr mac hmac-md5 691s 14:40:52.913146340 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 692s 14:40:54.079671636 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 693s 14:40:55.450185649 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 694s 14:40:56.614818890 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 696s 14:40:57.814864344 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 697s 14:40:59.243540312 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 698s 14:41:00.426678964 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 699s 14:41:01.235770721 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 700s 14:41:02.266873681 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 701s 14:41:02.779762335 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 702s 14:41:03.915735218 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 703s 14:41:05.412996237 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 705s 14:41:07.322767097 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 707s 14:41:08.783286807 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 708s 14:41:10.134880131 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 710s 14:41:12.087523194 O: test try ciphers: cipher aes256-ctr mac hmac-md5 711s 14:41:13.248499883 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 713s 14:41:14.679880362 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 714s 14:41:16.075403095 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 715s 14:41:17.557793136 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 717s 14:41:19.008415945 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 718s 14:41:20.379558376 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 719s 14:41:21.531550941 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 720s 14:41:22.056381535 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 721s 14:41:23.440402602 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 722s 14:41:24.143201931 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 723s 14:41:24.966195035 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 725s 14:41:26.747463556 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 726s 14:41:28.257145337 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 728s 14:41:29.969114691 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 729s 14:41:31.497185632 E: run test yes-head.sh ... 729s 14:41:31.497423269 O: ok try ciphers 733s 14:41:35.061488923 O: ok yes pipe head 733s 14:41:35.063641053 E: run test login-timeout.sh ... 750s 14:41:51.876398191 E: run test agent.sh ... 750s 14:41:51.878566720 O: ok connect after login grace timeout 796s 14:42:38.479332322 E: run test agent-getpeereid.sh ... 796s 14:42:38.477977842 O: ok simple agent test 796s 14:42:38.659430909 O: ok disallow agent attach from other uid 796s 14:42:38.661392131 E: run test agent-timeout.sh ... 818s 14:43:00.138899811 O: ok agent timeout test 818s 14:43:00.139127970 E: run test agent-ptrace.sh ... 818s 14:43:00.541343173 O: skipped (gdb not found) 818s 14:43:00.544068402 E: run test agent-subprocess.sh ... 830s 14:43:12.103069156 O: ok agent subprocess 830s 14:43:12.123942184 E: run test keyscan.sh ... 841s 14:43:23.125200345 O: ok keyscan 841s 14:43:23.126797657 E: run test keygen-change.sh ... 868s 14:43:50.629750550 O: ok change passphrase for key 868s 14:43:50.641221429 E: run test keygen-comment.sh ... 885s 14:44:07.360483126 O: ok Comment extraction from private key 885s 14:44:07.361333743 E: run test keygen-convert.sh ... 898s 14:44:20.476818782 O: ok convert keys 898s 14:44:20.476910564 E: run test keygen-knownhosts.sh ... 900s 14:44:21.952840011 O: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts updated. 900s 14:44:21.954962957 O: Original contents retained as /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts.old 900s 14:44:21.966349127 O: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts updated. 900s 14:44:21.969221507 O: Original contents retained as /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts.old 900s 14:44:21.972747049 O: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts updated. 900s 14:44:21.976171960 O: Original contents retained as /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts.old 900s 14:44:21.987534190 O: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts updated. 900s 14:44:22.017252158 O: Original contents retained as /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hosts.old 900s 14:44:22.082994886 O: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hashed updated. 900s 14:44:22.097908433 O: Original contents retained as /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/kh.hashed.old 900s 14:44:22.117529069 O: ok ssh-keygen known_hosts 900s 14:44:22.126614379 E: run test keygen-moduli.sh ... 924s 14:44:46.569858171 E: run test keygen-sshfp.sh ... 924s 14:44:46.587155328 O: ok keygen moduli 926s 14:44:47.855867681 E: run test key-options.sh ... 926s 14:44:47.857765426 O: ok keygen-sshfp 927s 14:44:48.892767430 O: key option command="echo bar" 927s 14:44:49.341510526 O: key option no-pty,command="echo bar" 928s 14:44:50.584853644 O: key option pty default 929s 14:44:51.466310347 O: key option pty no-pty 930s 14:44:52.042877267 O: key option pty restrict 931s 14:44:53.557327783 O: key option pty restrict,pty 932s 14:44:54.506528023 O: key option environment 935s 14:44:56.929602997 O: key option from="127.0.0.1" 938s 14:44:59.975805184 O: key option from="127.0.0.0/8" 940s 14:45:01.795202928 O: key option expiry-time default 941s 14:45:02.771560613 O: key option expiry-time invalid 942s 14:45:03.965808126 O: key option expiry-time expired 943s 14:45:04.782659039 O: key option expiry-time valid-64b 943s 14:45:05.330611237 E: run test scp.sh ... 943s 14:45:05.330640683 O: ok key options 944s 14:45:05.789195218 O: scp: scp mode: simple copy local file to local file 944s 14:45:05.799256009 O: scp: scp mode: simple copy local file to remote file 944s 14:45:05.809728586 O: scp: scp mode: simple copy remote file to local file 944s 14:45:05.818940663 O: scp: scp mode: copy local file to remote file in place 944s 14:45:05.849151173 O: scp: scp mode: copy remote file to local file in place 944s 14:45:05.904759235 O: scp: scp mode: copy local file to remote file clobber 944s 14:45:05.914931656 O: -rw-rw-r-- 1 ubuntu ubuntu 953384 Jan 24 14:45 /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/copy 944s 14:45:05.917229523 O: -rw-rw-r-- 1 ubuntu ubuntu 953384 Jan 24 14:45 /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/data 944s 14:45:05.919625058 O: scp: scp mode: copy remote file to local file clobber 944s 14:45:05.966135725 O: scp: scp mode: simple copy local file to remote dir 944s 14:45:05.973886374 O: scp: scp mode: simple copy local file to local dir 944s 14:45:06.094825360 O: scp: scp mode: simple copy remote file to local dir 944s 14:45:06.096907876 O: scp: scp mode: recursive local dir to remote dir 944s 14:45:06.099119797 O: scp: scp mode: recursive local dir to local dir 944s 14:45:06.379433436 O: scp: scp mode: recursive remote dir to local dir 944s 14:45:06.547414913 O: scp: scp mode: unmatched glob file local->remote 944s 14:45:06.564029947 O: scp: scp mode: unmatched glob file remote->local 944s 14:45:06.569057804 O: scp: scp mode: unmatched glob dir recursive local->remote 944s 14:45:06.586875718 O: scp: scp mode: unmatched glob dir recursive remote->local 945s 14:45:06.682813264 O: scp: scp mode: shell metacharacters 945s 14:45:06.786868312 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 945s 14:45:06.984848872 O: scp: scp mode: disallow bad server #0 945s 14:45:07.001724246 O: scp: scp mode: disallow bad server #1 945s 14:45:07.019198563 O: scp: scp mode: disallow bad server #2 945s 14:45:07.037061839 O: scp: scp mode: disallow bad server #3 945s 14:45:07.057100620 O: scp: scp mode: disallow bad server #4 945s 14:45:07.081894272 O: scp: scp mode: disallow bad server #5 945s 14:45:07.101430890 O: scp: scp mode: disallow bad server #6 945s 14:45:07.134261134 O: scp: scp mode: disallow bad server #7 945s 14:45:07.252279000 O: scp: scp mode: detect non-directory target 945s 14:45:07.256605872 E: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/copy2: Not a directory 945s 14:45:07.268927595 O: scp: sftp mode: simple copy local file to local file 945s 14:45:07.295718712 O: scp: sftp mode: simple copy local file to remote file 945s 14:45:07.317186061 O: scp: sftp mode: simple copy remote file to local file 945s 14:45:07.332204377 O: scp: sftp mode: copy local file to remote file in place 945s 14:45:07.432834158 O: scp: sftp mode: copy remote file to local file in place 945s 14:45:07.496850471 O: scp: sftp mode: copy local file to remote file clobber 945s 14:45:07.532689580 O: -rw-rw-r-- 1 ubuntu ubuntu 953384 Jan 24 14:45 /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/copy 945s 14:45:07.549855949 O: -rw-rw-r-- 1 ubuntu ubuntu 953384 Jan 24 14:45 /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/data 945s 14:45:07.553283860 O: scp: sftp mode: copy remote file to local file clobber 945s 14:45:07.581935025 O: scp: sftp mode: simple copy local file to remote dir 945s 14:45:07.647549613 O: scp: sftp mode: simple copy local file to local dir 945s 14:45:07.653473190 O: scp: sftp mode: simple copy remote file to local dir 945s 14:45:07.662116956 O: scp: sftp mode: recursive local dir to remote dir 946s 14:45:07.711807686 O: scp: sftp mode: recursive local dir to local dir 946s 14:45:07.785997275 O: scp: sftp mode: recursive remote dir to local dir 946s 14:45:07.857459483 O: scp: sftp mode: unmatched glob file local->remote 946s 14:45:07.868506218 O: scp: sftp mode: unmatched glob file remote->local 946s 14:45:07.872726064 O: scp: sftp mode: unmatched glob dir recursive local->remote 946s 14:45:07.899311681 O: scp: sftp mode: unmatched glob dir recursive remote->local 946s 14:45:07.914006252 O: scp: sftp mode: shell metacharacters 946s 14:45:07.916114455 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 946s 14:45:07.935463000 O: scp: sftp mode: disallow bad server #0 946s 14:45:08.185324702 O: scp: sftp mode: disallow bad server #1 946s 14:45:08.224387813 O: scp: sftp mode: disallow bad server #2 946s 14:45:08.662679761 O: scp: sftp mode: disallow bad server #3 947s 14:45:09.017175672 O: scp: sftp mode: disallow bad server #4 947s 14:45:09.052317090 O: scp: sftp mode: disallow bad server #5 947s 14:45:09.246637034 O: scp: sftp mode: disallow bad server #6 947s 14:45:09.282192806 O: scp: sftp mode: disallow bad server #7 947s 14:45:09.302700633 O: scp: sftp mode: detect non-directory target 947s 14:45:09.305671632 E: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/copy2: Not a directory 947s 14:45:09.309580637 O: ok scp 947s 14:45:09.311253861 E: run test scp3.sh ... 948s 14:45:09.925727672 O: scp3: scp mode: simple copy remote file to remote file 949s 14:45:11.242449652 O: scp3: scp mode: simple copy remote file to remote dir 949s 14:45:11.251193434 E: ln: Already exists 951s 14:45:12.701098931 O: scp3: scp mode: recursive remote dir to remote dir 951s 14:45:12.714380281 E: ln: Already exists 952s 14:45:14.197756971 O: scp3: scp mode: detect non-directory target 956s 14:45:18.132628059 O: scp3: sftp mode: simple copy remote file to remote file 956s 14:45:18.143306660 O: scp3: sftp mode: simple copy remote file to remote dir 956s 14:45:18.157775659 O: scp3: sftp mode: recursive remote dir to remote dir 956s 14:45:18.207337737 O: scp3: sftp mode: detect non-directory target 956s 14:45:18.215504509 E: scp: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/copy2: destination is not a directory 956s 14:45:18.222453109 E: scp: /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/copy2: destination is not a directory 956s 14:45:18.250338164 O: ok scp3 956s 14:45:18.251545176 E: run test scp-uri.sh ... 957s 14:45:19.307478243 O: scp-uri: scp mode: simple copy local file to remote file 957s 14:45:19.313321655 O: scp-uri: scp mode: simple copy remote file to local file 957s 14:45:19.329791996 O: scp-uri: scp mode: simple copy local file to remote dir 957s 14:45:19.360035190 O: scp-uri: scp mode: simple copy remote file to local dir 957s 14:45:19.366923651 O: scp-uri: scp mode: recursive local dir to remote dir 957s 14:45:19.460330983 O: scp-uri: scp mode: recursive remote dir to local dir 957s 14:45:19.474813509 O: scp-uri: sftp mode: simple copy local file to remote file 957s 14:45:19.480673983 O: scp-uri: sftp mode: simple copy remote file to local file 957s 14:45:19.489175778 O: scp-uri: sftp mode: simple copy local file to remote dir 957s 14:45:19.544983973 O: scp-uri: sftp mode: simple copy remote file to local dir 957s 14:45:19.587854488 O: scp-uri: sftp mode: recursive local dir to remote dir 957s 14:45:19.609649485 O: scp-uri: sftp mode: recursive remote dir to local dir 957s 14:45:19.641401352 E: run test sftp.sh ... 957s 14:45:19.643417547 O: ok scp-uri 958s 14:45:20.524749045 O: test basic sftp put/get: buffer_size 5 num_requests 1 1000s 14:46:01.978318739 O: test basic sftp put/get: buffer_size 5 num_requests 2 1026s 14:46:27.816167075 O: test basic sftp put/get: buffer_size 5 num_requests 10 1033s 14:46:35.201623515 O: test basic sftp put/get: buffer_size 1000 num_requests 1 1033s 14:46:35.223742706 O: test basic sftp put/get: buffer_size 1000 num_requests 2 1033s 14:46:35.270914344 O: test basic sftp put/get: buffer_size 1000 num_requests 10 1033s 14:46:35.390468681 O: test basic sftp put/get: buffer_size 32000 num_requests 1 1033s 14:46:35.401732752 O: test basic sftp put/get: buffer_size 32000 num_requests 2 1033s 14:46:35.422913697 O: test basic sftp put/get: buffer_size 32000 num_requests 10 1033s 14:46:35.483893992 O: test basic sftp put/get: buffer_size 64000 num_requests 1 1033s 14:46:35.500697272 O: test basic sftp put/get: buffer_size 64000 num_requests 2 1033s 14:46:35.519323119 O: test basic sftp put/get: buffer_size 64000 num_requests 10 1033s 14:46:35.551355329 E: run test sftp-chroot.sh ... 1033s 14:46:35.561670971 O: ok basic sftp put/get 1035s 14:46:37.511503598 O: test sftp in chroot: get 1036s 14:46:38.067909931 O: test sftp in chroot: match 1038s 14:46:39.848495973 O: ok sftp in chroot 1038s 14:46:39.892643384 E: run test sftp-cmds.sh ... 1039s 14:46:40.887683046 O: sftp commands: lls 1039s 14:46:41.297570283 O: sftp commands: lls w/path 1039s 14:46:41.343783278 O: sftp commands: ls 1039s 14:46:41.352539157 O: sftp commands: shell 1039s 14:46:41.358810894 O: sftp commands: pwd 1039s 14:46:41.361508526 O: sftp commands: lpwd 1039s 14:46:41.364222845 O: sftp commands: quit 1039s 14:46:41.367121278 O: sftp commands: help 1039s 14:46:41.370352718 O: sftp commands: get 1039s 14:46:41.376343655 O: sftp commands: get quoted 1039s 14:46:41.399802613 O: sftp commands: get filename with quotes 1039s 14:46:41.536275358 O: sftp commands: get filename with spaces 1039s 14:46:41.592649713 O: sftp commands: get filename with glob metacharacters 1039s 14:46:41.624908808 O: sftp commands: get to directory 1039s 14:46:41.630232863 O: sftp commands: glob get to directory 1040s 14:46:42.079615988 O: sftp commands: get to local dir 1040s 14:46:42.089976624 O: sftp commands: glob get to local dir 1040s 14:46:42.220287483 O: sftp commands: put 1040s 14:46:42.239804822 O: sftp commands: put filename with quotes 1040s 14:46:42.272563346 O: sftp commands: put filename with spaces 1040s 14:46:42.310938180 O: sftp commands: put to directory 1040s 14:46:42.362949830 O: sftp commands: glob put to directory 1040s 14:46:42.365867439 O: sftp commands: put to local dir 1040s 14:46:42.389891897 O: sftp commands: glob put to local dir 1041s 14:46:42.829741710 O: sftp commands: rename 1041s 14:46:42.863462087 O: sftp commands: rename directory 1041s 14:46:42.885514346 O: sftp commands: ln 1041s 14:46:42.898203891 O: sftp commands: ln -s 1041s 14:46:42.900354445 O: sftp commands: cp 1041s 14:46:42.902350135 O: sftp commands: mkdir 1041s 14:46:42.901731062 E: run test sftp-badcmds.sh ... 1041s 14:46:42.904454836 O: sftp commands: chdir 1041s 14:46:42.910389533 O: sftp commands: rmdir 1041s 14:46:42.930178684 O: sftp commands: lmkdir 1041s 14:46:42.944657671 O: sftp commands: lchdir 1041s 14:46:42.981454838 O: ok sftp commands 1041s 14:46:43.580856577 O: sftp invalid commands: get nonexistent 1041s 14:46:43.583825687 O: sftp invalid commands: glob get to nonexistent directory 1041s 14:46:43.594209573 O: sftp invalid commands: put nonexistent 1041s 14:46:43.628263183 O: sftp invalid commands: glob put to nonexistent directory 1041s 14:46:43.669403376 O: sftp invalid commands: rename nonexistent 1042s 14:46:43.671539747 O: sftp invalid commands: rename target exists (directory) 1042s 14:46:43.673461425 O: sftp invalid commands: glob put files to local file 1042s 14:46:43.714693914 O: ok sftp invalid commands 1042s 14:46:43.743567710 E: run test sftp-batch.sh ... 1042s 14:46:44.289581548 O: sftp batchfile: good commands 1042s 14:46:44.483622047 O: sftp batchfile: bad commands 1042s 14:46:44.490671518 O: sftp batchfile: comments and blanks 1042s 14:46:44.545663050 E: run test sftp-glob.sh ... 1042s 14:46:44.540484608 O: sftp batchfile: junk command 1042s 14:46:44.580196445 O: ok sftp batchfile 1043s 14:46:45.172848477 O: sftp glob: file glob 1043s 14:46:45.293823383 O: sftp glob: dir glob 1043s 14:46:45.299166180 O: sftp glob: quoted glob 1043s 14:46:45.329284137 O: sftp glob: escaped glob 1043s 14:46:45.346584407 O: sftp glob: escaped quote 1043s 14:46:45.361957519 O: sftp glob: quoted quote 1043s 14:46:45.385778205 O: sftp glob: single-quoted quote 1043s 14:46:45.439558660 O: sftp glob: escaped space 1043s 14:46:45.443917805 O: sftp glob: quoted space 1044s 14:46:45.771889316 O: sftp glob: escaped slash 1044s 14:46:45.807931462 O: sftp glob: quoted slash 1044s 14:46:45.812677809 O: sftp glob: escaped slash at EOL 1044s 14:46:45.817689386 O: sftp glob: quoted slash at EOL 1044s 14:46:45.824709926 O: sftp glob: escaped slash+quote 1044s 14:46:45.838871452 O: sftp glob: quoted slash+quote 1044s 14:46:45.883493007 E: run test sftp-perm.sh ... 1044s 14:46:45.885330674 O: ok sftp glob 1045s 14:46:46.845735363 O: sftp permissions: read-only upload 1045s 14:46:46.919612519 O: sftp permissions: read-only setstat 1045s 14:46:46.962392840 O: sftp permissions: read-only rm 1045s 14:46:46.976235939 O: sftp permissions: read-only mkdir 1045s 14:46:47.016241906 O: sftp permissions: read-only rmdir 1045s 14:46:47.117994973 O: sftp permissions: read-only posix-rename 1045s 14:46:47.170140207 O: sftp permissions: read-only oldrename 1045s 14:46:47.184816061 O: sftp permissions: read-only symlink 1045s 14:46:47.241353039 O: sftp permissions: read-only hardlink 1045s 14:46:47.321188191 O: sftp permissions: explicit open 1045s 14:46:47.382850868 O: sftp permissions: explicit read 1045s 14:46:47.520579467 O: sftp permissions: explicit write 1046s 14:46:47.799595368 O: sftp permissions: explicit lstat 1046s 14:46:47.873339546 O: sftp permissions: explicit opendir 1046s 14:46:48.081189066 O: sftp permissions: explicit readdir 1046s 14:46:48.198351892 O: sftp permissions: explicit setstat 1046s 14:46:48.271685176 O: sftp permissions: explicit remove 1046s 14:46:48.346262687 O: sftp permissions: explicit mkdir 1046s 14:46:48.498875578 O: sftp permissions: explicit rmdir 1047s 14:46:48.669469214 O: sftp permissions: explicit rename 1047s 14:46:48.679008313 O: sftp permissions: explicit symlink 1047s 14:46:48.795379089 O: sftp permissions: explicit hardlink 1047s 14:46:49.310357378 O: sftp permissions: explicit statvfs 1047s 14:46:49.462111516 O: ok sftp permissions 1047s 14:46:49.462076929 E: run test sftp-resume.sh ... 1048s 14:46:50.160503817 O: sftp resume: put 1049s 14:46:51.051758275 O: sftp resume: get 1050s 14:46:51.639658439 E: run test sftp-uri.sh ... 1050s 14:46:51.674383249 O: ok sftp resume 1051s 14:46:53.266636275 O: sftp-uri: non-interactive fetch to local file 1052s 14:46:54.336896781 O: sftp-uri: non-interactive fetch to local dir 1053s 14:46:55.037900436 O: sftp-uri: put to remote directory (trailing slash) 1054s 14:46:56.415938882 O: sftp-uri: put to remote directory (no slash) 1055s 14:46:57.122932929 E: run test reconfigure.sh ... 1055s 14:46:57.124983946 O: ok sftp-uri 1071s 14:47:13.543414327 E: run test dynamic-forward.sh ... 1071s 14:47:13.541034863 O: ok simple connect after reconfigure 1073s 14:47:15.048645000 O: test -D forwarding 1080s 14:47:21.683476937 O: test -R forwarding 1087s 14:47:29.117825285 O: PermitRemoteOpen=any 1093s 14:47:35.506251225 O: PermitRemoteOpen=none 1096s 14:47:37.884724253 O: PermitRemoteOpen=explicit 1103s 14:47:44.936211108 O: PermitRemoteOpen=disallowed 1104s 14:47:46.589780581 E: run test forwarding.sh ... 1104s 14:47:46.592021497 O: ok dynamic forwarding 1125s 14:48:07.659638238 O: ok local and remote forwarding 1125s 14:48:07.659497179 E: run test multiplex.sh ... 1128s 14:48:09.979918520 O: test connection multiplexing: setenv 1128s 14:48:10.016844957 O: test connection multiplexing: envpass 1128s 14:48:10.024132011 O: test connection multiplexing: transfer 1128s 14:48:10.233084250 O: test connection multiplexing: transfer -Oproxy 1129s 14:48:10.867634187 O: test connection multiplexing: forward 1131s 14:48:13.038277439 O: test connection multiplexing: status 0 () 1136s 14:48:18.163383198 O: test connection multiplexing: status 0 (-Oproxy) 1141s 14:48:23.187318369 O: test connection multiplexing: status 1 () 1146s 14:48:28.209682992 O: test connection multiplexing: status 1 (-Oproxy) 1151s 14:48:33.232409907 O: test connection multiplexing: status 4 () 1156s 14:48:38.260200079 O: test connection multiplexing: status 4 (-Oproxy) 1161s 14:48:43.283142358 O: test connection multiplexing: status 5 () 1166s 14:48:48.305270988 O: test connection multiplexing: status 5 (-Oproxy) 1171s 14:48:53.327943496 O: test connection multiplexing: status 44 () 1176s 14:48:58.350302008 O: test connection multiplexing: status 44 (-Oproxy) 1181s 14:49:03.372672143 O: test connection multiplexing: cmd check 1181s 14:49:03.378762143 O: test connection multiplexing: cmd forward local (TCP) 1182s 14:49:04.617493695 O: test connection multiplexing: cmd forward remote (TCP) 1184s 14:49:05.867011437 O: test connection multiplexing: cmd forward local (UNIX) 1185s 14:49:07.157757684 O: test connection multiplexing: cmd forward remote (UNIX) 1186s 14:49:08.270063171 O: test connection multiplexing: cmd exit 1186s 14:49:08.277481003 O: test connection multiplexing: cmd stop 1198s 14:49:20.016810012 O: ok connection multiplexing 1198s 14:49:20.020887836 E: run test reexec.sh ... 1198s 14:49:20.584044562 O: test config passing 1199s 14:49:21.668822197 E: ln: failed to create hard link '/usr/sbin/sshd' => '/tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/sshd': Permission denied 1199s 14:49:21.667591931 O: test reexec fallback 1201s 14:49:23.018431651 E: run test brokenkeys.sh ... 1201s 14:49:23.020656360 O: ok reexec tests 1204s 14:49:24.947245551 O: ok broken keys 1204s 14:49:24.951639753 E: run test sshcfgparse.sh ... 1204s 14:49:25.676284631 O: reparse minimal config 1204s 14:49:25.704350164 O: ssh -W opts 1204s 14:49:25.745032905 O: user first match 1204s 14:49:25.802494394 O: pubkeyacceptedalgorithms 1204s 14:49:25.866613651 O: agentforwarding 1204s 14:49:26.035342178 O: command line override 1204s 14:49:26.075556748 E: run test cfgparse.sh ... 1204s 14:49:26.075491720 O: ok ssh config parse 1204s 14:49:26.302419549 O: reparse minimal config 1205s 14:49:27.124054126 O: reparse regress config 1206s 14:49:27.798972038 O: listenaddress order 1206s 14:49:28.151089531 O: maxstartups idempotent 1206s 14:49:28.273852921 E: run test cfgmatch.sh ... 1206s 14:49:28.275612549 O: ok sshd config parse 1221s 14:49:43.036250433 E: run test cfgmatchlisten.sh ... 1221s 14:49:43.035626492 O: ok sshd_config match 1244s 14:50:06.381219134 O: ok sshd_config matchlisten 1244s 14:50:06.381489253 E: run test percent.sh ... 1246s 14:50:08.050808866 O: percent expansions matchexec percent 1259s 14:50:21.302214973 O: percent expansions localcommand percent 1269s 14:50:31.233364893 O: percent expansions remotecommand percent 1269s 14:50:31.450337664 O: percent expansions controlpath percent 1270s 14:50:32.172161191 O: percent expansions identityagent percent 1270s 14:50:32.552399440 O: percent expansions forwardagent percent 1270s 14:50:32.670774955 O: percent expansions localforward percent 1271s 14:50:32.804321550 O: percent expansions remoteforward percent 1272s 14:50:33.697448596 O: percent expansions revokedhostkeys percent 1272s 14:50:34.033960848 O: percent expansions user percent 1272s 14:50:34.107790312 O: percent expansions setenv percent 1273s 14:50:35.103405261 O: percent expansions userknownhostsfile percent 1284s 14:50:45.757390988 O: percent expansions controlpath dollar 1284s 14:50:45.785261722 O: percent expansions identityagent dollar 1284s 14:50:45.848586893 O: percent expansions forwardagent dollar 1284s 14:50:45.861297039 O: percent expansions localforward dollar 1284s 14:50:45.921233184 O: percent expansions remoteforward dollar 1284s 14:50:45.934923127 O: percent expansions user dollar 1284s 14:50:46.116583948 O: percent expansions setenv dollar 1284s 14:50:46.180447008 O: percent expansions userknownhostsfile dollar 1285s 14:50:47.313913190 O: percent expansions controlpath tilde 1285s 14:50:47.400368775 O: percent expansions identityagent tilde 1285s 14:50:47.449899723 O: percent expansions forwardagent tilde 1285s 14:50:47.518378987 O: percent expansions user-l noexpand 1285s 14:50:47.600915835 O: percent expansions user-at noexpand 1285s 14:50:47.648385500 O: percent expansions user-l noenv 1285s 14:50:47.656181845 O: percent expansions user-at noenv 1286s 14:50:47.751621518 O: percent expansions user-l badchar 1286s 14:50:47.782125089 O: percent expansions user-at badchar 1286s 14:50:47.814847734 O: percent expansions user control-literal 1286s 14:50:47.835982059 E: run test addrmatch.sh ... 1286s 14:50:47.857570023 O: ok percent expansions 1287s 14:50:48.784709109 O: test first entry for user 192.168.0.1 somehost 1287s 14:50:49.275110648 O: test negative match for user 192.168.30.1 somehost 1288s 14:50:49.786084948 O: test no match for user 19.0.0.1 somehost 1288s 14:50:50.168015032 O: test list middle for user 10.255.255.254 somehost 1288s 14:50:50.648537268 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 1289s 14:50:50.897235257 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 1290s 14:50:51.239627961 O: test localaddress for user 19.0.0.1 somehost 1290s 14:50:51.564498109 O: test localport for user 19.0.0.1 somehost 1290s 14:50:52.061328200 O: test bare IP6 address for user ::1 somehost.example.com 1290s 14:50:52.440333682 O: test deny IPv6 for user ::2 somehost.example.com 1291s 14:50:52.843847315 O: test IP6 negated for user ::3 somehost 1291s 14:50:53.320966128 O: test IP6 no match for user ::4 somehost 1292s 14:50:53.742695582 O: test IP6 network for user 2000::1 somehost 1292s 14:50:53.818049651 O: test IP6 network for user 2001::1 somehost 1292s 14:50:53.893028304 O: test IP6 localaddress for user ::5 somehost 1292s 14:50:54.465500500 O: test IP6 localport for user ::5 somehost 1293s 14:50:54.721658867 O: test invalid Match address 10.0.1.0/8 1293s 14:50:54.765982465 O: test invalid Match localaddress 10.0.1.0/8 1293s 14:50:54.790464308 O: test invalid Match address 10.0.0.1/24 1293s 14:50:54.838331499 O: test invalid Match localaddress 10.0.0.1/24 1293s 14:50:54.856512306 O: test invalid Match address 2000:aa:bb:01::/56 1293s 14:50:54.859138039 O: test invalid Match localaddress 2000:aa:bb:01::/56 1293s 14:50:55.039882848 O: ok address match 1293s 14:50:55.045975688 E: run test localcommand.sh ... 1294s 14:50:55.931576330 O: test localcommand: proto localcommand 1295s 14:50:56.973247846 O: ok localcommand 1295s 14:50:56.975077113 E: run test forcecommand.sh ... 1301s 14:51:02.971688710 E: Connection closed. 1301s 14:51:02.973885221 E: Connection closed 1304s 14:51:05.779084444 E: Connection closed 1305s 14:51:07.043382644 O: ok forced command 1305s 14:51:07.043794608 E: run test portnum.sh ... 1306s 14:51:08.176581908 O: port number parsing: invalid port 0 1306s 14:51:08.222672093 O: port number parsing: invalid port 65536 1306s 14:51:08.292030477 O: port number parsing: invalid port 131073 1306s 14:51:08.305119502 O: port number parsing: invalid port 2000blah 1306s 14:51:08.327614592 O: port number parsing: invalid port blah2000 1306s 14:51:08.441432098 O: port number parsing: valid port 1 1308s 14:51:09.681754129 O: port number parsing: valid port 22 1308s 14:51:10.480411464 O: port number parsing: valid port 2222 1309s 14:51:10.979542236 O: port number parsing: valid port 22222 1310s 14:51:12.143075700 O: port number parsing: valid port 65535 1311s 14:51:13.305860147 E: run test keytype.sh ... 1311s 14:51:13.304441976 O: ok port number parsing 1312s 14:51:14.193726246 O: keygen ed25519, 512 bits 1312s 14:51:14.316621246 O: keygen ed25519-sk, n/a bits 1312s 14:51:14.327114281 O: keygen ecdsa, 256 bits 1312s 14:51:14.346167941 O: keygen ecdsa, 384 bits 1312s 14:51:14.511029563 O: keygen ecdsa, 521 bits 1312s 14:51:14.516102780 O: keygen ecdsa-sk, n/a bits 1312s 14:51:14.541301272 O: keygen rsa, 2048 bits 1313s 14:51:14.983844370 O: keygen rsa, 3072 bits 1315s 14:51:17.536924877 O: userkey ed25519-512, hostkey ed25519-512 1316s 14:51:17.694632491 O: userkey ed25519-512, hostkey ed25519-512 1316s 14:51:17.857827762 O: userkey ed25519-512, hostkey ed25519-512 1316s 14:51:18.109206012 O: userkey ed25519-sk, hostkey ed25519-sk 1316s 14:51:18.331776260 O: userkey ed25519-sk, hostkey ed25519-sk 1316s 14:51:18.559195085 O: userkey ed25519-sk, hostkey ed25519-sk 1317s 14:51:18.825822533 O: userkey ecdsa-256, hostkey ecdsa-256 1317s 14:51:19.253792316 O: userkey ecdsa-256, hostkey ecdsa-256 1318s 14:51:20.078005764 O: userkey ecdsa-256, hostkey ecdsa-256 1318s 14:51:20.253789997 O: userkey ecdsa-384, hostkey ecdsa-384 1319s 14:51:20.696876861 O: userkey ecdsa-384, hostkey ecdsa-384 1319s 14:51:21.449678081 O: userkey ecdsa-384, hostkey ecdsa-384 1320s 14:51:22.129795075 O: userkey ecdsa-521, hostkey ecdsa-521 1321s 14:51:23.283586086 O: userkey ecdsa-521, hostkey ecdsa-521 1322s 14:51:24.251962653 O: userkey ecdsa-521, hostkey ecdsa-521 1323s 14:51:25.450030408 O: userkey ecdsa-sk, hostkey ecdsa-sk 1324s 14:51:25.805939908 O: userkey ecdsa-sk, hostkey ecdsa-sk 1324s 14:51:26.494533658 O: userkey ecdsa-sk, hostkey ecdsa-sk 1325s 14:51:27.010438406 O: userkey rsa-2048, hostkey rsa-2048 1325s 14:51:27.338471031 O: userkey rsa-2048, hostkey rsa-2048 1325s 14:51:27.517879101 O: userkey rsa-2048, hostkey rsa-2048 1326s 14:51:28.293391836 O: userkey rsa-3072, hostkey rsa-3072 1326s 14:51:28.500592803 O: userkey rsa-3072, hostkey rsa-3072 1327s 14:51:28.751318703 O: userkey rsa-3072, hostkey rsa-3072 1327s 14:51:29.040838535 O: ok login with different key types 1327s 14:51:29.041487619 E: run test kextype.sh ... 1327s 14:51:29.643901885 O: kex diffie-hellman-group1-sha1 1331s 14:51:33.457021897 O: kex diffie-hellman-group14-sha1 1335s 14:51:36.966681861 O: kex diffie-hellman-group14-sha256 1338s 14:51:40.477281194 O: kex diffie-hellman-group16-sha512 1342s 14:51:44.317082226 O: kex diffie-hellman-group18-sha512 1349s 14:51:51.558927225 O: kex diffie-hellman-group-exchange-sha1 1356s 14:51:57.854869563 O: kex diffie-hellman-group-exchange-sha256 1360s 14:52:02.472894945 O: kex ecdh-sha2-nistp256 1365s 14:52:07.262842137 O: kex ecdh-sha2-nistp384 1370s 14:52:11.663398455 O: kex ecdh-sha2-nistp521 1374s 14:52:15.969652084 O: kex curve25519-sha256 1378s 14:52:19.741474586 O: kex curve25519-sha256@libssh.org 1381s 14:52:23.105320768 O: kex sntrup761x25519-sha512 1384s 14:52:26.488033940 O: kex sntrup761x25519-sha512@openssh.com 1388s 14:52:29.807766633 O: kex mlkem768x25519-sha256 1391s 14:52:33.572305812 E: run test cert-hostkey.sh ... 1391s 14:52:33.574226932 O: ok login with different key exchange algorithms 1396s 14:52:37.926015210 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/host_ca_key.pub 1396s 14:52:37.928373214 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/host_ca_key2.pub 1396s 14:52:37.930410946 O: certified host keys: sign host ed25519 cert 1396s 14:52:37.998859501 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ed25519.pub 1396s 14:52:38.031488761 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ed25519-cert.pub 1396s 14:52:38.054188262 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 1396s 14:52:38.101937870 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 1396s 14:52:38.151065004 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 1396s 14:52:38.153909189 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 1396s 14:52:38.165384063 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp256.pub 1396s 14:52:38.248340313 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 1396s 14:52:38.255962821 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 1396s 14:52:38.301397265 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp384.pub 1396s 14:52:38.401043816 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 1396s 14:52:38.419682047 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 1396s 14:52:38.504304032 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp521.pub 1396s 14:52:38.584111071 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 1396s 14:52:38.609858047 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 1397s 14:52:38.768429485 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 1397s 14:52:38.783909698 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1397s 14:52:38.813113096 O: certified host keys: sign host rsa cert 1398s 14:52:40.227869301 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_rsa.pub 1398s 14:52:40.398670616 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_rsa-cert.pub 1398s 14:52:40.433801086 O: certified host keys: sign host rsa-sha2-256 cert 1399s 14:52:41.623480305 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-256.pub 1400s 14:52:41.698324198 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-256-cert.pub 1400s 14:52:41.722587681 O: certified host keys: sign host rsa-sha2-512 cert 1403s 14:52:44.882182428 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-512.pub 1403s 14:52:44.906101673 O: Revoking from /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/cert_host_key_rsa-sha2-512-cert.pub 1403s 14:52:44.911259215 O: certified host keys: host ed25519 cert connect 1403s 14:52:44.925625694 O: certified host keys: ed25519 basic connect expect success yes 1404s 14:52:45.717141436 O: certified host keys: ed25519 empty KRL expect success yes 1404s 14:52:46.054443726 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 1405s 14:52:46.704201406 E: mm_log_handler: write: Broken pipe 1405s 14:52:46.710725074 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 1405s 14:52:47.320976633 E: mm_log_handler: write: Broken pipe 1405s 14:52:47.343317223 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 1406s 14:52:47.690711703 E: mm_log_handler: write: Broken pipe 1406s 14:52:47.694054575 O: certified host keys: ed25519 empty plaintext revocation expect success yes 1406s 14:52:48.558118614 O: certified host keys: ed25519 plain key plaintext revocation expect success no 1407s 14:52:49.009405868 E: mm_log_handler: write: Broken pipe 1407s 14:52:49.010614501 O: certified host keys: ed25519 cert plaintext revocation expect success no 1407s 14:52:49.496703501 E: mm_log_handler: write: Broken pipe 1407s 14:52:49.533830371 O: certified host keys: ed25519 CA plaintext revocation expect success no 1408s 14:52:50.614426425 E: mm_log_handler: write: Broken pipe 1408s 14:52:50.615988061 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 1408s 14:52:50.618111221 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 1409s 14:52:51.176724079 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 1409s 14:52:51.587051274 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 1410s 14:52:52.403415347 E: mm_log_handler: write: Broken pipe 1410s 14:52:52.411355720 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 1411s 14:52:52.834750348 E: mm_log_handler: write: Broken pipe 1411s 14:52:52.837830827 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 1411s 14:52:53.390873653 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 1412s 14:52:54.519309457 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 1413s 14:52:54.922988589 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 1414s 14:52:55.687840808 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 1414s 14:52:56.473339165 E: mm_log_handler: write: Broken pipe 1414s 14:52:56.477819463 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 1414s 14:52:56.490259678 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 1415s 14:52:57.657786808 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 1417s 14:52:59.159892360 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 1417s 14:52:59.594678139 E: mm_log_handler: write: Broken pipe 1417s 14:52:59.623007518 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 1418s 14:53:00.456935956 E: mm_log_handler: write: Broken pipe 1418s 14:53:00.490039022 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 1419s 14:53:01.469395992 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 1420s 14:53:02.484539309 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 1421s 14:53:03.430558539 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 1422s 14:53:03.947629660 E: mm_log_handler: write: Broken pipe 1422s 14:53:03.949977962 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 1423s 14:53:04.871033132 E: mm_log_handler: write: Broken pipe 1423s 14:53:04.871500101 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 1423s 14:53:04.875393476 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 1424s 14:53:06.588803596 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 1426s 14:53:07.798539659 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 1426s 14:53:08.424957643 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 1426s 14:53:08.426979842 E: mm_log_handler: write: Broken pipe 1427s 14:53:09.328146518 E: mm_log_handler: write: Broken pipe 1427s 14:53:09.328441644 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 1428s 14:53:10.191230139 E: mm_log_handler: write: Broken pipe 1428s 14:53:10.191805650 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 1429s 14:53:11.094872686 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 1430s 14:53:12.175219649 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 1431s 14:53:13.364956363 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 1431s 14:53:13.364956391 E: mm_log_handler: write: Broken pipe 1432s 14:53:14.578457179 E: mm_log_handler: write: Broken pipe 1432s 14:53:14.578588968 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 1432s 14:53:14.585104183 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 1434s 14:53:16.240004611 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 1436s 14:53:17.936150431 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 1437s 14:53:19.211904219 E: mm_log_handler: write: Broken pipe 1437s 14:53:19.212962700 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 1438s 14:53:20.545034923 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 1440s 14:53:21.816956587 E: mm_log_handler: write: Broken pipe 1440s 14:53:21.831427721 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 1441s 14:53:23.190794281 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 1442s 14:53:24.546357756 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 1443s 14:53:25.545408977 E: mm_log_handler: write: Broken pipe 1443s 14:53:25.546413048 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 1444s 14:53:26.613616250 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 1444s 14:53:26.625041300 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 1445s 14:53:26.976160511 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 1446s 14:53:27.797760363 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 1446s 14:53:28.422593672 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 1447s 14:53:29.522875236 E: mm_log_handler: write: Broken pipe 1447s 14:53:29.523424017 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 1448s 14:53:29.924949509 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 1449s 14:53:31.034218268 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 1449s 14:53:31.571359339 E: mm_log_handler: write: Broken pipe 1449s 14:53:31.570009625 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 1451s 14:53:32.809820776 E: mm_log_handler: write: Broken pipe 1451s 14:53:32.805575673 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 1452s 14:53:33.860181636 E: mm_log_handler: write: Broken pipe 1452s 14:53:33.909586473 O: certified host keys: host rsa cert connect 1452s 14:53:33.919171593 O: certified host keys: rsa basic connect expect success yes 1452s 14:53:34.468099806 O: certified host keys: rsa empty KRL expect success yes 1454s 14:53:35.794997952 O: certified host keys: rsa KRL w/ plain key revoked expect success no 1455s 14:53:36.927266627 O: certified host keys: rsa KRL w/ cert revoked expect success no 1456s 14:53:37.929371931 O: certified host keys: rsa KRL w/ CA revoked expect success no 1456s 14:53:38.418905080 E: mm_log_handler: write: Broken pipe 1456s 14:53:38.419946117 O: certified host keys: rsa empty plaintext revocation expect success yes 1457s 14:53:39.011999926 O: certified host keys: rsa plain key plaintext revocation expect success no 1458s 14:53:39.751597554 O: certified host keys: rsa cert plaintext revocation expect success no 1458s 14:53:39.753889239 E: mm_log_handler: write: Broken pipe 1459s 14:53:40.869039814 E: mm_log_handler: write: Broken pipe 1459s 14:53:40.869363508 O: certified host keys: rsa CA plaintext revocation expect success no 1459s 14:53:41.389727754 E: mm_log_handler: write: Broken pipe 1459s 14:53:41.389588565 O: certified host keys: host rsa-sha2-256 cert connect 1459s 14:53:41.398622026 O: certified host keys: rsa-sha2-256 basic connect expect success yes 1460s 14:53:42.141060658 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 1461s 14:53:43.389370484 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 1462s 14:53:44.031545143 E: mm_log_handler: write: Broken pipe 1462s 14:53:44.033191488 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 1463s 14:53:45.200202069 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 1464s 14:53:46.467718151 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 1466s 14:53:47.723839077 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 1467s 14:53:48.869955879 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 1468s 14:53:49.800979720 E: mm_log_handler: write: Broken pipe 1468s 14:53:49.803194868 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 1468s 14:53:50.544525261 O: certified host keys: host rsa-sha2-512 cert connect 1468s 14:53:50.562261197 O: certified host keys: rsa-sha2-512 basic connect expect success yes 1469s 14:53:51.371087269 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 1471s 14:53:52.678683682 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 1472s 14:53:53.755366447 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 1472s 14:53:54.229153039 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 1472s 14:53:54.227361584 E: mm_log_handler: write: Broken pipe 1473s 14:53:54.669128346 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 1473s 14:53:55.368460455 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 1474s 14:53:56.503864688 E: mm_log_handler: write: Broken pipe 1474s 14:53:56.504010775 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 1475s 14:53:57.432838518 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 1476s 14:53:58.514507249 E: mm_log_handler: write: Broken pipe 1476s 14:53:58.574449839 O: certified host keys: host ed25519 revoked cert 1477s 14:53:59.267321150 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 1478s 14:54:00.359556459 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 1479s 14:54:01.421644160 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 1481s 14:54:02.674473546 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 1482s 14:54:04.594531128 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 1484s 14:54:05.912370689 O: certified host keys: host rsa revoked cert 1485s 14:54:07.074201086 O: certified host keys: host rsa-sha2-256 revoked cert 1486s 14:54:08.108767789 O: certified host keys: host rsa-sha2-512 revoked cert 1486s 14:54:08.640434189 O: certified host keys: host ed25519 revoked cert 1487s 14:54:09.662939312 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 1488s 14:54:10.164079839 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 1489s 14:54:10.845980588 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 1490s 14:54:11.795683284 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 1490s 14:54:12.656630902 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 1491s 14:54:13.326675495 O: certified host keys: host rsa revoked cert 1492s 14:54:14.156512518 O: certified host keys: host rsa-sha2-256 revoked cert 1492s 14:54:14.541775690 O: certified host keys: host rsa-sha2-512 revoked cert 1549s 14:55:10.925781811 O: certified host keys: host ed25519 cert downgrade to raw key 1551s 14:55:13.049291618 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 1552s 14:55:14.455267429 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 1555s 14:55:16.712028495 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 1558s 14:55:19.762816526 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 1561s 14:55:23.099002051 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 1563s 14:55:25.201373563 O: certified host keys: host rsa cert downgrade to raw key 1570s 14:55:28.131601210 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 1570s 14:55:31.818856225 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 1576s 14:55:38.048607531 O: certified host keys: host ed25519 connect wrong cert 1577s 14:55:38.676792023 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 1577s 14:55:39.622144657 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 1578s 14:55:40.533947203 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 1580s 14:55:41.678874923 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 1581s 14:55:43.175077078 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 1582s 14:55:43.709615065 O: certified host keys: host rsa connect wrong cert 1584s 14:55:46.212455821 O: certified host keys: host rsa-sha2-256 connect wrong cert 1592s 14:55:53.703585128 O: certified host keys: host rsa-sha2-512 connect wrong cert 1599s 14:56:01.617827581 E: run test cert-userkey.sh ... 1599s 14:56:01.619867961 O: ok certified host keys 1605s 14:56:07.471348921 O: certified user keys: sign user ed25519 cert 1605s 14:56:07.514221015 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 1605s 14:56:07.564610171 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 1605s 14:56:07.612602337 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 1606s 14:56:07.677838323 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 1606s 14:56:07.789825171 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 1606s 14:56:07.882598750 O: certified user keys: sign user rsa cert 1608s 14:56:09.963028519 O: certified user keys: sign user rsa-sha2-256 cert 1609s 14:56:10.888054346 O: certified user keys: sign user rsa-sha2-512 cert 1612s 14:56:14.344396762 O: certified user keys: ed25519 missing authorized_principals 1613s 14:56:15.093447944 O: certified user keys: ed25519 empty authorized_principals 1614s 14:56:16.121347211 O: certified user keys: ed25519 wrong authorized_principals 1615s 14:56:17.073331078 O: certified user keys: ed25519 correct authorized_principals 1615s 14:56:17.634782283 O: certified user keys: ed25519 authorized_principals bad key opt 1616s 14:56:18.379557969 O: certified user keys: ed25519 authorized_principals command=false 1617s 14:56:18.946351585 O: certified user keys: ed25519 authorized_principals command=true 1617s 14:56:19.489893278 O: certified user keys: ed25519 wrong principals key option 1618s 14:56:20.368706423 O: certified user keys: ed25519 correct principals key option 1619s 14:56:21.416498737 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 1620s 14:56:22.225997347 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 1621s 14:56:23.005116058 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 1622s 14:56:24.551452909 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 1623s 14:56:25.309133491 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 1624s 14:56:26.043753705 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 1625s 14:56:26.832356760 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 1626s 14:56:28.011636985 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 1627s 14:56:29.390755515 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 1629s 14:56:30.701228874 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 1630s 14:56:32.111531450 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 1631s 14:56:33.410900094 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 1633s 14:56:34.798971387 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 1634s 14:56:35.778473910 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 1634s 14:56:36.645432674 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 1636s 14:56:38.061433900 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 1637s 14:56:38.785401577 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 1638s 14:56:39.761653109 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 1639s 14:56:41.021407330 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 1640s 14:56:42.104592272 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 1641s 14:56:43.211162002 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 1642s 14:56:44.055747100 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 1642s 14:56:44.628341672 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 1643s 14:56:45.566339875 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 1644s 14:56:46.061492627 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 1645s 14:56:47.015072322 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 1645s 14:56:47.485042447 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 1646s 14:56:48.589615722 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 1647s 14:56:49.362323954 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 1648s 14:56:50.065115649 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 1649s 14:56:51.511670260 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 1650s 14:56:52.502279199 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 1651s 14:56:52.984648815 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 1651s 14:56:53.430894245 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 1653s 14:56:54.676030383 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 1653s 14:56:55.291976501 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 1654s 14:56:56.062760992 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 1655s 14:56:57.165518063 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 1656s 14:56:58.029239649 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 1656s 14:56:58.666148954 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 1658s 14:56:59.942799019 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 1659s 14:57:01.094151334 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 1660s 14:57:02.211107534 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 1661s 14:57:03.645688374 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 1662s 14:57:04.451682822 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 1663s 14:57:05.652208442 O: certified user keys: rsa missing authorized_principals 1664s 14:57:06.168330719 O: certified user keys: rsa empty authorized_principals 1665s 14:57:06.989369766 O: certified user keys: rsa wrong authorized_principals 1665s 14:57:07.261376882 O: certified user keys: rsa correct authorized_principals 1667s 14:57:08.786604612 O: certified user keys: rsa authorized_principals bad key opt 1667s 14:57:09.223646063 O: certified user keys: rsa authorized_principals command=false 1668s 14:57:10.204570276 O: certified user keys: rsa authorized_principals command=true 1669s 14:57:11.310721832 O: certified user keys: rsa wrong principals key option 1670s 14:57:12.366440420 O: certified user keys: rsa correct principals key option 1672s 14:57:13.964391003 O: certified user keys: rsa-sha2-256 missing authorized_principals 1673s 14:57:14.728116429 O: certified user keys: rsa-sha2-256 empty authorized_principals 1674s 14:57:15.793737743 O: certified user keys: rsa-sha2-256 wrong authorized_principals 1674s 14:57:16.624395633 O: certified user keys: rsa-sha2-256 correct authorized_principals 1676s 14:57:17.645643970 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 1677s 14:57:19.111745276 O: certified user keys: rsa-sha2-256 authorized_principals command=false 1679s 14:57:20.673828789 O: certified user keys: rsa-sha2-256 authorized_principals command=true 1680s 14:57:21.994810332 O: certified user keys: rsa-sha2-256 wrong principals key option 1681s 14:57:22.966208289 O: certified user keys: rsa-sha2-256 correct principals key option 1682s 14:57:23.989413796 O: certified user keys: rsa-sha2-512 missing authorized_principals 1682s 14:57:24.395229467 O: certified user keys: rsa-sha2-512 empty authorized_principals 1683s 14:57:25.422492819 O: certified user keys: rsa-sha2-512 wrong authorized_principals 1684s 14:57:26.100146330 O: certified user keys: rsa-sha2-512 correct authorized_principals 1685s 14:57:26.894073211 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 1686s 14:57:27.745686921 O: certified user keys: rsa-sha2-512 authorized_principals command=false 1686s 14:57:28.429748030 O: certified user keys: rsa-sha2-512 authorized_principals command=true 1687s 14:57:29.605539300 O: certified user keys: rsa-sha2-512 wrong principals key option 1688s 14:57:29.996890268 O: certified user keys: rsa-sha2-512 correct principals key option 1688s 14:57:30.558035007 O: certified user keys: ed25519 authorized_keys connect 1689s 14:57:31.648970972 O: certified user keys: ed25519 authorized_keys revoked key 1690s 14:57:32.279446124 O: certified user keys: ed25519 authorized_keys revoked via KRL 1691s 14:57:33.340586858 O: certified user keys: ed25519 authorized_keys empty KRL 1692s 14:57:34.327010088 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 1693s 14:57:35.159306734 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 1693s 14:57:35.637944774 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 1695s 14:57:36.809247445 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 1696s 14:57:38.020493813 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 1697s 14:57:39.155598782 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 1699s 14:57:40.755420190 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 1700s 14:57:42.236843205 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 1701s 14:57:42.941798619 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 1702s 14:57:44.062197128 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 1703s 14:57:45.217320789 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 1704s 14:57:46.316968720 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 1705s 14:57:47.413387964 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 1706s 14:57:48.218254524 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 1707s 14:57:49.467510384 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 1708s 14:57:50.536466508 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 1709s 14:57:51.542432525 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 1711s 14:57:53.298916939 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 1712s 14:57:54.063600707 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 1713s 14:57:54.858148734 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 1714s 14:57:55.911656627 O: certified user keys: rsa authorized_keys connect 1715s 14:57:57.145417238 O: certified user keys: rsa authorized_keys revoked key 1716s 14:57:57.753836901 O: certified user keys: rsa authorized_keys revoked via KRL 1716s 14:57:58.218988594 O: certified user keys: rsa authorized_keys empty KRL 1717s 14:57:58.891769633 O: certified user keys: rsa-sha2-256 authorized_keys connect 1718s 14:57:59.886819432 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 1718s 14:58:00.457838045 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 1720s 14:58:01.797423834 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 1721s 14:58:03.086099145 O: certified user keys: rsa-sha2-512 authorized_keys connect 1722s 14:58:04.600217684 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 1724s 14:58:05.716746955 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 1725s 14:58:06.924934163 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 1726s 14:58:08.075520723 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 1727s 14:58:09.334790323 O: certified user keys: authorized_keys CA does not authenticate 1727s 14:58:09.342734828 O: certified user keys: ensure CA key does not authenticate user 1728s 14:58:09.759682203 O: certified user keys: ed25519 TrustedUserCAKeys connect 1729s 14:58:11.003077347 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 1729s 14:58:11.401803241 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 1730s 14:58:12.642853077 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 1732s 14:58:14.007569574 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 1733s 14:58:15.272206655 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 1734s 14:58:16.476030371 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 1735s 14:58:17.349409767 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 1736s 14:58:18.174935176 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 1737s 14:58:19.521318285 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 1738s 14:58:20.541745085 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 1739s 14:58:20.841955570 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 1740s 14:58:21.761580025 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 1741s 14:58:22.803184838 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 1742s 14:58:24.074687800 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 1743s 14:58:24.910703866 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 1744s 14:58:25.803159694 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 1745s 14:58:26.841104328 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 1746s 14:58:28.149766218 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 1747s 14:58:28.861863772 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 1748s 14:58:30.091368960 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 1749s 14:58:30.770739286 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 1749s 14:58:31.430994774 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 1750s 14:58:32.667675078 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 1752s 14:58:33.903151272 O: certified user keys: rsa TrustedUserCAKeys connect 1753s 14:58:35.047404709 O: certified user keys: rsa TrustedUserCAKeys revoked key 1754s 14:58:35.948068011 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 1755s 14:58:36.891338967 O: certified user keys: rsa TrustedUserCAKeys empty KRL 1756s 14:58:37.889892266 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 1757s 14:58:38.797868453 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 1757s 14:58:39.467755624 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 1758s 14:58:40.405507892 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 1759s 14:58:41.055287088 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 1760s 14:58:42.220911137 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 1761s 14:58:43.080715546 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 1762s 14:58:44.194417246 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 1763s 14:58:45.319272885 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 1764s 14:58:46.219603938 O: certified user keys: TrustedUserCAKeys CA does not authenticate 1764s 14:58:46.222169621 O: certified user keys: ensure CA key does not authenticate user 1766s 14:58:47.684539492 O: certified user keys: correct principal auth authorized_keys expect success rsa 1767s 14:58:49.047376383 O: certified user keys: correct principal auth authorized_keys expect success ed25519 1768s 14:58:50.212931885 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 1769s 14:58:50.789049914 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 1770s 14:58:52.114567921 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 1771s 14:58:53.316565993 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 1772s 14:58:53.861361003 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 1772s 14:58:54.498711181 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 1774s 14:58:55.692185097 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 1774s 14:58:56.583238616 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 1776s 14:58:57.798060382 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 1776s 14:58:58.364307312 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 1777s 14:58:59.367739572 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 1778s 14:59:00.179140236 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 1778s 14:59:00.556086636 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 1780s 14:59:01.727675402 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 1781s 14:59:03.012623910 O: certified user keys: cert expired auth authorized_keys expect failure rsa 1782s 14:59:03.820973921 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 1783s 14:59:04.670370885 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 1784s 14:59:05.735443017 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 1784s 14:59:06.417278940 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 1786s 14:59:07.816834766 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 1787s 14:59:09.087288762 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 1787s 14:59:09.423702018 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 1789s 14:59:10.963632786 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 1789s 14:59:11.616865816 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 1791s 14:59:13.161111736 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 1792s 14:59:14.335521093 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 1793s 14:59:15.593339659 O: certified user keys: force-command auth authorized_keys expect failure rsa 1794s 14:59:16.643318551 O: certified user keys: force-command auth authorized_keys expect failure ed25519 1796s 14:59:17.752456141 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 1797s 14:59:19.280584776 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 1798s 14:59:20.618374510 O: certified user keys: empty principals auth authorized_keys expect success rsa 1801s 14:59:22.726854876 O: certified user keys: empty principals auth authorized_keys expect success ed25519 1802s 14:59:23.980584074 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 1803s 14:59:25.417005315 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 1804s 14:59:26.231879745 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 1806s 14:59:27.764423039 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 1807s 14:59:28.745590238 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 1808s 14:59:29.994750679 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 1809s 14:59:30.938344911 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 1810s 14:59:32.123864955 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 1811s 14:59:32.879809988 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 1812s 14:59:33.728447859 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 1813s 14:59:34.779364610 O: certified user keys: force-command match true auth authorized_keys expect success rsa 1814s 14:59:35.796425485 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 1815s 14:59:37.200410195 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 1816s 14:59:38.027127515 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 1817s 14:59:39.098349120 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 1817s 14:59:39.666187878 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 1818s 14:59:40.656798572 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 1819s 14:59:41.496954684 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 1820s 14:59:42.249345939 O: certified user keys: user ed25519 connect wrong cert 1821s 14:59:43.195745722 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 1822s 14:59:44.420666180 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 1823s 14:59:45.282726656 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 1824s 14:59:46.496872807 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 1825s 14:59:47.413198366 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 1826s 14:59:48.243496620 O: certified user keys: user rsa connect wrong cert 1827s 14:59:49.473379930 O: certified user keys: user rsa-sha2-256 connect wrong cert 1829s 14:59:50.850585026 O: certified user keys: user rsa-sha2-512 connect wrong cert 1830s 14:59:52.133022892 O: ok certified user keys 1830s 14:59:52.134838338 E: run test host-expand.sh ... 1832s 14:59:54.320247449 O: ok expand %h and %n 1832s 14:59:54.322085796 E: run test keys-command.sh ... 1833s 14:59:55.090592499 O: SKIPPED: /var/run/keycommand_ubuntu.47426 not executable (/var/run mounted noexec?) 1833s 14:59:55.125614289 E: run test forward-control.sh ... 1837s 14:59:59.310706056 O: check_lfwd done (expecting Y): default configuration 1839s 15:00:00.980841703 O: check_rfwd done (expecting Y): default configuration 1841s 15:00:03.559330457 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 1844s 15:00:06.615366360 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 1846s 15:00:08.392088675 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 1849s 15:00:10.742056893 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 1850s 15:00:12.326495360 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 1852s 15:00:14.252485439 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 1855s 15:00:16.693587913 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 1856s 15:00:17.856101621 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 1859s 15:00:21.018675322 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 1861s 15:00:23.127248757 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 1862s 15:00:23.847813074 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 1863s 15:00:25.345352643 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 1865s 15:00:27.028935367 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 1866s 15:00:28.239575491 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 1870s 15:00:31.442981516 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 1871s 15:00:33.268351488 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 1874s 15:00:36.018849343 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 1874s 15:00:36.553343109 O: check_rfwd done (expecting N): AllowTcpForwarding=local 1876s 15:00:37.895748894 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 1877s 15:00:39.089159879 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 1879s 15:00:41.068740182 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 1880s 15:00:41.770252996 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 1881s 15:00:42.753625700 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 1882s 15:00:44.143670581 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 1885s 15:00:46.682095970 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 1886s 15:00:48.002835007 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 1888s 15:00:49.816321414 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 1889s 15:00:50.994622101 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 1890s 15:00:52.472597925 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 1892s 15:00:53.817163574 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 1894s 15:00:56.198667556 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 1895s 15:00:57.074478858 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 1897s 15:00:58.799191815 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 1900s 15:01:02.003848928 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 1901s 15:01:02.972335653 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 1902s 15:01:04.447766970 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 1903s 15:01:05.286965610 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 1906s 15:01:07.755848152 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 1907s 15:01:08.870961499 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 1910s 15:01:11.992700885 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 1912s 15:01:13.750546399 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 1914s 15:01:16.115937235 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 1916s 15:01:17.930196145 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 1916s 15:01:18.392235278 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 1917s 15:01:19.473366931 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 1919s 15:01:20.832443315 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 1920s 15:01:22.418746823 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 1922s 15:01:24.117286261 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 1924s 15:01:26.507397142 O: check_lfwd done (expecting N): AllowTcpForwarding=no 1925s 15:01:27.012956235 O: check_rfwd done (expecting N): AllowTcpForwarding=no 1925s 15:01:27.306636132 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1926s 15:01:28.253584970 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1928s 15:01:30.576750047 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1929s 15:01:31.343174518 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1931s 15:01:33.599849113 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1932s 15:01:34.427866972 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1933s 15:01:35.109456694 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 1934s 15:01:36.172759492 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 1936s 15:01:38.650164593 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1937s 15:01:38.943293422 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1938s 15:01:40.131744407 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 1938s 15:01:40.637705768 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 1939s 15:01:41.316118715 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1940s 15:01:42.005884277 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1941s 15:01:43.556268281 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 1942s 15:01:44.270875582 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 1945s 15:01:46.892230331 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1947s 15:01:48.991081938 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1949s 15:01:51.403299932 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 1950s 15:01:52.184870988 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 1953s 15:01:54.785858610 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 1954s 15:01:56.215103501 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 1956s 15:01:58.664945799 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1957s 15:01:58.983082382 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1958s 15:02:00.504954018 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 1960s 15:02:02.371547554 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 1963s 15:02:04.701663989 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1964s 15:02:05.907960749 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1966s 15:02:08.485951563 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1967s 15:02:08.803566233 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1967s 15:02:08.827870914 O: ok sshd control of local and remote forwarding 1967s 15:02:08.826964468 E: run test integrity.sh ... 1968s 15:02:09.697269301 O: test integrity: hmac-sha1 @2900 1968s 15:02:10.513932643 O: test integrity: hmac-sha1 @2901 1969s 15:02:11.497410143 O: test integrity: hmac-sha1 @2902 1970s 15:02:12.085531238 O: test integrity: hmac-sha1 @2903 1971s 15:02:13.077026874 O: test integrity: hmac-sha1 @2904 1972s 15:02:14.126980283 O: test integrity: hmac-sha1 @2905 1973s 15:02:15.116002405 O: test integrity: hmac-sha1 @2906 1974s 15:02:16.350343190 O: test integrity: hmac-sha1 @2907 1975s 15:02:17.060005874 O: test integrity: hmac-sha1 @2908 1976s 15:02:17.768438742 O: test integrity: hmac-sha1 @2909 1976s 15:02:18.581550941 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1976s 15:02:18.621234015 O: test integrity: hmac-sha1-96 @2900 1978s 15:02:20.108954306 O: test integrity: hmac-sha1-96 @2901 1979s 15:02:20.773175038 O: test integrity: hmac-sha1-96 @2902 1980s 15:02:21.972440422 O: test integrity: hmac-sha1-96 @2903 1981s 15:02:23.034911549 O: test integrity: hmac-sha1-96 @2904 1982s 15:02:24.431533118 O: test integrity: hmac-sha1-96 @2905 1983s 15:02:25.215205444 O: test integrity: hmac-sha1-96 @2906 1984s 15:02:25.954679366 O: test integrity: hmac-sha1-96 @2907 1985s 15:02:27.055601706 O: test integrity: hmac-sha1-96 @2908 1986s 15:02:28.023153788 O: test integrity: hmac-sha1-96 @2909 1987s 15:02:29.142192527 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1987s 15:02:29.165898824 O: test integrity: hmac-sha2-256 @2900 1988s 15:02:30.115966593 O: test integrity: hmac-sha2-256 @2901 1989s 15:02:31.331268323 O: test integrity: hmac-sha2-256 @2902 1990s 15:02:32.573500080 O: test integrity: hmac-sha2-256 @2903 1992s 15:02:33.693938581 O: test integrity: hmac-sha2-256 @2904 1992s 15:02:34.012104673 O: test integrity: hmac-sha2-256 @2905 1993s 15:02:35.215127493 O: test integrity: hmac-sha2-256 @2906 1994s 15:02:36.101524096 O: test integrity: hmac-sha2-256 @2907 1994s 15:02:36.582601695 O: test integrity: hmac-sha2-256 @2908 1996s 15:02:38.000589211 O: test integrity: hmac-sha2-256 @2909 1996s 15:02:38.275287765 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1996s 15:02:38.288935659 O: test integrity: hmac-sha2-512 @2900 1997s 15:02:39.418305248 O: test integrity: hmac-sha2-512 @2901 1998s 15:02:40.277112246 O: test integrity: hmac-sha2-512 @2902 1999s 15:02:40.757855342 O: test integrity: hmac-sha2-512 @2903 1999s 15:02:41.474335161 O: test integrity: hmac-sha2-512 @2904 2000s 15:02:42.344377195 O: test integrity: hmac-sha2-512 @2905 2001s 15:02:43.168923399 O: test integrity: hmac-sha2-512 @2906 2002s 15:02:44.419068984 O: test integrity: hmac-sha2-512 @2907 2003s 15:02:45.184443095 O: test integrity: hmac-sha2-512 @2908 2004s 15:02:46.227374276 O: test integrity: hmac-sha2-512 @2909 2005s 15:02:46.832428327 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2005s 15:02:46.860013142 O: test integrity: hmac-md5 @2900 2006s 15:02:47.876428165 O: test integrity: hmac-md5 @2901 2007s 15:02:48.630941436 O: test integrity: hmac-md5 @2902 2007s 15:02:49.307912090 O: test integrity: hmac-md5 @2903 2008s 15:02:50.137544925 O: test integrity: hmac-md5 @2904 2008s 15:02:50.455636372 O: test integrity: hmac-md5 @2905 2009s 15:02:50.889095737 O: test integrity: hmac-md5 @2906 2009s 15:02:51.630377205 O: test integrity: hmac-md5 @2907 2010s 15:02:52.339044720 O: test integrity: hmac-md5 @2908 2011s 15:02:53.340621163 O: test integrity: hmac-md5 @2909 2012s 15:02:54.258018189 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2012s 15:02:54.367551870 O: test integrity: hmac-md5-96 @2900 2013s 15:02:55.588218210 O: test integrity: hmac-md5-96 @2901 2014s 15:02:56.512269414 O: test integrity: hmac-md5-96 @2902 2015s 15:02:57.229843030 O: test integrity: hmac-md5-96 @2903 2016s 15:02:58.192433366 O: test integrity: hmac-md5-96 @2904 2017s 15:02:58.740331031 O: test integrity: hmac-md5-96 @2905 2018s 15:03:00.055690796 O: test integrity: hmac-md5-96 @2906 2019s 15:03:01.182157710 O: test integrity: hmac-md5-96 @2907 2020s 15:03:02.315322274 O: test integrity: hmac-md5-96 @2908 2021s 15:03:03.463959762 O: test integrity: hmac-md5-96 @2909 2022s 15:03:04.479529098 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2022s 15:03:04.523084789 O: test integrity: umac-64@openssh.com @2900 2023s 15:03:05.472023313 O: test integrity: umac-64@openssh.com @2901 2024s 15:03:06.450924189 O: test integrity: umac-64@openssh.com @2902 2025s 15:03:07.635419834 O: test integrity: umac-64@openssh.com @2903 2027s 15:03:09.127165799 O: test integrity: umac-64@openssh.com @2904 2029s 15:03:10.656072843 O: test integrity: umac-64@openssh.com @2905 2030s 15:03:12.169070281 O: test integrity: umac-64@openssh.com @2906 2031s 15:03:13.433420120 O: test integrity: umac-64@openssh.com @2907 2032s 15:03:14.534972925 O: test integrity: umac-64@openssh.com @2908 2033s 15:03:15.096967142 O: test integrity: umac-64@openssh.com @2909 2034s 15:03:16.416784387 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2034s 15:03:16.459151512 O: test integrity: umac-128@openssh.com @2900 2036s 15:03:17.899984650 O: test integrity: umac-128@openssh.com @2901 2037s 15:03:19.637729436 O: test integrity: umac-128@openssh.com @2902 2039s 15:03:20.874852463 O: test integrity: umac-128@openssh.com @2903 2041s 15:03:22.896501069 O: test integrity: umac-128@openssh.com @2904 2042s 15:03:23.965049425 O: test integrity: umac-128@openssh.com @2905 2043s 15:03:25.305587056 O: test integrity: umac-128@openssh.com @2906 2044s 15:03:26.656842968 O: test integrity: umac-128@openssh.com @2907 2046s 15:03:28.064784722 O: test integrity: umac-128@openssh.com @2908 2047s 15:03:29.103696483 O: test integrity: umac-128@openssh.com @2909 2048s 15:03:30.439017487 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2048s 15:03:30.594031651 O: test integrity: hmac-sha1-etm@openssh.com @2900 2049s 15:03:31.630334992 O: test integrity: hmac-sha1-etm@openssh.com @2901 2051s 15:03:33.377990477 O: test integrity: hmac-sha1-etm@openssh.com @2902 2053s 15:03:34.926091252 O: test integrity: hmac-sha1-etm@openssh.com @2903 2054s 15:03:35.912916816 O: test integrity: hmac-sha1-etm@openssh.com @2904 2055s 15:03:37.091404507 O: test integrity: hmac-sha1-etm@openssh.com @2905 2057s 15:03:39.342390241 O: test integrity: hmac-sha1-etm@openssh.com @2906 2058s 15:03:40.615406214 O: test integrity: hmac-sha1-etm@openssh.com @2907 2060s 15:03:41.688606673 O: test integrity: hmac-sha1-etm@openssh.com @2908 2060s 15:03:42.165118768 O: test integrity: hmac-sha1-etm@openssh.com @2909 2061s 15:03:43.522667336 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2061s 15:03:43.596609218 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 2063s 15:03:45.202514717 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 2065s 15:03:46.811672687 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 2066s 15:03:47.963773205 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 2067s 15:03:49.213129948 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 2069s 15:03:50.758759619 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 2070s 15:03:52.163341445 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 2071s 15:03:53.610225069 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 2073s 15:03:55.373055359 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 2075s 15:03:57.174429527 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 2077s 15:03:59.280094557 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2077s 15:03:59.307733188 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 2079s 15:04:01.340387858 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 2081s 15:04:02.993045838 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 2082s 15:04:04.375017714 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 2083s 15:04:05.475920992 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 2085s 15:04:06.828329319 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 2086s 15:04:08.331921940 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 2088s 15:04:09.971483320 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 2089s 15:04:11.468601897 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 2091s 15:04:12.723684953 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 2092s 15:04:14.269624399 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2092s 15:04:14.415157913 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 2094s 15:04:15.671312737 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 2095s 15:04:17.184687743 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 2096s 15:04:18.262383135 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 2097s 15:04:18.942025308 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 2098s 15:04:20.336532765 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 2099s 15:04:21.274300343 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 2100s 15:04:22.480833222 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 2102s 15:04:24.173271581 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 2103s 15:04:25.466154984 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 2104s 15:04:26.590103251 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2104s 15:04:26.631469612 O: test integrity: hmac-md5-etm@openssh.com @2900 2106s 15:04:28.118785163 O: test integrity: hmac-md5-etm@openssh.com @2901 2107s 15:04:29.335214363 O: test integrity: hmac-md5-etm@openssh.com @2902 2108s 15:04:30.371858986 O: test integrity: hmac-md5-etm@openssh.com @2903 2110s 15:04:31.755203963 O: test integrity: hmac-md5-etm@openssh.com @2904 2111s 15:04:33.410001548 O: test integrity: hmac-md5-etm@openssh.com @2905 2113s 15:04:34.856047135 O: test integrity: hmac-md5-etm@openssh.com @2906 2114s 15:04:35.849059913 O: test integrity: hmac-md5-etm@openssh.com @2907 2115s 15:04:36.836582887 O: test integrity: hmac-md5-etm@openssh.com @2908 2116s 15:04:38.189251164 O: test integrity: hmac-md5-etm@openssh.com @2909 2117s 15:04:39.505719908 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2117s 15:04:39.520205461 O: test integrity: hmac-md5-96-etm@openssh.com @2900 2119s 15:04:40.836144670 O: test integrity: hmac-md5-96-etm@openssh.com @2901 2120s 15:04:42.423739688 O: test integrity: hmac-md5-96-etm@openssh.com @2902 2121s 15:04:43.251497307 O: test integrity: hmac-md5-96-etm@openssh.com @2903 2122s 15:04:44.070223635 O: test integrity: hmac-md5-96-etm@openssh.com @2904 2123s 15:04:45.425615548 O: test integrity: hmac-md5-96-etm@openssh.com @2905 2124s 15:04:46.358793204 O: test integrity: hmac-md5-96-etm@openssh.com @2906 2126s 15:04:47.696457673 O: test integrity: hmac-md5-96-etm@openssh.com @2907 2126s 15:04:48.396237122 O: test integrity: hmac-md5-96-etm@openssh.com @2908 2128s 15:04:49.758468463 O: test integrity: hmac-md5-96-etm@openssh.com @2909 2128s 15:04:50.617710330 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2129s 15:04:50.827042848 O: test integrity: umac-64-etm@openssh.com @2900 2130s 15:04:52.156473285 O: test integrity: umac-64-etm@openssh.com @2901 2131s 15:04:53.394973658 O: test integrity: umac-64-etm@openssh.com @2902 2132s 15:04:54.184758714 O: test integrity: umac-64-etm@openssh.com @2903 2133s 15:04:55.081212559 O: test integrity: umac-64-etm@openssh.com @2904 2135s 15:04:56.692511194 O: test integrity: umac-64-etm@openssh.com @2905 2136s 15:04:58.165226783 O: test integrity: umac-64-etm@openssh.com @2906 2138s 15:04:59.806455377 O: test integrity: umac-64-etm@openssh.com @2907 2139s 15:05:01.076986073 O: test integrity: umac-64-etm@openssh.com @2908 2141s 15:05:03.248114909 O: test integrity: umac-64-etm@openssh.com @2909 2143s 15:05:04.924263123 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2143s 15:05:04.939198297 O: test integrity: umac-128-etm@openssh.com @2900 2144s 15:05:06.075644760 O: test integrity: umac-128-etm@openssh.com @2901 2146s 15:05:07.898048205 O: test integrity: umac-128-etm@openssh.com @2902 2147s 15:05:08.781495012 O: test integrity: umac-128-etm@openssh.com @2903 2148s 15:05:10.259711372 O: test integrity: umac-128-etm@openssh.com @2904 2149s 15:05:11.573030694 O: test integrity: umac-128-etm@openssh.com @2905 2151s 15:05:13.033694815 O: test integrity: umac-128-etm@openssh.com @2906 2152s 15:05:14.213306309 O: test integrity: umac-128-etm@openssh.com @2907 2154s 15:05:15.795388926 O: test integrity: umac-128-etm@openssh.com @2908 2155s 15:05:17.527341660 O: test integrity: umac-128-etm@openssh.com @2909 2157s 15:05:19.013907031 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2157s 15:05:19.042410939 O: test integrity: aes128-gcm@openssh.com @2900 2158s 15:05:19.928202929 O: test integrity: aes128-gcm@openssh.com @2901 2159s 15:05:20.907590878 O: test integrity: aes128-gcm@openssh.com @2902 2160s 15:05:21.936410963 O: test integrity: aes128-gcm@openssh.com @2903 2161s 15:05:23.145513445 O: test integrity: aes128-gcm@openssh.com @2904 2162s 15:05:24.595417134 O: test integrity: aes128-gcm@openssh.com @2905 2164s 15:05:25.826042931 O: test integrity: aes128-gcm@openssh.com @2906 2165s 15:05:27.059361118 O: test integrity: aes128-gcm@openssh.com @2907 2166s 15:05:28.503184424 O: test integrity: aes128-gcm@openssh.com @2908 2168s 15:05:30.046451564 O: test integrity: aes128-gcm@openssh.com @2909 2169s 15:05:31.453004949 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2169s 15:05:31.552363984 O: test integrity: aes256-gcm@openssh.com @2900 2171s 15:05:32.854530813 O: test integrity: aes256-gcm@openssh.com @2901 2172s 15:05:34.554012805 O: test integrity: aes256-gcm@openssh.com @2902 2174s 15:05:36.403222703 O: test integrity: aes256-gcm@openssh.com @2903 2175s 15:05:37.275796965 O: test integrity: aes256-gcm@openssh.com @2904 2176s 15:05:38.619487711 O: test integrity: aes256-gcm@openssh.com @2905 2177s 15:05:39.636468761 O: test integrity: aes256-gcm@openssh.com @2906 2178s 15:05:40.633711516 O: test integrity: aes256-gcm@openssh.com @2907 2180s 15:05:41.953188538 O: test integrity: aes256-gcm@openssh.com @2908 2181s 15:05:43.499203851 O: test integrity: aes256-gcm@openssh.com @2909 2183s 15:05:45.269013459 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2183s 15:05:45.387548739 O: test integrity: chacha20-poly1305@openssh.com @2900 2185s 15:05:46.733302700 O: test integrity: chacha20-poly1305@openssh.com @2901 2186s 15:05:48.260180159 O: test integrity: chacha20-poly1305@openssh.com @2902 2188s 15:05:49.992017175 O: test integrity: chacha20-poly1305@openssh.com @2903 2189s 15:05:51.152485956 O: test integrity: chacha20-poly1305@openssh.com @2904 2190s 15:05:52.142272624 O: test integrity: chacha20-poly1305@openssh.com @2905 2191s 15:05:53.552369204 O: test integrity: chacha20-poly1305@openssh.com @2906 2193s 15:05:55.450611001 O: test integrity: chacha20-poly1305@openssh.com @2907 2194s 15:05:56.081669923 O: test integrity: chacha20-poly1305@openssh.com @2908 2195s 15:05:56.963861682 O: test integrity: chacha20-poly1305@openssh.com @2909 2196s 15:05:57.746094556 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 2196s 15:05:57.748859913 E: run test krl.sh ... 2196s 15:05:57.760693284 O: ok integrity 2196s 15:05:58.384701925 O: key revocation lists: generating test keys 2203s 15:06:05.195482155 O: key revocation lists: generating KRLs 2204s 15:06:05.779347783 O: key revocation lists: checking revocations for revoked keys 2206s 15:06:08.635735572 O: key revocation lists: checking revocations for unrevoked keys 2209s 15:06:10.901719848 O: key revocation lists: checking revocations for revoked certs 2213s 15:06:15.221943453 O: key revocation lists: checking revocations for unrevoked certs 2217s 15:06:18.763792070 O: key revocation lists: testing KRL update 2222s 15:06:24.655063828 O: key revocation lists: checking revocations for revoked keys 2226s 15:06:27.826254243 O: key revocation lists: checking revocations for unrevoked keys 2228s 15:06:29.946772057 O: key revocation lists: checking revocations for revoked certs 2232s 15:06:34.152834891 O: key revocation lists: checking revocations for unrevoked certs 2236s 15:06:38.114468305 O: ok key revocation lists 2236s 15:06:38.114423220 E: run test multipubkey.sh ... 2237s 15:06:39.479150694 E: mm_log_handler: write: Broken pipe 2238s 15:06:40.145946226 E: mm_log_handler: write: Broken pipe 2241s 15:06:43.351538275 E: mm_log_handler: write: Broken pipe 2242s 15:06:44.441368619 E: mm_log_handler: write: Broken pipe 2245s 15:06:46.978298423 O: ok multiple pubkey 2245s 15:06:46.976907688 E: run test limit-keytype.sh ... 2254s 15:06:55.781558152 O: allow rsa,ed25519 2257s 15:06:59.089035626 O: allow ed25519 2258s 15:07:00.277499272 E: mm_log_handler: write: Broken pipe 2260s 15:07:02.620275632 O: allow cert only 2263s 15:07:05.075757049 E: mm_log_handler: write: Broken pipe 2264s 15:07:06.247472762 O: match w/ no match 2265s 15:07:07.160328971 E: mm_log_handler: write: Broken pipe 2266s 15:07:08.365651536 E: mm_log_handler: write: Broken pipe 2267s 15:07:09.017390432 O: match w/ matching 2270s 15:07:12.096107276 O: ok restrict pubkey type 2270s 15:07:12.095458641 E: run test hostkey-agent.sh ... 2274s 15:07:16.163953401 O: key type ssh-ed25519 2275s 15:07:16.717722569 O: key type sk-ssh-ed25519@openssh.com 2275s 15:07:16.973178053 O: key type ecdsa-sha2-nistp256 2275s 15:07:17.331508960 O: key type ecdsa-sha2-nistp384 2276s 15:07:17.800184274 O: key type ecdsa-sha2-nistp521 2276s 15:07:18.132737218 O: key type sk-ecdsa-sha2-nistp256@openssh.com 2276s 15:07:18.569489200 O: key type ssh-rsa 2277s 15:07:19.014301286 O: cert type ssh-ed25519-cert-v01@openssh.com 2278s 15:07:19.765370848 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 2278s 15:07:20.653598875 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 2279s 15:07:21.573904109 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 2280s 15:07:22.577303737 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 2282s 15:07:23.916087549 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 2283s 15:07:24.898407280 O: cert type ssh-rsa-cert-v01@openssh.com 2283s 15:07:25.425525377 O: cert type rsa-sha2-256-cert-v01@openssh.com 2284s 15:07:26.617718906 O: cert type rsa-sha2-512-cert-v01@openssh.com 2285s 15:07:27.134182214 O: multiple hostkeys 2285s 15:07:27.148040078 O: Addkey type ssh-ed25519 2285s 15:07:27.157483409 O: Addkey type sk-ssh-ed25519@openssh.com 2285s 15:07:27.164418960 O: Addkey type ecdsa-sha2-nistp256 2285s 15:07:27.170885046 O: Addkey type ecdsa-sha2-nistp384 2285s 15:07:27.185511509 O: Addkey type ecdsa-sha2-nistp521 2285s 15:07:27.190512265 O: Addkey type sk-ecdsa-sha2-nistp256@openssh.com 2285s 15:07:27.270641843 O: Addkey type ssh-rsa 2286s 15:07:28.460899883 E: run test hostkey-rotate.sh ... 2286s 15:07:28.458946877 O: ok hostkey agent 2290s 15:07:32.322273804 O: learn hostkey with StrictHostKeyChecking=no 2291s 15:07:32.774699113 O: learn additional hostkeys 2292s 15:07:33.684990863 O: learn additional hostkeys, type=ssh-ed25519 2293s 15:07:34.899778649 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 2294s 15:07:35.871064074 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 2295s 15:07:37.193281430 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 2296s 15:07:38.567622774 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 2298s 15:07:39.826476221 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 2299s 15:07:41.082051220 O: learn additional hostkeys, type=ssh-rsa 2300s 15:07:42.083471843 O: learn changed non-primary hostkey type=ssh-rsa 2304s 15:07:46.629056647 O: learn new primary hostkey 2306s 15:07:47.892641438 O: rotate primary hostkey 2307s 15:07:49.227072595 O: check rotate primary hostkey 2308s 15:07:50.465179219 O: ok hostkey rotate 2308s 15:07:50.496186047 E: run test principals-command.sh ... 2310s 15:07:52.483359589 O: SKIPPED: /var/run/principals_command_ubuntu.64612 not executable (/var/run mounted noexec?) 2310s 15:07:52.486302316 E: run test cert-file.sh ... 2311s 15:07:53.358126898 O: identity cert with no plain public file 2312s 15:07:53.792646648 O: CertificateFile with no plain public file 2313s 15:07:54.922020134 O: plain keys 2313s 15:07:55.617255379 O: untrusted cert 2314s 15:07:56.479929176 O: good cert, bad key 2315s 15:07:57.539458375 O: single trusted 2317s 15:07:58.905724311 O: multiple trusted 2322s 15:08:04.382009093 E: run test cfginclude.sh ... 2322s 15:08:04.387075094 O: ok ssh with certificates 2323s 15:08:04.713269768 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:04.720768877 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:04.743186049 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:04.781207413 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:04.788048054 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:04.794840691 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:04.802825234 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:04.808731906 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.011364861 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.013931525 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.015927152 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.044731815 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.114188828 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.121875295 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.128685139 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.135899590 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.143146074 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.150398579 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.157309840 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.166156659 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.172446508 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.188754725 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.197269772 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.204884012 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.418628059 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.432687000 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 2323s 15:08:05.443733664 O: ok config include 2323s 15:08:05.446217796 E: run test servcfginclude.sh ... 2325s 15:08:07.260649289 O: ok server config include 2325s 15:08:07.269496431 E: run test allow-deny-users.sh ... 2333s 15:08:14.774903719 O: ok AllowUsers/DenyUsers 2333s 15:08:14.776670358 E: run test authinfo.sh ... 2333s 15:08:15.382615835 O: ExposeAuthInfo=no 2334s 15:08:15.965191573 O: ExposeAuthInfo=yes 2335s 15:08:16.772662374 E: run test sshsig.sh ... 2335s 15:08:16.773396260 O: ok authinfo 2335s 15:08:17.527741135 O: sshsig: make certificates 2336s 15:08:17.686599825 O: sshsig: check signature for ssh-ed25519 2337s 15:08:18.751437875 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 2338s 15:08:19.752268523 O: sshsig: check signature for ecdsa-sha2-nistp256 2339s 15:08:20.951637674 O: sshsig: check signature for ecdsa-sha2-nistp384 2341s 15:08:23.207833113 O: sshsig: check signature for ecdsa-sha2-nistp521 2347s 15:08:28.689215985 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 2349s 15:08:31.429047648 O: sshsig: check signature for ssh-rsa 2350s 15:08:32.381735359 O: sshsig: check signature for ssh-ed25519-cert.pub 2353s 15:08:35.125017687 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 2356s 15:08:38.258118502 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 2360s 15:08:41.809971494 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 2366s 15:08:47.752258773 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 2372s 15:08:54.629472832 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 2378s 15:08:59.721140179 O: sshsig: check signature for ssh-rsa-cert.pub 2381s 15:09:02.815135721 O: sshsig: match principals 2381s 15:09:02.847952862 O: sshsig: nomatch principals 2381s 15:09:02.868482101 O: ok sshsig 2381s 15:09:02.868164489 E: run test knownhosts.sh ... 2389s 15:09:10.953254796 E: run test knownhosts-command.sh ... 2389s 15:09:10.951608149 O: ok known hosts 2390s 15:09:11.771081744 O: simple connection 2391s 15:09:13.015542188 O: no keys 2392s 15:09:13.684449612 O: bad exit status 2392s 15:09:14.229583710 O: keytype ssh-ed25519 2393s 15:09:15.412737127 O: keytype sk-ssh-ed25519@openssh.com 2394s 15:09:16.484533098 O: keytype ecdsa-sha2-nistp256 2395s 15:09:17.362539009 O: keytype ecdsa-sha2-nistp384 2396s 15:09:18.160883714 O: keytype ecdsa-sha2-nistp521 2397s 15:09:19.271388574 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 2398s 15:09:20.062835408 O: keytype ssh-rsa 2399s 15:09:21.153468879 O: ok known hosts command 2399s 15:09:21.156465266 E: run test agent-restrict.sh ... 2400s 15:09:22.436839858 O: generate keys 2400s 15:09:22.644475329 O: prepare client config 2401s 15:09:22.980409562 O: prepare known_hosts 2401s 15:09:23.212533761 O: prepare server configs 2401s 15:09:23.215029994 O: authentication w/o agent 2406s 15:09:28.294805428 O: start agent 2410s 15:09:32.299756988 O: authentication with agent (no restrict) 2414s 15:09:35.761910553 O: unrestricted keylist 2415s 15:09:37.158014626 O: authentication with agent (basic restrict) 2417s 15:09:39.621164074 O: authentication with agent incorrect key (basic restrict) 2418s 15:09:40.151135900 E: mm_log_handler: write: Broken pipe 2418s 15:09:40.411117636 E: mm_log_handler: write: Broken pipe 2419s 15:09:41.364881327 E: mm_log_handler: write: Broken pipe 2419s 15:09:41.555622538 O: keylist (basic restrict) 2419s 15:09:41.558675222 E: mm_log_handler: write: Broken pipe 2422s 15:09:44.209232565 O: username 2423s 15:09:45.123176661 O: username wildcard 2426s 15:09:48.053847850 O: username incorrect 2426s 15:09:48.537280198 O: agent restriction honours certificate principal 2427s 15:09:48.784671876 O: multihop without agent 2430s 15:09:51.800636893 O: multihop agent unrestricted 2433s 15:09:54.936534348 O: multihop restricted 2435s 15:09:57.270874108 O: multihop username 2437s 15:09:59.360091551 O: multihop wildcard username 2440s 15:10:02.032451381 O: multihop wrong username 2441s 15:10:03.605762498 O: multihop cycle no agent 2445s 15:10:07.397682894 O: multihop cycle agent unrestricted 2450s 15:10:12.408656031 O: multihop cycle restricted deny 2451s 15:10:13.588840680 E: mm_log_handler: write: Broken pipe 2451s 15:10:13.632917151 O: multihop cycle restricted allow 2457s 15:10:18.983890534 O: ok agent restrictions 2457s 15:10:19.083670066 E: run test hostbased.sh ... 2458s 15:10:19.859641283 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 2458s 15:10:19.861945696 E: run test password.sh ... 2458s 15:10:20.515479905 E: run test channel-timeout.sh ... 2458s 15:10:20.516340887 O: SKIPPED: Password auth requires SUDO and password file. 2459s 15:10:21.465150468 O: no timeout 2466s 15:10:27.817236176 O: command timeout 2467s 15:10:29.067362065 O: command long timeout 2468s 15:10:30.201913931 O: command wildcard timeout 2470s 15:10:32.067770447 O: command irrelevant timeout 2475s 15:10:37.400015996 O: multiplexed command timeout 2487s 15:10:49.256516534 O: irrelevant multiplexed command timeout 2502s 15:11:04.646432039 O: global command timeout 2524s 15:11:26.195947142 O: sftp no timeout 2529s 15:11:31.565369610 O: sftp timeout 2530s 15:11:32.063722180 E: Connection closed 2530s 15:11:32.066587079 O: sftp irrelevant timeout 2536s 15:11:38.232169523 O: ok channel timeout 2536s 15:11:38.234595811 E: run test connection-timeout.sh ... 2536s 15:11:38.410520188 O: no timeout 2543s 15:11:44.793382487 O: timeout 2551s 15:11:53.074675828 O: session inhibits timeout 2560s 15:12:02.170255643 O: timeout after session 2568s 15:12:10.178200840 O: timeout with listeners 2577s 15:12:19.398337223 E: run test match-subsystem.sh ... 2577s 15:12:19.398596399 O: ok unused connection timeout 2584s 15:12:26.133857476 O: ok sshd_config match subsystem 2584s 15:12:26.135367307 E: run test agent-pkcs11-restrict.sh ... 2585s 15:12:26.703283633 E: run test agent-pkcs11-cert.sh ... 2585s 15:12:26.703381217 O: SKIPPED: No PKCS#11 library found 2585s 15:12:27.511453361 E: run test penalty.sh ... 2585s 15:12:27.511197246 O: SKIPPED: No PKCS#11 library found 2586s 15:12:28.594511182 O: test connect 2587s 15:12:29.036346046 O: penalty for authentication failure 2596s 15:12:37.765317639 O: penalty for no authentication 2598s 15:12:39.890369072 O: ok penalties 2598s 15:12:39.891332882 E: run test penalty-expire.sh ... 2599s 15:12:41.118124142 O: test connect 2600s 15:12:41.996116901 O: penalty expiry 2614s 15:12:56.278319125 O: ok penalties 2614s 15:12:56.278872180 E: run test connect-bigconf.sh ... 2616s 15:12:58.176453562 O: ok simple connect 2616s 15:12:58.178549891 O: set -e ; if test -z "" ; then \ 2616s 15:12:58.180488096 O: V="" ; \ 2616s 15:12:58.182668016 O: test "x" = "x" || \ 2616s 15:12:58.184605001 O: V=/tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/valgrind-unit.sh ; \ 2616s 15:12:58.186627136 O: ARGS=""; \ 2616s 15:12:58.188657679 O: test "xunit" = "xunit-bench" && ARGS="-b"; \ 2616s 15:12:58.190676938 O: test "x" = "x" || ARGS="$ARGS -f"; \ 2616s 15:12:58.192782058 O: test "x" = "x" || ARGS="$ARGS -F"; \ 2616s 15:12:58.194705586 O: test "x" = "x" || ARGS="$ARGS -v"; \ 2616s 15:12:58.196615594 O: test "x" = "x" || ARGS="$ARGS -B"; \ 2616s 15:12:58.198566901 O: test "x" = "x" || ARGS="$ARGS -O "; \ 2616s 15:12:58.200724955 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/sshbuf/test_sshbuf ${ARGS}; \ 2616s 15:12:58.202831447 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/sshkey/test_sshkey \ 2616s 15:12:58.204898283 O: -d /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/sshkey/testdata ${ARGS}; \ 2616s 15:12:58.206831851 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/sshsig/test_sshsig \ 2616s 15:12:58.208808682 O: -d /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/sshsig/testdata ${ARGS}; \ 2616s 15:12:58.210846236 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/authopt/test_authopt \ 2616s 15:12:58.212770683 O: -d /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/authopt/testdata ${ARGS}; \ 2616s 15:12:58.214739986 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/bitmap/test_bitmap ${ARGS}; \ 2616s 15:12:58.216812198 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/conversion/test_conversion ${ARGS}; \ 2616s 15:12:58.218785893 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/kex/test_kex ${ARGS}; \ 2616s 15:12:58.220759663 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/hostkeys/test_hostkeys \ 2616s 15:12:58.222833132 O: -d /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/hostkeys/testdata ${ARGS}; \ 2616s 15:12:58.224731361 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/match/test_match ${ARGS}; \ 2616s 15:12:58.226738957 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/misc/test_misc ${ARGS}; \ 2616s 15:12:58.228815094 O: if test "x" = "xyes" ; then \ 2616s 15:12:58.230843431 O: $V /tmp/autopkgtest.koxaBD/autopkgtest_tmp/regress/unittests/utf8/test_utf8 ${ARGS}; \ 2616s 15:12:58.232887602 O: fi \ 2616s 15:12:58.234885242 O: fi 2685s 15:14:07.367270266 O: test_sshbuf: ................................................................................................................. 114 tests ok 3189s 15:22:31.368493205 O: test_sshkey: .................................................................................... 84 tests ok 3189s 15:22:31.472415081 O: test_sshsig: ....... 7 tests ok 3190s 15:22:32.585171831 O: test_authopt: .................................................................................................................................................. 146 tests ok 4395s autopkgtest-virt-ssh [15:40:54]: ------- nova console-log 4c4a0dc3-e0a6-4572-aa38-7a85d435f3b3 (adt-resolute-s390x-openssh-20260124-120636-juju-7f2275-prod-proposed-migration-environment-20-bfadab52-d732-4450-9fff-b8dbd5c4d67c) ------ 4395s ERROR (CommandError): No server with a name or ID of '4c4a0dc3-e0a6-4572-aa38-7a85d435f3b3' exists. 4395s --------------------------------------------------- 4395s ------- nova show 4c4a0dc3-e0a6-4572-aa38-7a85d435f3b3 (adt-resolute-s390x-openssh-20260124-120636-juju-7f2275-prod-proposed-migration-environment-20-bfadab52-d732-4450-9fff-b8dbd5c4d67c) ------ 4395s ERROR (CommandError): No server with a name or ID of '4c4a0dc3-e0a6-4572-aa38-7a85d435f3b3' exists. 4395s --------------------------------------------------- 4395s 4534s autopkgtest [15:43:13]: ERROR: testbed failure: testbed auxverb failed with exit code 255