0s autopkgtest [08:55:52]: starting date and time: 2025-05-02 08:55:52+0000 0s autopkgtest [08:55:52]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [08:55:52]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.beud_qxt/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:e2fsprogs,src:fuse3 --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=e2fsprogs/1.47.2-1ubuntu2 fuse3/3.17.1+git250416-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-15.secgroup --name adt-questing-s390x-openssh-20250502-085552-juju-7f2275-prod-proposed-migration-environment-15-6122a9b6-811f-4ea9-9e2e-5df623886990 --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 87s autopkgtest [08:57:19]: testbed dpkg architecture: s390x 87s autopkgtest [08:57:19]: testbed apt version: 3.0.0 87s autopkgtest [08:57:19]: @@@@@@@@@@@@@@@@@@@@ test bed setup 88s autopkgtest [08:57:20]: testbed release detected to be: None 88s autopkgtest [08:57:20]: updating testbed package index (apt update) 89s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 89s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 89s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 89s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 89s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [175 kB] 89s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2076 kB] 89s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.4 kB] 89s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [208 kB] 89s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [1079 kB] 90s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [9148 B] 90s Fetched 3701 kB in 1s (3123 kB/s) 90s Reading package lists... 91s autopkgtest [08:57:23]: upgrading testbed (apt dist-upgrade and autopurge) 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 92s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 92s Starting 2 pkgProblemResolver with broken count: 0 92s Done 92s Entering ResolveByKeep 92s 92s Calculating upgrade... 92s The following NEW packages will be installed: 92s libfuse3-4 92s The following packages will be upgraded: 92s e2fsprogs e2fsprogs-l10n fuse3 htop libcom-err2 libext2fs2t64 libss2 logsave 92s nano 92s 9 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 92s Need to get 1544 kB of archives. 92s After this operation, 321 kB of additional disk space will be used. 92s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x logsave s390x 1.47.2-1ubuntu2 [26.7 kB] 92s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs-l10n all 1.47.2-1ubuntu2 [7036 B] 92s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libext2fs2t64 s390x 1.47.2-1ubuntu2 [236 kB] 93s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs s390x 1.47.2-1ubuntu2 [624 kB] 93s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libcom-err2 s390x 1.47.2-1ubuntu2 [26.9 kB] 93s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libss2 s390x 1.47.2-1ubuntu2 [18.0 kB] 93s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libfuse3-4 s390x 3.17.1+git250416-1 [89.1 kB] 93s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x fuse3 s390x 3.17.1+git250416-1 [28.4 kB] 93s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x nano s390x 8.4-1 [299 kB] 93s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x htop s390x 3.4.1-4 [189 kB] 93s Fetched 1544 kB in 1s (1501 kB/s) 94s (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 ... 81472 files and directories currently installed.) 94s Preparing to unpack .../logsave_1.47.2-1ubuntu2_s390x.deb ... 94s Unpacking logsave (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 94s Preparing to unpack .../e2fsprogs-l10n_1.47.2-1ubuntu2_all.deb ... 94s Unpacking e2fsprogs-l10n (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 94s Preparing to unpack .../libext2fs2t64_1.47.2-1ubuntu2_s390x.deb ... 94s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2 to /lib/s390x-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 94s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2.3 to /lib/s390x-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 94s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2 to /lib/s390x-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 94s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2.4 to /lib/s390x-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 94s Unpacking libext2fs2t64:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 94s Setting up libext2fs2t64:s390x (1.47.2-1ubuntu2) ... 94s (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 ... 81472 files and directories currently installed.) 94s Preparing to unpack .../0-e2fsprogs_1.47.2-1ubuntu2_s390x.deb ... 94s Unpacking e2fsprogs (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 94s Preparing to unpack .../1-libcom-err2_1.47.2-1ubuntu2_s390x.deb ... 94s Unpacking libcom-err2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 94s Preparing to unpack .../2-libss2_1.47.2-1ubuntu2_s390x.deb ... 94s Unpacking libss2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 94s Selecting previously unselected package libfuse3-4:s390x. 94s Preparing to unpack .../3-libfuse3-4_3.17.1+git250416-1_s390x.deb ... 94s Unpacking libfuse3-4:s390x (3.17.1+git250416-1) ... 94s Preparing to unpack .../4-fuse3_3.17.1+git250416-1_s390x.deb ... 94s Adding 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 94s Adding 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 94s Unpacking fuse3 (3.17.1+git250416-1) over (3.14.0-10) ... 94s Preparing to unpack .../5-nano_8.4-1_s390x.deb ... 94s Unpacking nano (8.4-1) over (8.3-1) ... 94s Preparing to unpack .../6-htop_3.4.1-4_s390x.deb ... 94s Unpacking htop (3.4.1-4) over (3.4.0-2) ... 94s Setting up htop (3.4.1-4) ... 94s Setting up libcom-err2:s390x (1.47.2-1ubuntu2) ... 94s Setting up libss2:s390x (1.47.2-1ubuntu2) ... 94s Setting up logsave (1.47.2-1ubuntu2) ... 94s Setting up nano (8.4-1) ... 94s Installing new version of config file /etc/nanorc ... 94s Setting up libfuse3-4:s390x (3.17.1+git250416-1) ... 94s Setting up e2fsprogs (1.47.2-1ubuntu2) ... 94s update-initramfs: deferring update (trigger activated) 94s Setting up fuse3 (3.17.1+git250416-1) ... 94s Installing new version of config file /etc/fuse.conf ... 94s Removing 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 94s Removing 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 94s update-initramfs: deferring update (trigger activated) 94s Setting up e2fsprogs-l10n (1.47.2-1ubuntu2) ... 94s Processing triggers for man-db (2.13.0-1) ... 95s Processing triggers for install-info (7.1.1-1) ... 95s Processing triggers for initramfs-tools (0.147ubuntu1) ... 95s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 95s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 98s Using config file '/etc/zipl.conf' 98s Building bootmap in '/boot' 98s Adding IPL section 'ubuntu' (default) 98s Preparing boot device for LD-IPL: vda (0000). 98s Done. 98s Processing triggers for libc-bin (2.41-6ubuntu1) ... 98s Reading package lists... 98s Building dependency tree... 98s Reading state information... 99s Starting pkgProblemResolver with broken count: 0 99s Starting 2 pkgProblemResolver with broken count: 0 99s Done 99s Solving dependencies... 99s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 99s autopkgtest [08:57:31]: rebooting testbed after setup commands that affected boot 118s autopkgtest [08:57:50]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 121s autopkgtest [08:57:53]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 126s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (dsc) [3480 B] 126s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (tar) [1965 kB] 126s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (asc) [833 B] 126s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (diff) [212 kB] 126s gpgv: Signature made Tue Feb 18 14:21:37 2025 UTC 126s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 126s gpgv: Can't check signature: No public key 126s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.dsc: no acceptable signature found 127s autopkgtest [08:57:59]: testing package openssh version 1:9.9p1-3ubuntu3 130s autopkgtest [08:58:02]: build not needed 135s autopkgtest [08:58:07]: test regress: preparing testbed 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 135s Starting pkgProblemResolver with broken count: 0 135s Starting 2 pkgProblemResolver with broken count: 0 135s Done 136s The following NEW packages will be installed: 136s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 136s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 136s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 136s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 136s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 136s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 136s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 136s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 136s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 136s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 136s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 136s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 136s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 136s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 136s python3-packaging python3-pyasn1 python3-pyasn1-modules 136s python3-service-identity python3-twisted python3-zope.interface wdiff 136s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 136s Need to get 8350 kB of archives. 136s After this operation, 38.1 MB of additional disk space will be used. 136s Get:1 http://ftpmaster.internal/ubuntu questing/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 136s Get:2 http://ftpmaster.internal/ubuntu questing/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 136s Get:3 http://ftpmaster.internal/ubuntu questing/universe s390x dropbear-bin s390x 2024.86-2 [151 kB] 136s Get:4 http://ftpmaster.internal/ubuntu questing/universe s390x dropbear all 2024.86-2 [8664 B] 136s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 136s Get:6 http://ftpmaster.internal/ubuntu questing/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 136s Get:7 http://ftpmaster.internal/ubuntu questing/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 136s Get:8 http://ftpmaster.internal/ubuntu questing/main s390x libfile-touch-perl all 0.12-2 [7498 B] 136s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 136s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 136s Get:11 http://ftpmaster.internal/ubuntu questing/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 136s Get:12 http://ftpmaster.internal/ubuntu questing/main s390x libclass-xsaccessor-perl s390x 1.19-4build6 [34.2 kB] 136s Get:13 http://ftpmaster.internal/ubuntu questing/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 136s Get:14 http://ftpmaster.internal/ubuntu questing/main s390x libdynaloader-functions-perl all 0.004-1 [11.4 kB] 136s Get:15 http://ftpmaster.internal/ubuntu questing/main s390x libdevel-callchecker-perl s390x 0.009-1build1 [14.2 kB] 136s Get:16 http://ftpmaster.internal/ubuntu questing/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 136s Get:17 http://ftpmaster.internal/ubuntu questing/main s390x libmodule-runtime-perl all 0.016-2 [16.4 kB] 136s Get:18 http://ftpmaster.internal/ubuntu questing/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 136s Get:19 http://ftpmaster.internal/ubuntu questing/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 136s Get:20 http://ftpmaster.internal/ubuntu questing/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 136s Get:21 http://ftpmaster.internal/ubuntu questing/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 136s Get:22 http://ftpmaster.internal/ubuntu questing/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 136s Get:23 http://ftpmaster.internal/ubuntu questing/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 136s Get:24 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 136s Get:25 http://ftpmaster.internal/ubuntu questing/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 136s Get:26 http://ftpmaster.internal/ubuntu questing/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 136s Get:27 http://ftpmaster.internal/ubuntu questing/main s390x liburi-perl all 5.30-1 [94.4 kB] 136s Get:28 http://ftpmaster.internal/ubuntu questing/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 136s Get:29 http://ftpmaster.internal/ubuntu questing/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 137s Get:30 http://ftpmaster.internal/ubuntu questing/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 137s Get:31 http://ftpmaster.internal/ubuntu questing/main s390x libio-html-perl all 1.004-3 [15.9 kB] 137s Get:32 http://ftpmaster.internal/ubuntu questing/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 137s Get:33 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 137s Get:34 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 137s Get:35 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 137s Get:36 http://ftpmaster.internal/ubuntu questing/main s390x perl-openssl-defaults s390x 7build3 [6628 B] 137s Get:37 http://ftpmaster.internal/ubuntu questing/main s390x libnet-ssleay-perl s390x 1.94-3 [320 kB] 137s Get:38 http://ftpmaster.internal/ubuntu questing/main s390x libio-socket-ssl-perl all 2.089-1 [200 kB] 137s Get:39 http://ftpmaster.internal/ubuntu questing/main s390x libnet-http-perl all 6.23-1 [22.3 kB] 137s Get:40 http://ftpmaster.internal/ubuntu questing/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 137s Get:41 http://ftpmaster.internal/ubuntu questing/main s390x libtry-tiny-perl all 0.32-1 [21.2 kB] 137s Get:42 http://ftpmaster.internal/ubuntu questing/main s390x libwww-robotrules-perl all 6.02-1 [12.6 kB] 137s Get:43 http://ftpmaster.internal/ubuntu questing/main s390x libwww-perl all 6.78-1 [139 kB] 137s Get:44 http://ftpmaster.internal/ubuntu questing/main s390x patchutils s390x 0.4.2-1build3 [79.2 kB] 137s Get:45 http://ftpmaster.internal/ubuntu questing/main s390x wdiff s390x 1.2.2-8 [29.8 kB] 137s Get:46 http://ftpmaster.internal/ubuntu questing/main s390x devscripts all 2.25.5 [1059 kB] 137s Get:47 http://ftpmaster.internal/ubuntu questing/universe s390x libhavege2 s390x 1.9.19-11ubuntu1 [27.3 kB] 137s Get:48 http://ftpmaster.internal/ubuntu questing/universe s390x putty-tools s390x 0.83-2 [741 kB] 137s Get:49 http://ftpmaster.internal/ubuntu questing/main s390x python3-hamcrest all 2.1.0-1 [28.1 kB] 137s Get:50 http://ftpmaster.internal/ubuntu questing/main s390x python3-pyasn1 all 0.6.1-1 [56.4 kB] 137s Get:51 http://ftpmaster.internal/ubuntu questing/main s390x python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 137s Get:52 http://ftpmaster.internal/ubuntu questing/main s390x python3-service-identity all 24.2.0-1 [11.0 kB] 137s Get:53 http://ftpmaster.internal/ubuntu questing/main s390x python3-automat all 24.8.1-1 [35.5 kB] 137s Get:54 http://ftpmaster.internal/ubuntu questing/main s390x python3-constantly all 23.10.4-2 [13.9 kB] 137s Get:55 http://ftpmaster.internal/ubuntu questing/main s390x python3-hyperlink all 21.0.0-6 [68.0 kB] 137s Get:56 http://ftpmaster.internal/ubuntu questing/main s390x python3-packaging all 24.2-1 [51.5 kB] 137s Get:57 http://ftpmaster.internal/ubuntu questing/main s390x python3-incremental all 24.7.2-3 [18.6 kB] 137s Get:58 http://ftpmaster.internal/ubuntu questing/main s390x python3-zope.interface s390x 7.2-1build1 [141 kB] 137s Get:59 http://ftpmaster.internal/ubuntu questing/main s390x python3-twisted all 24.11.0-1 [2066 kB] 138s Get:60 http://ftpmaster.internal/ubuntu questing/universe s390x openssh-tests s390x 1:9.9p1-3ubuntu3 [1410 kB] 138s Get:61 http://ftpmaster.internal/ubuntu questing/universe s390x haveged s390x 1.9.19-11ubuntu1 [32.7 kB] 138s Fetched 8350 kB in 2s (3687 kB/s) 138s Selecting previously unselected package libtommath1:s390x. 138s (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 ... 81482 files and directories currently installed.) 138s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 138s Unpacking libtommath1:s390x (1.3.0-1) ... 138s Selecting previously unselected package libtomcrypt1:s390x. 138s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 138s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 138s Selecting previously unselected package dropbear-bin. 138s Preparing to unpack .../02-dropbear-bin_2024.86-2_s390x.deb ... 138s Unpacking dropbear-bin (2024.86-2) ... 138s Selecting previously unselected package dropbear. 138s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 138s Unpacking dropbear (2024.86-2) ... 138s Selecting previously unselected package libfile-dirlist-perl. 138s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 138s Unpacking libfile-dirlist-perl (0.05-3) ... 138s Selecting previously unselected package libfile-which-perl. 138s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 138s Unpacking libfile-which-perl (1.27-2) ... 138s Selecting previously unselected package libfile-homedir-perl. 138s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 138s Unpacking libfile-homedir-perl (1.006-2) ... 138s Selecting previously unselected package libfile-touch-perl. 138s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 138s Unpacking libfile-touch-perl (0.12-2) ... 138s Selecting previously unselected package libio-pty-perl. 138s Preparing to unpack .../08-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 138s Unpacking libio-pty-perl (1:1.20-1build3) ... 138s Selecting previously unselected package libipc-run-perl. 138s Preparing to unpack .../09-libipc-run-perl_20231003.0-2_all.deb ... 138s Unpacking libipc-run-perl (20231003.0-2) ... 138s Selecting previously unselected package libclass-method-modifiers-perl. 138s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 138s Unpacking libclass-method-modifiers-perl (2.15-1) ... 138s Selecting previously unselected package libclass-xsaccessor-perl. 138s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_s390x.deb ... 138s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 138s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 138s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 138s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 139s Selecting previously unselected package libdynaloader-functions-perl. 139s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-1_all.deb ... 139s Unpacking libdynaloader-functions-perl (0.004-1) ... 139s Selecting previously unselected package libdevel-callchecker-perl:s390x. 139s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-1build1_s390x.deb ... 139s Unpacking libdevel-callchecker-perl:s390x (0.009-1build1) ... 139s Selecting previously unselected package libparams-classify-perl:s390x. 139s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_s390x.deb ... 139s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 139s Selecting previously unselected package libmodule-runtime-perl. 139s Preparing to unpack .../16-libmodule-runtime-perl_0.016-2_all.deb ... 139s Unpacking libmodule-runtime-perl (0.016-2) ... 139s Selecting previously unselected package libimport-into-perl. 139s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 139s Unpacking libimport-into-perl (1.002005-2) ... 139s Selecting previously unselected package librole-tiny-perl. 139s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 139s Unpacking librole-tiny-perl (2.002004-1) ... 139s Selecting previously unselected package libsub-quote-perl. 139s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 139s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 139s Selecting previously unselected package libmoo-perl. 139s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 139s Unpacking libmoo-perl (2.005005-1) ... 139s Selecting previously unselected package libencode-locale-perl. 139s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 139s Unpacking libencode-locale-perl (1.05-3) ... 139s Selecting previously unselected package libtimedate-perl. 139s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 139s Unpacking libtimedate-perl (2.3300-2) ... 139s Selecting previously unselected package libhttp-date-perl. 139s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 139s Unpacking libhttp-date-perl (6.06-1) ... 139s Selecting previously unselected package libfile-listing-perl. 139s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 139s Unpacking libfile-listing-perl (6.16-1) ... 139s Selecting previously unselected package libhtml-tagset-perl. 139s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 139s Unpacking libhtml-tagset-perl (3.24-1) ... 139s Selecting previously unselected package liburi-perl. 139s Preparing to unpack .../26-liburi-perl_5.30-1_all.deb ... 139s Unpacking liburi-perl (5.30-1) ... 139s Selecting previously unselected package libhtml-parser-perl:s390x. 139s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_s390x.deb ... 139s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 139s Selecting previously unselected package libhtml-tree-perl. 139s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 139s Unpacking libhtml-tree-perl (5.07-3) ... 139s Selecting previously unselected package libclone-perl:s390x. 139s Preparing to unpack .../29-libclone-perl_0.47-1_s390x.deb ... 139s Unpacking libclone-perl:s390x (0.47-1) ... 139s Selecting previously unselected package libio-html-perl. 139s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 139s Unpacking libio-html-perl (1.004-3) ... 139s Selecting previously unselected package liblwp-mediatypes-perl. 139s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 139s Unpacking liblwp-mediatypes-perl (6.04-2) ... 139s Selecting previously unselected package libhttp-message-perl. 139s Preparing to unpack .../32-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 139s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 139s Selecting previously unselected package libhttp-cookies-perl. 139s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 139s Unpacking libhttp-cookies-perl (6.11-1) ... 139s Selecting previously unselected package libhttp-negotiate-perl. 139s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 139s Unpacking libhttp-negotiate-perl (6.01-2) ... 139s Selecting previously unselected package perl-openssl-defaults:s390x. 139s Preparing to unpack .../35-perl-openssl-defaults_7build3_s390x.deb ... 139s Unpacking perl-openssl-defaults:s390x (7build3) ... 139s Selecting previously unselected package libnet-ssleay-perl:s390x. 139s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_s390x.deb ... 139s Unpacking libnet-ssleay-perl:s390x (1.94-3) ... 139s Selecting previously unselected package libio-socket-ssl-perl. 139s Preparing to unpack .../37-libio-socket-ssl-perl_2.089-1_all.deb ... 139s Unpacking libio-socket-ssl-perl (2.089-1) ... 139s Selecting previously unselected package libnet-http-perl. 139s Preparing to unpack .../38-libnet-http-perl_6.23-1_all.deb ... 139s Unpacking libnet-http-perl (6.23-1) ... 139s Selecting previously unselected package liblwp-protocol-https-perl. 139s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 139s Unpacking liblwp-protocol-https-perl (6.14-1) ... 139s Selecting previously unselected package libtry-tiny-perl. 139s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 139s Unpacking libtry-tiny-perl (0.32-1) ... 139s Selecting previously unselected package libwww-robotrules-perl. 139s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1_all.deb ... 139s Unpacking libwww-robotrules-perl (6.02-1) ... 139s Selecting previously unselected package libwww-perl. 139s Preparing to unpack .../42-libwww-perl_6.78-1_all.deb ... 139s Unpacking libwww-perl (6.78-1) ... 139s Selecting previously unselected package patchutils. 139s Preparing to unpack .../43-patchutils_0.4.2-1build3_s390x.deb ... 139s Unpacking patchutils (0.4.2-1build3) ... 139s Selecting previously unselected package wdiff. 139s Preparing to unpack .../44-wdiff_1.2.2-8_s390x.deb ... 139s Unpacking wdiff (1.2.2-8) ... 139s Selecting previously unselected package devscripts. 139s Preparing to unpack .../45-devscripts_2.25.5_all.deb ... 139s Unpacking devscripts (2.25.5) ... 139s Selecting previously unselected package libhavege2:s390x. 139s Preparing to unpack .../46-libhavege2_1.9.19-11ubuntu1_s390x.deb ... 139s Unpacking libhavege2:s390x (1.9.19-11ubuntu1) ... 139s Selecting previously unselected package putty-tools. 139s Preparing to unpack .../47-putty-tools_0.83-2_s390x.deb ... 139s Unpacking putty-tools (0.83-2) ... 139s Selecting previously unselected package python3-hamcrest. 139s Preparing to unpack .../48-python3-hamcrest_2.1.0-1_all.deb ... 139s Unpacking python3-hamcrest (2.1.0-1) ... 139s Selecting previously unselected package python3-pyasn1. 139s Preparing to unpack .../49-python3-pyasn1_0.6.1-1_all.deb ... 139s Unpacking python3-pyasn1 (0.6.1-1) ... 139s Selecting previously unselected package python3-pyasn1-modules. 139s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-2_all.deb ... 139s Unpacking python3-pyasn1-modules (0.4.1-2) ... 139s Selecting previously unselected package python3-service-identity. 139s Preparing to unpack .../51-python3-service-identity_24.2.0-1_all.deb ... 139s Unpacking python3-service-identity (24.2.0-1) ... 139s Selecting previously unselected package python3-automat. 139s Preparing to unpack .../52-python3-automat_24.8.1-1_all.deb ... 139s Unpacking python3-automat (24.8.1-1) ... 139s Selecting previously unselected package python3-constantly. 139s Preparing to unpack .../53-python3-constantly_23.10.4-2_all.deb ... 139s Unpacking python3-constantly (23.10.4-2) ... 139s Selecting previously unselected package python3-hyperlink. 139s Preparing to unpack .../54-python3-hyperlink_21.0.0-6_all.deb ... 139s Unpacking python3-hyperlink (21.0.0-6) ... 139s Selecting previously unselected package python3-packaging. 139s Preparing to unpack .../55-python3-packaging_24.2-1_all.deb ... 139s Unpacking python3-packaging (24.2-1) ... 139s Selecting previously unselected package python3-incremental. 139s Preparing to unpack .../56-python3-incremental_24.7.2-3_all.deb ... 139s Unpacking python3-incremental (24.7.2-3) ... 139s Selecting previously unselected package python3-zope.interface. 139s Preparing to unpack .../57-python3-zope.interface_7.2-1build1_s390x.deb ... 139s Unpacking python3-zope.interface (7.2-1build1) ... 139s Selecting previously unselected package python3-twisted. 139s Preparing to unpack .../58-python3-twisted_24.11.0-1_all.deb ... 139s Unpacking python3-twisted (24.11.0-1) ... 139s Selecting previously unselected package openssh-tests. 139s Preparing to unpack .../59-openssh-tests_1%3a9.9p1-3ubuntu3_s390x.deb ... 139s Unpacking openssh-tests (1:9.9p1-3ubuntu3) ... 139s Selecting previously unselected package haveged. 139s Preparing to unpack .../60-haveged_1.9.19-11ubuntu1_s390x.deb ... 139s Unpacking haveged (1.9.19-11ubuntu1) ... 139s Setting up wdiff (1.2.2-8) ... 139s Setting up libfile-which-perl (1.27-2) ... 139s Setting up libdynaloader-functions-perl (0.004-1) ... 139s Setting up libclass-method-modifiers-perl (2.15-1) ... 139s Setting up libio-pty-perl (1:1.20-1build3) ... 139s Setting up python3-zope.interface (7.2-1build1) ... 140s Setting up libclone-perl:s390x (0.47-1) ... 140s Setting up libtommath1:s390x (1.3.0-1) ... 140s Setting up libhtml-tagset-perl (3.24-1) ... 140s Setting up python3-automat (24.8.1-1) ... 140s Setting up liblwp-mediatypes-perl (6.04-2) ... 140s Setting up libtry-tiny-perl (0.32-1) ... 140s Setting up perl-openssl-defaults:s390x (7build3) ... 140s Setting up libencode-locale-perl (1.05-3) ... 140s Setting up python3-hamcrest (2.1.0-1) ... 140s Setting up putty-tools (0.83-2) ... 140s Setting up libhavege2:s390x (1.9.19-11ubuntu1) ... 140s Setting up patchutils (0.4.2-1build3) ... 140s Setting up python3-packaging (24.2-1) ... 140s Setting up python3-incremental (24.7.2-3) ... 140s Setting up python3-hyperlink (21.0.0-6) ... 140s Setting up libio-html-perl (1.004-3) ... 140s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 140s Setting up libipc-run-perl (20231003.0-2) ... 140s Setting up libtimedate-perl (2.3300-2) ... 140s Setting up librole-tiny-perl (2.002004-1) ... 140s Setting up python3-pyasn1 (0.6.1-1) ... 140s Setting up python3-constantly (23.10.4-2) ... 140s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 140s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 140s Setting up libfile-dirlist-perl (0.05-3) ... 140s Setting up libfile-homedir-perl (1.006-2) ... 140s Setting up liburi-perl (5.30-1) ... 140s Setting up libfile-touch-perl (0.12-2) ... 140s Setting up libnet-ssleay-perl:s390x (1.94-3) ... 140s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 140s Setting up libhttp-date-perl (6.06-1) ... 140s Setting up haveged (1.9.19-11ubuntu1) ... 140s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 141s Setting up dropbear-bin (2024.86-2) ... 141s Setting up libfile-listing-perl (6.16-1) ... 141s Setting up libnet-http-perl (6.23-1) ... 141s Setting up libdevel-callchecker-perl:s390x (0.009-1build1) ... 141s Setting up dropbear (2024.86-2) ... 141s Converting existing OpenSSH RSA host key to Dropbear format. 141s Key is a ssh-rsa key 141s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 141s 3072 SHA256:H50IWxTogmIrgYTdEA0wGt3qCQ4Lr927cYJKbLMax+8 /etc/dropbear/dropbear_rsa_host_key (RSA) 141s +---[RSA 3072]----+ 141s |=++B .o. | 141s |o+o + .. | 141s |+ . . .. . | 141s |* + . . .+ o . | 141s |+B + .S o o | 141s |+o=. . . | 141s |.O+.o . . | 141s |+++..+ | 141s |+. .Eo | 141s +----[SHA256]-----+ 141s Converting existing OpenSSH ECDSA host key to Dropbear format. 141s Key is a ecdsa-sha2-nistp256 key 141s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 141s 256 SHA256:QEUtNMDUoR/V8oBMz5aOqs4i61qzx3xKqEHIjR+BRNk /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 141s +---[ECDSA 256]---+ 141s |.oo o+OB+.. | 141s |...E ...==+.. | 141s | . . o ..=+ | 141s |o o . o = . | 141s |.+ o S . | 141s |. ... . | 141s |. ++. . | 141s |.+.=+.. | 141s |*+.+=o | 141s +----[SHA256]-----+ 141s Converting existing OpenSSH ED25519 host key to Dropbear format. 141s Key is a ssh-ed25519 key 141s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 141s 256 SHA256:IjOIkf4cC2XgZhiMu8ZuruUIrRqAjTG/SqgSQhMzqro /etc/dropbear/dropbear_ed25519_host_key (ED25519) 141s +--[ED25519 256]--+ 141s |+. | 141s |+B. | 141s |B=+o | 141s |B@o. | 141s |O+=.+ . S | 141s |B++.o+ . | 141s |Oo++ | 141s |*X | 141s |Eo. | 141s +----[SHA256]-----+ 141s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 142s Setting up python3-pyasn1-modules (0.4.1-2) ... 142s Setting up python3-service-identity (24.2.0-1) ... 142s Setting up libwww-robotrules-perl (6.02-1) ... 142s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 142s Setting up libio-socket-ssl-perl (2.089-1) ... 142s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 142s Setting up libhttp-negotiate-perl (6.01-2) ... 142s Setting up libhttp-cookies-perl (6.11-1) ... 142s Setting up libhtml-tree-perl (5.07-3) ... 142s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 142s Setting up libmodule-runtime-perl (0.016-2) ... 142s Setting up python3-twisted (24.11.0-1) ... 144s Setting up libimport-into-perl (1.002005-2) ... 144s Setting up libmoo-perl (2.005005-1) ... 144s Setting up openssh-tests (1:9.9p1-3ubuntu3) ... 144s Setting up liblwp-protocol-https-perl (6.14-1) ... 144s Setting up libwww-perl (6.78-1) ... 144s Setting up devscripts (2.25.5) ... 144s Processing triggers for install-info (7.1.1-1) ... 144s Processing triggers for libc-bin (2.41-6ubuntu1) ... 144s Processing triggers for man-db (2.13.0-1) ... 148s autopkgtest [08:58:19]: test regress: [----------------------- 148s info: Adding user `openssh-tests' ... 148s info: Selecting UID/GID from range 1000 to 59999 ... 148s info: Adding new group `openssh-tests' (1001) ... 148s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 148s info: Creating home directory `/home/openssh-tests' ... 148s info: Copying files from `/etc/skel' ... 148s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 148s info: Adding user `openssh-tests' to group `users' ... 148s I: annotate-output 2.25.5 148s I: prefix='%H:%M:%S.%N ' 148s 08:58:19.586548471 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user 148s 08:58:19.605753846 O: make: Entering directory '/tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress' 148s 08:58:19.606299916 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/valgrind-out 148s 08:58:19.606821413 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 148s 08:58:19.607006149 E: + /usr/bin/ssh -Q key 148s 08:58:19.607506194 E: + grep -q ^ssh-rsa 148s 08:58:19.607430598 O: ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 148s 08:58:19.608341210 O: tr '\n' '\r' /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 148s 08:58:19.608888758 O: ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 148s 08:58:19.609521020 O: awk '{print $0 "\r"}' /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 148s 08:58:19.610174505 O: ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 148s 08:58:19.612243547 O: fi 148s 08:58:19.613817160 E: + ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2.prv 148s 08:58:19.614486421 E: + diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv 148s 08:58:19.617225715 E: + tr \n \r 148s 08:58:19.618209257 E: + ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 148s 08:58:19.618712857 E: + diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv 148s 08:58:19.622206920 E: + awk {print $0 "\r"} /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2.prv 148s 08:58:19.623807949 E: + ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 148s 08:58:19.624300170 E: + diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv 148s 08:58:19.627426046 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 148s 08:58:19.628545902 O: cat /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t2.out ; \ 148s 08:58:19.628307640 E: + /usr/bin/ssh -Q key 148s 08:58:19.629255437 O: chmod 600 /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t2.out ; \ 148s 08:58:19.629307004 E: + grep -q ^ssh-rsa 148s 08:58:19.632383769 O: ssh-keygen -yf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 148s 08:58:19.632976130 O: fi 148s 08:58:19.633144218 E: + cat /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.prv 148s 08:58:19.634054069 E: + chmod 600 /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t2.out 148s 08:58:19.634692979 E: + ssh-keygen -yf+ /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t2.outdiff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub 148s 08:58:19.635245716 E: 148s 08:58:19.638717893 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 148s 08:58:19.639269632 O: ssh-keygen -ef /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t3.out ; \ 148s 08:58:19.639771434 O: ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 148s 08:58:19.640240819 O: fi 148s 08:58:19.641234835 E: + /usr/bin/ssh -Q key 148s 08:58:19.641722413 E: + grep -q ^ssh-rsa 148s 08:58:19.642630133 E: + ssh-keygen -ef /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub 148s 08:58:19.645644599 E: + ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t3.out 148s 08:58:19.646130813 E: + diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub 148s 08:58:19.648149470 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 148s 08:58:19.648640638 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 148s 08:58:19.649155715 O: awk '{print $2}' | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t4.ok ; \ 148s 08:58:19.649688333 O: fi 148s 08:58:19.650665969 E: + /usr/bin/ssh -Q key 148s 08:58:19.651235260 E: + grep -q ^ssh-rsa 148s 08:58:19.651741253 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub 148s 08:58:19.653543488 E: + awk {print $2} 148s 08:58:19.654339341 E: + diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t4.ok 148s 08:58:19.654828945 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 148s 08:58:19.655310559 E: + /usr/bin/ssh -Q key 148s 08:58:19.655811400 O: ssh-keygen -Bf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 148s 08:58:19.656341511 O: awk '{print $2}' | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t5.ok ; \ 148s 08:58:19.656812827 O: fi 148s 08:58:19.657972123 E: + grep -q ^ssh-rsa 148s 08:58:19.660728584 E: + ssh-keygen -Bf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub 148s 08:58:19.661212972 E: + awk {print $2} 148s 08:58:19.661692833 E: + diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t5.ok 148s 08:58:19.662823436 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 148s 08:58:19.663366235 O: ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t6.out1 ; \ 148s 08:58:19.663867816 O: ssh-keygen -if /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t6.out2 ; \ 148s 08:58:19.664347476 O: chmod 600 /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t6.out1 ; \ 148s 08:58:19.664860184 O: ssh-keygen -yf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t6.out2 ; \ 148s 08:58:19.665330109 O: fi 148s 08:58:19.666958982 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 148s 08:58:19.667551097 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t7.out ; \ 148s 08:58:19.666178940 E: + /usr/bin/ssh -Q key 148s 08:58:19.668351807 O: fi 148s 08:58:19.668840204 E: + grep -q ^ssh-dss 148s 08:58:19.669331079 E: + /usr/bin/ssh -Q key 148s 08:58:19.669797288 E: + grep -q ^ssh-dss 148s 08:58:19.671338595 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 148s 08:58:19.671830039 O: ssh-keygen -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 148s 08:58:19.672303660 O: ssh-keygen -Bf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 148s 08:58:19.672773832 O: fi 148s 08:58:19.673749966 E: + /usr/bin/ssh -Q key 148s 08:58:19.674230685 E: + grep -q ^ssh-dss 148s 08:58:19.675000155 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 148s 08:58:19.675466153 E: + ssh -Q key 148s 08:58:19.675944236 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t8.out ; \ 148s 08:58:19.676463724 E: + grep -q ^ssh-dss 148s 08:58:19.676915049 O: fi 148s 08:58:19.679282393 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 148s 08:58:19.679760027 E: + ssh -Q key 148s 08:58:19.680241981 O: ssh-keygen -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 148s 08:58:19.680720006 O: ssh-keygen -Bf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 148s 08:58:19.681167254 O: fi 148s 08:58:19.682146978 E: + grep -q ^ssh-dss 148s 08:58:19.682793416 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 148s 08:58:19.683277693 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t9.out 148s 08:58:19.689264950 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 148s 08:58:19.689788843 O: ssh-keygen -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t9.out > /dev/null 148s 08:58:19.697172237 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 148s 08:58:19.698161780 O: ssh-keygen -Bf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t9.out > /dev/null 148s 08:58:19.705515946 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t10.out 148s 08:58:19.710014534 O: ssh-keygen -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t10.out > /dev/null 148s 08:58:19.713115971 O: ssh-keygen -Bf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t10.out > /dev/null 148s 08:58:19.716087730 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 148s 08:58:19.716631669 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 148s 08:58:19.717173522 O: awk '{print $2}' | diff - /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t11.ok ; \ 148s 08:58:19.718185433 O: fi 148s 08:58:19.718383265 E: + /usr/bin/ssh -Q key 148s 08:58:19.718858997 E: + grep -q ^ssh-dss 148s 08:58:19.720605817 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t12.out 148s 08:58:19.724062287 O: ssh-keygen -lf /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 148s 08:58:19.729095473 E: run test connect.sh ... 149s 08:58:21.503817543 E: run test proxy-connect.sh ... 149s 08:58:21.503370518 O: ok simple connect 149s 08:58:21.646761204 O: plain username comp=no 149s 08:58:21.954259707 O: plain username comp=yes 150s 08:58:22.256119546 O: username with style 150s 08:58:22.575009197 O: ok proxy connect 150s 08:58:22.575488781 E: run test sshfp-connect.sh ... 150s 08:58:22.713042732 E: run test connect-privsep.sh ... 150s 08:58:22.713550333 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 154s 08:58:26.783833437 E: run test connect-uri.sh ... 154s 08:58:26.784430198 O: ok proxy connect with privsep 155s 08:58:27.056394498 O: uri connect: no trailing slash 155s 08:58:27.393137721 O: uri connect: trailing slash 155s 08:58:27.713204244 O: uri connect: with path name 155s 08:58:27.730352476 O: ok uri connect 155s 08:58:27.730924350 E: run test proto-version.sh ... 156s 08:58:27.983825754 E: run test proto-mismatch.sh ... 156s 08:58:27.984500170 O: ok sshd version with different protocol combinations 156s 08:58:28.235505309 O: ok protocol version mismatch 156s 08:58:28.235628714 E: run test exit-status.sh ... 156s 08:58:28.375870508 O: test remote exit status: status 0 161s 08:58:33.963603044 O: test remote exit status: status 1 167s 08:58:39.556035138 O: test remote exit status: status 4 173s 08:58:45.172536295 O: test remote exit status: status 5 178s 08:58:50.765428936 O: test remote exit status: status 44 184s 08:58:56.379733144 O: ok remote exit status 184s 08:58:56.380282570 E: run test exit-status-signal.sh ... 185s 08:58:57.522104568 O: ok exit status on signal 185s 08:58:57.522759132 E: run test envpass.sh ... 185s 08:58:57.658153926 O: test environment passing: pass env, don't accept 185s 08:58:57.951333708 O: test environment passing: setenv, don't accept 186s 08:58:58.245378144 O: test environment passing: don't pass env, accept 186s 08:58:58.540708620 O: test environment passing: pass single env, accept single env 186s 08:58:58.865115358 O: test environment passing: pass multiple env, accept multiple env 187s 08:58:59.197060118 O: test environment passing: setenv, accept 187s 08:58:59.498270112 O: test environment passing: setenv, first match wins 187s 08:58:59.799691032 O: test environment passing: server setenv wins 188s 08:59:00.113961657 O: test environment passing: server setenv wins 188s 08:59:00.443430903 O: ok environment passing 188s 08:59:00.444149034 E: run test transfer.sh ... 191s 08:59:03.082446117 O: ok transfer data 191s 08:59:03.083058598 E: run test banner.sh ... 191s 08:59:03.214058071 O: test banner: missing banner file 191s 08:59:03.514998287 O: test banner: size 0 191s 08:59:03.812779493 O: test banner: size 10 192s 08:59:04.124108427 O: test banner: size 100 192s 08:59:04.430350416 O: test banner: size 1000 192s 08:59:04.726939834 O: test banner: size 10000 193s 08:59:05.026929634 O: test banner: size 100000 193s 08:59:05.330632564 O: test banner: suppress banner (-q) 193s 08:59:05.635431507 O: ok banner 193s 08:59:05.636222397 E: run test rekey.sh ... 193s 08:59:05.791872341 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 194s 08:59:06.054386482 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 194s 08:59:06.336142878 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 194s 08:59:06.644961601 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 195s 08:59:07.049143955 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 195s 08:59:07.828228232 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 196s 08:59:08.642434822 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 197s 08:59:09.441005179 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 197s 08:59:09.704856211 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 197s 08:59:09.963336526 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 198s 08:59:10.251575734 O: client rekey KexAlgorithms=curve25519-sha256 198s 08:59:10.530478379 O: client rekey KexAlgorithms=sntrup761x25519-sha512 199s 08:59:11.027980714 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 199s 08:59:11.536575455 O: client rekey KexAlgorithms=mlkem768x25519-sha256 199s 08:59:11.824235334 O: client rekey Ciphers=3des-cbc 200s 08:59:12.115909160 O: client rekey Ciphers=aes128-cbc 200s 08:59:12.395158311 O: client rekey Ciphers=aes192-cbc 200s 08:59:12.667093252 O: client rekey Ciphers=aes256-cbc 200s 08:59:12.935340983 O: client rekey Ciphers=aes128-ctr 201s 08:59:13.215114520 O: client rekey Ciphers=aes192-ctr 201s 08:59:13.492022673 O: client rekey Ciphers=aes256-ctr 201s 08:59:13.780208682 O: client rekey Ciphers=aes128-gcm@openssh.com 206s 08:59:18.308260758 O: client rekey Ciphers=aes256-gcm@openssh.com 211s 08:59:23.387212474 O: client rekey Ciphers=chacha20-poly1305@openssh.com 217s 08:59:29.289832748 O: client rekey MACs=hmac-sha1 217s 08:59:29.558319960 O: client rekey MACs=hmac-sha1-96 217s 08:59:29.833011189 O: client rekey MACs=hmac-sha2-256 218s 08:59:30.108002432 O: client rekey MACs=hmac-sha2-512 218s 08:59:30.377243385 O: client rekey MACs=hmac-md5 218s 08:59:30.654528734 O: client rekey MACs=hmac-md5-96 218s 08:59:30.936355791 O: client rekey MACs=umac-64@openssh.com 219s 08:59:31.210196474 O: client rekey MACs=umac-128@openssh.com 219s 08:59:31.486397613 O: client rekey MACs=hmac-sha1-etm@openssh.com 219s 08:59:31.762459005 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 220s 08:59:32.048942806 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 220s 08:59:32.331176068 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 220s 08:59:32.615616538 O: client rekey MACs=hmac-md5-etm@openssh.com 220s 08:59:32.891307466 O: client rekey MACs=hmac-md5-96-etm@openssh.com 221s 08:59:33.162860032 O: client rekey MACs=umac-64-etm@openssh.com 221s 08:59:33.436350084 O: client rekey MACs=umac-128-etm@openssh.com 221s 08:59:33.714787484 O: client rekeylimit 16 222s 08:59:34.008151869 O: client rekeylimit 1k 222s 08:59:34.289410231 O: client rekeylimit 128k 222s 08:59:34.587260748 O: client rekeylimit 256k 222s 08:59:34.948952922 O: client rekeylimit default 5 238s 08:59:50.193283617 O: client rekeylimit default 10 258s 09:00:10.465733290 O: client rekeylimit default 5 no data 273s 09:00:25.714572023 O: client rekeylimit default 10 no data 294s 09:00:45.946615796 O: server rekeylimit 16 294s 09:00:46.247899074 O: server rekeylimit 1k 294s 09:00:46.550884115 O: server rekeylimit 128k 294s 09:00:46.851627188 O: server rekeylimit 256k 295s 09:00:47.214249380 O: server rekeylimit default 5 no data 310s 09:01:02.474125910 O: server rekeylimit default 10 no data 330s 09:01:22.745015680 O: rekeylimit parsing: bytes 330s 09:01:22.795567755 O: rekeylimit parsing: time 330s 09:01:22.846666835 O: ok rekey 330s 09:01:22.847326596 E: run test dhgex.sh ... 331s 09:01:22.990846855 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 331s 09:01:23.233184282 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 331s 09:01:23.477750388 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 331s 09:01:23.722673107 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 332s 09:01:23.965927796 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 332s 09:01:24.205907199 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 332s 09:01:24.452743646 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 332s 09:01:24.714532352 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 333s 09:01:24.992741039 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 333s 09:01:25.310683184 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 333s 09:01:25.636360015 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 334s 09:01:25.947159494 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 334s 09:01:26.261458131 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 334s 09:01:26.579771241 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 334s 09:01:26.902266663 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 335s 09:01:27.221552687 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 335s 09:01:27.539681447 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 335s 09:01:27.858928824 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 336s 09:01:28.180178650 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 336s 09:01:28.582066341 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 337s 09:01:28.988603927 O: ok dhgex 337s 09:01:28.989355794 E: run test stderr-data.sh ... 337s 09:01:29.127366807 O: test stderr data transfer: () 343s 09:01:35.801887244 O: test stderr data transfer: (-n) 350s 09:01:42.505287669 O: ok stderr data transfer 350s 09:01:42.505938529 E: run test stderr-after-eof.sh ... 353s 09:01:44.974619429 O: ok stderr data after eof 353s 09:01:44.975108491 E: run test broken-pipe.sh ... 353s 09:01:45.132262860 E: run test try-ciphers.sh ... 353s 09:01:45.132763856 O: ok broken pipe test 353s 09:01:45.279361218 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 353s 09:01:45.593140374 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 354s 09:01:45.904017989 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 354s 09:01:46.214300467 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 354s 09:01:46.530610367 O: test try ciphers: cipher 3des-cbc mac hmac-md5 354s 09:01:46.847626983 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 355s 09:01:47.156292872 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 355s 09:01:47.464515771 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 355s 09:01:47.775932396 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 356s 09:01:48.083897897 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 356s 09:01:48.389524718 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 356s 09:01:48.694057860 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 357s 09:01:48.995530453 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 357s 09:01:49.301923705 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 357s 09:01:49.611882392 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 357s 09:01:49.920973684 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 358s 09:01:50.239401731 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 358s 09:01:50.546787028 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 359s 09:01:50.853682577 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 359s 09:01:51.173033251 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 359s 09:01:51.480730150 O: test try ciphers: cipher aes128-cbc mac hmac-md5 359s 09:01:51.793030246 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 360s 09:01:52.102410641 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 360s 09:01:52.414235088 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 360s 09:01:52.722547787 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 361s 09:01:53.041328879 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 361s 09:01:53.346669950 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 361s 09:01:53.649928305 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 362s 09:01:53.963926568 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 362s 09:01:54.267798712 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 362s 09:01:54.574589667 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 362s 09:01:54.880382534 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 363s 09:01:55.213652851 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 363s 09:01:55.519546763 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 363s 09:01:55.820957193 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 364s 09:01:56.127041504 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 364s 09:01:56.443667669 O: test try ciphers: cipher aes192-cbc mac hmac-md5 364s 09:01:56.748873236 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 365s 09:01:57.054235835 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 365s 09:01:57.357448264 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 365s 09:01:57.661833327 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 366s 09:01:57.973401893 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 366s 09:01:58.279520393 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 366s 09:01:58.581588690 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 366s 09:01:58.888480775 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 367s 09:01:59.197540564 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 367s 09:01:59.507326719 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 367s 09:01:59.808371361 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 368s 09:02:00.122108214 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 368s 09:02:00.424424936 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 368s 09:02:00.728412406 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 369s 09:02:01.028072981 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 369s 09:02:01.330033951 O: test try ciphers: cipher aes256-cbc mac hmac-md5 369s 09:02:01.633285390 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 369s 09:02:01.944328442 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 370s 09:02:02.251925361 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 370s 09:02:02.560458381 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 370s 09:02:02.878813566 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 371s 09:02:03.187384528 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 371s 09:02:03.499280635 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 371s 09:02:03.809004967 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 372s 09:02:04.122469259 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 372s 09:02:04.425012858 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 372s 09:02:04.746019619 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 373s 09:02:05.070778033 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 373s 09:02:05.377314017 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 373s 09:02:05.685886119 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 374s 09:02:05.995224844 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 374s 09:02:06.301980629 O: test try ciphers: cipher aes128-ctr mac hmac-md5 374s 09:02:06.611541623 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 374s 09:02:06.919001306 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 375s 09:02:07.228513622 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 375s 09:02:07.538232579 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 375s 09:02:07.857959929 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 376s 09:02:08.176796911 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 376s 09:02:08.489458275 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 376s 09:02:08.807428713 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 377s 09:02:09.114662321 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 377s 09:02:09.417598193 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 377s 09:02:09.725888617 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 378s 09:02:10.043539824 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 378s 09:02:10.351417165 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 378s 09:02:10.653238846 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 379s 09:02:10.962402107 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 379s 09:02:11.270247618 O: test try ciphers: cipher aes192-ctr mac hmac-md5 379s 09:02:11.577733614 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 379s 09:02:11.885613557 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 380s 09:02:12.194263367 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 380s 09:02:12.498825903 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 380s 09:02:12.807977999 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 381s 09:02:13.112024507 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 381s 09:02:13.417822094 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 381s 09:02:13.729862679 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 382s 09:02:14.035436452 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 382s 09:02:14.342781195 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 382s 09:02:14.650702723 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 383s 09:02:14.958495786 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 383s 09:02:15.264505300 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 383s 09:02:15.567304529 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 383s 09:02:15.885537834 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 384s 09:02:16.193909695 O: test try ciphers: cipher aes256-ctr mac hmac-md5 384s 09:02:16.502896658 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 384s 09:02:16.809659111 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 385s 09:02:17.117242668 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 385s 09:02:17.430264566 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 385s 09:02:17.742622346 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 386s 09:02:18.055483958 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 386s 09:02:18.369875849 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 386s 09:02:18.681373867 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 387s 09:02:18.984745321 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 387s 09:02:19.297354982 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 387s 09:02:19.616139959 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 387s 09:02:19.931683613 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 388s 09:02:20.249602083 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 388s 09:02:20.571886691 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 388s 09:02:20.883691501 O: ok try ciphers 388s 09:02:20.884347236 E: run test yes-head.sh ... 392s 09:02:24.023080528 O: ok yes pipe head 392s 09:02:24.023801498 E: run test login-timeout.sh ... 407s 09:02:39.661107957 O: ok connect after login grace timeout 407s 09:02:39.661722569 E: run test agent.sh ... 417s 09:02:49.902985830 O: ok simple agent test 417s 09:02:49.903698662 E: run test agent-getpeereid.sh ... 418s 09:02:50.061072314 O: ok disallow agent attach from other uid 418s 09:02:50.061882621 E: run test agent-timeout.sh ... 438s 09:03:10.307002094 O: ok agent timeout test 438s 09:03:10.307742379 E: run test agent-ptrace.sh ... 438s 09:03:10.462680716 E: run test agent-subprocess.sh ... 438s 09:03:10.463701743 O: skipped (gdb not found) 448s 09:03:20.622728895 O: ok agent subprocess 448s 09:03:20.623310398 E: run test keyscan.sh ... 451s 09:03:23.095242776 O: ok keyscan 451s 09:03:23.095953430 E: run test keygen-change.sh ... 456s 09:03:28.243179832 O: ok change passphrase for key 456s 09:03:28.243978212 E: run test keygen-comment.sh ... 460s 09:03:32.481271391 O: ok Comment extraction from private key 460s 09:03:32.482006869 E: run test keygen-convert.sh ... 463s 09:03:35.429328832 O: ok convert keys 463s 09:03:35.430032907 E: run test keygen-knownhosts.sh ... 463s 09:03:35.755966799 O: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts updated. 463s 09:03:35.756625203 O: Original contents retained as /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts.old 463s 09:03:35.767581713 O: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts updated. 463s 09:03:35.768154958 O: Original contents retained as /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts.old 463s 09:03:35.772287698 O: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts updated. 463s 09:03:35.772794765 O: Original contents retained as /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts.old 463s 09:03:35.780915624 O: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts updated. 463s 09:03:35.781467487 O: Original contents retained as /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hosts.old 463s 09:03:35.795380952 O: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hashed updated. 463s 09:03:35.795941356 O: Original contents retained as /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/kh.hashed.old 463s 09:03:35.798828681 O: ok ssh-keygen known_hosts 463s 09:03:35.799353295 E: run test keygen-moduli.sh ... 470s 09:03:42.186401157 O: ok keygen moduli 470s 09:03:42.187099015 E: run test keygen-sshfp.sh ... 470s 09:03:42.374406626 O: ok keygen-sshfp 470s 09:03:42.375460753 E: run test key-options.sh ... 470s 09:03:42.519629635 O: key option command="echo bar" 470s 09:03:42.819288547 O: key option no-pty,command="echo bar" 471s 09:03:43.117777969 O: key option pty default 471s 09:03:43.418878487 O: key option pty no-pty 471s 09:03:43.716076257 O: key option pty restrict 472s 09:03:44.017410267 O: key option pty restrict,pty 472s 09:03:44.321445156 O: key option environment 473s 09:03:45.105507920 O: key option from="127.0.0.1" 474s 09:03:46.143448461 O: key option from="127.0.0.0/8" 474s 09:03:46.873702214 O: key option expiry-time default 475s 09:03:47.182939221 O: key option expiry-time invalid 475s 09:03:47.595634707 O: key option expiry-time expired 476s 09:03:48.003915279 O: key option expiry-time valid 476s 09:03:48.318684036 O: ok key options 476s 09:03:48.319442611 E: run test scp.sh ... 476s 09:03:48.464591430 O: scp: scp mode: simple copy local file to local file 476s 09:03:48.470862210 O: scp: scp mode: simple copy local file to remote file 476s 09:03:48.476446475 O: scp: scp mode: simple copy remote file to local file 476s 09:03:48.482023821 O: scp: scp mode: copy local file to remote file in place 476s 09:03:48.489163560 O: scp: scp mode: copy remote file to local file in place 476s 09:03:48.495071696 O: scp: scp mode: copy local file to remote file clobber 476s 09:03:48.500959696 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 May 2 09:03 /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/copy 476s 09:03:48.501452245 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 May 2 09:03 /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/data 476s 09:03:48.502390071 O: scp: scp mode: copy remote file to local file clobber 476s 09:03:48.508495949 O: scp: scp mode: simple copy local file to remote dir 476s 09:03:48.515127431 O: scp: scp mode: simple copy local file to local dir 476s 09:03:48.524619400 O: scp: scp mode: simple copy remote file to local dir 476s 09:03:48.531877480 O: scp: scp mode: recursive local dir to remote dir 476s 09:03:48.544589647 O: scp: scp mode: recursive local dir to local dir 476s 09:03:48.557139872 O: scp: scp mode: recursive remote dir to local dir 476s 09:03:48.571193483 O: scp: scp mode: unmatched glob file local->remote 476s 09:03:48.577440672 O: scp: scp mode: unmatched glob file remote->local 476s 09:03:48.581229896 O: scp: scp mode: unmatched glob dir recursive local->remote 476s 09:03:48.590892047 O: scp: scp mode: unmatched glob dir recursive remote->local 476s 09:03:48.596315275 O: scp: scp mode: shell metacharacters 476s 09:03:48.601811239 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 476s 09:03:48.624735009 O: scp: scp mode: disallow bad server #0 476s 09:03:48.639071987 O: scp: scp mode: disallow bad server #1 476s 09:03:48.652833901 O: scp: scp mode: disallow bad server #2 476s 09:03:48.667007464 O: scp: scp mode: disallow bad server #3 476s 09:03:48.680847275 O: scp: scp mode: disallow bad server #4 476s 09:03:48.695283319 O: scp: scp mode: disallow bad server #5 476s 09:03:48.709294092 O: scp: scp mode: disallow bad server #6 476s 09:03:48.722902460 O: scp: scp mode: disallow bad server #7 476s 09:03:48.736217018 O: scp: scp mode: detect non-directory target 476s 09:03:48.738741356 E: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/copy2: Not a directory 476s 09:03:48.739983065 O: scp: sftp mode: simple copy local file to local file 476s 09:03:48.744830779 O: scp: sftp mode: simple copy local file to remote file 476s 09:03:48.752458745 O: scp: sftp mode: simple copy remote file to local file 476s 09:03:48.758989250 O: scp: sftp mode: copy local file to remote file in place 476s 09:03:48.765891588 O: scp: sftp mode: copy remote file to local file in place 476s 09:03:48.773049973 O: scp: sftp mode: copy local file to remote file clobber 476s 09:03:48.780172697 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 May 2 09:03 /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/copy 476s 09:03:48.780673403 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 May 2 09:03 /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/data 476s 09:03:48.781818923 O: scp: sftp mode: copy remote file to local file clobber 476s 09:03:48.789894317 O: scp: sftp mode: simple copy local file to remote dir 476s 09:03:48.797900805 O: scp: sftp mode: simple copy local file to local dir 476s 09:03:48.804134945 O: scp: sftp mode: simple copy remote file to local dir 476s 09:03:48.810881967 O: scp: sftp mode: recursive local dir to remote dir 476s 09:03:48.825890890 O: scp: sftp mode: recursive local dir to local dir 476s 09:03:48.836890783 O: scp: sftp mode: recursive remote dir to local dir 476s 09:03:48.852956120 O: scp: sftp mode: unmatched glob file local->remote 476s 09:03:48.860141792 O: scp: sftp mode: unmatched glob file remote->local 476s 09:03:48.865138696 O: scp: sftp mode: unmatched glob dir recursive local->remote 476s 09:03:48.875177733 O: scp: sftp mode: unmatched glob dir recursive remote->local 476s 09:03:48.883925219 O: scp: sftp mode: shell metacharacters 476s 09:03:48.889213196 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 476s 09:03:48.914673848 O: scp: sftp mode: disallow bad server #0 476s 09:03:48.935810923 O: scp: sftp mode: disallow bad server #1 476s 09:03:48.955960631 O: scp: sftp mode: disallow bad server #2 477s 09:03:48.976739570 O: scp: sftp mode: disallow bad server #3 477s 09:03:48.997758785 O: scp: sftp mode: disallow bad server #4 477s 09:03:49.019550721 O: scp: sftp mode: disallow bad server #5 477s 09:03:49.040688529 O: scp: sftp mode: disallow bad server #6 477s 09:03:49.061653429 O: scp: sftp mode: disallow bad server #7 477s 09:03:49.082933206 O: scp: sftp mode: detect non-directory target 477s 09:03:49.085633650 E: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/copy2: Not a directory 477s 09:03:49.089962101 O: ok scp 477s 09:03:49.090574252 E: run test scp3.sh ... 477s 09:03:49.231845351 O: scp3: scp mode: simple copy remote file to remote file 477s 09:03:49.588888810 O: scp3: scp mode: simple copy remote file to remote dir 477s 09:03:49.924901986 O: scp3: scp mode: recursive remote dir to remote dir 478s 09:03:50.318544307 O: scp3: scp mode: detect non-directory target 479s 09:03:51.132274260 O: scp3: sftp mode: simple copy remote file to remote file 479s 09:03:51.145671544 O: scp3: sftp mode: simple copy remote file to remote dir 479s 09:03:51.157306993 O: scp3: sftp mode: recursive remote dir to remote dir 479s 09:03:51.181190424 O: scp3: sftp mode: detect non-directory target 479s 09:03:51.185734385 E: scp: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/copy2: destination is not a directory 479s 09:03:51.186465463 E: scp: /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/copy2: destination is not a directory 479s 09:03:51.191098860 E: run test scp-uri.sh ... 479s 09:03:51.191640747 O: ok scp3 479s 09:03:51.344261072 O: scp-uri: scp mode: simple copy local file to remote file 479s 09:03:51.349353795 O: scp-uri: scp mode: simple copy remote file to local file 479s 09:03:51.354135415 O: scp-uri: scp mode: simple copy local file to remote dir 479s 09:03:51.359325744 O: scp-uri: scp mode: simple copy remote file to local dir 479s 09:03:51.364541021 O: scp-uri: scp mode: recursive local dir to remote dir 479s 09:03:51.370467013 O: scp-uri: scp mode: recursive remote dir to local dir 479s 09:03:51.376583931 O: scp-uri: sftp mode: simple copy local file to remote file 479s 09:03:51.381665969 O: scp-uri: sftp mode: simple copy remote file to local file 479s 09:03:51.386861044 O: scp-uri: sftp mode: simple copy local file to remote dir 479s 09:03:51.393687260 O: scp-uri: sftp mode: simple copy remote file to local dir 479s 09:03:51.400350124 O: scp-uri: sftp mode: recursive local dir to remote dir 479s 09:03:51.407073590 O: scp-uri: sftp mode: recursive remote dir to local dir 479s 09:03:51.417237416 E: run test sftp.sh ... 479s 09:03:51.417966788 O: ok scp-uri 479s 09:03:51.562653142 O: test basic sftp put/get: buffer_size 5 num_requests 1 483s 09:03:55.866299866 O: test basic sftp put/get: buffer_size 5 num_requests 2 486s 09:03:58.195433320 O: test basic sftp put/get: buffer_size 5 num_requests 10 487s 09:03:59.745581595 O: test basic sftp put/get: buffer_size 1000 num_requests 1 487s 09:03:59.761710563 O: test basic sftp put/get: buffer_size 1000 num_requests 2 487s 09:03:59.781338586 O: test basic sftp put/get: buffer_size 1000 num_requests 10 487s 09:03:59.792980018 O: test basic sftp put/get: buffer_size 32000 num_requests 1 487s 09:03:59.802548606 O: test basic sftp put/get: buffer_size 32000 num_requests 2 487s 09:03:59.809638020 O: test basic sftp put/get: buffer_size 32000 num_requests 10 487s 09:03:59.816731553 O: test basic sftp put/get: buffer_size 64000 num_requests 1 487s 09:03:59.824219621 O: test basic sftp put/get: buffer_size 64000 num_requests 2 487s 09:03:59.830342652 O: test basic sftp put/get: buffer_size 64000 num_requests 10 487s 09:03:59.837569801 O: ok basic sftp put/get 487s 09:03:59.838212844 E: run test sftp-chroot.sh ... 488s 09:04:00.152907985 O: test sftp in chroot: get 488s 09:04:00.498158012 O: test sftp in chroot: match 489s 09:04:00.985729420 O: ok sftp in chroot 489s 09:04:00.990224965 E: run test sftp-cmds.sh ... 489s 09:04:01.133067959 O: sftp commands: lls 489s 09:04:01.136754839 O: sftp commands: lls w/path 489s 09:04:01.140570504 O: sftp commands: ls 489s 09:04:01.144694619 O: sftp commands: shell 489s 09:04:01.147581442 O: sftp commands: pwd 489s 09:04:01.149430378 O: sftp commands: lpwd 489s 09:04:01.151311033 O: sftp commands: quit 489s 09:04:01.153002255 O: sftp commands: help 489s 09:04:01.155411066 O: sftp commands: get 489s 09:04:01.160541172 O: sftp commands: get quoted 489s 09:04:01.166647639 O: sftp commands: get filename with quotes 489s 09:04:01.173084085 O: sftp commands: get filename with spaces 489s 09:04:01.178765556 O: sftp commands: get filename with glob metacharacters 489s 09:04:01.183179001 O: sftp commands: get to directory 489s 09:04:01.187874704 O: sftp commands: glob get to directory 489s 09:04:01.234240393 O: sftp commands: get to local dir 489s 09:04:01.239426035 O: sftp commands: glob get to local dir 489s 09:04:01.268195900 O: sftp commands: put 489s 09:04:01.273283905 O: sftp commands: put filename with quotes 489s 09:04:01.278450494 O: sftp commands: put filename with spaces 489s 09:04:01.284180722 O: sftp commands: put to directory 489s 09:04:01.290403711 O: sftp commands: glob put to directory 489s 09:04:01.295988296 O: sftp commands: put to local dir 489s 09:04:01.301382109 O: sftp commands: glob put to local dir 489s 09:04:01.313187268 O: sftp commands: rename 489s 09:04:01.316048537 O: sftp commands: rename directory 489s 09:04:01.318093527 O: sftp commands: ln 489s 09:04:01.320497148 O: sftp commands: ln -s 489s 09:04:01.322966919 O: sftp commands: cp 489s 09:04:01.327196899 O: sftp commands: mkdir 489s 09:04:01.329767733 O: sftp commands: chdir 489s 09:04:01.331626413 O: sftp commands: rmdir 489s 09:04:01.333467569 O: sftp commands: lmkdir 489s 09:04:01.335295963 O: sftp commands: lchdir 489s 09:04:01.340277209 O: ok sftp commands 489s 09:04:01.340883299 E: run test sftp-badcmds.sh ... 489s 09:04:01.480750798 O: sftp invalid commands: get nonexistent 489s 09:04:01.483372234 O: sftp invalid commands: glob get to nonexistent directory 489s 09:04:01.491264074 O: sftp invalid commands: put nonexistent 489s 09:04:01.494686022 O: sftp invalid commands: glob put to nonexistent directory 489s 09:04:01.497312207 O: sftp invalid commands: rename nonexistent 489s 09:04:01.501555807 O: sftp invalid commands: rename target exists (directory) 489s 09:04:01.507528503 O: sftp invalid commands: glob put files to local file 489s 09:04:01.512160010 O: ok sftp invalid commands 489s 09:04:01.512828375 E: run test sftp-batch.sh ... 489s 09:04:01.653656687 O: sftp batchfile: good commands 489s 09:04:01.658326725 O: sftp batchfile: bad commands 489s 09:04:01.665100443 O: sftp batchfile: comments and blanks 489s 09:04:01.668671641 O: sftp batchfile: junk command 489s 09:04:01.671375803 E: run test sftp-glob.sh ... 489s 09:04:01.670842570 O: ok sftp batchfile 489s 09:04:01.808734466 O: sftp glob: file glob 489s 09:04:01.813114529 O: sftp glob: dir glob 489s 09:04:01.816597112 O: sftp glob: quoted glob 489s 09:04:01.820587529 O: sftp glob: escaped glob 489s 09:04:01.825234540 O: sftp glob: escaped quote 489s 09:04:01.828922974 O: sftp glob: quoted quote 489s 09:04:01.832134396 O: sftp glob: single-quoted quote 489s 09:04:01.835304157 O: sftp glob: escaped space 489s 09:04:01.839107980 O: sftp glob: quoted space 489s 09:04:01.842689729 O: sftp glob: escaped slash 489s 09:04:01.846437236 O: sftp glob: quoted slash 489s 09:04:01.850061266 O: sftp glob: escaped slash at EOL 489s 09:04:01.853661739 O: sftp glob: quoted slash at EOL 489s 09:04:01.857040027 O: sftp glob: escaped slash+quote 489s 09:04:01.860267510 O: sftp glob: quoted slash+quote 489s 09:04:01.864009975 O: ok sftp glob 489s 09:04:01.864641276 E: run test sftp-perm.sh ... 490s 09:04:01.999802672 O: sftp permissions: read-only upload 490s 09:04:02.011423283 O: sftp permissions: read-only setstat 490s 09:04:02.020550259 O: sftp permissions: read-only rm 490s 09:04:02.028595626 O: sftp permissions: read-only mkdir 490s 09:04:02.035559292 O: sftp permissions: read-only rmdir 490s 09:04:02.042966485 O: sftp permissions: read-only posix-rename 490s 09:04:02.050739030 O: sftp permissions: read-only oldrename 490s 09:04:02.058213777 O: sftp permissions: read-only symlink 490s 09:04:02.065408848 O: sftp permissions: read-only hardlink 490s 09:04:02.073487547 O: sftp permissions: explicit open 490s 09:04:02.090686970 O: sftp permissions: explicit read 490s 09:04:02.107529166 O: sftp permissions: explicit write 490s 09:04:02.126285764 O: sftp permissions: explicit lstat 490s 09:04:02.143474363 O: sftp permissions: explicit opendir 490s 09:04:02.160456454 O: sftp permissions: explicit readdir 490s 09:04:02.181236077 O: sftp permissions: explicit setstat 490s 09:04:02.196989647 O: sftp permissions: explicit remove 490s 09:04:02.211419309 O: sftp permissions: explicit mkdir 490s 09:04:02.223791151 O: sftp permissions: explicit rmdir 490s 09:04:02.238656316 O: sftp permissions: explicit rename 490s 09:04:02.252595940 O: sftp permissions: explicit symlink 490s 09:04:02.266555132 O: sftp permissions: explicit hardlink 490s 09:04:02.280468168 O: sftp permissions: explicit statvfs 490s 09:04:02.290671017 O: ok sftp permissions 490s 09:04:02.291634486 E: run test sftp-uri.sh ... 490s 09:04:02.568836273 O: sftp-uri: non-interactive fetch to local file 490s 09:04:02.910379350 O: sftp-uri: non-interactive fetch to local dir 491s 09:04:03.260203469 O: sftp-uri: put to remote directory (trailing slash) 491s 09:04:03.612218504 O: sftp-uri: put to remote directory (no slash) 492s 09:04:03.952166863 O: ok sftp-uri 492s 09:04:03.952800577 E: run test reconfigure.sh ... 505s 09:04:17.626168782 O: ok simple connect after reconfigure 505s 09:04:17.627546505 E: run test dynamic-forward.sh ... 506s 09:04:18.047594492 O: test -D forwarding 508s 09:04:20.702446789 O: test -R forwarding 510s 09:04:22.285288860 O: PermitRemoteOpen=any 512s 09:04:23.988677839 O: PermitRemoteOpen=none 512s 09:04:24.484700660 O: PermitRemoteOpen=explicit 514s 09:04:26.245963421 O: PermitRemoteOpen=disallowed 514s 09:04:26.884177204 E: run test forwarding.sh ... 514s 09:04:26.885819162 O: ok dynamic forwarding 522s 09:04:34.550143563 O: ok local and remote forwarding 522s 09:04:34.550689505 E: run test multiplex.sh ... 524s 09:04:36.015068480 O: test connection multiplexing: setenv 524s 09:04:36.023610326 O: test connection multiplexing: envpass 524s 09:04:36.035364778 O: test connection multiplexing: transfer 524s 09:04:36.072883031 O: test connection multiplexing: transfer -Oproxy 524s 09:04:36.158434222 O: test connection multiplexing: forward 526s 09:04:38.218818902 O: test connection multiplexing: status 0 () 531s 09:04:43.243741444 O: test connection multiplexing: status 0 (-Oproxy) 536s 09:04:48.266148399 O: test connection multiplexing: status 1 () 541s 09:04:53.296019828 O: test connection multiplexing: status 1 (-Oproxy) 546s 09:04:58.320583225 O: test connection multiplexing: status 4 () 551s 09:05:03.347032871 O: test connection multiplexing: status 4 (-Oproxy) 556s 09:05:08.384234526 O: test connection multiplexing: status 5 () 561s 09:05:13.411320365 O: test connection multiplexing: status 5 (-Oproxy) 566s 09:05:18.435090666 O: test connection multiplexing: status 44 () 571s 09:05:23.460763796 O: test connection multiplexing: status 44 (-Oproxy) 576s 09:05:28.492460385 O: test connection multiplexing: cmd check 576s 09:05:28.508122231 O: test connection multiplexing: cmd forward local (TCP) 577s 09:05:29.872122088 O: test connection multiplexing: cmd forward remote (TCP) 579s 09:05:31.212414478 O: test connection multiplexing: cmd forward local (UNIX) 580s 09:05:32.281515109 O: test connection multiplexing: cmd forward remote (UNIX) 581s 09:05:33.363367957 O: test connection multiplexing: cmd exit 581s 09:05:33.370201069 O: test connection multiplexing: cmd stop 592s 09:05:44.406069473 O: ok connection multiplexing 592s 09:05:44.406712882 E: run test reexec.sh ... 592s 09:05:44.542781578 O: test config passing 593s 09:05:45.009766129 O: test reexec fallback 593s 09:05:45.010109613 E: ln: failed to create hard link '/tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 593s 09:05:45.498039344 O: ok reexec tests 593s 09:05:45.498498779 E: run test brokenkeys.sh ... 594s 09:05:46.133692689 O: ok broken keys 594s 09:05:46.134433645 E: run test sshcfgparse.sh ... 594s 09:05:46.269855945 O: reparse minimal config 594s 09:05:46.281408140 O: ssh -W opts 594s 09:05:46.313606111 O: user first match 594s 09:05:46.335898714 O: pubkeyacceptedalgorithms 594s 09:05:46.368744035 O: agentforwarding 594s 09:05:46.393289914 O: command line override 594s 09:05:46.407553255 O: ok ssh config parse 594s 09:05:46.408565376 E: run test cfgparse.sh ... 594s 09:05:46.547684192 O: reparse minimal config 594s 09:05:46.688719657 O: reparse regress config 594s 09:05:46.833262708 O: listenaddress order 595s 09:05:46.978714753 E: run test cfgmatch.sh ... 595s 09:05:46.979402147 O: ok sshd config parse 602s 09:05:54.901635733 O: ok sshd_config match 602s 09:05:54.902312446 E: run test cfgmatchlisten.sh ... 615s 09:06:07.092148961 O: ok sshd_config matchlisten 615s 09:06:07.092802668 E: run test percent.sh ... 615s 09:06:07.229754268 O: percent expansions matchexec percent 619s 09:06:11.267803919 O: percent expansions localcommand percent 623s 09:06:14.956653167 O: percent expansions remotecommand percent 623s 09:06:15.045339371 O: percent expansions controlpath percent 623s 09:06:15.134144672 O: percent expansions identityagent percent 623s 09:06:15.220877408 O: percent expansions forwardagent percent 623s 09:06:15.304488812 O: percent expansions localforward percent 623s 09:06:15.391861818 O: percent expansions remoteforward percent 623s 09:06:15.479239418 O: percent expansions revokedhostkeys percent 623s 09:06:15.565772679 O: percent expansions userknownhostsfile percent 626s 09:06:18.363991552 O: percent expansions controlpath dollar 626s 09:06:18.373218264 O: percent expansions identityagent dollar 626s 09:06:18.381952526 O: percent expansions forwardagent dollar 626s 09:06:18.390625800 O: percent expansions localforward dollar 626s 09:06:18.399092692 O: percent expansions remoteforward dollar 626s 09:06:18.407561492 O: percent expansions userknownhostsfile dollar 626s 09:06:18.685935464 O: percent expansions controlpath tilde 626s 09:06:18.702873118 O: percent expansions identityagent tilde 626s 09:06:18.718953610 O: percent expansions forwardagent tilde 626s 09:06:18.734471999 E: run test addrmatch.sh ... 626s 09:06:18.733965461 O: ok percent expansions 626s 09:06:18.875023748 O: test first entry for user 192.168.0.1 somehost 626s 09:06:18.943455387 O: test negative match for user 192.168.30.1 somehost 627s 09:06:19.022112125 O: test no match for user 19.0.0.1 somehost 627s 09:06:19.111274343 O: test list middle for user 10.255.255.254 somehost 627s 09:06:19.177593046 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 627s 09:06:19.251247295 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 627s 09:06:19.318496104 O: test localaddress for user 19.0.0.1 somehost 627s 09:06:19.393263516 O: test localport for user 19.0.0.1 somehost 627s 09:06:19.459754425 O: test bare IP6 address for user ::1 somehost.example.com 627s 09:06:19.536609766 O: test deny IPv6 for user ::2 somehost.example.com 627s 09:06:19.603036910 O: test IP6 negated for user ::3 somehost 627s 09:06:19.668837141 O: test IP6 no match for user ::4 somehost 627s 09:06:19.743042508 O: test IP6 network for user 2000::1 somehost 627s 09:06:19.808994726 O: test IP6 network for user 2001::1 somehost 627s 09:06:19.883303537 O: test IP6 localaddress for user ::5 somehost 627s 09:06:19.949754387 O: test IP6 localport for user ::5 somehost 628s 09:06:20.015852668 O: test invalid Match address 10.0.1.0/8 628s 09:06:20.024708129 O: test invalid Match localaddress 10.0.1.0/8 628s 09:06:20.032400340 O: test invalid Match address 10.0.0.1/24 628s 09:06:20.038597362 O: test invalid Match localaddress 10.0.0.1/24 628s 09:06:20.045229166 O: test invalid Match address 2000:aa:bb:01::/56 628s 09:06:20.052701070 O: test invalid Match localaddress 2000:aa:bb:01::/56 628s 09:06:20.060259665 O: ok address match 628s 09:06:20.060907070 E: run test localcommand.sh ... 628s 09:06:20.198251651 O: test localcommand: proto localcommand 628s 09:06:20.494482100 O: ok localcommand 628s 09:06:20.494955084 E: run test forcecommand.sh ... 629s 09:06:21.857250269 E: Connection closed. 629s 09:06:21.857846337 E: Connection closed 630s 09:06:22.464673660 E: Connection closed. 630s 09:06:22.465344601 E: Connection closed 630s 09:06:22.782752011 O: ok forced command 630s 09:06:22.784335747 E: run test portnum.sh ... 630s 09:06:22.927527856 O: port number parsing: invalid port 0 630s 09:06:22.933420253 O: port number parsing: invalid port 65536 630s 09:06:22.940197860 O: port number parsing: invalid port 131073 630s 09:06:22.946045918 O: port number parsing: invalid port 2000blah 630s 09:06:22.951160987 O: port number parsing: invalid port blah2000 631s 09:06:22.956691058 O: port number parsing: valid port 1 631s 09:06:23.250387622 O: port number parsing: valid port 22 631s 09:06:23.545603675 O: port number parsing: valid port 2222 631s 09:06:23.841546294 O: port number parsing: valid port 22222 632s 09:06:24.134791078 O: port number parsing: valid port 65535 632s 09:06:24.432338692 E: run test keytype.sh ... 632s 09:06:24.432929647 O: ok port number parsing 632s 09:06:24.575545247 O: keygen ed25519, 512 bits 632s 09:06:24.583285108 O: keygen ed25519-sk, n/a bits 632s 09:06:24.592500974 O: keygen ecdsa, 256 bits 632s 09:06:24.599155348 O: keygen ecdsa, 384 bits 632s 09:06:24.605957700 O: keygen ecdsa, 521 bits 632s 09:06:24.613296219 O: keygen ecdsa-sk, n/a bits 632s 09:06:24.625800396 O: keygen rsa, 2048 bits 633s 09:06:25.091981379 O: keygen rsa, 3072 bits 633s 09:06:25.333664853 O: userkey ed25519-512, hostkey ed25519-512 633s 09:06:25.472289088 O: userkey ed25519-512, hostkey ed25519-512 633s 09:06:25.605958114 O: userkey ed25519-512, hostkey ed25519-512 633s 09:06:25.746434512 O: userkey ed25519-sk, hostkey ed25519-sk 633s 09:06:25.905083456 O: userkey ed25519-sk, hostkey ed25519-sk 634s 09:06:26.042732785 O: userkey ed25519-sk, hostkey ed25519-sk 634s 09:06:26.183796578 O: userkey ecdsa-256, hostkey ecdsa-256 634s 09:06:26.349259596 O: userkey ecdsa-256, hostkey ecdsa-256 634s 09:06:26.512003754 O: userkey ecdsa-256, hostkey ecdsa-256 634s 09:06:26.677942735 O: userkey ecdsa-384, hostkey ecdsa-384 634s 09:06:26.906653283 O: userkey ecdsa-384, hostkey ecdsa-384 635s 09:06:27.130680737 O: userkey ecdsa-384, hostkey ecdsa-384 635s 09:06:27.363339613 O: userkey ecdsa-521, hostkey ecdsa-521 635s 09:06:27.650342281 O: userkey ecdsa-521, hostkey ecdsa-521 635s 09:06:27.933457816 O: userkey ecdsa-521, hostkey ecdsa-521 636s 09:06:28.224192878 O: userkey ecdsa-sk, hostkey ecdsa-sk 636s 09:06:28.401568642 O: userkey ecdsa-sk, hostkey ecdsa-sk 636s 09:06:28.576371507 O: userkey ecdsa-sk, hostkey ecdsa-sk 636s 09:06:28.757464543 O: userkey rsa-2048, hostkey rsa-2048 636s 09:06:28.898619741 O: userkey rsa-2048, hostkey rsa-2048 637s 09:06:29.038135441 O: userkey rsa-2048, hostkey rsa-2048 637s 09:06:29.188380563 O: userkey rsa-3072, hostkey rsa-3072 637s 09:06:29.340205955 O: userkey rsa-3072, hostkey rsa-3072 637s 09:06:29.487301087 O: userkey rsa-3072, hostkey rsa-3072 637s 09:06:29.634904849 E: run test kextype.sh ... 637s 09:06:29.635587887 O: ok login with different key types 637s 09:06:29.788787328 O: kex diffie-hellman-group1-sha1 638s 09:06:30.695309388 O: kex diffie-hellman-group14-sha1 639s 09:06:31.661737270 O: kex diffie-hellman-group14-sha256 640s 09:06:32.602794770 O: kex diffie-hellman-group16-sha512 641s 09:06:33.687991966 O: kex diffie-hellman-group18-sha512 643s 09:06:35.352814044 O: kex diffie-hellman-group-exchange-sha1 645s 09:06:37.015858674 O: kex diffie-hellman-group-exchange-sha256 646s 09:06:38.693156606 O: kex ecdh-sha2-nistp256 647s 09:06:39.590741189 O: kex ecdh-sha2-nistp384 648s 09:06:40.521024236 O: kex ecdh-sha2-nistp521 649s 09:06:41.509095050 O: kex curve25519-sha256 650s 09:06:42.472576536 O: kex curve25519-sha256@libssh.org 651s 09:06:43.432261643 O: kex sntrup761x25519-sha512 652s 09:06:44.663762275 O: kex sntrup761x25519-sha512@openssh.com 653s 09:06:45.866134172 O: kex mlkem768x25519-sha256 654s 09:06:46.794383143 E: run test cert-hostkey.sh ... 654s 09:06:46.795139565 O: ok login with different key exchange algorithms 655s 09:06:47.337249574 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/host_ca_key.pub 655s 09:06:47.337846609 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/host_ca_key2.pub 655s 09:06:47.338452879 O: certified host keys: sign host ed25519 cert 655s 09:06:47.344102463 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 655s 09:06:47.353005740 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 655s 09:06:47.354822524 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 655s 09:06:47.363471193 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 655s 09:06:47.372125354 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 655s 09:06:47.373814217 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 655s 09:06:47.381466991 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 655s 09:06:47.394631129 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 655s 09:06:47.396133097 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 655s 09:06:47.405519235 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 655s 09:06:47.422713311 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 655s 09:06:47.424566560 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 655s 09:06:47.437801930 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 655s 09:06:47.461818318 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 655s 09:06:47.463333478 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 655s 09:06:47.477083204 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 655s 09:06:47.489874154 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 655s 09:06:47.491435958 O: certified host keys: sign host rsa cert 656s 09:06:48.107841767 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 656s 09:06:48.116777982 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 656s 09:06:48.118511037 O: certified host keys: sign host rsa-sha2-256 cert 657s 09:06:49.358441065 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 657s 09:06:49.375563118 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 657s 09:06:49.377491453 O: certified host keys: sign host rsa-sha2-512 cert 657s 09:06:49.642640811 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 657s 09:06:49.657921348 O: Revoking from /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 657s 09:06:49.659887322 O: certified host keys: host ed25519 cert connect 657s 09:06:49.660920725 O: certified host keys: ed25519 basic connect expect success yes 657s 09:06:49.918806024 O: certified host keys: ed25519 empty KRL expect success yes 658s 09:06:50.172483847 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 658s 09:06:50.381773670 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 658s 09:06:50.592286867 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 658s 09:06:50.801739237 O: certified host keys: ed25519 empty plaintext revocation expect success yes 659s 09:06:51.057871116 O: certified host keys: ed25519 plain key plaintext revocation expect success no 659s 09:06:51.268781304 O: certified host keys: ed25519 cert plaintext revocation expect success no 659s 09:06:51.483954025 O: certified host keys: ed25519 CA plaintext revocation expect success no 659s 09:06:51.698145523 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 659s 09:06:51.699133782 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 659s 09:06:51.956203474 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 660s 09:06:52.214134416 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 660s 09:06:52.429605892 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 660s 09:06:52.642868411 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 660s 09:06:52.854397382 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 661s 09:06:53.110455732 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 661s 09:06:53.323275330 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 661s 09:06:53.548196522 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 661s 09:06:53.764103173 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 661s 09:06:53.765562950 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 662s 09:06:54.048932833 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 662s 09:06:54.334683302 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 662s 09:06:54.564327071 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 662s 09:06:54.799419998 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 663s 09:06:55.030425058 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 663s 09:06:55.307849961 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 663s 09:06:55.534422004 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 663s 09:06:55.770185193 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 664s 09:06:56.004769711 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 664s 09:06:56.005896016 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 664s 09:06:56.316929074 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 664s 09:06:56.619472505 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 664s 09:06:56.866179479 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 665s 09:06:57.111822369 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 665s 09:06:57.358530179 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 665s 09:06:57.654542319 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 665s 09:06:57.905685358 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 666s 09:06:58.166632860 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 666s 09:06:58.424039217 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 666s 09:06:58.425055108 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 666s 09:06:58.770387467 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 667s 09:06:59.119228152 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 667s 09:06:59.404831989 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 667s 09:06:59.693273255 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 668s 09:06:59.980886095 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 668s 09:07:00.333859424 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 668s 09:07:00.634243440 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 668s 09:07:00.943996256 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 669s 09:07:01.233854873 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 669s 09:07:01.235849830 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 669s 09:07:01.525453224 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 669s 09:07:01.812655762 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 670s 09:07:02.046970697 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 670s 09:07:02.276181356 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 670s 09:07:02.510225036 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 670s 09:07:02.788352863 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 671s 09:07:03.037278123 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 671s 09:07:03.295671833 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 671s 09:07:03.532076188 O: certified host keys: host rsa cert connect 671s 09:07:03.532780642 O: certified host keys: rsa basic connect expect success yes 671s 09:07:03.794769083 O: certified host keys: rsa empty KRL expect success yes 672s 09:07:04.061896734 O: certified host keys: rsa KRL w/ plain key revoked expect success no 672s 09:07:04.286351264 O: certified host keys: rsa KRL w/ cert revoked expect success no 672s 09:07:04.518652938 O: certified host keys: rsa KRL w/ CA revoked expect success no 672s 09:07:04.747562962 O: certified host keys: rsa empty plaintext revocation expect success yes 673s 09:07:05.007738743 O: certified host keys: rsa plain key plaintext revocation expect success no 673s 09:07:05.242449650 O: certified host keys: rsa cert plaintext revocation expect success no 673s 09:07:05.494615723 O: certified host keys: rsa CA plaintext revocation expect success no 673s 09:07:05.720490988 O: certified host keys: host rsa-sha2-256 cert connect 673s 09:07:05.721473256 O: certified host keys: rsa-sha2-256 basic connect expect success yes 674s 09:07:05.974162367 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 674s 09:07:06.230457666 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 674s 09:07:06.449040317 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 674s 09:07:06.669064100 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 674s 09:07:06.888564777 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 675s 09:07:07.147119393 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 675s 09:07:07.380848187 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 675s 09:07:07.625509203 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 675s 09:07:07.842426020 O: certified host keys: host rsa-sha2-512 cert connect 675s 09:07:07.843866311 O: certified host keys: rsa-sha2-512 basic connect expect success yes 676s 09:07:08.103652839 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 676s 09:07:08.366051993 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 676s 09:07:08.588177562 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 676s 09:07:08.814403221 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 677s 09:07:09.037162556 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 677s 09:07:09.301789246 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 677s 09:07:09.541741843 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 677s 09:07:09.797355568 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 678s 09:07:10.041480904 O: certified host keys: host ed25519 revoked cert 678s 09:07:10.283836656 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 678s 09:07:10.531945318 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 678s 09:07:10.789024728 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 679s 09:07:11.070394449 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 679s 09:07:11.402006176 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 679s 09:07:11.672493976 O: certified host keys: host rsa revoked cert 679s 09:07:11.934243277 O: certified host keys: host rsa-sha2-256 revoked cert 680s 09:07:12.185719789 O: certified host keys: host rsa-sha2-512 revoked cert 680s 09:07:12.437580356 O: certified host keys: host ed25519 revoked cert 680s 09:07:12.652902550 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 680s 09:07:12.872697894 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 681s 09:07:13.105060111 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 681s 09:07:13.356390573 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 681s 09:07:13.641659163 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 681s 09:07:13.882445586 O: certified host keys: host rsa revoked cert 682s 09:07:14.107974724 O: certified host keys: host rsa-sha2-256 revoked cert 682s 09:07:14.325872103 O: certified host keys: host rsa-sha2-512 revoked cert 699s 09:07:30.718664818 O: certified host keys: host ed25519 cert downgrade to raw key 699s 09:07:31.233983360 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 699s 09:07:31.755985032 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 700s 09:07:32.311436935 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 700s 09:07:32.936586723 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 701s 09:07:33.660557681 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 702s 09:07:34.225753997 O: certified host keys: host rsa cert downgrade to raw key 703s 09:07:35.185788665 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 704s 09:07:36.570150863 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 705s 09:07:37.681056924 O: certified host keys: host ed25519 connect wrong cert 705s 09:07:37.901147423 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 706s 09:07:38.131866621 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 706s 09:07:38.375433246 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 706s 09:07:38.650008512 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 707s 09:07:38.981676015 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 707s 09:07:39.246170961 O: certified host keys: host rsa connect wrong cert 708s 09:07:40.123383817 O: certified host keys: host rsa-sha2-256 connect wrong cert 708s 09:07:40.916368165 O: certified host keys: host rsa-sha2-512 connect wrong cert 709s 09:07:41.367457521 O: ok certified host keys 709s 09:07:41.368408398 E: run test cert-userkey.sh ... 710s 09:07:42.136631108 O: certified user keys: sign user ed25519 cert 710s 09:07:42.153766986 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 710s 09:07:42.172828153 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 710s 09:07:42.190223671 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 710s 09:07:42.209763372 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 710s 09:07:42.232684967 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 710s 09:07:42.257318192 O: certified user keys: sign user rsa cert 710s 09:07:42.746271130 O: certified user keys: sign user rsa-sha2-256 cert 710s 09:07:42.916555206 O: certified user keys: sign user rsa-sha2-512 cert 712s 09:07:44.002555006 O: certified user keys: ed25519 missing authorized_principals 712s 09:07:44.340874308 O: certified user keys: ed25519 empty authorized_principals 712s 09:07:44.664466216 O: certified user keys: ed25519 wrong authorized_principals 713s 09:07:44.982624879 O: certified user keys: ed25519 correct authorized_principals 713s 09:07:45.294249716 O: certified user keys: ed25519 authorized_principals bad key opt 713s 09:07:45.603903292 O: certified user keys: ed25519 authorized_principals command=false 713s 09:07:45.912609482 O: certified user keys: ed25519 authorized_principals command=true 714s 09:07:46.226510127 O: certified user keys: ed25519 wrong principals key option 714s 09:07:46.529415749 O: certified user keys: ed25519 correct principals key option 714s 09:07:46.840959236 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 715s 09:07:47.149919072 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 715s 09:07:47.453053163 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 715s 09:07:47.757597945 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 716s 09:07:48.071727125 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 716s 09:07:48.374707920 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 716s 09:07:48.680631443 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 717s 09:07:48.985892083 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 717s 09:07:49.311266616 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 717s 09:07:49.627446326 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 717s 09:07:49.934019453 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 718s 09:07:50.236278121 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 718s 09:07:50.547835329 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 718s 09:07:50.863210526 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 719s 09:07:51.173473054 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 719s 09:07:51.493952165 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 719s 09:07:51.819545376 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 720s 09:07:52.128576888 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 720s 09:07:52.461040281 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 720s 09:07:52.805154673 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 721s 09:07:53.148293728 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 721s 09:07:53.467400429 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 721s 09:07:53.800412855 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 722s 09:07:54.117899804 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 722s 09:07:54.459564295 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 722s 09:07:54.819927357 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 723s 09:07:55.146454443 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 723s 09:07:55.492459175 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 723s 09:07:55.848663831 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 724s 09:07:56.216833854 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 724s 09:07:56.571517017 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 724s 09:07:56.961873835 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 725s 09:07:57.299454879 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 725s 09:07:57.672776387 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 726s 09:07:58.054546698 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 726s 09:07:58.398283661 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 726s 09:07:58.789378515 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 727s 09:07:59.119094164 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 727s 09:07:59.451789685 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 727s 09:07:59.781346647 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 728s 09:08:00.113403397 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 728s 09:08:00.435043932 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 728s 09:08:00.759274199 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 729s 09:08:01.084615680 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 729s 09:08:01.406051327 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 729s 09:08:01.734752746 O: certified user keys: rsa missing authorized_principals 730s 09:08:02.033435711 O: certified user keys: rsa empty authorized_principals 730s 09:08:02.337298517 O: certified user keys: rsa wrong authorized_principals 730s 09:08:02.643692211 O: certified user keys: rsa correct authorized_principals 731s 09:08:02.961086864 O: certified user keys: rsa authorized_principals bad key opt 731s 09:08:03.273442580 O: certified user keys: rsa authorized_principals command=false 731s 09:08:03.598023600 O: certified user keys: rsa authorized_principals command=true 731s 09:08:03.917626708 O: certified user keys: rsa wrong principals key option 732s 09:08:04.230840290 O: certified user keys: rsa correct principals key option 732s 09:08:04.560957621 O: certified user keys: rsa-sha2-256 missing authorized_principals 732s 09:08:04.872799132 O: certified user keys: rsa-sha2-256 empty authorized_principals 733s 09:08:05.182163114 O: certified user keys: rsa-sha2-256 wrong authorized_principals 733s 09:08:05.492085983 O: certified user keys: rsa-sha2-256 correct authorized_principals 733s 09:08:05.811036767 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 734s 09:08:06.121839428 O: certified user keys: rsa-sha2-256 authorized_principals command=false 734s 09:08:06.441886248 O: certified user keys: rsa-sha2-256 authorized_principals command=true 734s 09:08:06.764749658 O: certified user keys: rsa-sha2-256 wrong principals key option 735s 09:08:07.082256938 O: certified user keys: rsa-sha2-256 correct principals key option 735s 09:08:07.404937871 O: certified user keys: rsa-sha2-512 missing authorized_principals 735s 09:08:07.709748124 O: certified user keys: rsa-sha2-512 empty authorized_principals 736s 09:08:08.018702483 O: certified user keys: rsa-sha2-512 wrong authorized_principals 736s 09:08:08.316578262 O: certified user keys: rsa-sha2-512 correct authorized_principals 736s 09:08:08.626621546 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 736s 09:08:08.926173620 O: certified user keys: rsa-sha2-512 authorized_principals command=false 737s 09:08:09.241396335 O: certified user keys: rsa-sha2-512 authorized_principals command=true 737s 09:08:09.558012622 O: certified user keys: rsa-sha2-512 wrong principals key option 737s 09:08:09.863905697 O: certified user keys: rsa-sha2-512 correct principals key option 738s 09:08:10.180381907 O: certified user keys: ed25519 authorized_keys connect 738s 09:08:10.497761402 O: certified user keys: ed25519 authorized_keys revoked key 738s 09:08:10.805810664 O: certified user keys: ed25519 authorized_keys revoked via KRL 739s 09:08:11.129500551 O: certified user keys: ed25519 authorized_keys empty KRL 739s 09:08:11.464535200 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 739s 09:08:11.792671088 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 740s 09:08:12.112224317 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 740s 09:08:12.431273416 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 740s 09:08:12.750311449 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 741s 09:08:13.073054487 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 741s 09:08:13.393409273 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 741s 09:08:13.728845449 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 742s 09:08:14.055996061 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 742s 09:08:14.401404763 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 742s 09:08:14.743875261 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 743s 09:08:15.075958733 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 743s 09:08:15.421466804 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 743s 09:08:15.813246646 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 744s 09:08:16.195396863 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 744s 09:08:16.582616179 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 745s 09:08:16.963032884 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 745s 09:08:17.290075332 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 745s 09:08:17.611983458 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 745s 09:08:17.939293571 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 746s 09:08:18.260254369 O: certified user keys: rsa authorized_keys connect 746s 09:08:18.570478275 O: certified user keys: rsa authorized_keys revoked key 746s 09:08:18.870941468 O: certified user keys: rsa authorized_keys revoked via KRL 747s 09:08:19.172009641 O: certified user keys: rsa authorized_keys empty KRL 747s 09:08:19.485089698 O: certified user keys: rsa-sha2-256 authorized_keys connect 747s 09:08:19.831029153 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 748s 09:08:20.139349661 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 748s 09:08:20.447431691 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 748s 09:08:20.762437578 O: certified user keys: rsa-sha2-512 authorized_keys connect 749s 09:08:21.079030474 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 749s 09:08:21.389893503 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 749s 09:08:21.702685480 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 750s 09:08:22.020698758 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 750s 09:08:22.323604777 O: certified user keys: authorized_keys CA does not authenticate 750s 09:08:22.324926180 O: certified user keys: ensure CA key does not authenticate user 750s 09:08:22.646048946 O: certified user keys: ed25519 TrustedUserCAKeys connect 751s 09:08:22.966845306 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 751s 09:08:23.284404600 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 751s 09:08:23.593263028 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 751s 09:08:23.906093689 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 752s 09:08:24.224887712 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 752s 09:08:24.539367339 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 752s 09:08:24.857183080 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 753s 09:08:25.175500821 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 753s 09:08:25.492718475 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 753s 09:08:25.809979595 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 754s 09:08:26.128029994 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 754s 09:08:26.448636330 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 754s 09:08:26.794789945 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 755s 09:08:27.131186964 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 755s 09:08:27.468112431 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 755s 09:08:27.807806792 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 756s 09:08:28.187738828 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 756s 09:08:28.607449445 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 757s 09:08:28.965074009 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 757s 09:08:29.365263156 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 757s 09:08:29.686787823 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 758s 09:08:30.002177094 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 758s 09:08:30.323153957 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 758s 09:08:30.657674886 O: certified user keys: rsa TrustedUserCAKeys connect 759s 09:08:30.967122132 O: certified user keys: rsa TrustedUserCAKeys revoked key 759s 09:08:31.274307285 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 759s 09:08:31.590413004 O: certified user keys: rsa TrustedUserCAKeys empty KRL 759s 09:08:31.919068363 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 760s 09:08:32.232056777 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 760s 09:08:32.541437658 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 760s 09:08:32.849484428 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 761s 09:08:33.169631031 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 761s 09:08:33.484290912 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 761s 09:08:33.799192986 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 762s 09:08:34.108151056 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 762s 09:08:34.426400117 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 762s 09:08:34.740118342 O: certified user keys: TrustedUserCAKeys CA does not authenticate 762s 09:08:34.741369121 O: certified user keys: ensure CA key does not authenticate user 763s 09:08:35.042150446 O: certified user keys: correct principal auth authorized_keys expect success rsa 763s 09:08:35.372859672 O: certified user keys: correct principal auth authorized_keys expect success ed25519 763s 09:08:35.697271286 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 764s 09:08:36.029698506 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 764s 09:08:36.362156578 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 764s 09:08:36.675265328 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 765s 09:08:36.983743379 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 765s 09:08:37.298842443 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 765s 09:08:37.612280122 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 765s 09:08:37.925959690 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 766s 09:08:38.238667193 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 766s 09:08:38.559443946 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 766s 09:08:38.882848631 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 767s 09:08:39.201273566 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 767s 09:08:39.517270160 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 767s 09:08:39.838840531 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 768s 09:08:40.152705560 O: certified user keys: cert expired auth authorized_keys expect failure rsa 768s 09:08:40.470853463 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 768s 09:08:40.776687800 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 769s 09:08:41.097627585 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 769s 09:08:41.419769701 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 769s 09:08:41.753354115 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 770s 09:08:42.077335284 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 770s 09:08:42.406206445 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 770s 09:08:42.731489675 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 771s 09:08:43.047242217 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 771s 09:08:43.356091801 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 771s 09:08:43.669739910 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 772s 09:08:43.998824721 O: certified user keys: force-command auth authorized_keys expect failure rsa 772s 09:08:44.326315586 O: certified user keys: force-command auth authorized_keys expect failure ed25519 772s 09:08:44.641797741 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 773s 09:08:44.969291976 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 773s 09:08:45.296480789 O: certified user keys: empty principals auth authorized_keys expect success rsa 773s 09:08:45.621340280 O: certified user keys: empty principals auth authorized_keys expect success ed25519 773s 09:08:45.935849128 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 774s 09:08:46.254912953 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 774s 09:08:46.571995156 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 774s 09:08:46.899117322 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 775s 09:08:47.245931918 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 775s 09:08:47.567183112 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 775s 09:08:47.887262905 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 776s 09:08:48.211483370 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 776s 09:08:48.533790983 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 776s 09:08:48.840466627 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 777s 09:08:49.154008950 O: certified user keys: force-command match true auth authorized_keys expect success rsa 777s 09:08:49.475252109 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 777s 09:08:49.792646492 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 778s 09:08:50.138985996 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 778s 09:08:50.460375487 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 778s 09:08:50.772607964 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 779s 09:08:51.087500670 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 779s 09:08:51.395970766 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 779s 09:08:51.713193807 O: certified user keys: user ed25519 connect wrong cert 780s 09:08:52.015758138 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 780s 09:08:52.309527928 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 780s 09:08:52.614328137 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 780s 09:08:52.927900692 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 781s 09:08:53.226012229 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 781s 09:08:53.529499539 O: certified user keys: user rsa connect wrong cert 781s 09:08:53.839491190 O: certified user keys: user rsa-sha2-256 connect wrong cert 782s 09:08:54.149279702 O: certified user keys: user rsa-sha2-512 connect wrong cert 782s 09:08:54.445575183 O: ok certified user keys 782s 09:08:54.445600729 E: run test host-expand.sh ... 782s 09:08:54.885591025 O: ok expand %h and %n 782s 09:08:54.886291201 E: run test keys-command.sh ... 783s 09:08:55.043519845 O: SKIPPED: /var/run/keycommand_openssh-tests.42919 not executable (/var/run mounted noexec?) 783s 09:08:55.047922785 E: run test forward-control.sh ... 784s 09:08:56.956035618 O: check_lfwd done (expecting Y): default configuration 786s 09:08:58.581153448 O: check_rfwd done (expecting Y): default configuration 787s 09:08:59.204942305 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 788s 09:09:00.833585176 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 790s 09:09:02.243441713 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 792s 09:09:04.015836291 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 793s 09:09:05.646943857 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 794s 09:09:06.300086649 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 795s 09:09:07.628713111 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 797s 09:09:09.282304722 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 798s 09:09:10.908784462 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 800s 09:09:12.541970552 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 801s 09:09:13.876223452 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 802s 09:09:14.185849511 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 803s 09:09:15.515920453 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 803s 09:09:15.835928978 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 805s 09:09:17.459370769 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 807s 09:09:19.100674879 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 808s 09:09:20.759220135 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 809s 09:09:21.078273234 O: check_rfwd done (expecting N): AllowTcpForwarding=local 810s 09:09:22.418436467 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 810s 09:09:22.733826913 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 812s 09:09:24.388334708 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 812s 09:09:24.705968991 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 813s 09:09:25.073976227 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 813s 09:09:25.387557236 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 815s 09:09:27.031130315 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 815s 09:09:27.367079267 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 816s 09:09:28.713997526 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 817s 09:09:29.063377001 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 818s 09:09:30.425830740 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 818s 09:09:30.827313015 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 819s 09:09:31.684311213 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 820s 09:09:32.115778449 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 821s 09:09:33.574335276 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 822s 09:09:34.472266507 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 824s 09:09:35.995342244 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 825s 09:09:37.934691230 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 827s 09:09:39.272564856 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 828s 09:09:40.911512076 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 830s 09:09:42.238898447 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 831s 09:09:43.915506332 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 832s 09:09:44.267607734 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 833s 09:09:45.922283209 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 834s 09:09:46.255506124 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 834s 09:09:46.576196651 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 835s 09:09:47.909687016 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 836s 09:09:48.237724446 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 836s 09:09:48.589062417 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 838s 09:09:50.231042987 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 839s 09:09:51.557850140 O: check_lfwd done (expecting N): AllowTcpForwarding=no 839s 09:09:51.865151736 O: check_rfwd done (expecting N): AllowTcpForwarding=no 841s 09:09:53.195378919 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 841s 09:09:53.504772286 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 842s 09:09:54.829429937 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 843s 09:09:55.137945145 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 844s 09:09:56.471627948 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 844s 09:09:56.777704082 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 846s 09:09:58.102365936 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 846s 09:09:58.408170745 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 847s 09:09:59.735070334 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 848s 09:10:00.041539156 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 849s 09:10:01.408622119 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 849s 09:10:01.715234520 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 851s 09:10:03.048739533 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 851s 09:10:03.365563328 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 853s 09:10:05.019287559 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 853s 09:10:05.320923558 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 855s 09:10:06.968728358 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 856s 09:10:08.620555710 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 858s 09:10:10.267504407 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 858s 09:10:10.588244691 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 860s 09:10:12.241078161 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 860s 09:10:12.562752900 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 861s 09:10:13.897327556 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 862s 09:10:14.216963780 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 863s 09:10:15.565130988 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 865s 09:10:17.211958787 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 865s 09:10:17.544015856 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 865s 09:10:17.851473216 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 867s 09:10:19.181142252 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 867s 09:10:19.495308838 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 867s 09:10:19.506682284 E: run test integrity.sh ... 867s 09:10:19.506164568 O: ok sshd control of local and remote forwarding 867s 09:10:19.664932981 O: test integrity: hmac-sha1 @2900 868s 09:10:19.975527672 O: test integrity: hmac-sha1 @2901 868s 09:10:20.281271972 O: test integrity: hmac-sha1 @2902 868s 09:10:20.589093034 O: test integrity: hmac-sha1 @2903 868s 09:10:20.899848529 O: test integrity: hmac-sha1 @2904 869s 09:10:21.208624963 O: test integrity: hmac-sha1 @2905 869s 09:10:21.521929933 O: test integrity: hmac-sha1 @2906 869s 09:10:21.844440149 O: test integrity: hmac-sha1 @2907 870s 09:10:22.179992415 O: test integrity: hmac-sha1 @2908 870s 09:10:22.492584357 O: test integrity: hmac-sha1 @2909 870s 09:10:22.803882129 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 870s 09:10:22.813834296 O: test integrity: hmac-sha1-96 @2900 871s 09:10:23.120410340 O: test integrity: hmac-sha1-96 @2901 871s 09:10:23.429479936 O: test integrity: hmac-sha1-96 @2902 871s 09:10:23.738886625 O: test integrity: hmac-sha1-96 @2903 872s 09:10:24.047417610 O: test integrity: hmac-sha1-96 @2904 872s 09:10:24.372442846 O: test integrity: hmac-sha1-96 @2905 872s 09:10:24.682380081 O: test integrity: hmac-sha1-96 @2906 873s 09:10:24.990080460 O: test integrity: hmac-sha1-96 @2907 873s 09:10:25.297860581 O: test integrity: hmac-sha1-96 @2908 873s 09:10:25.625186607 O: test integrity: hmac-sha1-96 @2909 873s 09:10:25.926575038 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 873s 09:10:25.937892687 O: test integrity: hmac-sha2-256 @2900 874s 09:10:26.244410620 O: test integrity: hmac-sha2-256 @2901 874s 09:10:26.549363696 O: test integrity: hmac-sha2-256 @2902 874s 09:10:26.854648753 O: test integrity: hmac-sha2-256 @2903 875s 09:10:27.168460426 O: test integrity: hmac-sha2-256 @2904 875s 09:10:27.475834976 O: test integrity: hmac-sha2-256 @2905 875s 09:10:27.781890511 O: test integrity: hmac-sha2-256 @2906 876s 09:10:28.088618731 O: test integrity: hmac-sha2-256 @2907 876s 09:10:28.395459101 O: test integrity: hmac-sha2-256 @2908 876s 09:10:28.703545260 O: test integrity: hmac-sha2-256 @2909 877s 09:10:29.010260555 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 877s 09:10:29.021735921 O: test integrity: hmac-sha2-512 @2900 877s 09:10:29.319110960 O: test integrity: hmac-sha2-512 @2901 877s 09:10:29.612708804 O: test integrity: hmac-sha2-512 @2902 877s 09:10:29.928159658 O: test integrity: hmac-sha2-512 @2903 878s 09:10:30.240935773 O: test integrity: hmac-sha2-512 @2904 878s 09:10:30.535036242 O: test integrity: hmac-sha2-512 @2905 878s 09:10:30.820556976 O: test integrity: hmac-sha2-512 @2906 879s 09:10:31.111739956 O: test integrity: hmac-sha2-512 @2907 879s 09:10:31.398341300 O: test integrity: hmac-sha2-512 @2908 879s 09:10:31.688845171 O: test integrity: hmac-sha2-512 @2909 880s 09:10:31.977512653 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 880s 09:10:31.988865756 O: test integrity: hmac-md5 @2900 880s 09:10:32.308093973 O: test integrity: hmac-md5 @2901 880s 09:10:32.611950601 O: test integrity: hmac-md5 @2902 880s 09:10:32.942904934 O: test integrity: hmac-md5 @2903 881s 09:10:33.277246890 O: test integrity: hmac-md5 @2904 881s 09:10:33.590297632 O: test integrity: hmac-md5 @2905 881s 09:10:33.904832339 O: test integrity: hmac-md5 @2906 882s 09:10:34.213738524 O: test integrity: hmac-md5 @2907 882s 09:10:34.519045861 O: test integrity: hmac-md5 @2908 882s 09:10:34.839613870 O: test integrity: hmac-md5 @2909 883s 09:10:35.140867123 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 883s 09:10:35.150630013 O: test integrity: hmac-md5-96 @2900 883s 09:10:35.460429827 O: test integrity: hmac-md5-96 @2901 883s 09:10:35.771249383 O: test integrity: hmac-md5-96 @2902 884s 09:10:36.089926438 O: test integrity: hmac-md5-96 @2903 884s 09:10:36.419218155 O: test integrity: hmac-md5-96 @2904 884s 09:10:36.729560849 O: test integrity: hmac-md5-96 @2905 885s 09:10:37.044730085 O: test integrity: hmac-md5-96 @2906 885s 09:10:37.354511958 O: test integrity: hmac-md5-96 @2907 885s 09:10:37.748790853 O: test integrity: hmac-md5-96 @2908 886s 09:10:38.072363035 O: test integrity: hmac-md5-96 @2909 886s 09:10:38.445703890 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 886s 09:10:38.458625037 O: test integrity: umac-64@openssh.com @2900 886s 09:10:38.887237058 O: test integrity: umac-64@openssh.com @2901 887s 09:10:39.436101004 O: test integrity: umac-64@openssh.com @2902 887s 09:10:39.821994394 O: test integrity: umac-64@openssh.com @2903 888s 09:10:40.160144424 O: test integrity: umac-64@openssh.com @2904 888s 09:10:40.494271967 O: test integrity: umac-64@openssh.com @2905 888s 09:10:40.820770238 O: test integrity: umac-64@openssh.com @2906 889s 09:10:41.195707219 O: test integrity: umac-64@openssh.com @2907 889s 09:10:41.603164120 O: test integrity: umac-64@openssh.com @2908 890s 09:10:42.017490203 O: test integrity: umac-64@openssh.com @2909 890s 09:10:42.457823963 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 890s 09:10:42.470604938 O: test integrity: umac-128@openssh.com @2900 890s 09:10:42.949703703 O: test integrity: umac-128@openssh.com @2901 891s 09:10:43.363209575 O: test integrity: umac-128@openssh.com @2902 891s 09:10:43.753831210 O: test integrity: umac-128@openssh.com @2903 892s 09:10:44.209921764 O: test integrity: umac-128@openssh.com @2904 892s 09:10:44.650868928 O: test integrity: umac-128@openssh.com @2905 893s 09:10:45.065955763 O: test integrity: umac-128@openssh.com @2906 893s 09:10:45.475876581 O: test integrity: umac-128@openssh.com @2907 893s 09:10:45.895721206 O: test integrity: umac-128@openssh.com @2908 894s 09:10:46.272340818 O: test integrity: umac-128@openssh.com @2909 894s 09:10:46.665680939 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 894s 09:10:46.676191912 O: test integrity: hmac-sha1-etm@openssh.com @2900 895s 09:10:47.152682561 O: test integrity: hmac-sha1-etm@openssh.com @2901 895s 09:10:47.644667000 O: test integrity: hmac-sha1-etm@openssh.com @2902 896s 09:10:48.016241823 O: test integrity: hmac-sha1-etm@openssh.com @2903 896s 09:10:48.338612136 O: test integrity: hmac-sha1-etm@openssh.com @2904 896s 09:10:48.664480275 O: test integrity: hmac-sha1-etm@openssh.com @2905 897s 09:10:49.111058413 O: test integrity: hmac-sha1-etm@openssh.com @2906 897s 09:10:49.509752217 O: test integrity: hmac-sha1-etm@openssh.com @2907 897s 09:10:49.874086800 O: test integrity: hmac-sha1-etm@openssh.com @2908 898s 09:10:50.221037636 O: test integrity: hmac-sha1-etm@openssh.com @2909 898s 09:10:50.584312525 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 898s 09:10:50.594013356 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 899s 09:10:51.003360133 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 899s 09:10:51.465931718 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 899s 09:10:51.887245905 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 900s 09:10:52.311447330 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 900s 09:10:52.675106892 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 901s 09:10:53.081662177 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 901s 09:10:53.446902196 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 901s 09:10:53.818095359 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 902s 09:10:54.153554104 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 902s 09:10:54.473926135 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 902s 09:10:54.492292122 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 902s 09:10:54.896591418 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 903s 09:10:55.276700914 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 903s 09:10:55.618907017 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 904s 09:10:56.050409862 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 904s 09:10:56.384144383 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 904s 09:10:56.747392222 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 905s 09:10:57.110830298 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 905s 09:10:57.480999351 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 905s 09:10:57.919110026 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 906s 09:10:58.318004563 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 906s 09:10:58.329803426 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 906s 09:10:58.639103891 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 907s 09:10:58.993366634 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 907s 09:10:59.349618079 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 907s 09:10:59.711631561 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 908s 09:11:00.054142432 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 908s 09:11:00.427869594 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 908s 09:11:00.713830477 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 909s 09:11:01.003650606 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 909s 09:11:01.297800334 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 909s 09:11:01.574380980 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 909s 09:11:01.583521254 O: test integrity: hmac-md5-etm@openssh.com @2900 909s 09:11:01.894873536 O: test integrity: hmac-md5-etm@openssh.com @2901 910s 09:11:02.206280686 O: test integrity: hmac-md5-etm@openssh.com @2902 910s 09:11:02.516480938 O: test integrity: hmac-md5-etm@openssh.com @2903 910s 09:11:02.824775198 O: test integrity: hmac-md5-etm@openssh.com @2904 911s 09:11:03.133493171 O: test integrity: hmac-md5-etm@openssh.com @2905 911s 09:11:03.442192761 O: test integrity: hmac-md5-etm@openssh.com @2906 911s 09:11:03.754784602 O: test integrity: hmac-md5-etm@openssh.com @2907 912s 09:11:04.068699455 O: test integrity: hmac-md5-etm@openssh.com @2908 912s 09:11:04.386969399 O: test integrity: hmac-md5-etm@openssh.com @2909 912s 09:11:04.693059452 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 912s 09:11:04.702926972 O: test integrity: hmac-md5-96-etm@openssh.com @2900 913s 09:11:05.021716962 O: test integrity: hmac-md5-96-etm@openssh.com @2901 913s 09:11:05.335583671 O: test integrity: hmac-md5-96-etm@openssh.com @2902 913s 09:11:05.638553021 O: test integrity: hmac-md5-96-etm@openssh.com @2903 913s 09:11:05.950146126 O: test integrity: hmac-md5-96-etm@openssh.com @2904 914s 09:11:06.263276736 O: test integrity: hmac-md5-96-etm@openssh.com @2905 914s 09:11:06.579666248 O: test integrity: hmac-md5-96-etm@openssh.com @2906 914s 09:11:06.897623231 O: test integrity: hmac-md5-96-etm@openssh.com @2907 915s 09:11:07.203413085 O: test integrity: hmac-md5-96-etm@openssh.com @2908 915s 09:11:07.516786143 O: test integrity: hmac-md5-96-etm@openssh.com @2909 915s 09:11:07.817831025 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 915s 09:11:07.827953633 O: test integrity: umac-64-etm@openssh.com @2900 916s 09:11:08.145094119 O: test integrity: umac-64-etm@openssh.com @2901 916s 09:11:08.461298815 O: test integrity: umac-64-etm@openssh.com @2902 916s 09:11:08.774859227 O: test integrity: umac-64-etm@openssh.com @2903 917s 09:11:09.092205230 O: test integrity: umac-64-etm@openssh.com @2904 917s 09:11:09.421969888 O: test integrity: umac-64-etm@openssh.com @2905 917s 09:11:09.734799356 O: test integrity: umac-64-etm@openssh.com @2906 918s 09:11:10.040371897 O: test integrity: umac-64-etm@openssh.com @2907 918s 09:11:10.350538451 O: test integrity: umac-64-etm@openssh.com @2908 918s 09:11:10.657133215 O: test integrity: umac-64-etm@openssh.com @2909 919s 09:11:10.963245223 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 919s 09:11:10.972574597 O: test integrity: umac-128-etm@openssh.com @2900 919s 09:11:11.305378779 O: test integrity: umac-128-etm@openssh.com @2901 919s 09:11:11.620182369 O: test integrity: umac-128-etm@openssh.com @2902 919s 09:11:11.945048269 O: test integrity: umac-128-etm@openssh.com @2903 920s 09:11:12.263948568 O: test integrity: umac-128-etm@openssh.com @2904 920s 09:11:12.575773390 O: test integrity: umac-128-etm@openssh.com @2905 920s 09:11:12.887097898 O: test integrity: umac-128-etm@openssh.com @2906 921s 09:11:13.195696794 O: test integrity: umac-128-etm@openssh.com @2907 921s 09:11:13.503118181 O: test integrity: umac-128-etm@openssh.com @2908 921s 09:11:13.811921263 O: test integrity: umac-128-etm@openssh.com @2909 922s 09:11:14.115777828 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 922s 09:11:14.125050080 O: test integrity: aes128-gcm@openssh.com @2900 922s 09:11:14.414057923 O: test integrity: aes128-gcm@openssh.com @2901 922s 09:11:14.697018704 O: test integrity: aes128-gcm@openssh.com @2902 923s 09:11:14.985775823 O: test integrity: aes128-gcm@openssh.com @2903 923s 09:11:15.273628996 O: test integrity: aes128-gcm@openssh.com @2904 923s 09:11:15.555972648 O: test integrity: aes128-gcm@openssh.com @2905 923s 09:11:15.842143896 O: test integrity: aes128-gcm@openssh.com @2906 924s 09:11:16.127515184 O: test integrity: aes128-gcm@openssh.com @2907 924s 09:11:16.410673136 O: test integrity: aes128-gcm@openssh.com @2908 924s 09:11:16.691793513 O: test integrity: aes128-gcm@openssh.com @2909 925s 09:11:16.975003719 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 925s 09:11:16.984846517 O: test integrity: aes256-gcm@openssh.com @2900 925s 09:11:17.273743674 O: test integrity: aes256-gcm@openssh.com @2901 925s 09:11:17.562672022 O: test integrity: aes256-gcm@openssh.com @2902 925s 09:11:17.845501143 O: test integrity: aes256-gcm@openssh.com @2903 926s 09:11:18.137555619 O: test integrity: aes256-gcm@openssh.com @2904 926s 09:11:18.427802101 O: test integrity: aes256-gcm@openssh.com @2905 926s 09:11:18.720171235 O: test integrity: aes256-gcm@openssh.com @2906 927s 09:11:19.003705991 O: test integrity: aes256-gcm@openssh.com @2907 927s 09:11:19.291520127 O: test integrity: aes256-gcm@openssh.com @2908 927s 09:11:19.576190304 O: test integrity: aes256-gcm@openssh.com @2909 927s 09:11:19.868991921 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 927s 09:11:19.879461960 O: test integrity: chacha20-poly1305@openssh.com @2900 928s 09:11:20.164947707 O: test integrity: chacha20-poly1305@openssh.com @2901 928s 09:11:20.448107248 O: test integrity: chacha20-poly1305@openssh.com @2902 928s 09:11:20.735524304 O: test integrity: chacha20-poly1305@openssh.com @2903 929s 09:11:21.020227288 O: test integrity: chacha20-poly1305@openssh.com @2904 929s 09:11:21.316360073 O: test integrity: chacha20-poly1305@openssh.com @2905 929s 09:11:21.607817247 O: test integrity: chacha20-poly1305@openssh.com @2906 929s 09:11:21.892439640 O: test integrity: chacha20-poly1305@openssh.com @2907 930s 09:11:22.181077601 O: test integrity: chacha20-poly1305@openssh.com @2908 930s 09:11:22.462591817 O: test integrity: chacha20-poly1305@openssh.com @2909 930s 09:11:22.741703701 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 930s 09:11:22.743027185 O: ok integrity 930s 09:11:22.743775469 E: run test krl.sh ... 930s 09:11:22.904053264 O: key revocation lists: generating test keys 933s 09:11:25.349975790 O: key revocation lists: generating KRLs 933s 09:11:25.487143783 O: key revocation lists: checking revocations for revoked keys 934s 09:11:25.973315584 O: key revocation lists: checking revocations for unrevoked keys 934s 09:11:26.356277197 O: key revocation lists: checking revocations for revoked certs 935s 09:11:27.185335684 O: key revocation lists: checking revocations for unrevoked certs 935s 09:11:27.962899057 O: key revocation lists: testing KRL update 937s 09:11:29.118172737 O: key revocation lists: checking revocations for revoked keys 937s 09:11:29.840308956 O: key revocation lists: checking revocations for unrevoked keys 938s 09:11:30.298985463 O: key revocation lists: checking revocations for revoked certs 939s 09:11:31.287843639 O: key revocation lists: checking revocations for unrevoked certs 940s 09:11:32.201653627 O: ok key revocation lists 940s 09:11:32.202716568 E: run test multipubkey.sh ... 943s 09:11:35.205103721 O: ok multiple pubkey 943s 09:11:35.205815304 E: run test limit-keytype.sh ... 945s 09:11:37.501960668 O: allow rsa,ed25519 946s 09:11:38.426651712 O: allow ed25519 947s 09:11:39.313045364 O: allow cert only 948s 09:11:40.217139965 O: match w/ no match 949s 09:11:41.119742111 O: match w/ matching 950s 09:11:42.044759313 O: ok restrict pubkey type 950s 09:11:42.045478621 E: run test hostkey-agent.sh ... 950s 09:11:42.692220544 O: key type ssh-ed25519 950s 09:11:42.841065042 O: key type sk-ssh-ed25519@openssh.com 951s 09:11:42.979505856 O: key type ecdsa-sha2-nistp256 951s 09:11:43.135866211 O: key type ecdsa-sha2-nistp384 951s 09:11:43.314493253 O: key type ecdsa-sha2-nistp521 951s 09:11:43.521123892 O: key type sk-ecdsa-sha2-nistp256@openssh.com 951s 09:11:43.676214237 O: key type ssh-rsa 951s 09:11:43.834354254 O: cert type ssh-ed25519-cert-v01@openssh.com 952s 09:11:44.064035165 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 952s 09:11:44.299960769 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 952s 09:11:44.545906741 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 952s 09:11:44.793611356 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 953s 09:11:45.066593547 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 953s 09:11:45.311238668 O: cert type ssh-rsa-cert-v01@openssh.com 953s 09:11:45.547159521 O: cert type rsa-sha2-256-cert-v01@openssh.com 953s 09:11:45.781267344 O: cert type rsa-sha2-512-cert-v01@openssh.com 954s 09:11:46.022224717 O: ok hostkey agent 954s 09:11:46.022904303 E: run test hostkey-rotate.sh ... 955s 09:11:47.207953677 O: learn hostkey with StrictHostKeyChecking=no 955s 09:11:47.458739627 O: learn additional hostkeys 955s 09:11:47.769316580 O: learn additional hostkeys, type=ssh-ed25519 956s 09:11:48.061533613 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 956s 09:11:48.359043072 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 956s 09:11:48.653786272 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 956s 09:11:48.949101672 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 957s 09:11:49.262675884 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 957s 09:11:49.562975971 O: learn additional hostkeys, type=ssh-rsa 957s 09:11:49.856914940 O: learn changed non-primary hostkey type=ssh-rsa 958s 09:11:50.695980437 O: learn new primary hostkey 959s 09:11:51.012354977 O: rotate primary hostkey 959s 09:11:51.338926850 O: check rotate primary hostkey 959s 09:11:51.660492483 O: ok hostkey rotate 959s 09:11:51.660986950 E: run test principals-command.sh ... 959s 09:11:51.922383377 O: SKIPPED: /var/run/principals_command_openssh-tests.58976 not executable (/var/run mounted noexec?) 959s 09:11:51.926710687 E: run test cert-file.sh ... 960s 09:11:52.114570864 O: identity cert with no plain public file 960s 09:11:52.425477806 O: CertificateFile with no plain public file 960s 09:11:52.745541829 O: plain keys 961s 09:11:53.058065047 O: untrusted cert 961s 09:11:53.377915457 O: good cert, bad key 961s 09:11:53.714042267 O: single trusted 962s 09:11:54.030237432 O: multiple trusted 963s 09:11:55.303967015 O: ok ssh with certificates 963s 09:11:55.304554183 E: run test cfginclude.sh ... 963s 09:11:55.450343017 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.457064293 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.463458522 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.469544339 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.475852445 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.481780450 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.487952416 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.493759997 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.499701870 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.513190649 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.519108053 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.524284226 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.532532225 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.538144905 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.543905804 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.550282936 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.559332164 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.565982875 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.573121159 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.579973733 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.585833845 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.599925779 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.605779572 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.611440414 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.621923229 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.628408840 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 963s 09:11:55.631845498 O: ok config include 963s 09:11:55.632388655 E: run test servcfginclude.sh ... 963s 09:11:55.927565096 O: ok server config include 963s 09:11:55.928445651 E: run test allow-deny-users.sh ... 966s 09:11:58.634410222 O: ok AllowUsers/DenyUsers 966s 09:11:58.635184446 E: run test authinfo.sh ... 966s 09:11:58.778058354 O: ExposeAuthInfo=no 967s 09:11:59.086448200 O: ExposeAuthInfo=yes 967s 09:11:59.390616168 O: ok authinfo 967s 09:11:59.391259963 E: run test sshsig.sh ... 967s 09:11:59.550335125 O: sshsig: make certificates 967s 09:11:59.591221701 O: sshsig: check signature for ssh-ed25519 967s 09:11:59.832046898 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 968s 09:12:00.100689431 O: sshsig: check signature for ecdsa-sha2-nistp256 968s 09:12:00.464919906 O: sshsig: check signature for ecdsa-sha2-nistp384 969s 09:12:01.037630268 O: sshsig: check signature for ecdsa-sha2-nistp521 970s 09:12:01.991298746 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 970s 09:12:02.450460860 O: sshsig: check signature for ssh-rsa 970s 09:12:02.707902332 O: sshsig: check signature for ssh-ed25519-cert.pub 971s 09:12:03.275799605 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 971s 09:12:03.864397730 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 972s 09:12:04.565938152 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 973s 09:12:05.530651268 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 975s 09:12:07.035688209 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 975s 09:12:07.824875175 O: sshsig: check signature for ssh-rsa-cert.pub 976s 09:12:08.374117034 O: sshsig: match principals 976s 09:12:08.393704984 O: sshsig: nomatch principals 976s 09:12:08.405831128 O: ok sshsig 976s 09:12:08.406405873 E: run test knownhosts.sh ... 978s 09:12:10.289549306 O: ok known hosts 978s 09:12:10.290215446 E: run test knownhosts-command.sh ... 978s 09:12:10.433320756 O: simple connection 978s 09:12:10.711490547 O: no keys 978s 09:12:10.922237202 O: bad exit status 979s 09:12:11.077151491 O: keytype ssh-ed25519 979s 09:12:11.322052319 O: keytype sk-ssh-ed25519@openssh.com 979s 09:12:11.581115652 O: keytype ecdsa-sha2-nistp256 979s 09:12:11.834123151 O: keytype ecdsa-sha2-nistp384 980s 09:12:12.099755825 O: keytype ecdsa-sha2-nistp521 980s 09:12:12.373618560 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 980s 09:12:12.632716200 O: keytype ssh-rsa 980s 09:12:12.885565005 O: ok known hosts command 980s 09:12:12.886204451 E: run test agent-restrict.sh ... 981s 09:12:13.028772642 O: generate keys 981s 09:12:13.092253733 O: prepare client config 981s 09:12:13.101808340 O: prepare known_hosts 981s 09:12:13.105764885 O: prepare server configs 981s 09:12:13.118195442 O: authentication w/o agent 982s 09:12:14.659963543 O: start agent 986s 09:12:18.664838677 O: authentication with agent (no restrict) 988s 09:12:20.159982523 O: unrestricted keylist 988s 09:12:20.938729589 O: authentication with agent (basic restrict) 989s 09:12:21.687202580 O: authentication with agent incorrect key (basic restrict) 990s 09:12:22.253985123 O: keylist (basic restrict) 991s 09:12:23.027213335 O: username 991s 09:12:23.773532710 O: username wildcard 992s 09:12:24.521694530 O: username incorrect 992s 09:12:24.575494063 O: agent restriction honours certificate principal 992s 09:12:24.601384443 O: multihop without agent 993s 09:12:25.444658778 O: multihop agent unrestricted 994s 09:12:26.330059392 O: multihop restricted 995s 09:12:27.194928932 O: multihop username 996s 09:12:28.029526040 O: multihop wildcard username 996s 09:12:28.866690232 O: multihop wrong username 997s 09:12:29.479071617 O: multihop cycle no agent 998s 09:12:30.737959262 O: multihop cycle agent unrestricted 1000s 09:12:31.968477653 O: multihop cycle restricted deny 1000s 09:12:32.423386529 O: multihop cycle restricted allow 1001s 09:12:33.701943045 O: ok agent restrictions 1001s 09:12:33.702806051 E: run test hostbased.sh ... 1001s 09:12:33.848884491 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1001s 09:12:33.849431844 E: run test channel-timeout.sh ... 1002s 09:12:33.996770293 O: no timeout 1007s 09:12:39.307979291 O: command timeout 1008s 09:12:40.021021582 O: command long timeout 1008s 09:12:40.318959066 O: command wildcard timeout 1009s 09:12:41.021383601 O: command irrelevant timeout 1014s 09:12:46.327721844 O: multiplexed command timeout 1025s 09:12:57.041650209 O: irrelevant multiplexed command timeout 1040s 09:13:12.366500311 O: global command timeout 1062s 09:13:34.053669859 O: sftp no timeout 1067s 09:13:39.359364406 O: sftp timeout 1068s 09:13:40.020377654 E: Connection closed 1068s 09:13:40.021677549 O: sftp irrelevant timeout 1073s 09:13:45.337756421 E: run test connection-timeout.sh ... 1073s 09:13:45.338260975 O: ok channel timeout 1073s 09:13:45.473618391 O: no timeout 1078s 09:13:50.776032009 O: timeout 1087s 09:13:59.077750877 O: session inhibits timeout 1095s 09:14:07.392156491 O: timeout after session 1103s 09:14:15.397354569 O: timeout with listeners 1111s 09:14:23.698176781 O: ok unused connection timeout 1111s 09:14:23.698697827 E: run test match-subsystem.sh ... 1114s 09:14:26.853180370 O: ok sshd_config match subsystem 1114s 09:14:26.853384306 E: run test agent-pkcs11-restrict.sh ... 1115s 09:14:26.993137212 O: SKIPPED: No PKCS#11 library found 1115s 09:14:26.993910664 E: run test agent-pkcs11-cert.sh ... 1115s 09:14:27.131929463 E: run test penalty.sh ... 1115s 09:14:27.132636414 O: SKIPPED: No PKCS#11 library found 1115s 09:14:27.423072909 O: test connect 1115s 09:14:27.742589517 O: penalty for authentication failure 1123s 09:14:35.312989588 O: penalty for no authentication 1125s 09:14:37.460408263 O: ok penalties 1125s 09:14:37.461071691 E: run test penalty-expire.sh ... 1125s 09:14:37.758736603 O: test connect 1126s 09:14:38.082840512 O: penalty expiry 1139s 09:14:51.772210420 O: ok penalties 1139s 09:14:51.772704641 O: set -e ; if test -z "" ; then \ 1139s 09:14:51.773241225 O: V="" ; \ 1139s 09:14:51.773732539 O: test "x" = "x" || \ 1139s 09:14:51.774236425 O: V=/tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1139s 09:14:51.774746042 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1139s 09:14:51.775242159 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1139s 09:14:51.775724274 O: -d /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1139s 09:14:51.776233283 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1139s 09:14:51.776735569 O: -d /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1139s 09:14:51.777231700 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1139s 09:14:51.777737355 O: -d /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1139s 09:14:51.778211410 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1139s 09:14:51.778691780 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1139s 09:14:51.779190303 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1139s 09:14:51.779663549 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1139s 09:14:51.780164785 O: -d /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1139s 09:14:51.780637255 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1139s 09:14:51.781131526 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1139s 09:14:51.781588091 O: if test "x" = "xyes" ; then \ 1139s 09:14:51.782083600 O: $V /tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1139s 09:14:51.782554631 O: fi \ 1139s 09:14:51.783030037 O: fi 1159s 09:15:11.026435139 O: test_sshbuf: ...................................................................................................... 103 tests ok 1264s 09:16:56.317507841 O: test_sshkey: .................................................................................... 84 tests ok 1264s 09:16:56.336570293 O: test_sshsig: ....... 7 tests ok 1264s 09:16:56.606153324 O: test_authopt: .................................................................................................................................................. 146 tests ok 1280s 09:17:12.567170113 O: test_bitmap: .. 2 tests ok 1280s 09:17:12.569683535 O: test_conversion: . 1 tests ok 1291s 09:17:23.241688133 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1291s 09:17:23.768746508 O: test_hostkeys: .................. 18 tests ok 1291s 09:17:23.772320552 O: test_match: ...... 6 tests ok 1291s 09:17:23.776936499 O: test_misc: ........................................... 43 tests ok 1291s 09:17:23.778991645 E: run test putty-transfer.sh ... 1292s 09:17:24.403024556 O: plink version 0.83 major 0 minor 83 1292s 09:17:24.409729906 O: putty transfer data: compression 0 1294s 09:17:26.720375136 O: putty transfer data: compression 1 1297s 09:17:29.010350387 O: ok putty transfer data 1297s 09:17:29.011037171 E: run test putty-ciphers.sh ... 1299s 09:17:31.001898848 O: plink version 0.83 major 0 minor 83 1299s 09:17:31.261360575 O: putty ciphers: cipher default mac default 1299s 09:17:31.554062014 O: putty ciphers: cipher default mac hmac-sha1 1299s 09:17:31.838486775 O: putty ciphers: cipher default mac hmac-sha1-96 1300s 09:17:32.123687851 O: putty ciphers: cipher default mac hmac-sha2-256 1300s 09:17:32.416193350 O: putty ciphers: cipher default mac hmac-sha2-512 1300s 09:17:32.708488735 O: putty ciphers: cipher default mac hmac-md5 1301s 09:17:32.985816642 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1301s 09:17:33.277802560 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1301s 09:17:33.559427517 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1301s 09:17:33.848265550 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1302s 09:17:34.136796429 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1302s 09:17:34.419889051 O: putty ciphers: cipher 3des-cbc mac default 1302s 09:17:34.709571106 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1303s 09:17:34.993984455 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1303s 09:17:35.276234903 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1303s 09:17:35.554704586 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1303s 09:17:35.832562224 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1304s 09:17:36.108911476 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1304s 09:17:36.404885902 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1304s 09:17:36.692074622 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1305s 09:17:36.989694938 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1305s 09:17:37.279242087 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1305s 09:17:37.578469582 O: putty ciphers: cipher aes128-cbc mac default 1305s 09:17:37.899649230 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1306s 09:17:38.186007111 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1306s 09:17:38.479459346 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1306s 09:17:38.771247616 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1307s 09:17:39.053076106 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1307s 09:17:39.338741525 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1307s 09:17:39.619511589 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1307s 09:17:39.898807332 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1308s 09:17:40.185154448 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1308s 09:17:40.462734745 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1308s 09:17:40.739710960 O: putty ciphers: cipher aes192-cbc mac default 1309s 09:17:41.016182056 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1309s 09:17:41.302682868 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1309s 09:17:41.591512887 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1309s 09:17:41.879815550 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1310s 09:17:42.168855026 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1310s 09:17:42.449135074 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1310s 09:17:42.733090616 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1311s 09:17:43.011780322 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1311s 09:17:43.288660125 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1311s 09:17:43.565937703 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1311s 09:17:43.843754676 O: putty ciphers: cipher aes256-cbc mac default 1312s 09:17:44.124218426 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1312s 09:17:44.412099958 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1312s 09:17:44.695333901 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1313s 09:17:44.983029640 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1313s 09:17:45.262860774 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1313s 09:17:45.539641587 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1313s 09:17:45.821352932 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1314s 09:17:46.104715878 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1314s 09:17:46.396426755 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1314s 09:17:46.677320402 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1314s 09:17:46.952680026 O: putty ciphers: cipher aes128-ctr mac default 1315s 09:17:47.235010832 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1315s 09:17:47.524047859 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1315s 09:17:47.805543457 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1316s 09:17:48.108198571 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1316s 09:17:48.387874882 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1316s 09:17:48.674370590 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1316s 09:17:48.952670932 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1317s 09:17:49.231014500 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1317s 09:17:49.517194726 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1317s 09:17:49.804001144 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1318s 09:17:50.081258236 O: putty ciphers: cipher aes192-ctr mac default 1318s 09:17:50.369545726 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1318s 09:17:50.651769808 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1318s 09:17:50.938588321 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1320s 09:17:51.233800995 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1320s 09:17:51.521834859 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1320s 09:17:51.800720582 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1320s 09:17:52.088900364 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1320s 09:17:52.369303585 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1320s 09:17:52.652781030 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1320s 09:17:52.932703300 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1321s 09:17:53.225698640 O: putty ciphers: cipher aes256-ctr mac default 1321s 09:17:53.541479581 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1321s 09:17:53.822327564 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1322s 09:17:54.115531837 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1322s 09:17:54.413135395 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1322s 09:17:54.692981496 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1323s 09:17:54.975400358 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1323s 09:17:55.263346786 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1323s 09:17:55.544987414 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1323s 09:17:55.822407335 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1324s 09:17:56.111542198 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1324s 09:17:56.403936991 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1324s 09:17:56.708013960 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1325s 09:17:56.991453370 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1325s 09:17:57.270529406 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1325s 09:17:57.559086682 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1325s 09:17:57.838069567 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1326s 09:17:58.124042452 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1326s 09:17:58.403852576 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1326s 09:17:58.683489094 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1327s 09:17:58.969556262 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1327s 09:17:59.259285683 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1327s 09:17:59.547254860 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1327s 09:17:59.829225016 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1328s 09:18:00.119137236 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1328s 09:18:00.397629601 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1328s 09:18:00.678205020 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1329s 09:18:00.965830355 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1329s 09:18:01.248009101 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1329s 09:18:01.535600798 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1329s 09:18:01.818172007 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1330s 09:18:02.100750477 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1330s 09:18:02.379781386 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1330s 09:18:02.656958766 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1330s 09:18:02.939425677 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1331s 09:18:03.236054784 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1331s 09:18:03.515103687 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1331s 09:18:03.804754282 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1332s 09:18:04.086036412 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1332s 09:18:04.362292756 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1332s 09:18:04.643019403 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1332s 09:18:04.919927193 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1333s 09:18:05.207374182 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1333s 09:18:05.491872569 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1333s 09:18:05.771063318 O: ok putty ciphers 1333s 09:18:05.771861165 E: run test putty-kex.sh ... 1334s 09:18:06.777618789 O: plink version 0.83 major 0 minor 83 1334s 09:18:06.918262001 O: putty KEX: kex diffie-hellman-group1-sha1 1335s 09:18:07.153875485 O: putty KEX: kex diffie-hellman-group14-sha1 1335s 09:18:07.539460897 O: putty KEX: kex diffie-hellman-group14-sha256 1335s 09:18:07.906816647 O: putty KEX: kex diffie-hellman-group16-sha512 1337s 09:18:09.117365045 O: putty KEX: kex diffie-hellman-group18-sha512 1345s 09:18:17.171034427 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1345s 09:18:17.568686265 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1346s 09:18:18.906898712 O: putty KEX: kex ecdh-sha2-nistp256 1347s 09:18:19.213299134 O: putty KEX: kex ecdh-sha2-nistp384 1347s 09:18:19.475391806 O: putty KEX: kex ecdh-sha2-nistp521 1347s 09:18:19.798313389 O: putty KEX: kex curve25519-sha256 1348s 09:18:20.018747955 O: putty KEX: kex curve25519-sha256@libssh.org 1348s 09:18:20.240452201 O: putty KEX: kex sntrup761x25519-sha512 1348s 09:18:20.502922569 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1348s 09:18:20.768692203 O: putty KEX: kex mlkem768x25519-sha256 1349s 09:18:20.997840847 O: ok putty KEX 1349s 09:18:20.997284718 E: run test conch-ciphers.sh ... 1349s 09:18:21.295469484 O: conch ciphers: cipher aes256-ctr 1349s 09:18:21.877001350 O: conch ciphers: cipher aes256-cbc 1350s 09:18:22.418096379 O: conch ciphers: cipher aes192-ctr 1351s 09:18:22.985018205 O: conch ciphers: cipher aes192-cbc 1351s 09:18:23.564231890 O: conch ciphers: cipher aes128-ctr 1352s 09:18:24.143118828 O: conch ciphers: cipher aes128-cbc 1352s 09:18:24.694375896 O: conch ciphers: cipher cast128-cbc 1353s 09:18:25.278258096 O: conch ciphers: cipher blowfish 1353s 09:18:25.833931128 O: conch ciphers: cipher 3des-cbc 1354s 09:18:26.393673065 O: ok conch ciphers 1354s 09:18:26.394472804 E: run test dropbear-ciphers.sh ... 1354s 09:18:26.480449143 O: Create dropbear key type ed25519 1354s 09:18:26.493629433 O: Create dropbear key type rsa 1354s 09:18:26.693939579 O: Create dropbear key type ecdsa 1354s 09:18:26.796301811 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 1355s 09:18:27.086347569 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 1355s 09:18:27.330550052 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 1355s 09:18:27.590849074 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1355s 09:18:27.887264016 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1356s 09:18:28.138891062 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1356s 09:18:28.398360128 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 1356s 09:18:28.692632878 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 1356s 09:18:28.943132861 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 1357s 09:18:29.215253961 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1357s 09:18:29.509766297 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1357s 09:18:29.760799227 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1358s 09:18:30.018835591 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 1358s 09:18:30.313075069 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 1358s 09:18:30.584638501 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 1358s 09:18:30.851655917 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1359s 09:18:31.152660996 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1359s 09:18:31.404945495 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1359s 09:18:31.676827116 O: ok dropbear ciphers 1359s 09:18:31.678248888 E: run test dropbear-kex.sh ... 1359s 09:18:31.855488917 O: diffie-hellman-group14-sha1 1359s 09:18:31.856174838 O: dropbear kex: kex curve25519-sha256 1360s 09:18:32.103882354 O: dropbear kex: kex curve25519-sha256@libssh.org 1360s 09:18:32.355780018 O: dropbear kex: kex diffie-hellman-group14-sha256 1360s 09:18:32.626619639 O: dropbear kex: kex diffie-hellman-group14-sha1 1360s 09:18:32.916317081 O: ok dropbear kex 1360s 09:18:32.916919537 O: make: Leaving directory '/tmp/autopkgtest.6IenIL/autopkgtest_tmp/user/regress' 1360s 09:18:32.917856772 I: Finished with exitcode 0 1360s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1361s info: Looking for files to backup/remove ... 1361s info: Removing files ... 1361s info: Removing crontab ... 1361s info: Removing user `openssh-tests' ... 1361s autopkgtest [09:18:33]: test regress: -----------------------] 1362s autopkgtest [09:18:34]: test regress: - - - - - - - - - - results - - - - - - - - - - 1362s regress PASS 1362s autopkgtest [09:18:34]: test ssh-gssapi: preparing testbed 1448s autopkgtest [09:20:00]: testbed dpkg architecture: s390x 1448s autopkgtest [09:20:00]: testbed apt version: 3.0.0 1449s autopkgtest [09:20:01]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1449s autopkgtest [09:20:01]: testbed release detected to be: questing 1450s autopkgtest [09:20:02]: updating testbed package index (apt update) 1450s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1450s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1450s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1450s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1451s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [175 kB] 1451s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.4 kB] 1451s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2076 kB] 1452s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [208 kB] 1452s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [1079 kB] 1452s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [9148 B] 1452s Fetched 3701 kB in 2s (1710 kB/s) 1453s Reading package lists... 1453s autopkgtest [09:20:05]: upgrading testbed (apt dist-upgrade and autopurge) 1453s Reading package lists... 1454s Building dependency tree... 1454s Reading state information... 1454s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1454s Starting 2 pkgProblemResolver with broken count: 0 1454s Done 1454s Entering ResolveByKeep 1454s 1454s Calculating upgrade... 1454s The following NEW packages will be installed: 1454s libfuse3-4 1454s The following packages will be upgraded: 1454s e2fsprogs e2fsprogs-l10n fuse3 htop libcom-err2 libext2fs2t64 libss2 logsave 1454s nano 1454s 9 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1454s Need to get 1544 kB of archives. 1454s After this operation, 321 kB of additional disk space will be used. 1454s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x logsave s390x 1.47.2-1ubuntu2 [26.7 kB] 1455s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs-l10n all 1.47.2-1ubuntu2 [7036 B] 1455s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libext2fs2t64 s390x 1.47.2-1ubuntu2 [236 kB] 1455s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs s390x 1.47.2-1ubuntu2 [624 kB] 1455s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libcom-err2 s390x 1.47.2-1ubuntu2 [26.9 kB] 1455s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libss2 s390x 1.47.2-1ubuntu2 [18.0 kB] 1455s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libfuse3-4 s390x 3.17.1+git250416-1 [89.1 kB] 1455s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x fuse3 s390x 3.17.1+git250416-1 [28.4 kB] 1455s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x nano s390x 8.4-1 [299 kB] 1455s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x htop s390x 3.4.1-4 [189 kB] 1455s Fetched 1544 kB in 1s (1670 kB/s) 1456s (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 ... 81472 files and directories currently installed.) 1456s Preparing to unpack .../logsave_1.47.2-1ubuntu2_s390x.deb ... 1456s Unpacking logsave (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1456s Preparing to unpack .../e2fsprogs-l10n_1.47.2-1ubuntu2_all.deb ... 1456s Unpacking e2fsprogs-l10n (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1456s Preparing to unpack .../libext2fs2t64_1.47.2-1ubuntu2_s390x.deb ... 1456s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2 to /lib/s390x-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 1456s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2.3 to /lib/s390x-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 1456s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2 to /lib/s390x-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 1456s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2.4 to /lib/s390x-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 1456s Unpacking libext2fs2t64:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1456s Setting up libext2fs2t64:s390x (1.47.2-1ubuntu2) ... 1456s (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 ... 81472 files and directories currently installed.) 1456s Preparing to unpack .../0-e2fsprogs_1.47.2-1ubuntu2_s390x.deb ... 1456s Unpacking e2fsprogs (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1456s Preparing to unpack .../1-libcom-err2_1.47.2-1ubuntu2_s390x.deb ... 1456s Unpacking libcom-err2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1456s Preparing to unpack .../2-libss2_1.47.2-1ubuntu2_s390x.deb ... 1456s Unpacking libss2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1456s Selecting previously unselected package libfuse3-4:s390x. 1456s Preparing to unpack .../3-libfuse3-4_3.17.1+git250416-1_s390x.deb ... 1456s Unpacking libfuse3-4:s390x (3.17.1+git250416-1) ... 1456s Preparing to unpack .../4-fuse3_3.17.1+git250416-1_s390x.deb ... 1456s Adding 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 1456s Adding 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 1456s Unpacking fuse3 (3.17.1+git250416-1) over (3.14.0-10) ... 1456s Preparing to unpack .../5-nano_8.4-1_s390x.deb ... 1456s Unpacking nano (8.4-1) over (8.3-1) ... 1456s Preparing to unpack .../6-htop_3.4.1-4_s390x.deb ... 1456s Unpacking htop (3.4.1-4) over (3.4.0-2) ... 1456s Setting up htop (3.4.1-4) ... 1456s Setting up libcom-err2:s390x (1.47.2-1ubuntu2) ... 1456s Setting up libss2:s390x (1.47.2-1ubuntu2) ... 1456s Setting up logsave (1.47.2-1ubuntu2) ... 1456s Setting up nano (8.4-1) ... 1456s Installing new version of config file /etc/nanorc ... 1456s Setting up libfuse3-4:s390x (3.17.1+git250416-1) ... 1456s Setting up e2fsprogs (1.47.2-1ubuntu2) ... 1456s update-initramfs: deferring update (trigger activated) 1456s Setting up fuse3 (3.17.1+git250416-1) ... 1456s Installing new version of config file /etc/fuse.conf ... 1456s Removing 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 1456s Removing 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 1456s update-initramfs: deferring update (trigger activated) 1456s Setting up e2fsprogs-l10n (1.47.2-1ubuntu2) ... 1456s Processing triggers for man-db (2.13.0-1) ... 1457s Processing triggers for install-info (7.1.1-1) ... 1457s Processing triggers for initramfs-tools (0.147ubuntu1) ... 1457s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 1457s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1460s Using config file '/etc/zipl.conf' 1460s Building bootmap in '/boot' 1460s Adding IPL section 'ubuntu' (default) 1460s Preparing boot device for LD-IPL: vda (0000). 1460s Done. 1460s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1460s Reading package lists... 1460s Building dependency tree... 1460s Reading state information... 1460s Starting pkgProblemResolver with broken count: 0 1460s Starting 2 pkgProblemResolver with broken count: 0 1460s Done 1460s Solving dependencies... 1461s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1461s autopkgtest [09:20:13]: rebooting testbed after setup commands that affected boot 1482s Reading package lists... 1482s Building dependency tree... 1482s Reading state information... 1482s Starting pkgProblemResolver with broken count: 0 1482s Starting 2 pkgProblemResolver with broken count: 0 1482s Done 1482s The following NEW packages will be installed: 1482s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1482s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1482s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1482s openssh-server-gssapi 1482s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1482s Need to get 796 kB of archives. 1482s After this operation, 2750 kB of additional disk space will be used. 1482s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x krb5-config all 2.7 [22.0 kB] 1483s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x libgssrpc4t64 s390x 1.21.3-4ubuntu2 [59.1 kB] 1483s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x libkadm5clnt-mit12 s390x 1.21.3-4ubuntu2 [40.6 kB] 1483s Get:4 http://ftpmaster.internal/ubuntu questing/main s390x libkdb5-10t64 s390x 1.21.3-4ubuntu2 [42.0 kB] 1483s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x libkadm5srv-mit12 s390x 1.21.3-4ubuntu2 [55.4 kB] 1483s Get:6 http://ftpmaster.internal/ubuntu questing/universe s390x krb5-user s390x 1.21.3-4ubuntu2 [110 kB] 1483s Get:7 http://ftpmaster.internal/ubuntu questing/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 1483s Get:8 http://ftpmaster.internal/ubuntu questing/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 1483s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 1483s Get:10 http://ftpmaster.internal/ubuntu questing/universe s390x krb5-kdc s390x 1.21.3-4ubuntu2 [198 kB] 1483s Get:11 http://ftpmaster.internal/ubuntu questing/universe s390x krb5-admin-server s390x 1.21.3-4ubuntu2 [95.7 kB] 1483s Get:12 http://ftpmaster.internal/ubuntu questing/universe s390x openssh-client-gssapi all 1:9.9p1-3ubuntu3 [5022 B] 1483s Get:13 http://ftpmaster.internal/ubuntu questing/universe s390x openssh-server-gssapi all 1:9.9p1-3ubuntu3 [5026 B] 1484s Preconfiguring packages ... 1484s Fetched 796 kB in 1s (760 kB/s) 1484s Selecting previously unselected package krb5-config. 1484s (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 ... 81482 files and directories currently installed.) 1484s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1484s Unpacking krb5-config (2.7) ... 1484s Selecting previously unselected package libgssrpc4t64:s390x. 1484s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_s390x.deb ... 1484s Unpacking libgssrpc4t64:s390x (1.21.3-4ubuntu2) ... 1484s Selecting previously unselected package libkadm5clnt-mit12:s390x. 1484s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_s390x.deb ... 1484s Unpacking libkadm5clnt-mit12:s390x (1.21.3-4ubuntu2) ... 1484s Selecting previously unselected package libkdb5-10t64:s390x. 1484s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_s390x.deb ... 1484s Unpacking libkdb5-10t64:s390x (1.21.3-4ubuntu2) ... 1484s Selecting previously unselected package libkadm5srv-mit12:s390x. 1484s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_s390x.deb ... 1484s Unpacking libkadm5srv-mit12:s390x (1.21.3-4ubuntu2) ... 1484s Selecting previously unselected package krb5-user. 1484s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_s390x.deb ... 1484s Unpacking krb5-user (1.21.3-4ubuntu2) ... 1484s Selecting previously unselected package libevent-2.1-7t64:s390x. 1484s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 1484s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1484s Selecting previously unselected package libverto1t64:s390x. 1484s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1484s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1484s Selecting previously unselected package libverto-libevent1t64:s390x. 1484s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1484s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1484s Selecting previously unselected package krb5-kdc. 1484s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_s390x.deb ... 1484s Unpacking krb5-kdc (1.21.3-4ubuntu2) ... 1484s Selecting previously unselected package krb5-admin-server. 1484s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_s390x.deb ... 1484s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ... 1484s Selecting previously unselected package openssh-client-gssapi. 1484s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 1484s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 1484s Selecting previously unselected package openssh-server-gssapi. 1484s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 1484s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 1484s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1484s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 1484s Setting up libgssrpc4t64:s390x (1.21.3-4ubuntu2) ... 1484s Setting up krb5-config (2.7) ... 1484s Setting up libkadm5clnt-mit12:s390x (1.21.3-4ubuntu2) ... 1484s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 1484s Setting up libkdb5-10t64:s390x (1.21.3-4ubuntu2) ... 1484s Setting up libkadm5srv-mit12:s390x (1.21.3-4ubuntu2) ... 1484s Setting up krb5-user (1.21.3-4ubuntu2) ... 1484s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1484s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1484s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1484s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1484s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1484s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1484s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1484s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1484s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1484s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1484s Setting up krb5-kdc (1.21.3-4ubuntu2) ... 1484s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1485s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1485s Setting up krb5-admin-server (1.21.3-4ubuntu2) ... 1485s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1485s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1485s Processing triggers for man-db (2.13.0-1) ... 1491s autopkgtest [09:20:43]: test ssh-gssapi: [----------------------- 1492s Generating public/private ed25519 key pair. 1492s Your identification has been saved in /root/.ssh/id_ed25519 1492s Your public key has been saved in /root/.ssh/id_ed25519.pub 1492s The key fingerprint is: 1492s SHA256:7guo22MH/d/OQtKIEg4GuDAFSCIU8KPw6GUCbIoSuxw root@autopkgtest 1492s The key's randomart image is: 1492s +--[ED25519 256]--+ 1492s |@*o | 1492s |X. | 1492s |=*o | 1492s |B*o.. | 1492s |BE.= o .So | 1492s |+.= +.o.o o | 1492s |.o .o...o | 1492s | oo .o. .o | 1492s | oo.o oo.o+ | 1492s +----[SHA256]-----+ 1492s ## Setting up test environment 1492s ## Creating Kerberos realm EXAMPLE.FAKE 1492s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1492s master key name 'K/M@EXAMPLE.FAKE' 1492s ## Creating principals 1492s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1492s Principal "testuser1651@EXAMPLE.FAKE" created. 1492s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1492s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1492s ## Extracting service principal host/sshd-gssapi.example.fake 1492s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1492s Entry for principal host/sshd-gssapi.example.fake with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/etc/krb5.keytab. 1492s Entry for principal host/sshd-gssapi.example.fake with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/etc/krb5.keytab. 1492s ## Adjusting /etc/krb5.conf 1492s ## TESTS 1492s 1492s ## TEST test_gssapi_login 1492s ## Configuring sshd for gssapi-with-mic authentication 1492s ## Restarting ssh 1492s ## Obtaining TGT 1492s Password for testuser1651@EXAMPLE.FAKE: 1492s Ticket cache: FILE:/tmp/krb5cc_0 1492s Default principal: testuser1651@EXAMPLE.FAKE 1492s 1492s Valid starting Expires Service principal 1492s 05/02/25 09:22:09 05/02/25 19:22:09 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1492s renew until 05/03/25 09:22:09 1492s 1492s ## ssh'ing into localhost using gssapi-with-mic auth 1492s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1492s Fri May 2 09:22:10 UTC 2025 1492s 1492s ## checking that we got a service ticket for ssh (host/) 1492s 05/02/25 09:22:09 05/02/25 19:22:09 host/sshd-gssapi.example.fake@ 1492s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1492s 1492s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1492s May 02 09:22:09 sshd-gssapi.example.fake sshd-session[1754]: Accepted gssapi-with-mic for testuser1651 from 127.0.0.1 port 46548 ssh2: testuser1651@EXAMPLE.FAKE 1492s ## PASS test_gssapi_login 1492s 1492s ## TEST test_gssapi_keyex_login 1492s ## Configuring sshd for gssapi-keyex authentication 1492s ## Restarting ssh 1492s ## Obtaining TGT 1492s Password for testuser1651@EXAMPLE.FAKE: 1492s Ticket cache: FILE:/tmp/krb5cc_0 1492s Default principal: testuser1651@EXAMPLE.FAKE 1492s 1492s Valid starting Expires Service principal 1492s 05/02/25 09:22:10 05/02/25 19:22:10 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1492s renew until 05/03/25 09:22:10 1492s 1492s ## ssh'ing into localhost using gssapi-keyex auth 1493s Fri May 2 09:22:10 UTC 2025 1493s 1493s ## checking that we got a service ticket for ssh (host/) 1493s 05/02/25 09:22:10 05/02/25 19:22:10 host/sshd-gssapi.example.fake@ 1493s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1493s 1493s ## Checking ssh logs to confirm gssapi-keyex auth was used 1493s May 02 09:22:10 sshd-gssapi.example.fake sshd-session[1809]: Accepted gssapi-keyex for testuser1651 from 127.0.0.1 port 46562 ssh2: testuser1651@EXAMPLE.FAKE 1493s ## PASS test_gssapi_keyex_login 1493s 1493s ## TEST test_gssapi_keyex_pubkey_fallback 1493s ## Configuring sshd for gssapi-keyex authentication 1493s ## Restarting ssh 1493s ## Obtaining TGT 1493s Password for testuser1651@EXAMPLE.FAKE: 1493s Ticket cache: FILE:/tmp/krb5cc_0 1493s Default principal: testuser1651@EXAMPLE.FAKE 1493s 1493s Valid starting Expires Service principal 1493s 05/02/25 09:22:10 05/02/25 19:22:10 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1493s renew until 05/03/25 09:22:10 1493s 1493s ## ssh'ing into localhost using gssapi-keyex auth 1493s Fri May 2 09:22:10 UTC 2025 1493s 1493s ## checking that we got a service ticket for ssh (host/) 1493s 05/02/25 09:22:10 05/02/25 19:22:10 host/sshd-gssapi.example.fake@ 1493s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1493s 1493s ## Checking ssh logs to confirm publickey auth was used 1493s May 02 09:22:10 sshd-gssapi.example.fake sshd-session[1850]: Accepted publickey for testuser1651-2 from 127.0.0.1 port 44644 ssh2: ED25519 SHA256:7guo22MH/d/OQtKIEg4GuDAFSCIU8KPw6GUCbIoSuxw 1493s ## PASS test_gssapi_keyex_pubkey_fallback 1493s 1493s ## ALL TESTS PASSED 1493s ## Cleaning up 1493s autopkgtest [09:20:45]: test ssh-gssapi: -----------------------] 1494s autopkgtest [09:20:46]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1494s ssh-gssapi PASS 1494s autopkgtest [09:20:46]: test socket-activation: preparing testbed 1623s autopkgtest [09:22:55]: testbed dpkg architecture: s390x 1623s autopkgtest [09:22:55]: testbed apt version: 3.0.0 1623s autopkgtest [09:22:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1623s autopkgtest [09:22:55]: testbed release detected to be: questing 1624s autopkgtest [09:22:56]: updating testbed package index (apt update) 1624s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1625s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1625s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1625s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1625s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.4 kB] 1625s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [175 kB] 1625s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2076 kB] 1625s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [208 kB] 1625s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [1079 kB] 1625s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [9148 B] 1626s Fetched 3701 kB in 1s (3067 kB/s) 1626s Reading package lists... 1627s autopkgtest [09:22:59]: upgrading testbed (apt dist-upgrade and autopurge) 1627s Reading package lists... 1627s Building dependency tree... 1627s Reading state information... 1627s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1627s Starting 2 pkgProblemResolver with broken count: 0 1627s Done 1628s Entering ResolveByKeep 1628s 1628s Calculating upgrade... 1628s The following NEW packages will be installed: 1628s libfuse3-4 1628s The following packages will be upgraded: 1628s e2fsprogs e2fsprogs-l10n fuse3 htop libcom-err2 libext2fs2t64 libss2 logsave 1628s nano 1628s 9 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1628s Need to get 1544 kB of archives. 1628s After this operation, 321 kB of additional disk space will be used. 1628s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x logsave s390x 1.47.2-1ubuntu2 [26.7 kB] 1628s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs-l10n all 1.47.2-1ubuntu2 [7036 B] 1628s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libext2fs2t64 s390x 1.47.2-1ubuntu2 [236 kB] 1628s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs s390x 1.47.2-1ubuntu2 [624 kB] 1629s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libcom-err2 s390x 1.47.2-1ubuntu2 [26.9 kB] 1629s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libss2 s390x 1.47.2-1ubuntu2 [18.0 kB] 1629s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libfuse3-4 s390x 3.17.1+git250416-1 [89.1 kB] 1629s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x fuse3 s390x 3.17.1+git250416-1 [28.4 kB] 1629s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x nano s390x 8.4-1 [299 kB] 1629s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x htop s390x 3.4.1-4 [189 kB] 1629s Fetched 1544 kB in 1s (2359 kB/s) 1629s (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 ... 81472 files and directories currently installed.) 1629s Preparing to unpack .../logsave_1.47.2-1ubuntu2_s390x.deb ... 1629s Unpacking logsave (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1629s Preparing to unpack .../e2fsprogs-l10n_1.47.2-1ubuntu2_all.deb ... 1629s Unpacking e2fsprogs-l10n (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1629s Preparing to unpack .../libext2fs2t64_1.47.2-1ubuntu2_s390x.deb ... 1629s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2 to /lib/s390x-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 1629s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2.3 to /lib/s390x-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 1629s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2 to /lib/s390x-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 1629s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2.4 to /lib/s390x-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 1629s Unpacking libext2fs2t64:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1629s Setting up libext2fs2t64:s390x (1.47.2-1ubuntu2) ... 1629s (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 ... 81472 files and directories currently installed.) 1629s Preparing to unpack .../0-e2fsprogs_1.47.2-1ubuntu2_s390x.deb ... 1629s Unpacking e2fsprogs (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1629s Preparing to unpack .../1-libcom-err2_1.47.2-1ubuntu2_s390x.deb ... 1629s Unpacking libcom-err2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1629s Preparing to unpack .../2-libss2_1.47.2-1ubuntu2_s390x.deb ... 1629s Unpacking libss2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1629s Selecting previously unselected package libfuse3-4:s390x. 1629s Preparing to unpack .../3-libfuse3-4_3.17.1+git250416-1_s390x.deb ... 1629s Unpacking libfuse3-4:s390x (3.17.1+git250416-1) ... 1629s Preparing to unpack .../4-fuse3_3.17.1+git250416-1_s390x.deb ... 1629s Adding 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 1629s Adding 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 1629s Unpacking fuse3 (3.17.1+git250416-1) over (3.14.0-10) ... 1629s Preparing to unpack .../5-nano_8.4-1_s390x.deb ... 1629s Unpacking nano (8.4-1) over (8.3-1) ... 1629s Preparing to unpack .../6-htop_3.4.1-4_s390x.deb ... 1629s Unpacking htop (3.4.1-4) over (3.4.0-2) ... 1629s Setting up htop (3.4.1-4) ... 1629s Setting up libcom-err2:s390x (1.47.2-1ubuntu2) ... 1629s Setting up libss2:s390x (1.47.2-1ubuntu2) ... 1629s Setting up logsave (1.47.2-1ubuntu2) ... 1629s Setting up nano (8.4-1) ... 1629s Installing new version of config file /etc/nanorc ... 1629s Setting up libfuse3-4:s390x (3.17.1+git250416-1) ... 1629s Setting up e2fsprogs (1.47.2-1ubuntu2) ... 1629s update-initramfs: deferring update (trigger activated) 1630s Setting up fuse3 (3.17.1+git250416-1) ... 1630s Installing new version of config file /etc/fuse.conf ... 1630s Removing 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 1630s Removing 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 1630s update-initramfs: deferring update (trigger activated) 1630s Setting up e2fsprogs-l10n (1.47.2-1ubuntu2) ... 1630s Processing triggers for man-db (2.13.0-1) ... 1631s Processing triggers for install-info (7.1.1-1) ... 1631s Processing triggers for initramfs-tools (0.147ubuntu1) ... 1631s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 1631s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1634s Using config file '/etc/zipl.conf' 1634s Building bootmap in '/boot' 1634s Adding IPL section 'ubuntu' (default) 1634s Preparing boot device for LD-IPL: vda (0000). 1634s Done. 1634s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1634s Reading package lists... 1635s Building dependency tree... 1635s Reading state information... 1635s Starting pkgProblemResolver with broken count: 0 1635s Starting 2 pkgProblemResolver with broken count: 0 1635s Done 1635s Solving dependencies... 1635s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1635s autopkgtest [09:23:07]: rebooting testbed after setup commands that affected boot 1639s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 1659s Reading package lists... 1659s Building dependency tree... 1659s Reading state information... 1659s Starting pkgProblemResolver with broken count: 0 1660s Starting 2 pkgProblemResolver with broken count: 0 1660s Done 1660s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1663s autopkgtest [09:23:35]: test socket-activation: [----------------------- 1663s Generating public/private ed25519 key pair. 1663s Your identification has been saved in /home/testuser1234/.ssh/id_ed25519 1663s Your public key has been saved in /home/testuser1234/.ssh/id_ed25519.pub 1663s The key fingerprint is: 1663s SHA256:HLDNeStT4PteDKDmX/6FQY0Xi3o5bapCW8lcxu/RIls testuser1234@autopkgtest 1663s The key's randomart image is: 1663s +--[ED25519 256]--+ 1663s | . . . | 1663s | * o + o | 1663s | . B o.+ + | 1663s | o * +++ | 1663s | o So++=.o. | 1663s | o .+=+.*E .| 1663s | .. oo ==.o | 1663s | .o+ o... | 1663s | ..+.. | 1663s +----[SHA256]-----+ 1663s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1663s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1664s Disabling 'ssh.service', but its triggering units are still active: 1664s ssh.socket 1664s Stopping 'ssh.service', but its triggering units are still active: 1664s ssh.socket 1664s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1665s Fri May 2 09:25:02 UTC 2025 1665s autopkgtest [09:23:37]: test socket-activation: -----------------------] 1665s socket-activation PASS 1665s autopkgtest [09:23:37]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1666s autopkgtest [09:23:38]: test xinetd: preparing testbed 1666s Reading package lists... 1666s Building dependency tree... 1666s Reading state information... 1666s Starting pkgProblemResolver with broken count: 0 1666s Starting 2 pkgProblemResolver with broken count: 0 1666s Done 1667s The following NEW packages will be installed: 1667s xinetd 1667s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1667s Need to get 118 kB of archives. 1667s After this operation, 316 kB of additional disk space will be used. 1667s Get:1 http://ftpmaster.internal/ubuntu questing/universe s390x xinetd s390x 1:2.3.15.4-5 [118 kB] 1667s Fetched 118 kB in 0s (340 kB/s) 1667s Selecting previously unselected package xinetd. 1667s (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 ... 81482 files and directories currently installed.) 1667s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_s390x.deb ... 1667s Unpacking xinetd (1:2.3.15.4-5) ... 1667s Setting up xinetd (1:2.3.15.4-5) ... 1668s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1668s Processing triggers for man-db (2.13.0-1) ... 1670s autopkgtest [09:23:42]: test xinetd: [----------------------- 1670s Generating public/private ed25519 key pair. 1670s Your identification has been saved in /home/testuser1771/.ssh/id_ed25519 1670s Your public key has been saved in /home/testuser1771/.ssh/id_ed25519.pub 1670s The key fingerprint is: 1670s SHA256:ItMsH1A7ZKefmhrQT72kcYZHheoHdV06rbXRsp+9FSU testuser1771@autopkgtest 1670s The key's randomart image is: 1670s +--[ED25519 256]--+ 1670s | + .... .. | 1670s | + +o.. .o . | 1670s | . +o.. o E o| 1670s | . +o* . + *.| 1670s | . =.O.S . o. | 1670s | . B.%.. .+| 1670s | . *.. .+| 1670s | o o| 1670s | . . | 1670s +----[SHA256]-----+ 1670s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1670s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1671s Disabling 'ssh.service', but its triggering units are still active: 1671s ssh.socket 1671s Stopping 'ssh.service', but its triggering units are still active: 1671s ssh.socket 1671s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1671s Fri May 2 09:25:08 UTC 2025 1671s autopkgtest [09:23:43]: test xinetd: -----------------------] 1672s xinetd PASS 1672s autopkgtest [09:23:44]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1673s autopkgtest [09:23:45]: test systemd-socket-activation: preparing testbed 1812s autopkgtest [09:26:04]: testbed dpkg architecture: s390x 1812s autopkgtest [09:26:04]: testbed apt version: 3.0.0 1813s autopkgtest [09:26:05]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1813s autopkgtest [09:26:05]: testbed release detected to be: questing 1814s autopkgtest [09:26:06]: updating testbed package index (apt update) 1814s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1814s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1814s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1814s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1814s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.4 kB] 1814s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2076 kB] 1815s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [175 kB] 1815s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [208 kB] 1815s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [1079 kB] 1815s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [9148 B] 1815s Fetched 3701 kB in 1s (2580 kB/s) 1816s Reading package lists... 1817s autopkgtest [09:26:09]: upgrading testbed (apt dist-upgrade and autopurge) 1817s Reading package lists... 1817s Building dependency tree... 1817s Reading state information... 1817s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1817s Starting 2 pkgProblemResolver with broken count: 0 1817s Done 1818s Entering ResolveByKeep 1818s 1818s Calculating upgrade... 1818s The following NEW packages will be installed: 1818s libfuse3-4 1818s The following packages will be upgraded: 1818s e2fsprogs e2fsprogs-l10n fuse3 htop libcom-err2 libext2fs2t64 libss2 logsave 1818s nano 1818s 9 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1818s Need to get 1544 kB of archives. 1818s After this operation, 321 kB of additional disk space will be used. 1818s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x logsave s390x 1.47.2-1ubuntu2 [26.7 kB] 1818s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs-l10n all 1.47.2-1ubuntu2 [7036 B] 1818s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libext2fs2t64 s390x 1.47.2-1ubuntu2 [236 kB] 1818s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs s390x 1.47.2-1ubuntu2 [624 kB] 1819s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libcom-err2 s390x 1.47.2-1ubuntu2 [26.9 kB] 1819s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libss2 s390x 1.47.2-1ubuntu2 [18.0 kB] 1819s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libfuse3-4 s390x 3.17.1+git250416-1 [89.1 kB] 1819s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x fuse3 s390x 3.17.1+git250416-1 [28.4 kB] 1819s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x nano s390x 8.4-1 [299 kB] 1819s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x htop s390x 3.4.1-4 [189 kB] 1819s Fetched 1544 kB in 1s (2110 kB/s) 1819s (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 ... 81472 files and directories currently installed.) 1819s Preparing to unpack .../logsave_1.47.2-1ubuntu2_s390x.deb ... 1819s Unpacking logsave (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1819s Preparing to unpack .../e2fsprogs-l10n_1.47.2-1ubuntu2_all.deb ... 1819s Unpacking e2fsprogs-l10n (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1819s Preparing to unpack .../libext2fs2t64_1.47.2-1ubuntu2_s390x.deb ... 1819s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2 to /lib/s390x-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 1819s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2.3 to /lib/s390x-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 1819s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2 to /lib/s390x-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 1819s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2.4 to /lib/s390x-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 1819s Unpacking libext2fs2t64:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1819s Setting up libext2fs2t64:s390x (1.47.2-1ubuntu2) ... 1820s (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 ... 81472 files and directories currently installed.) 1820s Preparing to unpack .../0-e2fsprogs_1.47.2-1ubuntu2_s390x.deb ... 1820s Unpacking e2fsprogs (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1820s Preparing to unpack .../1-libcom-err2_1.47.2-1ubuntu2_s390x.deb ... 1820s Unpacking libcom-err2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1820s Preparing to unpack .../2-libss2_1.47.2-1ubuntu2_s390x.deb ... 1820s Unpacking libss2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 1820s Selecting previously unselected package libfuse3-4:s390x. 1820s Preparing to unpack .../3-libfuse3-4_3.17.1+git250416-1_s390x.deb ... 1820s Unpacking libfuse3-4:s390x (3.17.1+git250416-1) ... 1820s Preparing to unpack .../4-fuse3_3.17.1+git250416-1_s390x.deb ... 1820s Adding 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 1820s Adding 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 1820s Unpacking fuse3 (3.17.1+git250416-1) over (3.14.0-10) ... 1820s Preparing to unpack .../5-nano_8.4-1_s390x.deb ... 1820s Unpacking nano (8.4-1) over (8.3-1) ... 1820s Preparing to unpack .../6-htop_3.4.1-4_s390x.deb ... 1820s Unpacking htop (3.4.1-4) over (3.4.0-2) ... 1820s Setting up htop (3.4.1-4) ... 1820s Setting up libcom-err2:s390x (1.47.2-1ubuntu2) ... 1820s Setting up libss2:s390x (1.47.2-1ubuntu2) ... 1820s Setting up logsave (1.47.2-1ubuntu2) ... 1820s Setting up nano (8.4-1) ... 1820s Installing new version of config file /etc/nanorc ... 1820s Setting up libfuse3-4:s390x (3.17.1+git250416-1) ... 1820s Setting up e2fsprogs (1.47.2-1ubuntu2) ... 1820s update-initramfs: deferring update (trigger activated) 1820s Setting up fuse3 (3.17.1+git250416-1) ... 1820s Installing new version of config file /etc/fuse.conf ... 1820s Removing 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 1820s Removing 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 1820s update-initramfs: deferring update (trigger activated) 1820s Setting up e2fsprogs-l10n (1.47.2-1ubuntu2) ... 1820s Processing triggers for man-db (2.13.0-1) ... 1822s Processing triggers for install-info (7.1.1-1) ... 1822s Processing triggers for initramfs-tools (0.147ubuntu1) ... 1822s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 1822s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1825s Using config file '/etc/zipl.conf' 1825s Building bootmap in '/boot' 1825s Adding IPL section 'ubuntu' (default) 1825s Preparing boot device for LD-IPL: vda (0000). 1825s Done. 1825s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1826s Reading package lists... 1826s Building dependency tree... 1826s Reading state information... 1826s Starting pkgProblemResolver with broken count: 0 1826s Starting 2 pkgProblemResolver with broken count: 0 1826s Done 1826s Solving dependencies... 1827s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1827s autopkgtest [09:26:19]: rebooting testbed after setup commands that affected boot 1859s Reading package lists... 1859s Building dependency tree... 1859s Reading state information... 1860s Starting pkgProblemResolver with broken count: 0 1860s Starting 2 pkgProblemResolver with broken count: 0 1860s Done 1860s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1863s autopkgtest [09:26:55]: test systemd-socket-activation: [----------------------- 1864s Stopping ssh.service... 1864s Checking that ssh.socket is active and listening... 1864s Checking that ssh.service is inactive/dead... 1864s Checking that a connection attempt activates ssh.service... 1864s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1864s Checking that sshd can be re-executed... 1865s Checking sshd can run in debug mode... 1866s debug1: PAM: establishing credentials 1866s debug1: permanently_set_uid: 0/0 1866s debug3: Copy environment: XDG_SESSION_ID=5 1866s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1866s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1866s debug3: Copy environment: XDG_SESSION_TYPE=tty 1866s debug3: Copy environment: XDG_SESSION_CLASS=user 1866s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1866s debug3: Copy environment: http_proxy=http://squid.internal:3128/ 1866s debug3: Copy environment: https_proxy=http://squid.internal:3128/ 1866s debug3: Copy environment: no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net 1866s debug3: Copy environment: TERM=linux 1866s debug3: Copy environment: LANG=C.UTF-8 1866s Environment: 1866s LANG=C.UTF-8 1866s USER=root 1866s LOGNAME=root 1866s HOME=/root 1866s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1866s SHELL=/bin/bash 1866s XDG_SESSION_ID=5 1866s XDG_RUNTIME_DIR=/run/user/0 1866s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1866s XDG_SESSION_TYPE=tty 1866s XDG_SESSION_CLASS=user 1866s http_proxy=http://squid.internal:3128/ 1866s https_proxy=http://squid.internal:3128/ 1866s no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchp 1866s TERM=linux 1866s SSH_CLIENT=::1 50148 22 1866s SSH_CONNECTION=::1 50148 ::1 22 1866s Done. 1866s autopkgtest [09:26:58]: test systemd-socket-activation: -----------------------] 1867s autopkgtest [09:26:59]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1867s systemd-socket-activation PASS 1867s autopkgtest [09:26:59]: test sshd-socket-generator: preparing testbed 1867s Reading package lists... 1867s Building dependency tree... 1867s Reading state information... 1867s Starting pkgProblemResolver with broken count: 0 1867s Starting 2 pkgProblemResolver with broken count: 0 1867s Done 1868s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1869s autopkgtest [09:27:01]: test sshd-socket-generator: [----------------------- 1869s test_default...PASS 1869s test_custom_port...PASS 1869s test_default_and_custom_port...PASS 1869s test_mutiple_custom_ports...PASS 1869s test_custom_listenaddress...PASS 1869s test_custom_listenaddress_and_port...PASS 1869s test_custom_ipv6_listenaddress...PASS 1869s test_custom_family_ipv4...PASS 1869s test_custom_family_ipv6...PASS 1869s test_custom_port_and_family_ipv4...PASS 1869s test_custom_port_and_family_ipv6...PASS 1869s test_match_on_port...PASS 1869s autopkgtest [09:27:01]: test sshd-socket-generator: -----------------------] 1870s autopkgtest [09:27:02]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1870s sshd-socket-generator PASS 1870s autopkgtest [09:27:02]: @@@@@@@@@@@@@@@@@@@@ summary 1870s regress PASS 1870s ssh-gssapi PASS 1870s socket-activation PASS 1870s xinetd PASS 1870s systemd-socket-activation PASS 1870s sshd-socket-generator PASS 1887s nova [W] Using flock in prodstack6-s390x 1887s Creating nova instance adt-questing-s390x-openssh-20250502-085552-juju-7f2275-prod-proposed-migration-environment-15-6122a9b6-811f-4ea9-9e2e-5df623886990 from image adt/ubuntu-questing-s390x-server-20250502.img (UUID 632ecfca-a19d-4f3a-af48-b7883fa0ccf2)... 1887s nova [W] Timed out waiting for 56dcbeec-d837-449f-a40e-4f3c068cb469 to get deleted. 1887s nova [W] Using flock in prodstack6-s390x 1887s Creating nova instance adt-questing-s390x-openssh-20250502-085552-juju-7f2275-prod-proposed-migration-environment-15-6122a9b6-811f-4ea9-9e2e-5df623886990 from image adt/ubuntu-questing-s390x-server-20250502.img (UUID 632ecfca-a19d-4f3a-af48-b7883fa0ccf2)... 1887s nova [W] Timed out waiting for ed196016-43b2-4e2c-95c5-909d71f655a0 to get deleted. 1887s nova [W] Using flock in prodstack6-s390x 1887s Creating nova instance adt-questing-s390x-openssh-20250502-085552-juju-7f2275-prod-proposed-migration-environment-15-6122a9b6-811f-4ea9-9e2e-5df623886990 from image adt/ubuntu-questing-s390x-server-20250502.img (UUID 632ecfca-a19d-4f3a-af48-b7883fa0ccf2)... 1887s nova [W] Timed out waiting for e8977583-d00a-4265-9923-8542739e6b24 to get deleted. 1887s nova [W] Using flock in prodstack6-s390x 1887s Creating nova instance adt-questing-s390x-openssh-20250502-085552-juju-7f2275-prod-proposed-migration-environment-15-6122a9b6-811f-4ea9-9e2e-5df623886990 from image adt/ubuntu-questing-s390x-server-20250502.img (UUID 632ecfca-a19d-4f3a-af48-b7883fa0ccf2)... 1887s nova [W] Timed out waiting for 279b4a1d-7029-484f-96c2-f96d67b6369e to get deleted.