0s autopkgtest [14:11:34]: starting date and time: 2025-05-05 14:11:34+0000 0s autopkgtest [14:11:34]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [14:11:34]: host juju-7f2275-prod-proposed-migration-environment-23; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ksbr7nnv/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ucf,src:dropbear --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ucf/3.0051 dropbear/2025.87-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-23@bos03-s390x-17.secgroup --name adt-questing-s390x-openssh-20250505-141133-juju-7f2275-prod-proposed-migration-environment-23-c0991584-a1b5-4104-ad18-32c88ea04b3b --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-23 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 88s autopkgtest [14:13:02]: testbed dpkg architecture: s390x 88s autopkgtest [14:13:02]: testbed apt version: 3.0.0 88s autopkgtest [14:13:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 89s autopkgtest [14:13:03]: testbed release detected to be: None 89s autopkgtest [14:13:03]: updating testbed package index (apt update) 90s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 90s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 90s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 90s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 90s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 90s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 90s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 90s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [116 kB] 90s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [888 kB] 91s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [17.0 kB] 91s Fetched 2163 kB in 1s (2007 kB/s) 91s Reading package lists... 92s autopkgtest [14:13:06]: upgrading testbed (apt dist-upgrade and autopurge) 92s Reading package lists... 92s Building dependency tree... 92s Reading state information... 92s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 92s Starting 2 pkgProblemResolver with broken count: 0 92s Done 93s Entering ResolveByKeep 93s 93s Calculating upgrade... 93s The following packages will be upgraded: 93s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 ucf 93s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 93s Need to get 5438 kB of archives. 93s After this operation, 259 kB disk space will be freed. 93s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x ucf all 3.0051 [43.6 kB] 93s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12t64 s390x 3.12.10-1 [2509 kB] 94s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-stdlib s390x 3.12.10-1 [2049 kB] 94s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-minimal s390x 3.12.10-1 [836 kB] 94s Preconfiguring packages ... 94s Fetched 5438 kB in 1s (6271 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 ... 81479 files and directories currently installed.) 94s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 94s Unpacking ucf (3.0051) over (3.0050) ... 94s Preparing to unpack .../libpython3.12t64_3.12.10-1_s390x.deb ... 94s Unpacking libpython3.12t64:s390x (3.12.10-1) over (3.12.8-3) ... 94s Preparing to unpack .../libpython3.12-stdlib_3.12.10-1_s390x.deb ... 94s Unpacking libpython3.12-stdlib:s390x (3.12.10-1) over (3.12.8-3) ... 94s Preparing to unpack .../libpython3.12-minimal_3.12.10-1_s390x.deb ... 94s Unpacking libpython3.12-minimal:s390x (3.12.10-1) over (3.12.8-3) ... 94s Setting up libpython3.12-minimal:s390x (3.12.10-1) ... 94s Setting up ucf (3.0051) ... 94s Setting up libpython3.12-stdlib:s390x (3.12.10-1) ... 94s Setting up libpython3.12t64:s390x (3.12.10-1) ... 94s Processing triggers for man-db (2.13.1-1) ... 95s Processing triggers for libc-bin (2.41-6ubuntu1) ... 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 96s Starting pkgProblemResolver with broken count: 0 96s Starting 2 pkgProblemResolver with broken count: 0 96s Done 96s Solving dependencies... 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 98s autopkgtest [14:13:12]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 99s autopkgtest [14:13:13]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 104s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (dsc) [3480 B] 104s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (tar) [1965 kB] 104s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (asc) [833 B] 104s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (diff) [212 kB] 104s gpgv: Signature made Tue Feb 18 14:21:37 2025 UTC 104s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 104s gpgv: Can't check signature: No public key 104s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.dsc: no acceptable signature found 104s autopkgtest [14:13:18]: testing package openssh version 1:9.9p1-3ubuntu3 106s autopkgtest [14:13:20]: build not needed 111s autopkgtest [14:13:25]: test regress: preparing testbed 111s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Starting pkgProblemResolver with broken count: 0 112s Starting 2 pkgProblemResolver with broken count: 0 112s Done 112s The following NEW packages will be installed: 112s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 112s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 112s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 112s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 112s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 112s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 112s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 112s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 112s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 112s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 112s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 112s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 112s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 112s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 112s python3-packaging python3-pyasn1 python3-pyasn1-modules 112s python3-service-identity python3-twisted python3-zope.interface wdiff 112s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 112s Need to get 8383 kB of archives. 112s After this operation, 38.3 MB of additional disk space will be used. 112s Get:1 http://ftpmaster.internal/ubuntu questing/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 112s Get:2 http://ftpmaster.internal/ubuntu questing/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 113s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x dropbear-bin s390x 2025.87-1 [185 kB] 113s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x dropbear all 2025.87-1 [8668 B] 113s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 113s Get:6 http://ftpmaster.internal/ubuntu questing/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 113s Get:7 http://ftpmaster.internal/ubuntu questing/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 113s Get:8 http://ftpmaster.internal/ubuntu questing/main s390x libfile-touch-perl all 0.12-2 [7498 B] 113s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 113s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 113s Get:11 http://ftpmaster.internal/ubuntu questing/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 113s Get:12 http://ftpmaster.internal/ubuntu questing/main s390x libclass-xsaccessor-perl s390x 1.19-4build6 [34.2 kB] 113s Get:13 http://ftpmaster.internal/ubuntu questing/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 113s Get:14 http://ftpmaster.internal/ubuntu questing/main s390x libdynaloader-functions-perl all 0.004-2 [11.5 kB] 113s Get:15 http://ftpmaster.internal/ubuntu questing/main s390x libdevel-callchecker-perl s390x 0.009-1build1 [14.2 kB] 113s Get:16 http://ftpmaster.internal/ubuntu questing/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 113s Get:17 http://ftpmaster.internal/ubuntu questing/main s390x libmodule-runtime-perl all 0.018-1 [15.2 kB] 113s Get:18 http://ftpmaster.internal/ubuntu questing/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 113s Get:19 http://ftpmaster.internal/ubuntu questing/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 113s Get:20 http://ftpmaster.internal/ubuntu questing/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 113s Get:21 http://ftpmaster.internal/ubuntu questing/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 113s Get:22 http://ftpmaster.internal/ubuntu questing/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 113s Get:23 http://ftpmaster.internal/ubuntu questing/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 113s Get:24 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 113s Get:25 http://ftpmaster.internal/ubuntu questing/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 113s Get:26 http://ftpmaster.internal/ubuntu questing/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 113s Get:27 http://ftpmaster.internal/ubuntu questing/main s390x liburi-perl all 5.30-1 [94.4 kB] 113s Get:28 http://ftpmaster.internal/ubuntu questing/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 113s Get:29 http://ftpmaster.internal/ubuntu questing/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 113s Get:30 http://ftpmaster.internal/ubuntu questing/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 113s Get:31 http://ftpmaster.internal/ubuntu questing/main s390x libio-html-perl all 1.004-3 [15.9 kB] 113s Get:32 http://ftpmaster.internal/ubuntu questing/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 113s Get:33 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 113s Get:34 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 113s Get:35 http://ftpmaster.internal/ubuntu questing/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 113s Get:36 http://ftpmaster.internal/ubuntu questing/main s390x perl-openssl-defaults s390x 7build3 [6628 B] 113s Get:37 http://ftpmaster.internal/ubuntu questing/main s390x libnet-ssleay-perl s390x 1.94-3 [320 kB] 113s Get:38 http://ftpmaster.internal/ubuntu questing/main s390x libio-socket-ssl-perl all 2.089-1 [200 kB] 113s Get:39 http://ftpmaster.internal/ubuntu questing/main s390x libnet-http-perl all 6.23-1 [22.3 kB] 113s Get:40 http://ftpmaster.internal/ubuntu questing/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 113s Get:41 http://ftpmaster.internal/ubuntu questing/main s390x libtry-tiny-perl all 0.32-1 [21.2 kB] 113s Get:42 http://ftpmaster.internal/ubuntu questing/main s390x libwww-robotrules-perl all 6.02-1 [12.6 kB] 113s Get:43 http://ftpmaster.internal/ubuntu questing/main s390x libwww-perl all 6.78-1 [139 kB] 113s Get:44 http://ftpmaster.internal/ubuntu questing/main s390x patchutils s390x 0.4.2-1build3 [79.2 kB] 113s Get:45 http://ftpmaster.internal/ubuntu questing/main s390x wdiff s390x 1.2.2-9 [29.7 kB] 113s Get:46 http://ftpmaster.internal/ubuntu questing/main s390x devscripts all 2.25.5 [1059 kB] 113s Get:47 http://ftpmaster.internal/ubuntu questing/universe s390x libhavege2 s390x 1.9.19-11ubuntu1 [27.3 kB] 113s Get:48 http://ftpmaster.internal/ubuntu questing/universe s390x putty-tools s390x 0.83-2 [741 kB] 113s Get:49 http://ftpmaster.internal/ubuntu questing/main s390x python3-hamcrest all 2.1.0-1 [28.1 kB] 113s Get:50 http://ftpmaster.internal/ubuntu questing/main s390x python3-pyasn1 all 0.6.1-1 [56.4 kB] 113s Get:51 http://ftpmaster.internal/ubuntu questing/main s390x python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 113s Get:52 http://ftpmaster.internal/ubuntu questing/main s390x python3-service-identity all 24.2.0-1 [11.0 kB] 113s Get:53 http://ftpmaster.internal/ubuntu questing/main s390x python3-automat all 25.4.16-1 [35.7 kB] 113s Get:54 http://ftpmaster.internal/ubuntu questing/main s390x python3-constantly all 23.10.4-2 [13.9 kB] 113s Get:55 http://ftpmaster.internal/ubuntu questing/main s390x python3-hyperlink all 21.0.0-6 [68.0 kB] 113s Get:56 http://ftpmaster.internal/ubuntu questing/main s390x python3-packaging all 24.2-1 [51.5 kB] 113s Get:57 http://ftpmaster.internal/ubuntu questing/main s390x python3-incremental all 24.7.2-3 [18.6 kB] 113s Get:58 http://ftpmaster.internal/ubuntu questing/main s390x python3-zope.interface s390x 7.2-1build1 [141 kB] 113s Get:59 http://ftpmaster.internal/ubuntu questing/main s390x python3-twisted all 24.11.0-1 [2066 kB] 113s Get:60 http://ftpmaster.internal/ubuntu questing/universe s390x openssh-tests s390x 1:9.9p1-3ubuntu3 [1410 kB] 114s Get:61 http://ftpmaster.internal/ubuntu questing/universe s390x haveged s390x 1.9.19-11ubuntu1 [32.7 kB] 114s Fetched 8383 kB in 2s (5204 kB/s) 114s Selecting previously unselected package libtommath1:s390x. 114s (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 ... 81479 files and directories currently installed.) 114s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 114s Unpacking libtommath1:s390x (1.3.0-1) ... 114s Selecting previously unselected package libtomcrypt1:s390x. 114s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 114s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 114s Selecting previously unselected package dropbear-bin. 114s Preparing to unpack .../02-dropbear-bin_2025.87-1_s390x.deb ... 114s Unpacking dropbear-bin (2025.87-1) ... 114s Selecting previously unselected package dropbear. 114s Preparing to unpack .../03-dropbear_2025.87-1_all.deb ... 114s Unpacking dropbear (2025.87-1) ... 114s Selecting previously unselected package libfile-dirlist-perl. 114s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 114s Unpacking libfile-dirlist-perl (0.05-3) ... 114s Selecting previously unselected package libfile-which-perl. 114s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 114s Unpacking libfile-which-perl (1.27-2) ... 114s Selecting previously unselected package libfile-homedir-perl. 114s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 114s Unpacking libfile-homedir-perl (1.006-2) ... 114s Selecting previously unselected package libfile-touch-perl. 114s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 114s Unpacking libfile-touch-perl (0.12-2) ... 114s Selecting previously unselected package libio-pty-perl. 114s Preparing to unpack .../08-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 114s Unpacking libio-pty-perl (1:1.20-1build3) ... 114s Selecting previously unselected package libipc-run-perl. 114s Preparing to unpack .../09-libipc-run-perl_20231003.0-2_all.deb ... 114s Unpacking libipc-run-perl (20231003.0-2) ... 114s Selecting previously unselected package libclass-method-modifiers-perl. 114s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 114s Unpacking libclass-method-modifiers-perl (2.15-1) ... 114s Selecting previously unselected package libclass-xsaccessor-perl. 114s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_s390x.deb ... 114s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 114s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 114s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 114s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 114s Selecting previously unselected package libdynaloader-functions-perl. 114s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-2_all.deb ... 114s Unpacking libdynaloader-functions-perl (0.004-2) ... 114s Selecting previously unselected package libdevel-callchecker-perl:s390x. 114s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-1build1_s390x.deb ... 114s Unpacking libdevel-callchecker-perl:s390x (0.009-1build1) ... 114s Selecting previously unselected package libparams-classify-perl:s390x. 114s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_s390x.deb ... 114s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 114s Selecting previously unselected package libmodule-runtime-perl. 114s Preparing to unpack .../16-libmodule-runtime-perl_0.018-1_all.deb ... 114s Unpacking libmodule-runtime-perl (0.018-1) ... 114s Selecting previously unselected package libimport-into-perl. 114s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 114s Unpacking libimport-into-perl (1.002005-2) ... 114s Selecting previously unselected package librole-tiny-perl. 114s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 114s Unpacking librole-tiny-perl (2.002004-1) ... 114s Selecting previously unselected package libsub-quote-perl. 114s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 114s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 114s Selecting previously unselected package libmoo-perl. 114s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 114s Unpacking libmoo-perl (2.005005-1) ... 114s Selecting previously unselected package libencode-locale-perl. 114s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 114s Unpacking libencode-locale-perl (1.05-3) ... 114s Selecting previously unselected package libtimedate-perl. 114s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 114s Unpacking libtimedate-perl (2.3300-2) ... 114s Selecting previously unselected package libhttp-date-perl. 114s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 114s Unpacking libhttp-date-perl (6.06-1) ... 114s Selecting previously unselected package libfile-listing-perl. 114s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 114s Unpacking libfile-listing-perl (6.16-1) ... 114s Selecting previously unselected package libhtml-tagset-perl. 114s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 114s Unpacking libhtml-tagset-perl (3.24-1) ... 114s Selecting previously unselected package liburi-perl. 114s Preparing to unpack .../26-liburi-perl_5.30-1_all.deb ... 114s Unpacking liburi-perl (5.30-1) ... 114s Selecting previously unselected package libhtml-parser-perl:s390x. 114s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_s390x.deb ... 114s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 114s Selecting previously unselected package libhtml-tree-perl. 114s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 114s Unpacking libhtml-tree-perl (5.07-3) ... 114s Selecting previously unselected package libclone-perl:s390x. 114s Preparing to unpack .../29-libclone-perl_0.47-1_s390x.deb ... 114s Unpacking libclone-perl:s390x (0.47-1) ... 114s Selecting previously unselected package libio-html-perl. 114s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 114s Unpacking libio-html-perl (1.004-3) ... 114s Selecting previously unselected package liblwp-mediatypes-perl. 114s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 114s Unpacking liblwp-mediatypes-perl (6.04-2) ... 114s Selecting previously unselected package libhttp-message-perl. 114s Preparing to unpack .../32-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 114s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 114s Selecting previously unselected package libhttp-cookies-perl. 114s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 114s Unpacking libhttp-cookies-perl (6.11-1) ... 114s Selecting previously unselected package libhttp-negotiate-perl. 114s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 114s Unpacking libhttp-negotiate-perl (6.01-2) ... 114s Selecting previously unselected package perl-openssl-defaults:s390x. 114s Preparing to unpack .../35-perl-openssl-defaults_7build3_s390x.deb ... 114s Unpacking perl-openssl-defaults:s390x (7build3) ... 114s Selecting previously unselected package libnet-ssleay-perl:s390x. 114s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_s390x.deb ... 114s Unpacking libnet-ssleay-perl:s390x (1.94-3) ... 114s Selecting previously unselected package libio-socket-ssl-perl. 114s Preparing to unpack .../37-libio-socket-ssl-perl_2.089-1_all.deb ... 114s Unpacking libio-socket-ssl-perl (2.089-1) ... 114s Selecting previously unselected package libnet-http-perl. 114s Preparing to unpack .../38-libnet-http-perl_6.23-1_all.deb ... 114s Unpacking libnet-http-perl (6.23-1) ... 114s Selecting previously unselected package liblwp-protocol-https-perl. 114s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 114s Unpacking liblwp-protocol-https-perl (6.14-1) ... 114s Selecting previously unselected package libtry-tiny-perl. 114s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 114s Unpacking libtry-tiny-perl (0.32-1) ... 115s Selecting previously unselected package libwww-robotrules-perl. 115s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1_all.deb ... 115s Unpacking libwww-robotrules-perl (6.02-1) ... 115s Selecting previously unselected package libwww-perl. 115s Preparing to unpack .../42-libwww-perl_6.78-1_all.deb ... 115s Unpacking libwww-perl (6.78-1) ... 115s Selecting previously unselected package patchutils. 115s Preparing to unpack .../43-patchutils_0.4.2-1build3_s390x.deb ... 115s Unpacking patchutils (0.4.2-1build3) ... 115s Selecting previously unselected package wdiff. 115s Preparing to unpack .../44-wdiff_1.2.2-9_s390x.deb ... 115s Unpacking wdiff (1.2.2-9) ... 115s Selecting previously unselected package devscripts. 115s Preparing to unpack .../45-devscripts_2.25.5_all.deb ... 115s Unpacking devscripts (2.25.5) ... 115s Selecting previously unselected package libhavege2:s390x. 115s Preparing to unpack .../46-libhavege2_1.9.19-11ubuntu1_s390x.deb ... 115s Unpacking libhavege2:s390x (1.9.19-11ubuntu1) ... 115s Selecting previously unselected package putty-tools. 115s Preparing to unpack .../47-putty-tools_0.83-2_s390x.deb ... 115s Unpacking putty-tools (0.83-2) ... 115s Selecting previously unselected package python3-hamcrest. 115s Preparing to unpack .../48-python3-hamcrest_2.1.0-1_all.deb ... 115s Unpacking python3-hamcrest (2.1.0-1) ... 115s Selecting previously unselected package python3-pyasn1. 115s Preparing to unpack .../49-python3-pyasn1_0.6.1-1_all.deb ... 115s Unpacking python3-pyasn1 (0.6.1-1) ... 115s Selecting previously unselected package python3-pyasn1-modules. 115s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-2_all.deb ... 115s Unpacking python3-pyasn1-modules (0.4.1-2) ... 115s Selecting previously unselected package python3-service-identity. 115s Preparing to unpack .../51-python3-service-identity_24.2.0-1_all.deb ... 115s Unpacking python3-service-identity (24.2.0-1) ... 115s Selecting previously unselected package python3-automat. 115s Preparing to unpack .../52-python3-automat_25.4.16-1_all.deb ... 115s Unpacking python3-automat (25.4.16-1) ... 115s Selecting previously unselected package python3-constantly. 115s Preparing to unpack .../53-python3-constantly_23.10.4-2_all.deb ... 115s Unpacking python3-constantly (23.10.4-2) ... 115s Selecting previously unselected package python3-hyperlink. 115s Preparing to unpack .../54-python3-hyperlink_21.0.0-6_all.deb ... 115s Unpacking python3-hyperlink (21.0.0-6) ... 115s Selecting previously unselected package python3-packaging. 115s Preparing to unpack .../55-python3-packaging_24.2-1_all.deb ... 115s Unpacking python3-packaging (24.2-1) ... 115s Selecting previously unselected package python3-incremental. 115s Preparing to unpack .../56-python3-incremental_24.7.2-3_all.deb ... 115s Unpacking python3-incremental (24.7.2-3) ... 115s Selecting previously unselected package python3-zope.interface. 115s Preparing to unpack .../57-python3-zope.interface_7.2-1build1_s390x.deb ... 115s Unpacking python3-zope.interface (7.2-1build1) ... 115s Selecting previously unselected package python3-twisted. 115s Preparing to unpack .../58-python3-twisted_24.11.0-1_all.deb ... 115s Unpacking python3-twisted (24.11.0-1) ... 115s Selecting previously unselected package openssh-tests. 115s Preparing to unpack .../59-openssh-tests_1%3a9.9p1-3ubuntu3_s390x.deb ... 115s Unpacking openssh-tests (1:9.9p1-3ubuntu3) ... 115s Selecting previously unselected package haveged. 115s Preparing to unpack .../60-haveged_1.9.19-11ubuntu1_s390x.deb ... 115s Unpacking haveged (1.9.19-11ubuntu1) ... 115s Setting up wdiff (1.2.2-9) ... 115s Setting up libfile-which-perl (1.27-2) ... 115s Setting up libdynaloader-functions-perl (0.004-2) ... 115s Setting up libclass-method-modifiers-perl (2.15-1) ... 115s Setting up libio-pty-perl (1:1.20-1build3) ... 115s Setting up python3-zope.interface (7.2-1build1) ... 115s Setting up libclone-perl:s390x (0.47-1) ... 115s Setting up libtommath1:s390x (1.3.0-1) ... 115s Setting up libhtml-tagset-perl (3.24-1) ... 115s Setting up python3-automat (25.4.16-1) ... 115s Setting up liblwp-mediatypes-perl (6.04-2) ... 115s Setting up libtry-tiny-perl (0.32-1) ... 115s Setting up perl-openssl-defaults:s390x (7build3) ... 115s Setting up libencode-locale-perl (1.05-3) ... 115s Setting up python3-hamcrest (2.1.0-1) ... 115s Setting up putty-tools (0.83-2) ... 115s Setting up libhavege2:s390x (1.9.19-11ubuntu1) ... 115s Setting up patchutils (0.4.2-1build3) ... 115s Setting up python3-packaging (24.2-1) ... 115s Setting up python3-incremental (24.7.2-3) ... 116s Setting up python3-hyperlink (21.0.0-6) ... 116s Setting up libio-html-perl (1.004-3) ... 116s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 116s Setting up libipc-run-perl (20231003.0-2) ... 116s Setting up libtimedate-perl (2.3300-2) ... 116s Setting up librole-tiny-perl (2.002004-1) ... 116s Setting up python3-pyasn1 (0.6.1-1) ... 116s Setting up python3-constantly (23.10.4-2) ... 116s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 116s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 116s Setting up libfile-dirlist-perl (0.05-3) ... 116s Setting up libfile-homedir-perl (1.006-2) ... 116s Setting up liburi-perl (5.30-1) ... 116s Setting up libfile-touch-perl (0.12-2) ... 116s Setting up libnet-ssleay-perl:s390x (1.94-3) ... 116s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 116s Setting up libhttp-date-perl (6.06-1) ... 116s Setting up haveged (1.9.19-11ubuntu1) ... 116s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 116s Setting up dropbear-bin (2025.87-1) ... 116s Setting up libfile-listing-perl (6.16-1) ... 116s Setting up libnet-http-perl (6.23-1) ... 116s Setting up libdevel-callchecker-perl:s390x (0.009-1build1) ... 116s Setting up dropbear (2025.87-1) ... 116s Converting existing OpenSSH RSA host key to Dropbear format. 116s Key is a ssh-rsa key 116s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 116s 3072 SHA256:xa1VRsU2XaxeAjtbxHkmHyixX92VdZxIZ3MCQR/27Kg /etc/dropbear/dropbear_rsa_host_key (RSA) 116s +---[RSA 3072]----+ 116s | o**XX&| 116s | . ooBBX/| 116s | o.++.OB| 116s | . o+ =oo| 116s | S . *.o.| 116s | ... | 116s | E | 116s | | 116s | | 116s +----[SHA256]-----+ 116s Converting existing OpenSSH ECDSA host key to Dropbear format. 116s Key is a ecdsa-sha2-nistp256 key 116s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 116s 256 SHA256:BAMnp4GyKG5XoYwperMghQyZYdJXXQXG/iGs1/6vTFs /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 116s +---[ECDSA 256]---+ 116s |o* .+o*. o+o. | 116s |B o .B o... | 116s |+++.o . .o | 116s |*+.o . . + . | 116s |=. . S. + . | 116s |+oo. . . o | 116s |oo.o . . . E| 116s | . .o o | 116s | .=o.| 116s +----[SHA256]-----+ 116s Converting existing OpenSSH ED25519 host key to Dropbear format. 116s Key is a ssh-ed25519 key 116s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 116s 256 SHA256:d9622hBDnJhUymj/i9aDU+/5Ju2+sSS3ls61m0Vfc9Y /etc/dropbear/dropbear_ed25519_host_key (ED25519) 116s +--[ED25519 256]--+ 116s | ... | 116s | + = . | 116s | o = + | 116s | . . . .| 116s | S o + .E| 116s | . +.+ o=| 116s | ++oo==| 116s | +.o=B=@| 116s | ...o+B/*| 116s +----[SHA256]-----+ 117s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 117s Setting up python3-pyasn1-modules (0.4.1-2) ... 117s Setting up python3-service-identity (24.2.0-1) ... 117s Setting up libwww-robotrules-perl (6.02-1) ... 117s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 117s Setting up libio-socket-ssl-perl (2.089-1) ... 117s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 117s Setting up libhttp-negotiate-perl (6.01-2) ... 117s Setting up libhttp-cookies-perl (6.11-1) ... 117s Setting up libhtml-tree-perl (5.07-3) ... 117s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 117s Setting up libmodule-runtime-perl (0.018-1) ... 117s Setting up python3-twisted (24.11.0-1) ... 119s Setting up libimport-into-perl (1.002005-2) ... 119s Setting up libmoo-perl (2.005005-1) ... 119s Setting up openssh-tests (1:9.9p1-3ubuntu3) ... 119s Setting up liblwp-protocol-https-perl (6.14-1) ... 119s Setting up libwww-perl (6.78-1) ... 119s Setting up devscripts (2.25.5) ... 119s Processing triggers for install-info (7.1.1-1) ... 119s Processing triggers for libc-bin (2.41-6ubuntu1) ... 119s Processing triggers for man-db (2.13.1-1) ... 121s autopkgtest [14:13:35]: test regress: [----------------------- 122s info: Adding user `openssh-tests' ... 122s info: Selecting UID/GID from range 1000 to 59999 ... 122s info: Adding new group `openssh-tests' (1001) ... 122s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 122s info: Creating home directory `/home/openssh-tests' ... 122s info: Copying files from `/etc/skel' ... 122s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 122s info: Adding user `openssh-tests' to group `users' ... 122s I: annotate-output 2.25.5 122s I: prefix='%H:%M:%S.%N ' 122s 14:13:36.367588828 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user 122s 14:13:36.383860053 O: make: Entering directory '/tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress' 122s 14:13:36.384783357 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/valgrind-out 122s 14:13:36.385290058 E: + /usr/bin/ssh -Q key 122s 14:13:36.385782663 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 122s 14:13:36.385965240 E: + grep -q ^ssh-rsa 122s 14:13:36.386388513 O: ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 122s 14:13:36.386923038 O: tr '\n' '\r' /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 122s 14:13:36.387431604 O: ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 122s 14:13:36.388002151 O: awk '{print $0 "\r"}' /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 122s 14:13:36.388532986 O: ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 122s 14:13:36.389024445 O: fi 122s 14:13:36.391683595 E: + ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2.prv 122s 14:13:36.392443944 E: + diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv 122s 14:13:36.395449782 E: + tr \n \r 122s 14:13:36.397400086 E: + ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 122s 14:13:36.397890391 E: + diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv 122s 14:13:36.399798876 E: + awk {print $0 "\r"} /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2.prv 122s 14:13:36.401333725 E: + ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 122s 14:13:36.401863643 E: + diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv 122s 14:13:36.404676992 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 122s 14:13:36.405230221 E: + /usr/bin/ssh -Q key 122s 14:13:36.405644843 O: cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t2.out ; \ 122s 14:13:36.406150729 O: chmod 600 /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t2.out ; \ 122s 14:13:36.406679107 O: ssh-keygen -yf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 122s 14:13:36.407186405 O: fi 122s 14:13:36.407454505 E: + grep -q ^ssh-rsa 122s 14:13:36.408844319 E: + cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.prv 122s 14:13:36.409371046 E: + chmod 600 /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t2.out 122s 14:13:36.409868194 E: + ssh-keygen -yf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t2.out 122s 14:13:36.410790808 E: + diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub 122s 14:13:36.412973439 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 122s 14:13:36.413469346 O: ssh-keygen -ef /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t3.out ; \ 122s 14:13:36.413956737 E: + /usr/bin/ssh -Q key 122s 14:13:36.414508450 E: + grep -q ^ssh-rsa 122s 14:13:36.414870540 O: ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 122s 14:13:36.415323322 O: fi 122s 14:13:36.416757560 E: + ssh-keygen -ef /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub 122s 14:13:36.419645573 E: + ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t3.out 122s 14:13:36.420124058 E: + diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub 122s 14:13:36.421997073 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 122s 14:13:36.422444138 E: + /usr/bin/ssh -Q key 122s 14:13:36.422908224 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 122s 14:13:36.423381998 O: awk '{print $2}' | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t4.ok ; \ 122s 14:13:36.423841549 O: fi 122s 14:13:36.425247336 E: + grep -q ^ssh-rsa 122s 14:13:36.425725416 E: + + diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t4.ok 122s 14:13:36.426203631 E: ssh-keygen -E md5 -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub 122s 14:13:36.426792432 E: + awk {print $2} 122s 14:13:36.429497388 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 122s 14:13:36.429984951 E: + /usr/bin/ssh -Q key 122s 14:13:36.430472298 O: ssh-keygen -Bf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 122s 14:13:36.430976245 O: awk '{print $2}' | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t5.ok ; \ 122s 14:13:36.431451733 O: fi 122s 14:13:36.432462052 E: + grep -q ^ssh-rsa 122s 14:13:36.434487934 E: + ssh-keygen -Bf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub 122s 14:13:36.434989651 E: + awk {print $2} 122s 14:13:36.435496020 E: + diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t5.ok 122s 14:13:36.436235496 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 122s 14:13:36.436870520 O: ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t6.out1 ; \ 122s 14:13:36.437746074 O: ssh-keygen -if /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t6.out2 ; \ 122s 14:13:36.437558991 E: + /usr/bin/ssh -Q key 122s 14:13:36.438598796 E: + grep -q ^ssh-dss 122s 14:13:36.438210026 O: chmod 600 /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t6.out1 ; \ 122s 14:13:36.439066636 O: ssh-keygen -yf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t6.out2 ; \ 122s 14:13:36.439502385 O: fi 122s 14:13:36.440768232 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 122s 14:13:36.441243998 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t7.out ; \ 122s 14:13:36.441697220 O: fi 122s 14:13:36.440692011 E: + /usr/bin/ssh -Q+ key 122s 14:13:36.442610099 E: grep -q ^ssh-dss 122s 14:13:36.443652173 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 122s 14:13:36.444107061 E: + /usr/bin/ssh -Q key 122s 14:13:36.444591033 O: ssh-keygen -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 122s 14:13:36.445095091 O: ssh-keygen -Bf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 122s 14:13:36.445561583 O: fi 122s 14:13:36.446083308 E: + grep -q ^ssh-dss 122s 14:13:36.447537954 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 122s 14:13:36.448002101 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t8.out ; \ 122s 14:13:36.448434822 O: fi 122s 14:13:36.449345326 E: + ssh -Q key 122s 14:13:36.449787762 E: + grep -q ^ssh-dss 122s 14:13:36.450875433 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 122s 14:13:36.451327658 E: + ssh -Q key 122s 14:13:36.451801148 O: ssh-keygen -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 122s 14:13:36.452267214 O: ssh-keygen -Bf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 122s 14:13:36.452704998 O: fi 122s 14:13:36.453698306 E: + grep -q ^ssh-dss 122s 14:13:36.454166954 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 122s 14:13:36.454628756 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t9.out 122s 14:13:36.460503041 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 122s 14:13:36.460976089 O: ssh-keygen -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t9.out > /dev/null 122s 14:13:36.468573980 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 122s 14:13:36.469107517 O: ssh-keygen -Bf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t9.out > /dev/null 122s 14:13:36.476837651 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t10.out 122s 14:13:36.480232960 O: ssh-keygen -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t10.out > /dev/null 122s 14:13:36.483783405 O: ssh-keygen -Bf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t10.out > /dev/null 122s 14:13:36.487640805 E: + /usr/bin/ssh -Q key 122s 14:13:36.487151666 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 122s 14:13:36.488224954 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 122s 14:13:36.488705415 O: awk '{print $2}' | diff - /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t11.ok ; \ 122s 14:13:36.489173685 O: fi 122s 14:13:36.490237742 E: + grep -q ^ssh-dss 122s 14:13:36.490931436 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t12.out 122s 14:13:36.494497189 O: ssh-keygen -lf /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 122s 14:13:36.498888634 E: run test connect.sh ... 124s 14:13:38.522698521 O: ok simple connect 124s 14:13:38.523223013 E: run test proxy-connect.sh ... 124s 14:13:38.661372240 O: plain username comp=no 125s 14:13:38.964886989 O: plain username comp=yes 125s 14:13:39.271626309 O: username with style 125s 14:13:39.589075744 E: run test sshfp-connect.sh ... 125s 14:13:39.589575792 O: ok proxy connect 125s 14:13:39.726518990 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 125s 14:13:39.726898825 E: run test connect-privsep.sh ... 129s 14:13:43.711926232 O: ok proxy connect with privsep 129s 14:13:43.712452449 E: run test connect-uri.sh ... 130s 14:13:43.977609273 O: uri connect: no trailing slash 130s 14:13:44.300601139 O: uri connect: trailing slash 130s 14:13:44.610275088 O: uri connect: with path name 130s 14:13:44.623722164 O: ok uri connect 130s 14:13:44.624200260 E: run test proto-version.sh ... 130s 14:13:44.868650269 O: ok sshd version with different protocol combinations 130s 14:13:44.869324842 E: run test proto-mismatch.sh ... 131s 14:13:45.117967209 O: ok protocol version mismatch 131s 14:13:45.118622242 E: run test exit-status.sh ... 131s 14:13:45.245935533 O: test remote exit status: status 0 136s 14:13:50.850255562 O: test remote exit status: status 1 142s 14:13:56.453560321 O: test remote exit status: status 4 148s 14:14:02.065768094 O: test remote exit status: status 5 153s 14:14:07.683364593 O: test remote exit status: status 44 159s 14:14:13.311838415 O: ok remote exit status 159s 14:14:13.312523466 E: run test exit-status-signal.sh ... 160s 14:14:14.461536110 O: ok exit status on signal 160s 14:14:14.462237157 E: run test envpass.sh ... 160s 14:14:14.606802162 O: test environment passing: pass env, don't accept 160s 14:14:14.922459015 O: test environment passing: setenv, don't accept 161s 14:14:15.243447826 O: test environment passing: don't pass env, accept 161s 14:14:15.562965156 O: test environment passing: pass single env, accept single env 161s 14:14:15.879894961 O: test environment passing: pass multiple env, accept multiple env 162s 14:14:16.207315410 O: test environment passing: setenv, accept 162s 14:14:16.538628832 O: test environment passing: setenv, first match wins 162s 14:14:16.845309708 O: test environment passing: server setenv wins 163s 14:14:17.150911865 O: test environment passing: server setenv wins 163s 14:14:17.489036046 O: ok environment passing 163s 14:14:17.490024270 E: run test transfer.sh ... 166s 14:14:20.267789343 O: ok transfer data 166s 14:14:20.268433209 E: run test banner.sh ... 166s 14:14:20.433546980 O: test banner: missing banner file 166s 14:14:20.752664370 O: test banner: size 0 167s 14:14:21.066444113 O: test banner: size 10 167s 14:14:21.401039450 O: test banner: size 100 167s 14:14:21.743049030 O: test banner: size 1000 168s 14:14:22.071390598 O: test banner: size 10000 168s 14:14:22.391711374 O: test banner: size 100000 168s 14:14:22.716133627 O: test banner: suppress banner (-q) 169s 14:14:23.030242614 O: ok banner 169s 14:14:23.030970985 E: run test rekey.sh ... 169s 14:14:23.199514521 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 169s 14:14:23.467259782 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 169s 14:14:23.755710761 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 170s 14:14:24.055298063 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 170s 14:14:24.455116696 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 171s 14:14:25.244369295 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 172s 14:14:26.056207575 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 172s 14:14:26.852748639 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 173s 14:14:27.103415570 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 173s 14:14:27.361531190 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 173s 14:14:27.645224853 O: client rekey KexAlgorithms=curve25519-sha256 173s 14:14:27.921378782 O: client rekey KexAlgorithms=sntrup761x25519-sha512 174s 14:14:28.423984791 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 174s 14:14:28.940728835 O: client rekey KexAlgorithms=mlkem768x25519-sha256 175s 14:14:29.227009782 O: client rekey Ciphers=3des-cbc 175s 14:14:29.507078890 O: client rekey Ciphers=aes128-cbc 175s 14:14:29.786206936 O: client rekey Ciphers=aes192-cbc 176s 14:14:30.065541325 O: client rekey Ciphers=aes256-cbc 176s 14:14:30.336332428 O: client rekey Ciphers=aes128-ctr 176s 14:14:30.612818618 O: client rekey Ciphers=aes192-ctr 176s 14:14:30.893930405 O: client rekey Ciphers=aes256-ctr 177s 14:14:31.174432356 O: client rekey Ciphers=aes128-gcm@openssh.com 181s 14:14:35.703535743 O: client rekey Ciphers=aes256-gcm@openssh.com 186s 14:14:40.861815505 O: client rekey Ciphers=chacha20-poly1305@openssh.com 193s 14:14:47.114242448 O: client rekey MACs=hmac-sha1 193s 14:14:47.395276438 O: client rekey MACs=hmac-sha1-96 193s 14:14:47.675041480 O: client rekey MACs=hmac-sha2-256 193s 14:14:47.967426967 O: client rekey MACs=hmac-sha2-512 194s 14:14:48.260730146 O: client rekey MACs=hmac-md5 194s 14:14:48.560747236 O: client rekey MACs=hmac-md5-96 194s 14:14:48.850790259 O: client rekey MACs=umac-64@openssh.com 195s 14:14:49.125751040 O: client rekey MACs=umac-128@openssh.com 195s 14:14:49.407623409 O: client rekey MACs=hmac-sha1-etm@openssh.com 195s 14:14:49.693157748 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 196s 14:14:49.972488523 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 196s 14:14:50.252404055 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 196s 14:14:50.534921865 O: client rekey MACs=hmac-md5-etm@openssh.com 196s 14:14:50.829880816 O: client rekey MACs=hmac-md5-96-etm@openssh.com 197s 14:14:51.161116638 O: client rekey MACs=umac-64-etm@openssh.com 197s 14:14:51.523146475 O: client rekey MACs=umac-128-etm@openssh.com 197s 14:14:51.857866970 O: client rekeylimit 16 198s 14:14:52.189926569 O: client rekeylimit 1k 198s 14:14:52.503513659 O: client rekeylimit 128k 198s 14:14:52.818276065 O: client rekeylimit 256k 199s 14:14:53.207257208 O: client rekeylimit default 5 214s 14:15:08.484674127 O: client rekeylimit default 10 234s 14:15:28.738220897 O: client rekeylimit default 5 no data 250s 14:15:44.034753894 O: client rekeylimit default 10 no data 270s 14:16:04.308841274 O: server rekeylimit 16 270s 14:16:04.664757094 O: server rekeylimit 1k 271s 14:16:04.976679658 O: server rekeylimit 128k 271s 14:16:05.299442064 O: server rekeylimit 256k 271s 14:16:05.715954806 O: server rekeylimit default 5 no data 287s 14:16:21.000732059 O: server rekeylimit default 10 no data 307s 14:16:41.295954048 O: rekeylimit parsing: bytes 307s 14:16:41.349779795 O: rekeylimit parsing: time 307s 14:16:41.395754291 E: run test dhgex.sh ... 307s 14:16:41.395267882 O: ok rekey 307s 14:16:41.536281995 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 307s 14:16:41.784198806 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 308s 14:16:42.035382749 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 308s 14:16:42.284147477 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 308s 14:16:42.531043706 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 308s 14:16:42.782099999 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 309s 14:16:43.031724353 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 309s 14:16:43.297694486 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 309s 14:16:43.572380551 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 309s 14:16:43.909333658 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 310s 14:16:44.225672602 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 310s 14:16:44.546787104 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 310s 14:16:44.861980331 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 311s 14:16:45.181790298 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 311s 14:16:45.496363653 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 311s 14:16:45.814506129 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 312s 14:16:46.138564645 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 312s 14:16:46.455922897 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 312s 14:16:46.778789002 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 313s 14:16:47.185911975 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 313s 14:16:47.597381004 O: ok dhgex 313s 14:16:47.598071120 E: run test stderr-data.sh ... 313s 14:16:47.732552871 O: test stderr data transfer: () 320s 14:16:54.366739184 O: test stderr data transfer: (-n) 327s 14:17:01.068613095 O: ok stderr data transfer 327s 14:17:01.069390742 E: run test stderr-after-eof.sh ... 329s 14:17:03.531865226 O: ok stderr data after eof 329s 14:17:03.532584644 E: run test broken-pipe.sh ... 329s 14:17:03.681944272 O: ok broken pipe test 329s 14:17:03.682844801 E: run test try-ciphers.sh ... 329s 14:17:03.841430736 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 330s 14:17:04.151606496 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 330s 14:17:04.461740889 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 330s 14:17:04.785008426 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 331s 14:17:05.106739245 O: test try ciphers: cipher 3des-cbc mac hmac-md5 331s 14:17:05.417822991 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 331s 14:17:05.737954895 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 332s 14:17:06.051599319 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 332s 14:17:06.363634485 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 332s 14:17:06.672773752 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 333s 14:17:06.999906703 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 333s 14:17:07.335346139 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 333s 14:17:07.652391434 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 334s 14:17:07.966723329 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 334s 14:17:08.280226813 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 334s 14:17:08.594065543 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 334s 14:17:08.911460714 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 335s 14:17:09.225639905 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 335s 14:17:09.540028513 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 335s 14:17:09.855823562 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 336s 14:17:10.187511132 O: test try ciphers: cipher aes128-cbc mac hmac-md5 336s 14:17:10.527300927 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 336s 14:17:10.863936102 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 337s 14:17:11.189127702 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 337s 14:17:11.513313292 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 337s 14:17:11.832262792 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 338s 14:17:12.153825662 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 338s 14:17:12.473541276 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 338s 14:17:12.799904918 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 339s 14:17:13.123645959 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 339s 14:17:13.434420865 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 339s 14:17:13.748442428 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 340s 14:17:14.092732331 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 340s 14:17:14.406217624 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 340s 14:17:14.719184364 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 341s 14:17:15.029455424 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 341s 14:17:15.350837763 O: test try ciphers: cipher aes192-cbc mac hmac-md5 341s 14:17:15.664529814 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 342s 14:17:15.976746335 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 342s 14:17:16.282831011 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 342s 14:17:16.598798290 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 342s 14:17:16.912282405 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 343s 14:17:17.228014409 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 343s 14:17:17.549837927 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 343s 14:17:17.860164369 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 344s 14:17:18.164145165 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 344s 14:17:18.470807122 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 344s 14:17:18.786996978 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 345s 14:17:19.102438281 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 345s 14:17:19.405546762 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 345s 14:17:19.707878391 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 346s 14:17:20.015123916 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 346s 14:17:20.323684209 O: test try ciphers: cipher aes256-cbc mac hmac-md5 346s 14:17:20.633868468 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 346s 14:17:20.939476862 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 347s 14:17:21.253877946 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 347s 14:17:21.573007975 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 347s 14:17:21.877729502 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 348s 14:17:22.183372505 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 348s 14:17:22.489511976 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 348s 14:17:22.796472666 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 349s 14:17:23.107578554 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 349s 14:17:23.418394662 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 349s 14:17:23.782942285 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 350s 14:17:24.096126135 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 350s 14:17:24.407625850 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 350s 14:17:24.719288484 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 351s 14:17:25.031292866 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 351s 14:17:25.363103969 O: test try ciphers: cipher aes128-ctr mac hmac-md5 351s 14:17:25.686516813 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 352s 14:17:25.987269352 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 352s 14:17:26.286181245 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 352s 14:17:26.592813720 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 352s 14:17:26.895524752 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 353s 14:17:27.200221657 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 353s 14:17:27.506796327 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 353s 14:17:27.816673298 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 354s 14:17:28.131059394 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 354s 14:17:28.447089735 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 354s 14:17:28.769924152 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 355s 14:17:29.115421445 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 355s 14:17:29.426528403 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 355s 14:17:29.735433868 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 356s 14:17:30.041103480 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 356s 14:17:30.346579413 O: test try ciphers: cipher aes192-ctr mac hmac-md5 356s 14:17:30.649639762 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 356s 14:17:30.953854903 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 357s 14:17:31.256218404 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 357s 14:17:31.561132288 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 357s 14:17:31.867691661 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 358s 14:17:32.171585214 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 358s 14:17:32.475357924 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 358s 14:17:32.778925800 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 359s 14:17:33.077783099 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 359s 14:17:33.382418211 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 359s 14:17:33.686809593 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 360s 14:17:33.990696211 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 360s 14:17:34.294209770 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 360s 14:17:34.594126505 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 360s 14:17:34.892197869 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 361s 14:17:35.194117795 O: test try ciphers: cipher aes256-ctr mac hmac-md5 361s 14:17:35.501767445 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 361s 14:17:35.806965779 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 362s 14:17:36.112802853 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 362s 14:17:36.419865395 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 362s 14:17:36.725318746 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 363s 14:17:37.028299018 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 363s 14:17:37.336671505 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 363s 14:17:37.640023335 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 363s 14:17:37.945129111 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 364s 14:17:38.250019813 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 364s 14:17:38.553405722 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 364s 14:17:38.861775632 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 365s 14:17:39.169530416 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 365s 14:17:39.475371671 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 365s 14:17:39.773582262 O: ok try ciphers 365s 14:17:39.773649701 E: run test yes-head.sh ... 368s 14:17:42.910956877 O: ok yes pipe head 368s 14:17:42.911702261 E: run test login-timeout.sh ... 384s 14:17:58.520903107 O: ok connect after login grace timeout 384s 14:17:58.521368481 E: run test agent.sh ... 395s 14:18:09.239407656 O: ok simple agent test 395s 14:18:09.240182512 E: run test agent-getpeereid.sh ... 395s 14:18:09.396947687 O: ok disallow agent attach from other uid 395s 14:18:09.397701008 E: run test agent-timeout.sh ... 415s 14:18:29.632708437 O: ok agent timeout test 415s 14:18:29.633191975 E: run test agent-ptrace.sh ... 415s 14:18:29.763335383 O: skipped (gdb not found) 415s 14:18:29.763786434 E: run test agent-subprocess.sh ... 425s 14:18:39.912705058 O: ok agent subprocess 425s 14:18:39.912689214 E: run test keyscan.sh ... 428s 14:18:42.269229499 E: run test keygen-change.sh ... 428s 14:18:42.269830948 O: ok keyscan 432s 14:18:46.687225580 O: ok change passphrase for key 432s 14:18:46.687936194 E: run test keygen-comment.sh ... 436s 14:18:49.994633608 O: ok Comment extraction from private key 436s 14:18:49.995293242 E: run test keygen-convert.sh ... 439s 14:18:53.502611044 O: ok convert keys 439s 14:18:53.503348241 E: run test keygen-knownhosts.sh ... 439s 14:18:53.728675935 O: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts updated. 439s 14:18:53.729244452 O: Original contents retained as /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts.old 439s 14:18:53.739887586 O: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts updated. 439s 14:18:53.740491416 O: Original contents retained as /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts.old 439s 14:18:53.744275545 O: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts updated. 439s 14:18:53.744821554 O: Original contents retained as /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts.old 439s 14:18:53.752188280 O: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts updated. 439s 14:18:53.752774301 O: Original contents retained as /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hosts.old 439s 14:18:53.766453262 O: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hashed updated. 439s 14:18:53.766943010 O: Original contents retained as /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/kh.hashed.old 439s 14:18:53.769776596 O: ok ssh-keygen known_hosts 439s 14:18:53.770379008 E: run test keygen-moduli.sh ... 444s 14:18:58.930715240 O: ok keygen moduli 444s 14:18:58.931268105 E: run test keygen-sshfp.sh ... 445s 14:18:59.098209914 O: ok keygen-sshfp 445s 14:18:59.098667460 E: run test key-options.sh ... 445s 14:18:59.238566834 O: key option command="echo bar" 445s 14:18:59.547979978 O: key option no-pty,command="echo bar" 445s 14:18:59.846905803 O: key option pty default 446s 14:19:00.149931862 O: key option pty no-pty 446s 14:19:00.449718450 O: key option pty restrict 446s 14:19:00.748923395 O: key option pty restrict,pty 447s 14:19:01.050188612 O: key option environment 447s 14:19:01.809456227 O: key option from="127.0.0.1" 448s 14:19:02.865592472 O: key option from="127.0.0.0/8" 449s 14:19:03.562341368 O: key option expiry-time default 449s 14:19:03.861233594 O: key option expiry-time invalid 450s 14:19:04.293371442 O: key option expiry-time expired 450s 14:19:04.681072669 O: key option expiry-time valid 451s 14:19:04.992414106 O: ok key options 451s 14:19:04.993044882 E: run test scp.sh ... 451s 14:19:05.129385017 O: scp: scp mode: simple copy local file to local file 451s 14:19:05.134611049 O: scp: scp mode: simple copy local file to remote file 451s 14:19:05.139150029 O: scp: scp mode: simple copy remote file to local file 451s 14:19:05.143957063 O: scp: scp mode: copy local file to remote file in place 451s 14:19:05.149778329 O: scp: scp mode: copy remote file to local file in place 451s 14:19:05.155107504 O: scp: scp mode: copy local file to remote file clobber 451s 14:19:05.160279956 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 May 5 14:19 /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/copy 451s 14:19:05.160772300 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 May 5 14:19 /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data 451s 14:19:05.161676585 O: scp: scp mode: copy remote file to local file clobber 451s 14:19:05.167118086 O: scp: scp mode: simple copy local file to remote dir 451s 14:19:05.172229220 O: scp: scp mode: simple copy local file to local dir 451s 14:19:05.177291748 O: scp: scp mode: simple copy remote file to local dir 451s 14:19:05.182867235 O: scp: scp mode: recursive local dir to remote dir 451s 14:19:05.193491062 O: scp: scp mode: recursive local dir to local dir 451s 14:19:05.205006643 O: scp: scp mode: recursive remote dir to local dir 451s 14:19:05.216471817 O: scp: scp mode: unmatched glob file local->remote 451s 14:19:05.221950684 O: scp: scp mode: unmatched glob file remote->local 451s 14:19:05.225200728 O: scp: scp mode: unmatched glob dir recursive local->remote 451s 14:19:05.232704245 O: scp: scp mode: unmatched glob dir recursive remote->local 451s 14:19:05.237963137 O: scp: scp mode: shell metacharacters 451s 14:19:05.242062108 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 451s 14:19:05.262488009 O: scp: scp mode: disallow bad server #0 451s 14:19:05.274389445 O: scp: scp mode: disallow bad server #1 451s 14:19:05.287267113 O: scp: scp mode: disallow bad server #2 451s 14:19:05.298657118 O: scp: scp mode: disallow bad server #3 451s 14:19:05.310561813 O: scp: scp mode: disallow bad server #4 451s 14:19:05.324123746 O: scp: scp mode: disallow bad server #5 451s 14:19:05.335251945 O: scp: scp mode: disallow bad server #6 451s 14:19:05.347290144 O: scp: scp mode: disallow bad server #7 451s 14:19:05.359855457 O: scp: scp mode: detect non-directory target 451s 14:19:05.361883316 E: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/copy2: Not a directory 451s 14:19:05.362740422 O: scp: sftp mode: simple copy local file to local file 451s 14:19:05.367752580 O: scp: sftp mode: simple copy local file to remote file 451s 14:19:05.374190726 O: scp: sftp mode: simple copy remote file to local file 451s 14:19:05.379752847 O: scp: sftp mode: copy local file to remote file in place 451s 14:19:05.386445058 O: scp: sftp mode: copy remote file to local file in place 451s 14:19:05.393240632 O: scp: sftp mode: copy local file to remote file clobber 451s 14:19:05.399516740 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 May 5 14:19 /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/copy 451s 14:19:05.400153090 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 May 5 14:19 /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data 451s 14:19:05.400893894 O: scp: sftp mode: copy remote file to local file clobber 451s 14:19:05.407458713 O: scp: sftp mode: simple copy local file to remote dir 451s 14:19:05.413961069 O: scp: sftp mode: simple copy local file to local dir 451s 14:19:05.419304301 O: scp: sftp mode: simple copy remote file to local dir 451s 14:19:05.425455102 O: scp: sftp mode: recursive local dir to remote dir 451s 14:19:05.438980846 O: scp: sftp mode: recursive local dir to local dir 451s 14:19:05.449942428 O: scp: sftp mode: recursive remote dir to local dir 451s 14:19:05.466102897 O: scp: sftp mode: unmatched glob file local->remote 451s 14:19:05.473249306 O: scp: sftp mode: unmatched glob file remote->local 451s 14:19:05.478302871 O: scp: sftp mode: unmatched glob dir recursive local->remote 451s 14:19:05.487533667 O: scp: sftp mode: unmatched glob dir recursive remote->local 451s 14:19:05.494971904 O: scp: sftp mode: shell metacharacters 451s 14:19:05.499187979 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 451s 14:19:05.520205631 O: scp: sftp mode: disallow bad server #0 451s 14:19:05.538658868 O: scp: sftp mode: disallow bad server #1 451s 14:19:05.556588152 O: scp: sftp mode: disallow bad server #2 451s 14:19:05.575026077 O: scp: sftp mode: disallow bad server #3 451s 14:19:05.593508401 O: scp: sftp mode: disallow bad server #4 451s 14:19:05.613550493 O: scp: sftp mode: disallow bad server #5 451s 14:19:05.633471383 O: scp: sftp mode: disallow bad server #6 451s 14:19:05.652150688 O: scp: sftp mode: disallow bad server #7 451s 14:19:05.670733261 O: scp: sftp mode: detect non-directory target 451s 14:19:05.673070162 E: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/copy2: Not a directory 451s 14:19:05.676063134 O: ok scp 451s 14:19:05.676567930 E: run test scp3.sh ... 451s 14:19:05.813669023 O: scp3: scp mode: simple copy remote file to remote file 452s 14:19:06.142602415 O: scp3: scp mode: simple copy remote file to remote dir 452s 14:19:06.488856923 O: scp3: scp mode: recursive remote dir to remote dir 452s 14:19:06.869096209 O: scp3: scp mode: detect non-directory target 453s 14:19:07.586918708 O: scp3: sftp mode: simple copy remote file to remote file 453s 14:19:07.594838575 O: scp3: sftp mode: simple copy remote file to remote dir 453s 14:19:07.603796708 O: scp3: sftp mode: recursive remote dir to remote dir 453s 14:19:07.624064445 O: scp3: sftp mode: detect non-directory target 453s 14:19:07.628518940 E: scp: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/copy2: destination is not a directory 453s 14:19:07.629166986 E: scp: /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/copy2: destination is not a directory 453s 14:19:07.632698071 O: ok scp3 453s 14:19:07.632784435 E: run test scp-uri.sh ... 453s 14:19:07.779856291 O: scp-uri: scp mode: simple copy local file to remote file 453s 14:19:07.784731158 O: scp-uri: scp mode: simple copy remote file to local file 453s 14:19:07.789168479 O: scp-uri: scp mode: simple copy local file to remote dir 453s 14:19:07.794451301 O: scp-uri: scp mode: simple copy remote file to local dir 453s 14:19:07.799574999 O: scp-uri: scp mode: recursive local dir to remote dir 453s 14:19:07.805628644 O: scp-uri: scp mode: recursive remote dir to local dir 453s 14:19:07.811629433 O: scp-uri: sftp mode: simple copy local file to remote file 453s 14:19:07.817755572 O: scp-uri: sftp mode: simple copy remote file to local file 453s 14:19:07.822665323 O: scp-uri: sftp mode: simple copy local file to remote dir 453s 14:19:07.828986633 O: scp-uri: sftp mode: simple copy remote file to local dir 453s 14:19:07.835712955 O: scp-uri: sftp mode: recursive local dir to remote dir 453s 14:19:07.843085414 O: scp-uri: sftp mode: recursive remote dir to local dir 453s 14:19:07.851525826 O: ok scp-uri 453s 14:19:07.851744675 E: run test sftp.sh ... 454s 14:19:07.990814320 O: test basic sftp put/get: buffer_size 5 num_requests 1 458s 14:19:12.556349587 O: test basic sftp put/get: buffer_size 5 num_requests 2 460s 14:19:14.317320049 O: test basic sftp put/get: buffer_size 5 num_requests 10 461s 14:19:15.624219303 O: test basic sftp put/get: buffer_size 1000 num_requests 1 461s 14:19:15.686842656 O: test basic sftp put/get: buffer_size 1000 num_requests 2 461s 14:19:15.701438999 O: test basic sftp put/get: buffer_size 1000 num_requests 10 461s 14:19:15.711521233 O: test basic sftp put/get: buffer_size 32000 num_requests 1 461s 14:19:15.719170987 O: test basic sftp put/get: buffer_size 32000 num_requests 2 461s 14:19:15.725364785 O: test basic sftp put/get: buffer_size 32000 num_requests 10 461s 14:19:15.731694887 O: test basic sftp put/get: buffer_size 64000 num_requests 1 461s 14:19:15.738139042 O: test basic sftp put/get: buffer_size 64000 num_requests 2 461s 14:19:15.743772426 O: test basic sftp put/get: buffer_size 64000 num_requests 10 461s 14:19:15.751421924 E: run test sftp-chroot.sh ... 461s 14:19:15.750934879 O: ok basic sftp put/get 462s 14:19:16.034503053 O: test sftp in chroot: get 462s 14:19:16.374276107 O: test sftp in chroot: match 462s 14:19:16.873992137 O: ok sftp in chroot 462s 14:19:16.878429932 E: run test sftp-cmds.sh ... 463s 14:19:17.016806001 O: sftp commands: lls 463s 14:19:17.020451596 O: sftp commands: lls w/path 463s 14:19:17.024353264 O: sftp commands: ls 463s 14:19:17.029509941 O: sftp commands: shell 463s 14:19:17.032236859 O: sftp commands: pwd 463s 14:19:17.034061605 O: sftp commands: lpwd 463s 14:19:17.035789976 O: sftp commands: quit 463s 14:19:17.037795345 O: sftp commands: help 463s 14:19:17.040595718 O: sftp commands: get 463s 14:19:17.046060886 O: sftp commands: get quoted 463s 14:19:17.051983454 O: sftp commands: get filename with quotes 463s 14:19:17.058574486 O: sftp commands: get filename with spaces 463s 14:19:17.064448745 O: sftp commands: get filename with glob metacharacters 463s 14:19:17.068982668 O: sftp commands: get to directory 463s 14:19:17.073288441 O: sftp commands: glob get to directory 463s 14:19:17.106263865 O: sftp commands: get to local dir 463s 14:19:17.111166273 O: sftp commands: glob get to local dir 463s 14:19:17.135659919 O: sftp commands: put 463s 14:19:17.140934062 O: sftp commands: put filename with quotes 463s 14:19:17.146714014 O: sftp commands: put filename with spaces 463s 14:19:17.152835492 O: sftp commands: put to directory 463s 14:19:17.158759518 O: sftp commands: glob put to directory 463s 14:19:17.165021785 O: sftp commands: put to local dir 463s 14:19:17.170234402 O: sftp commands: glob put to local dir 463s 14:19:17.182159258 O: sftp commands: rename 463s 14:19:17.184983998 O: sftp commands: rename directory 463s 14:19:17.186780544 O: sftp commands: ln 463s 14:19:17.188967756 O: sftp commands: ln -s 463s 14:19:17.191325744 O: sftp commands: cp 463s 14:19:17.194830751 O: sftp commands: mkdir 463s 14:19:17.196905530 O: sftp commands: chdir 463s 14:19:17.198474150 O: sftp commands: rmdir 463s 14:19:17.200603322 O: sftp commands: lmkdir 463s 14:19:17.202170921 O: sftp commands: lchdir 463s 14:19:17.206517250 O: ok sftp commands 463s 14:19:17.207121698 E: run test sftp-badcmds.sh ... 463s 14:19:17.342108136 O: sftp invalid commands: get nonexistent 463s 14:19:17.344394418 O: sftp invalid commands: glob get to nonexistent directory 463s 14:19:17.353706502 O: sftp invalid commands: put nonexistent 463s 14:19:17.356200687 O: sftp invalid commands: glob put to nonexistent directory 463s 14:19:17.358930108 O: sftp invalid commands: rename nonexistent 463s 14:19:17.363182446 O: sftp invalid commands: rename target exists (directory) 463s 14:19:17.367369066 O: sftp invalid commands: glob put files to local file 463s 14:19:17.370682784 E: run test sftp-batch.sh ... 463s 14:19:17.371201332 O: ok sftp invalid commands 463s 14:19:17.507686426 O: sftp batchfile: good commands 463s 14:19:17.512777656 O: sftp batchfile: bad commands 463s 14:19:17.519362340 O: sftp batchfile: comments and blanks 463s 14:19:17.522997929 O: sftp batchfile: junk command 463s 14:19:17.525631700 E: run test sftp-glob.sh ... 463s 14:19:17.526152308 O: ok sftp batchfile 463s 14:19:17.667440133 O: sftp glob: file glob 463s 14:19:17.671574017 O: sftp glob: dir glob 463s 14:19:17.675104139 O: sftp glob: quoted glob 463s 14:19:17.679326791 O: sftp glob: escaped glob 463s 14:19:17.683249221 O: sftp glob: escaped quote 463s 14:19:17.686874444 O: sftp glob: quoted quote 463s 14:19:17.690383989 O: sftp glob: single-quoted quote 463s 14:19:17.693489744 O: sftp glob: escaped space 463s 14:19:17.696803716 O: sftp glob: quoted space 463s 14:19:17.700159992 O: sftp glob: escaped slash 463s 14:19:17.703434332 O: sftp glob: quoted slash 463s 14:19:17.706926092 O: sftp glob: escaped slash at EOL 463s 14:19:17.710086670 O: sftp glob: quoted slash at EOL 463s 14:19:17.713210443 O: sftp glob: escaped slash+quote 463s 14:19:17.716496731 O: sftp glob: quoted slash+quote 463s 14:19:17.720506806 O: ok sftp glob 463s 14:19:17.721048102 E: run test sftp-perm.sh ... 463s 14:19:17.857234914 O: sftp permissions: read-only upload 463s 14:19:17.867834430 O: sftp permissions: read-only setstat 463s 14:19:17.877550831 O: sftp permissions: read-only rm 463s 14:19:17.886656759 O: sftp permissions: read-only mkdir 463s 14:19:17.894717081 O: sftp permissions: read-only rmdir 463s 14:19:17.903286735 O: sftp permissions: read-only posix-rename 463s 14:19:17.912202510 O: sftp permissions: read-only oldrename 463s 14:19:17.920716164 O: sftp permissions: read-only symlink 463s 14:19:17.928935428 O: sftp permissions: read-only hardlink 463s 14:19:17.936804807 O: sftp permissions: explicit open 463s 14:19:17.955395416 O: sftp permissions: explicit read 464s 14:19:17.973875841 O: sftp permissions: explicit write 464s 14:19:17.993641885 O: sftp permissions: explicit lstat 464s 14:19:18.012439142 O: sftp permissions: explicit opendir 464s 14:19:18.030319659 O: sftp permissions: explicit readdir 464s 14:19:18.048459264 O: sftp permissions: explicit setstat 464s 14:19:18.066219285 O: sftp permissions: explicit remove 464s 14:19:18.082686367 O: sftp permissions: explicit mkdir 464s 14:19:18.096573416 O: sftp permissions: explicit rmdir 464s 14:19:18.114520719 O: sftp permissions: explicit rename 464s 14:19:18.131230305 O: sftp permissions: explicit symlink 464s 14:19:18.147825861 O: sftp permissions: explicit hardlink 464s 14:19:18.163559540 O: sftp permissions: explicit statvfs 464s 14:19:18.175342992 O: ok sftp permissions 464s 14:19:18.175957527 E: run test sftp-uri.sh ... 464s 14:19:18.459354072 O: sftp-uri: non-interactive fetch to local file 464s 14:19:18.788657968 O: sftp-uri: non-interactive fetch to local dir 465s 14:19:19.120389536 O: sftp-uri: put to remote directory (trailing slash) 465s 14:19:19.457302211 O: sftp-uri: put to remote directory (no slash) 465s 14:19:19.809193429 E: run test reconfigure.sh ... 465s 14:19:19.808695743 O: ok sftp-uri 479s 14:19:33.492869674 O: ok simple connect after reconfigure 479s 14:19:33.493504644 E: run test dynamic-forward.sh ... 479s 14:19:33.790572211 O: test -D forwarding 481s 14:19:35.354891471 O: test -R forwarding 483s 14:19:37.026255474 O: PermitRemoteOpen=any 484s 14:19:38.823872229 O: PermitRemoteOpen=none 486s 14:19:40.314506342 O: PermitRemoteOpen=explicit 488s 14:19:42.018473642 O: PermitRemoteOpen=disallowed 488s 14:19:42.553571659 O: ok dynamic forwarding 488s 14:19:42.554124232 E: run test forwarding.sh ... 495s 14:19:49.249274371 O: ok local and remote forwarding 495s 14:19:49.249874063 E: run test multiplex.sh ... 496s 14:19:50.534337339 O: test connection multiplexing: setenv 496s 14:19:50.542655680 O: test connection multiplexing: envpass 496s 14:19:50.550041549 O: test connection multiplexing: transfer 496s 14:19:50.573832937 O: test connection multiplexing: transfer -Oproxy 496s 14:19:50.630893960 O: test connection multiplexing: forward 498s 14:19:52.667373251 O: test connection multiplexing: status 0 () 503s 14:19:57.688118947 O: test connection multiplexing: status 0 (-Oproxy) 508s 14:20:02.708123156 O: test connection multiplexing: status 1 () 513s 14:20:07.727555962 O: test connection multiplexing: status 1 (-Oproxy) 518s 14:20:12.747599279 O: test connection multiplexing: status 4 () 523s 14:20:17.768218406 O: test connection multiplexing: status 4 (-Oproxy) 528s 14:20:22.787866136 O: test connection multiplexing: status 5 () 533s 14:20:27.806901010 O: test connection multiplexing: status 5 (-Oproxy) 538s 14:20:32.826202403 O: test connection multiplexing: status 44 () 543s 14:20:37.845916106 O: test connection multiplexing: status 44 (-Oproxy) 548s 14:20:42.865166970 O: test connection multiplexing: cmd check 548s 14:20:42.870677908 O: test connection multiplexing: cmd forward local (TCP) 550s 14:20:44.199805843 O: test connection multiplexing: cmd forward remote (TCP) 551s 14:20:45.539758870 O: test connection multiplexing: cmd forward local (UNIX) 552s 14:20:46.599774788 O: test connection multiplexing: cmd forward remote (UNIX) 553s 14:20:47.659925714 O: test connection multiplexing: cmd exit 553s 14:20:47.666175907 O: test connection multiplexing: cmd stop 564s 14:20:58.710969177 E: run test reexec.sh ... 564s 14:20:58.710474348 O: ok connection multiplexing 564s 14:20:58.845803712 O: test config passing 565s 14:20:59.337383578 O: test reexec fallback 565s 14:20:59.337742878 E: ln: failed to create hard link '/tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 565s 14:20:59.815847406 O: ok reexec tests 565s 14:20:59.816455327 E: run test brokenkeys.sh ... 566s 14:21:00.410996867 O: ok broken keys 566s 14:21:00.411594025 E: run test sshcfgparse.sh ... 566s 14:21:00.539588020 O: reparse minimal config 566s 14:21:00.550086554 O: ssh -W opts 566s 14:21:00.579714551 O: user first match 566s 14:21:00.601815078 O: pubkeyacceptedalgorithms 566s 14:21:00.631354129 O: agentforwarding 566s 14:21:00.653858649 O: command line override 566s 14:21:00.666992070 O: ok ssh config parse 566s 14:21:00.667667286 E: run test cfgparse.sh ... 566s 14:21:00.797858887 O: reparse minimal config 566s 14:21:00.936379661 O: reparse regress config 567s 14:21:01.075549895 O: listenaddress order 567s 14:21:01.218400601 E: run test cfgmatch.sh ... 567s 14:21:01.219018030 O: ok sshd config parse 575s 14:21:09.117031746 E: run test cfgmatchlisten.sh ... 575s 14:21:09.117713795 O: ok sshd_config match 587s 14:21:21.107795360 E: run test percent.sh ... 587s 14:21:21.108373938 O: ok sshd_config matchlisten 587s 14:21:21.236664075 O: percent expansions matchexec percent 591s 14:21:25.115664606 O: percent expansions localcommand percent 594s 14:21:28.749248991 O: percent expansions remotecommand percent 594s 14:21:28.826888356 O: percent expansions controlpath percent 594s 14:21:28.903592616 O: percent expansions identityagent percent 595s 14:21:28.981669154 O: percent expansions forwardagent percent 595s 14:21:29.059193689 O: percent expansions localforward percent 595s 14:21:29.137657387 O: percent expansions remoteforward percent 595s 14:21:29.217053710 O: percent expansions revokedhostkeys percent 595s 14:21:29.294734895 O: percent expansions userknownhostsfile percent 598s 14:21:32.080099674 O: percent expansions controlpath dollar 598s 14:21:32.087914455 O: percent expansions identityagent dollar 598s 14:21:32.095033693 O: percent expansions forwardagent dollar 598s 14:21:32.101941010 O: percent expansions localforward dollar 598s 14:21:32.108921041 O: percent expansions remoteforward dollar 598s 14:21:32.116179221 O: percent expansions userknownhostsfile dollar 598s 14:21:32.392608100 O: percent expansions controlpath tilde 598s 14:21:32.406376327 O: percent expansions identityagent tilde 598s 14:21:32.419821994 O: percent expansions forwardagent tilde 598s 14:21:32.433245835 E: run test addrmatch.sh ... 598s 14:21:32.433761709 O: ok percent expansions 598s 14:21:32.560589507 O: test first entry for user 192.168.0.1 somehost 598s 14:21:32.626364269 O: test negative match for user 192.168.30.1 somehost 598s 14:21:32.691891224 O: test no match for user 19.0.0.1 somehost 598s 14:21:32.757862380 O: test list middle for user 10.255.255.254 somehost 598s 14:21:32.823498180 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 598s 14:21:32.889224736 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 598s 14:21:32.954625967 O: test localaddress for user 19.0.0.1 somehost 599s 14:21:33.019781084 O: test localport for user 19.0.0.1 somehost 599s 14:21:33.094499842 O: test bare IP6 address for user ::1 somehost.example.com 599s 14:21:33.159856277 O: test deny IPv6 for user ::2 somehost.example.com 599s 14:21:33.226606546 O: test IP6 negated for user ::3 somehost 599s 14:21:33.293949982 O: test IP6 no match for user ::4 somehost 599s 14:21:33.360124213 O: test IP6 network for user 2000::1 somehost 599s 14:21:33.425558419 O: test IP6 network for user 2001::1 somehost 599s 14:21:33.491639812 O: test IP6 localaddress for user ::5 somehost 599s 14:21:33.557407146 O: test IP6 localport for user ::5 somehost 599s 14:21:33.623837099 O: test invalid Match address 10.0.1.0/8 599s 14:21:33.630760823 O: test invalid Match localaddress 10.0.1.0/8 599s 14:21:33.636657505 O: test invalid Match address 10.0.0.1/24 599s 14:21:33.642481211 O: test invalid Match localaddress 10.0.0.1/24 599s 14:21:33.648265004 O: test invalid Match address 2000:aa:bb:01::/56 599s 14:21:33.654128287 O: test invalid Match localaddress 2000:aa:bb:01::/56 599s 14:21:33.661183655 E: run test localcommand.sh ... 599s 14:21:33.661706365 O: ok address match 599s 14:21:33.789854761 O: test localcommand: proto localcommand 600s 14:21:34.093632020 O: ok localcommand 600s 14:21:34.094124032 E: run test forcecommand.sh ... 601s 14:21:35.464124521 E: Connection closed. 601s 14:21:35.465426688 E: Connection closed 602s 14:21:36.082475593 E: Connection closed. 602s 14:21:36.083070933 E: Connection closed 602s 14:21:36.389806581 O: ok forced command 602s 14:21:36.390441322 E: run test portnum.sh ... 602s 14:21:36.519477903 O: port number parsing: invalid port 0 602s 14:21:36.524486299 O: port number parsing: invalid port 65536 602s 14:21:36.529243766 O: port number parsing: invalid port 131073 602s 14:21:36.533921841 O: port number parsing: invalid port 2000blah 602s 14:21:36.538583822 O: port number parsing: invalid port blah2000 602s 14:21:36.543406120 O: port number parsing: valid port 1 602s 14:21:36.844796482 O: port number parsing: valid port 22 603s 14:21:37.147250653 O: port number parsing: valid port 2222 603s 14:21:37.449882747 O: port number parsing: valid port 22222 603s 14:21:37.753538161 O: port number parsing: valid port 65535 604s 14:21:38.058884556 E: run test keytype.sh ... 604s 14:21:38.058431909 O: ok port number parsing 604s 14:21:38.189147633 O: keygen ed25519, 512 bits 604s 14:21:38.196802181 O: keygen ed25519-sk, n/a bits 604s 14:21:38.206727182 O: keygen ecdsa, 256 bits 604s 14:21:38.212247339 O: keygen ecdsa, 384 bits 604s 14:21:38.219167539 O: keygen ecdsa, 521 bits 604s 14:21:38.224850651 O: keygen ecdsa-sk, n/a bits 604s 14:21:38.239580638 O: keygen rsa, 2048 bits 604s 14:21:38.305130019 O: keygen rsa, 3072 bits 605s 14:21:39.239046060 O: userkey ed25519-512, hostkey ed25519-512 605s 14:21:39.376773485 O: userkey ed25519-512, hostkey ed25519-512 605s 14:21:39.506360427 O: userkey ed25519-512, hostkey ed25519-512 605s 14:21:39.647290934 O: userkey ed25519-sk, hostkey ed25519-sk 605s 14:21:39.777123774 O: userkey ed25519-sk, hostkey ed25519-sk 605s 14:21:39.906898752 O: userkey ed25519-sk, hostkey ed25519-sk 606s 14:21:40.038502125 O: userkey ecdsa-256, hostkey ecdsa-256 606s 14:21:40.194191899 O: userkey ecdsa-256, hostkey ecdsa-256 606s 14:21:40.358051704 O: userkey ecdsa-256, hostkey ecdsa-256 606s 14:21:40.514476369 O: userkey ecdsa-384, hostkey ecdsa-384 606s 14:21:40.724658152 O: userkey ecdsa-384, hostkey ecdsa-384 606s 14:21:40.937427100 O: userkey ecdsa-384, hostkey ecdsa-384 607s 14:21:41.150634432 O: userkey ecdsa-521, hostkey ecdsa-521 607s 14:21:41.443863872 O: userkey ecdsa-521, hostkey ecdsa-521 607s 14:21:41.737970211 O: userkey ecdsa-521, hostkey ecdsa-521 608s 14:21:42.038238886 O: userkey ecdsa-sk, hostkey ecdsa-sk 608s 14:21:42.204261949 O: userkey ecdsa-sk, hostkey ecdsa-sk 608s 14:21:42.373837312 O: userkey ecdsa-sk, hostkey ecdsa-sk 608s 14:21:42.544960920 O: userkey rsa-2048, hostkey rsa-2048 608s 14:21:42.684026724 O: userkey rsa-2048, hostkey rsa-2048 608s 14:21:42.817498487 O: userkey rsa-2048, hostkey rsa-2048 608s 14:21:42.950089756 O: userkey rsa-3072, hostkey rsa-3072 609s 14:21:43.089343780 O: userkey rsa-3072, hostkey rsa-3072 609s 14:21:43.234655325 O: userkey rsa-3072, hostkey rsa-3072 609s 14:21:43.378736194 O: ok login with different key types 609s 14:21:43.379291714 E: run test kextype.sh ... 609s 14:21:43.518757173 O: kex diffie-hellman-group1-sha1 610s 14:21:44.431574498 O: kex diffie-hellman-group14-sha1 611s 14:21:45.407530155 O: kex diffie-hellman-group14-sha256 612s 14:21:46.327582393 O: kex diffie-hellman-group16-sha512 613s 14:21:47.396501498 O: kex diffie-hellman-group18-sha512 615s 14:21:49.021474742 O: kex diffie-hellman-group-exchange-sha1 616s 14:21:50.651962002 O: kex diffie-hellman-group-exchange-sha256 618s 14:21:52.267382031 O: kex ecdh-sha2-nistp256 619s 14:21:53.152105983 O: kex ecdh-sha2-nistp384 620s 14:21:54.057702240 O: kex ecdh-sha2-nistp521 621s 14:21:55.025876468 O: kex curve25519-sha256 622s 14:21:55.974504022 O: kex curve25519-sha256@libssh.org 622s 14:21:56.916723379 O: kex sntrup761x25519-sha512 624s 14:21:58.119264684 O: kex sntrup761x25519-sha512@openssh.com 625s 14:21:59.337957027 O: kex mlkem768x25519-sha256 626s 14:22:00.274804567 E: run test cert-hostkey.sh ... 626s 14:22:00.275510838 O: ok login with different key exchange algorithms 627s 14:22:00.992239746 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/host_ca_key.pub 627s 14:22:00.992750578 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/host_ca_key2.pub 627s 14:22:00.993242952 O: certified host keys: sign host ed25519 cert 627s 14:22:00.998998269 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 627s 14:22:01.007464904 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 627s 14:22:01.008851196 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 627s 14:22:01.016841977 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 627s 14:22:01.025411807 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 627s 14:22:01.027158072 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 627s 14:22:01.034151275 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 627s 14:22:01.046070283 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 627s 14:22:01.047855911 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 627s 14:22:01.057021377 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 627s 14:22:01.073849214 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 627s 14:22:01.075191815 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 627s 14:22:01.087954471 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 627s 14:22:01.111651916 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 627s 14:22:01.113050686 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 627s 14:22:01.126617478 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 627s 14:22:01.138489858 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 627s 14:22:01.140050199 O: certified host keys: sign host rsa cert 627s 14:22:01.368714118 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 627s 14:22:01.377586189 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 627s 14:22:01.379149949 O: certified host keys: sign host rsa-sha2-256 cert 628s 14:22:02.222765925 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 628s 14:22:02.238154164 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 628s 14:22:02.239651974 O: certified host keys: sign host rsa-sha2-512 cert 629s 14:22:03.148555414 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 629s 14:22:03.164273353 O: Revoking from /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 629s 14:22:03.165876806 O: certified host keys: host ed25519 cert connect 629s 14:22:03.166862812 O: certified host keys: ed25519 basic connect expect success yes 629s 14:22:03.421813474 O: certified host keys: ed25519 empty KRL expect success yes 629s 14:22:03.677410798 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 629s 14:22:03.889982696 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 630s 14:22:04.103334401 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 630s 14:22:04.314644726 O: certified host keys: ed25519 empty plaintext revocation expect success yes 630s 14:22:04.571658242 O: certified host keys: ed25519 plain key plaintext revocation expect success no 630s 14:22:04.782609447 O: certified host keys: ed25519 cert plaintext revocation expect success no 631s 14:22:04.996882990 O: certified host keys: ed25519 CA plaintext revocation expect success no 631s 14:22:05.215145803 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 631s 14:22:05.216247770 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 631s 14:22:05.482982440 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 631s 14:22:05.734461592 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 631s 14:22:05.944880457 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 632s 14:22:06.154841580 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 632s 14:22:06.365275349 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 632s 14:22:06.621156418 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 632s 14:22:06.833510626 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 633s 14:22:07.048685926 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 633s 14:22:07.264335518 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 633s 14:22:07.265428884 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 633s 14:22:07.536653394 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 633s 14:22:07.814950697 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 634s 14:22:08.043101670 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 634s 14:22:08.271726687 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 634s 14:22:08.501214222 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 634s 14:22:08.773512050 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 635s 14:22:09.006002092 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 635s 14:22:09.241443823 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 635s 14:22:09.471800971 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 635s 14:22:09.472933682 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 635s 14:22:09.771416452 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 636s 14:22:10.071926684 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 636s 14:22:10.322955699 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 636s 14:22:10.577742644 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 636s 14:22:10.837056357 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 637s 14:22:11.134119269 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 637s 14:22:11.391720944 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 637s 14:22:11.653979551 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 637s 14:22:11.911136812 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 637s 14:22:11.912229055 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 638s 14:22:12.265853430 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 638s 14:22:12.619950499 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 638s 14:22:12.908547626 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 639s 14:22:13.190323276 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 639s 14:22:13.479185346 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 639s 14:22:13.825221502 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 640s 14:22:14.116849537 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 640s 14:22:14.423800647 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 640s 14:22:14.703668598 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 640s 14:22:14.704736150 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 641s 14:22:14.972764536 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 641s 14:22:15.238079532 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 641s 14:22:15.473761029 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 641s 14:22:15.706031888 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 641s 14:22:15.938025221 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 642s 14:22:16.211367107 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 642s 14:22:16.456429745 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 642s 14:22:16.717003344 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 642s 14:22:16.954339041 O: certified host keys: host rsa cert connect 642s 14:22:16.955568895 O: certified host keys: rsa basic connect expect success yes 643s 14:22:17.228253910 O: certified host keys: rsa empty KRL expect success yes 643s 14:22:17.492705448 O: certified host keys: rsa KRL w/ plain key revoked expect success no 643s 14:22:17.717081865 O: certified host keys: rsa KRL w/ cert revoked expect success no 643s 14:22:17.938322371 O: certified host keys: rsa KRL w/ CA revoked expect success no 644s 14:22:18.164087188 O: certified host keys: rsa empty plaintext revocation expect success yes 644s 14:22:18.430958510 O: certified host keys: rsa plain key plaintext revocation expect success no 644s 14:22:18.670913510 O: certified host keys: rsa cert plaintext revocation expect success no 644s 14:22:18.923708765 O: certified host keys: rsa CA plaintext revocation expect success no 645s 14:22:19.146808048 O: certified host keys: host rsa-sha2-256 cert connect 645s 14:22:19.148020770 O: certified host keys: rsa-sha2-256 basic connect expect success yes 645s 14:22:19.408418582 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 645s 14:22:19.670757379 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 645s 14:22:19.889201365 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 646s 14:22:20.120773113 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 646s 14:22:20.340159248 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 646s 14:22:20.590247241 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 646s 14:22:20.819224020 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 647s 14:22:21.066836911 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 647s 14:22:21.282634991 O: certified host keys: host rsa-sha2-512 cert connect 647s 14:22:21.283515086 O: certified host keys: rsa-sha2-512 basic connect expect success yes 647s 14:22:21.537182957 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 647s 14:22:21.800986081 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 648s 14:22:22.020549560 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 648s 14:22:22.234439972 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 648s 14:22:22.446502745 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 648s 14:22:22.699004332 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 648s 14:22:22.925913328 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 649s 14:22:23.168050574 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 649s 14:22:23.387965068 O: certified host keys: host ed25519 revoked cert 649s 14:22:23.632405919 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 649s 14:22:23.873722644 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 650s 14:22:24.134784881 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 650s 14:22:24.407988405 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 650s 14:22:24.717178010 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 651s 14:22:24.975318393 O: certified host keys: host rsa revoked cert 651s 14:22:25.243610539 O: certified host keys: host rsa-sha2-256 revoked cert 651s 14:22:25.492458219 O: certified host keys: host rsa-sha2-512 revoked cert 651s 14:22:25.744594270 O: certified host keys: host ed25519 revoked cert 651s 14:22:25.957750101 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 652s 14:22:26.168322267 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 652s 14:22:26.393394402 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 652s 14:22:26.638681391 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 652s 14:22:26.925189175 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 653s 14:22:27.160997786 O: certified host keys: host rsa revoked cert 653s 14:22:27.398100804 O: certified host keys: host rsa-sha2-256 revoked cert 653s 14:22:27.611763164 O: certified host keys: host rsa-sha2-512 revoked cert 669s 14:22:43.647372305 O: certified host keys: host ed25519 cert downgrade to raw key 670s 14:22:44.171976604 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 670s 14:22:44.695526151 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 671s 14:22:45.265355531 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 671s 14:22:45.881519748 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 672s 14:22:46.627728387 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 673s 14:22:47.210565551 O: certified host keys: host rsa cert downgrade to raw key 673s 14:22:47.964430163 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 674s 14:22:48.601303954 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 676s 14:22:50.161746284 O: certified host keys: host ed25519 connect wrong cert 676s 14:22:50.384022748 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 676s 14:22:50.612323875 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 676s 14:22:50.845372967 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 677s 14:22:51.120230783 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 677s 14:22:51.445486934 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 677s 14:22:51.702241151 O: certified host keys: host rsa connect wrong cert 679s 14:22:53.138420183 O: certified host keys: host rsa-sha2-256 connect wrong cert 679s 14:22:53.708180481 O: certified host keys: host rsa-sha2-512 connect wrong cert 680s 14:22:54.290490696 O: ok certified host keys 680s 14:22:54.291127538 E: run test cert-userkey.sh ... 680s 14:22:54.684716274 O: certified user keys: sign user ed25519 cert 680s 14:22:54.700122769 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 680s 14:22:54.717719427 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 680s 14:22:54.734087092 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 680s 14:22:54.752564522 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 680s 14:22:54.774350268 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 680s 14:22:54.796757954 O: certified user keys: sign user rsa cert 681s 14:22:55.570873195 O: certified user keys: sign user rsa-sha2-256 cert 681s 14:22:55.856199137 O: certified user keys: sign user rsa-sha2-512 cert 682s 14:22:56.790816142 O: certified user keys: ed25519 missing authorized_principals 683s 14:22:57.089548035 O: certified user keys: ed25519 empty authorized_principals 683s 14:22:57.390334051 O: certified user keys: ed25519 wrong authorized_principals 683s 14:22:57.692389557 O: certified user keys: ed25519 correct authorized_principals 684s 14:22:57.996911331 O: certified user keys: ed25519 authorized_principals bad key opt 684s 14:22:58.297296063 O: certified user keys: ed25519 authorized_principals command=false 684s 14:22:58.601602332 O: certified user keys: ed25519 authorized_principals command=true 684s 14:22:58.902008500 O: certified user keys: ed25519 wrong principals key option 685s 14:22:59.199884380 O: certified user keys: ed25519 correct principals key option 685s 14:22:59.509149274 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 685s 14:22:59.824873306 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 686s 14:23:00.139240851 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 686s 14:23:00.453569336 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 686s 14:23:00.758150079 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 687s 14:23:01.074271695 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 687s 14:23:01.390379790 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 687s 14:23:01.704060521 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 688s 14:23:02.014291876 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 688s 14:23:02.322098977 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 688s 14:23:02.627822413 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 688s 14:23:02.932364545 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 689s 14:23:03.232302114 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 689s 14:23:03.552500139 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 689s 14:23:03.857747709 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 690s 14:23:04.173330587 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 690s 14:23:04.487959827 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 690s 14:23:04.792160006 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 691s 14:23:05.106434067 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 691s 14:23:05.428732984 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 691s 14:23:05.750641848 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 692s 14:23:06.079610834 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 692s 14:23:06.422910352 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 692s 14:23:06.741764366 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 693s 14:23:07.071871914 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 693s 14:23:07.406262747 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 693s 14:23:07.740865617 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 694s 14:23:08.081676711 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 694s 14:23:08.452704587 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 694s 14:23:08.824401339 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 695s 14:23:09.197715705 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 695s 14:23:09.583304718 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 695s 14:23:09.953737955 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 696s 14:23:10.333777050 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 696s 14:23:10.717664081 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 697s 14:23:11.092661872 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 697s 14:23:11.477307331 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 697s 14:23:11.795844199 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 698s 14:23:12.106703241 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 698s 14:23:12.417623397 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 698s 14:23:12.730683984 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 699s 14:23:13.043442048 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 699s 14:23:13.355972516 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 699s 14:23:13.672335049 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 700s 14:23:13.980250150 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 700s 14:23:14.297771544 O: certified user keys: rsa missing authorized_principals 700s 14:23:14.598346202 O: certified user keys: rsa empty authorized_principals 700s 14:23:14.900827592 O: certified user keys: rsa wrong authorized_principals 701s 14:23:15.206417615 O: certified user keys: rsa correct authorized_principals 701s 14:23:15.512377453 O: certified user keys: rsa authorized_principals bad key opt 701s 14:23:15.812229070 O: certified user keys: rsa authorized_principals command=false 702s 14:23:16.120844271 O: certified user keys: rsa authorized_principals command=true 702s 14:23:16.432823178 O: certified user keys: rsa wrong principals key option 702s 14:23:16.725690823 O: certified user keys: rsa correct principals key option 703s 14:23:17.032142143 O: certified user keys: rsa-sha2-256 missing authorized_principals 703s 14:23:17.332473035 O: certified user keys: rsa-sha2-256 empty authorized_principals 703s 14:23:17.633082097 O: certified user keys: rsa-sha2-256 wrong authorized_principals 703s 14:23:17.938478981 O: certified user keys: rsa-sha2-256 correct authorized_principals 704s 14:23:18.243072616 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 704s 14:23:18.539506914 O: certified user keys: rsa-sha2-256 authorized_principals command=false 704s 14:23:18.844322945 O: certified user keys: rsa-sha2-256 authorized_principals command=true 705s 14:23:19.149645226 O: certified user keys: rsa-sha2-256 wrong principals key option 705s 14:23:19.448474787 O: certified user keys: rsa-sha2-256 correct principals key option 705s 14:23:19.762855171 O: certified user keys: rsa-sha2-512 missing authorized_principals 706s 14:23:20.070861771 O: certified user keys: rsa-sha2-512 empty authorized_principals 706s 14:23:20.378473644 O: certified user keys: rsa-sha2-512 wrong authorized_principals 706s 14:23:20.686263444 O: certified user keys: rsa-sha2-512 correct authorized_principals 707s 14:23:20.990117380 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 707s 14:23:21.290948956 O: certified user keys: rsa-sha2-512 authorized_principals command=false 707s 14:23:21.599767936 O: certified user keys: rsa-sha2-512 authorized_principals command=true 707s 14:23:21.913073918 O: certified user keys: rsa-sha2-512 wrong principals key option 708s 14:23:22.212013396 O: certified user keys: rsa-sha2-512 correct principals key option 708s 14:23:22.534300782 O: certified user keys: ed25519 authorized_keys connect 708s 14:23:22.836660230 O: certified user keys: ed25519 authorized_keys revoked key 709s 14:23:23.144267211 O: certified user keys: ed25519 authorized_keys revoked via KRL 709s 14:23:23.459215198 O: certified user keys: ed25519 authorized_keys empty KRL 709s 14:23:23.825360384 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 710s 14:23:24.139582748 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 710s 14:23:24.437803215 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 710s 14:23:24.735192645 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 711s 14:23:25.033074157 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 711s 14:23:25.340041340 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 711s 14:23:25.650669746 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 711s 14:23:25.948592049 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 712s 14:23:26.254391671 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 712s 14:23:26.577708655 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 712s 14:23:26.904887454 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 713s 14:23:27.237940014 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 713s 14:23:27.571815121 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 714s 14:23:27.988499161 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 714s 14:23:28.379629995 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 714s 14:23:28.728087788 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 715s 14:23:29.103014720 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 715s 14:23:29.419414319 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 715s 14:23:29.737612771 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 716s 14:23:30.043573683 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 716s 14:23:30.365869545 O: certified user keys: rsa authorized_keys connect 716s 14:23:30.674438789 O: certified user keys: rsa authorized_keys revoked key 716s 14:23:30.965567650 O: certified user keys: rsa authorized_keys revoked via KRL 717s 14:23:31.263358149 O: certified user keys: rsa authorized_keys empty KRL 717s 14:23:31.568255469 O: certified user keys: rsa-sha2-256 authorized_keys connect 717s 14:23:31.876326164 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 718s 14:23:32.173155121 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 718s 14:23:32.467826137 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 718s 14:23:32.772436746 O: certified user keys: rsa-sha2-512 authorized_keys connect 719s 14:23:33.091616745 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 719s 14:23:33.406204362 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 719s 14:23:33.721767130 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 720s 14:23:34.037636131 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 720s 14:23:34.339300849 O: certified user keys: authorized_keys CA does not authenticate 720s 14:23:34.340203751 O: certified user keys: ensure CA key does not authenticate user 720s 14:23:34.633145935 O: certified user keys: ed25519 TrustedUserCAKeys connect 720s 14:23:34.953853408 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 721s 14:23:35.252054304 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 721s 14:23:35.550069768 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 721s 14:23:35.851769044 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 722s 14:23:36.156128360 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 722s 14:23:36.483034203 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 722s 14:23:36.794561321 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 723s 14:23:37.101545096 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 723s 14:23:37.408606602 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 723s 14:23:37.722747164 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 724s 14:23:38.043106707 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 724s 14:23:38.358462332 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 724s 14:23:38.693650106 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 725s 14:23:39.023179587 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 725s 14:23:39.359717755 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 725s 14:23:39.697424529 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 726s 14:23:40.078519412 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 726s 14:23:40.451684395 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 726s 14:23:40.823056532 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 727s 14:23:41.204149591 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 727s 14:23:41.537218843 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 727s 14:23:41.860760778 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 728s 14:23:42.180880394 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 728s 14:23:42.500387648 O: certified user keys: rsa TrustedUserCAKeys connect 728s 14:23:42.817434993 O: certified user keys: rsa TrustedUserCAKeys revoked key 729s 14:23:43.120578558 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 729s 14:23:43.440162854 O: certified user keys: rsa TrustedUserCAKeys empty KRL 729s 14:23:43.759518117 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 730s 14:23:44.069560962 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 730s 14:23:44.380704226 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 730s 14:23:44.688468483 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 731s 14:23:45.003797266 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 731s 14:23:45.313298623 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 731s 14:23:45.613606567 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 731s 14:23:45.916149735 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 732s 14:23:46.226152840 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 732s 14:23:46.521537281 O: certified user keys: TrustedUserCAKeys CA does not authenticate 732s 14:23:46.522795388 O: certified user keys: ensure CA key does not authenticate user 732s 14:23:46.817943912 O: certified user keys: correct principal auth authorized_keys expect success rsa 733s 14:23:47.139362024 O: certified user keys: correct principal auth authorized_keys expect success ed25519 733s 14:23:47.461676454 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 733s 14:23:47.786831249 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 734s 14:23:48.102755624 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 734s 14:23:48.406865086 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 734s 14:23:48.711595261 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 735s 14:23:49.014850215 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 735s 14:23:49.318985293 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 735s 14:23:49.631052574 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 735s 14:23:49.952899021 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 736s 14:23:50.266187967 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 736s 14:23:50.577465242 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 736s 14:23:50.889785506 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 737s 14:23:51.205288796 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 737s 14:23:51.517009961 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 737s 14:23:51.830742715 O: certified user keys: cert expired auth authorized_keys expect failure rsa 738s 14:23:52.144427931 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 738s 14:23:52.456431510 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 738s 14:23:52.774726671 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 739s 14:23:53.089177690 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 739s 14:23:53.410117344 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 739s 14:23:53.726403292 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 740s 14:23:54.052614724 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 740s 14:23:54.367228175 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 740s 14:23:54.687856681 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 741s 14:23:55.007182634 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 741s 14:23:55.326043894 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 741s 14:23:55.647400424 O: certified user keys: force-command auth authorized_keys expect failure rsa 742s 14:23:55.977047077 O: certified user keys: force-command auth authorized_keys expect failure ed25519 742s 14:23:56.300184691 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 742s 14:23:56.627281218 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 742s 14:23:56.952165341 O: certified user keys: empty principals auth authorized_keys expect success rsa 743s 14:23:57.279735967 O: certified user keys: empty principals auth authorized_keys expect success ed25519 743s 14:23:57.607661472 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 743s 14:23:57.925858675 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 744s 14:23:58.245261550 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 744s 14:23:58.567290260 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 744s 14:23:58.893141265 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 745s 14:23:59.225658811 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 745s 14:23:59.548400949 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 745s 14:23:59.870613358 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 746s 14:24:00.189251885 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 746s 14:24:00.520268806 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 746s 14:24:00.839984717 O: certified user keys: force-command match true auth authorized_keys expect success rsa 747s 14:24:01.163217647 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 747s 14:24:01.489486172 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 747s 14:24:01.836813939 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 748s 14:24:02.162408660 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 748s 14:24:02.475200803 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 748s 14:24:02.787660922 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 749s 14:24:03.106601135 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 749s 14:24:03.425783382 O: certified user keys: user ed25519 connect wrong cert 749s 14:24:03.738899752 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 750s 14:24:04.038107398 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 750s 14:24:04.352862423 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 750s 14:24:04.665612040 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 750s 14:24:04.969411262 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 751s 14:24:05.295772429 O: certified user keys: user rsa connect wrong cert 751s 14:24:05.626063006 O: certified user keys: user rsa-sha2-256 connect wrong cert 751s 14:24:05.936746892 O: certified user keys: user rsa-sha2-512 connect wrong cert 752s 14:24:06.238008965 E: run test host-expand.sh ... 752s 14:24:06.237469365 O: ok certified user keys 752s 14:24:06.673534767 O: ok expand %h and %n 752s 14:24:06.674671459 E: run test keys-command.sh ... 752s 14:24:06.824888156 O: SKIPPED: /var/run/keycommand_openssh-tests.43364 not executable (/var/run mounted noexec?) 752s 14:24:06.829043226 E: run test forward-control.sh ... 754s 14:24:08.744050774 O: check_lfwd done (expecting Y): default configuration 756s 14:24:10.389581179 O: check_rfwd done (expecting Y): default configuration 758s 14:24:12.045110042 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 758s 14:24:12.677287313 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 760s 14:24:13.996919008 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 761s 14:24:15.619946763 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 763s 14:24:17.256158360 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 763s 14:24:17.938794095 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 764s 14:24:18.267317606 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 765s 14:24:19.919457163 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 767s 14:24:21.566772838 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 769s 14:24:23.220465530 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 770s 14:24:24.552758997 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 770s 14:24:24.864053107 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 772s 14:24:26.186734275 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 772s 14:24:26.489426197 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 774s 14:24:28.114506974 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 775s 14:24:29.749345170 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 777s 14:24:31.364062787 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 777s 14:24:31.666639802 O: check_rfwd done (expecting N): AllowTcpForwarding=local 779s 14:24:32.991222192 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 779s 14:24:33.298950278 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 780s 14:24:34.925190037 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 781s 14:24:35.227415751 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 781s 14:24:35.555910941 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 781s 14:24:35.896362026 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 782s 14:24:36.533096968 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 782s 14:24:36.836031113 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 784s 14:24:38.157358936 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 784s 14:24:38.476357084 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 785s 14:24:39.798056976 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 786s 14:24:40.115742569 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 787s 14:24:41.765065377 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 788s 14:24:42.085756304 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 788s 14:24:42.422501247 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 790s 14:24:44.131810421 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 791s 14:24:45.469376367 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 793s 14:24:47.112264507 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 794s 14:24:48.432247632 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 796s 14:24:50.060753993 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 797s 14:24:51.383727169 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 798s 14:24:52.040430697 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 799s 14:24:53.367505058 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 800s 14:24:53.998164092 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 801s 14:24:55.335776473 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 801s 14:24:55.662262391 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 802s 14:24:56.003892572 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 802s 14:24:56.331345752 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 803s 14:24:57.670017508 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 805s 14:24:59.322656107 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 806s 14:25:00.659368393 O: check_lfwd done (expecting N): AllowTcpForwarding=no 807s 14:25:00.976135706 O: check_rfwd done (expecting N): AllowTcpForwarding=no 808s 14:25:02.317635097 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 808s 14:25:02.670011372 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 810s 14:25:04.024476838 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 810s 14:25:04.330040091 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 810s 14:25:04.672807656 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 811s 14:25:04.991087411 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 812s 14:25:06.324231129 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 812s 14:25:06.637519436 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 813s 14:25:07.969634859 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 814s 14:25:08.285164164 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 814s 14:25:08.625362323 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 814s 14:25:08.945276950 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 816s 14:25:10.310494119 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 816s 14:25:10.625484021 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 817s 14:25:11.286941260 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 817s 14:25:11.616626521 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 819s 14:25:13.270297630 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 820s 14:25:14.941194880 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 822s 14:25:16.599874797 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 822s 14:25:16.914926494 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 824s 14:25:18.591130775 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 824s 14:25:18.918137307 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 826s 14:25:20.265566630 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 826s 14:25:20.590788346 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 827s 14:25:21.931609405 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 829s 14:25:23.592762280 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 830s 14:25:24.928934103 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 831s 14:25:25.250272660 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 832s 14:25:26.586047201 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 832s 14:25:26.920078421 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 832s 14:25:26.933401546 O: ok sshd control of local and remote forwarding 832s 14:25:26.934040156 E: run test integrity.sh ... 833s 14:25:27.104734729 O: test integrity: hmac-sha1 @2900 833s 14:25:27.429856882 O: test integrity: hmac-sha1 @2901 833s 14:25:27.753887255 O: test integrity: hmac-sha1 @2902 834s 14:25:28.093725343 O: test integrity: hmac-sha1 @2903 834s 14:25:28.399459512 O: test integrity: hmac-sha1 @2904 834s 14:25:28.717158732 O: test integrity: hmac-sha1 @2905 835s 14:25:29.023292691 O: test integrity: hmac-sha1 @2906 835s 14:25:29.331875781 O: test integrity: hmac-sha1 @2907 835s 14:25:29.639890793 O: test integrity: hmac-sha1 @2908 835s 14:25:29.946106138 O: test integrity: hmac-sha1 @2909 836s 14:25:30.249009036 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 836s 14:25:30.258692673 O: test integrity: hmac-sha1-96 @2900 836s 14:25:30.573149412 O: test integrity: hmac-sha1-96 @2901 836s 14:25:30.888901893 O: test integrity: hmac-sha1-96 @2902 837s 14:25:31.201501215 O: test integrity: hmac-sha1-96 @2903 837s 14:25:31.512536390 O: test integrity: hmac-sha1-96 @2904 837s 14:25:31.831597332 O: test integrity: hmac-sha1-96 @2905 838s 14:25:32.152712548 O: test integrity: hmac-sha1-96 @2906 838s 14:25:32.474610493 O: test integrity: hmac-sha1-96 @2907 838s 14:25:32.789423458 O: test integrity: hmac-sha1-96 @2908 839s 14:25:33.117634036 O: test integrity: hmac-sha1-96 @2909 839s 14:25:33.455940717 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 839s 14:25:33.466360132 O: test integrity: hmac-sha2-256 @2900 839s 14:25:33.817107862 O: test integrity: hmac-sha2-256 @2901 840s 14:25:34.143814699 O: test integrity: hmac-sha2-256 @2902 840s 14:25:34.466141121 O: test integrity: hmac-sha2-256 @2903 840s 14:25:34.795432846 O: test integrity: hmac-sha2-256 @2904 841s 14:25:35.121359062 O: test integrity: hmac-sha2-256 @2905 841s 14:25:35.444955493 O: test integrity: hmac-sha2-256 @2906 841s 14:25:35.760382632 O: test integrity: hmac-sha2-256 @2907 842s 14:25:36.077007020 O: test integrity: hmac-sha2-256 @2908 842s 14:25:36.398180764 O: test integrity: hmac-sha2-256 @2909 842s 14:25:36.746827880 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 842s 14:25:36.757217112 O: test integrity: hmac-sha2-512 @2900 843s 14:25:37.072832679 O: test integrity: hmac-sha2-512 @2901 843s 14:25:37.369275544 O: test integrity: hmac-sha2-512 @2902 843s 14:25:37.661071832 O: test integrity: hmac-sha2-512 @2903 843s 14:25:37.958547294 O: test integrity: hmac-sha2-512 @2904 844s 14:25:38.247699933 O: test integrity: hmac-sha2-512 @2905 844s 14:25:38.540006698 O: test integrity: hmac-sha2-512 @2906 844s 14:25:38.833285997 O: test integrity: hmac-sha2-512 @2907 845s 14:25:39.122278334 O: test integrity: hmac-sha2-512 @2908 845s 14:25:39.406188030 O: test integrity: hmac-sha2-512 @2909 845s 14:25:39.685754988 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 845s 14:25:39.695530917 O: test integrity: hmac-md5 @2900 846s 14:25:40.015089193 O: test integrity: hmac-md5 @2901 846s 14:25:40.327723103 O: test integrity: hmac-md5 @2902 846s 14:25:40.636037189 O: test integrity: hmac-md5 @2903 846s 14:25:40.950623329 O: test integrity: hmac-md5 @2904 847s 14:25:41.261842593 O: test integrity: hmac-md5 @2905 847s 14:25:41.575845387 O: test integrity: hmac-md5 @2906 847s 14:25:41.896566893 O: test integrity: hmac-md5 @2907 848s 14:25:42.214944772 O: test integrity: hmac-md5 @2908 848s 14:25:42.525237963 O: test integrity: hmac-md5 @2909 848s 14:25:42.829670577 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 848s 14:25:42.838858414 O: test integrity: hmac-md5-96 @2900 849s 14:25:43.153171535 O: test integrity: hmac-md5-96 @2901 849s 14:25:43.468426100 O: test integrity: hmac-md5-96 @2902 849s 14:25:43.795975820 O: test integrity: hmac-md5-96 @2903 850s 14:25:44.120073724 O: test integrity: hmac-md5-96 @2904 850s 14:25:44.436051990 O: test integrity: hmac-md5-96 @2905 850s 14:25:44.755356289 O: test integrity: hmac-md5-96 @2906 851s 14:25:45.081804820 O: test integrity: hmac-md5-96 @2907 851s 14:25:45.402453388 O: test integrity: hmac-md5-96 @2908 851s 14:25:45.718072759 O: test integrity: hmac-md5-96 @2909 852s 14:25:46.050465887 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 852s 14:25:46.060895627 O: test integrity: umac-64@openssh.com @2900 852s 14:25:46.376273094 O: test integrity: umac-64@openssh.com @2901 852s 14:25:46.696253789 O: test integrity: umac-64@openssh.com @2902 853s 14:25:47.031154087 O: test integrity: umac-64@openssh.com @2903 853s 14:25:47.348587260 O: test integrity: umac-64@openssh.com @2904 853s 14:25:47.657632484 O: test integrity: umac-64@openssh.com @2905 853s 14:25:47.967027617 O: test integrity: umac-64@openssh.com @2906 854s 14:25:48.284463878 O: test integrity: umac-64@openssh.com @2907 854s 14:25:48.604604895 O: test integrity: umac-64@openssh.com @2908 854s 14:25:48.914173441 O: test integrity: umac-64@openssh.com @2909 855s 14:25:49.218071448 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 855s 14:25:49.227221984 O: test integrity: umac-128@openssh.com @2900 855s 14:25:49.544609205 O: test integrity: umac-128@openssh.com @2901 855s 14:25:49.864729634 O: test integrity: umac-128@openssh.com @2902 856s 14:25:50.177443009 O: test integrity: umac-128@openssh.com @2903 856s 14:25:50.495122135 O: test integrity: umac-128@openssh.com @2904 856s 14:25:50.815963869 O: test integrity: umac-128@openssh.com @2905 857s 14:25:51.131485429 O: test integrity: umac-128@openssh.com @2906 857s 14:25:51.451311943 O: test integrity: umac-128@openssh.com @2907 857s 14:25:51.767442593 O: test integrity: umac-128@openssh.com @2908 858s 14:25:52.090472746 O: test integrity: umac-128@openssh.com @2909 858s 14:25:52.411431869 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 858s 14:25:52.421671829 O: test integrity: hmac-sha1-etm@openssh.com @2900 858s 14:25:52.739408144 O: test integrity: hmac-sha1-etm@openssh.com @2901 859s 14:25:53.059113665 O: test integrity: hmac-sha1-etm@openssh.com @2902 859s 14:25:53.372333725 O: test integrity: hmac-sha1-etm@openssh.com @2903 859s 14:25:53.686303581 O: test integrity: hmac-sha1-etm@openssh.com @2904 860s 14:25:54.005832538 O: test integrity: hmac-sha1-etm@openssh.com @2905 860s 14:25:54.314646065 O: test integrity: hmac-sha1-etm@openssh.com @2906 860s 14:25:54.632619400 O: test integrity: hmac-sha1-etm@openssh.com @2907 860s 14:25:54.948602674 O: test integrity: hmac-sha1-etm@openssh.com @2908 861s 14:25:55.257654889 O: test integrity: hmac-sha1-etm@openssh.com @2909 861s 14:25:55.559389361 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 861s 14:25:55.568767367 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 861s 14:25:55.875814276 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 862s 14:25:56.183605991 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 862s 14:25:56.491729854 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 862s 14:25:56.795158052 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 863s 14:25:57.104794014 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 863s 14:25:57.424182658 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 863s 14:25:57.738244670 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 864s 14:25:58.042361042 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 864s 14:25:58.347536634 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 864s 14:25:58.648856689 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 864s 14:25:58.657894475 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 864s 14:25:58.965765735 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 865s 14:25:59.275435471 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 865s 14:25:59.581106397 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 865s 14:25:59.887537819 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 866s 14:26:00.195194557 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 866s 14:26:00.500608794 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 866s 14:26:00.814757213 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 867s 14:26:01.129110472 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 867s 14:26:01.438402138 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 867s 14:26:01.738667482 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 867s 14:26:01.748107019 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 868s 14:26:02.038602116 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 868s 14:26:02.328228930 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 868s 14:26:02.622739324 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 868s 14:26:02.913248786 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 869s 14:26:03.202805840 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 869s 14:26:03.495963847 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 869s 14:26:03.787207803 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 870s 14:26:04.077736421 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 870s 14:26:04.366411968 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 870s 14:26:04.649871746 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 870s 14:26:04.659790689 O: test integrity: hmac-md5-etm@openssh.com @2900 871s 14:26:04.971488606 O: test integrity: hmac-md5-etm@openssh.com @2901 871s 14:26:05.281659363 O: test integrity: hmac-md5-etm@openssh.com @2902 871s 14:26:05.589738566 O: test integrity: hmac-md5-etm@openssh.com @2903 871s 14:26:05.899576752 O: test integrity: hmac-md5-etm@openssh.com @2904 872s 14:26:06.206422357 O: test integrity: hmac-md5-etm@openssh.com @2905 872s 14:26:06.516089962 O: test integrity: hmac-md5-etm@openssh.com @2906 872s 14:26:06.826272750 O: test integrity: hmac-md5-etm@openssh.com @2907 873s 14:26:07.137829330 O: test integrity: hmac-md5-etm@openssh.com @2908 873s 14:26:07.448037575 O: test integrity: hmac-md5-etm@openssh.com @2909 873s 14:26:07.753201089 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 873s 14:26:07.761819764 O: test integrity: hmac-md5-96-etm@openssh.com @2900 874s 14:26:08.063278967 O: test integrity: hmac-md5-96-etm@openssh.com @2901 874s 14:26:08.368428755 O: test integrity: hmac-md5-96-etm@openssh.com @2902 874s 14:26:08.678093908 O: test integrity: hmac-md5-96-etm@openssh.com @2903 875s 14:26:08.983463479 O: test integrity: hmac-md5-96-etm@openssh.com @2904 875s 14:26:09.295382970 O: test integrity: hmac-md5-96-etm@openssh.com @2905 875s 14:26:09.603060712 O: test integrity: hmac-md5-96-etm@openssh.com @2906 875s 14:26:09.910648336 O: test integrity: hmac-md5-96-etm@openssh.com @2907 876s 14:26:10.219122516 O: test integrity: hmac-md5-96-etm@openssh.com @2908 876s 14:26:10.529760593 O: test integrity: hmac-md5-96-etm@openssh.com @2909 876s 14:26:10.834287284 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 876s 14:26:10.843202506 O: test integrity: umac-64-etm@openssh.com @2900 877s 14:26:11.155284504 O: test integrity: umac-64-etm@openssh.com @2901 877s 14:26:11.467825834 O: test integrity: umac-64-etm@openssh.com @2902 877s 14:26:11.778970385 O: test integrity: umac-64-etm@openssh.com @2903 878s 14:26:12.093768023 O: test integrity: umac-64-etm@openssh.com @2904 878s 14:26:12.406901027 O: test integrity: umac-64-etm@openssh.com @2905 878s 14:26:12.720406673 O: test integrity: umac-64-etm@openssh.com @2906 879s 14:26:13.033860434 O: test integrity: umac-64-etm@openssh.com @2907 879s 14:26:13.353123616 O: test integrity: umac-64-etm@openssh.com @2908 879s 14:26:13.666411286 O: test integrity: umac-64-etm@openssh.com @2909 880s 14:26:13.995316622 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 880s 14:26:14.004227815 O: test integrity: umac-128-etm@openssh.com @2900 880s 14:26:14.334074250 O: test integrity: umac-128-etm@openssh.com @2901 880s 14:26:14.639546111 O: test integrity: umac-128-etm@openssh.com @2902 881s 14:26:14.966092564 O: test integrity: umac-128-etm@openssh.com @2903 881s 14:26:15.282152388 O: test integrity: umac-128-etm@openssh.com @2904 881s 14:26:15.587744358 O: test integrity: umac-128-etm@openssh.com @2905 881s 14:26:15.897103041 O: test integrity: umac-128-etm@openssh.com @2906 882s 14:26:16.226972988 O: test integrity: umac-128-etm@openssh.com @2907 882s 14:26:16.538932900 O: test integrity: umac-128-etm@openssh.com @2908 882s 14:26:16.852379488 O: test integrity: umac-128-etm@openssh.com @2909 883s 14:26:17.151127729 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 883s 14:26:17.161652328 O: test integrity: aes128-gcm@openssh.com @2900 883s 14:26:17.452624816 O: test integrity: aes128-gcm@openssh.com @2901 883s 14:26:17.737588814 O: test integrity: aes128-gcm@openssh.com @2902 884s 14:26:18.023621094 O: test integrity: aes128-gcm@openssh.com @2903 884s 14:26:18.310950839 O: test integrity: aes128-gcm@openssh.com @2904 884s 14:26:18.600480671 O: test integrity: aes128-gcm@openssh.com @2905 884s 14:26:18.887511566 O: test integrity: aes128-gcm@openssh.com @2906 885s 14:26:19.179691950 O: test integrity: aes128-gcm@openssh.com @2907 885s 14:26:19.465799439 O: test integrity: aes128-gcm@openssh.com @2908 885s 14:26:19.753238581 O: test integrity: aes128-gcm@openssh.com @2909 886s 14:26:20.030217352 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 886s 14:26:20.039648640 O: test integrity: aes256-gcm@openssh.com @2900 886s 14:26:20.325125368 O: test integrity: aes256-gcm@openssh.com @2901 886s 14:26:20.606420280 O: test integrity: aes256-gcm@openssh.com @2902 886s 14:26:20.887665268 O: test integrity: aes256-gcm@openssh.com @2903 887s 14:26:21.172866576 O: test integrity: aes256-gcm@openssh.com @2904 887s 14:26:21.455599665 O: test integrity: aes256-gcm@openssh.com @2905 887s 14:26:21.738308396 O: test integrity: aes256-gcm@openssh.com @2906 888s 14:26:22.017602158 O: test integrity: aes256-gcm@openssh.com @2907 888s 14:26:22.296550592 O: test integrity: aes256-gcm@openssh.com @2908 888s 14:26:22.577698191 O: test integrity: aes256-gcm@openssh.com @2909 888s 14:26:22.857041304 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 888s 14:26:22.866486802 O: test integrity: chacha20-poly1305@openssh.com @2900 889s 14:26:23.154733802 O: test integrity: chacha20-poly1305@openssh.com @2901 889s 14:26:23.438823571 O: test integrity: chacha20-poly1305@openssh.com @2902 889s 14:26:23.731513486 O: test integrity: chacha20-poly1305@openssh.com @2903 890s 14:26:24.019816252 O: test integrity: chacha20-poly1305@openssh.com @2904 890s 14:26:24.304213991 O: test integrity: chacha20-poly1305@openssh.com @2905 890s 14:26:24.590251970 O: test integrity: chacha20-poly1305@openssh.com @2906 890s 14:26:24.877249860 O: test integrity: chacha20-poly1305@openssh.com @2907 891s 14:26:25.162810407 O: test integrity: chacha20-poly1305@openssh.com @2908 891s 14:26:25.448766422 O: test integrity: chacha20-poly1305@openssh.com @2909 891s 14:26:25.727035487 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 891s 14:26:25.727954978 O: ok integrity 891s 14:26:25.728502007 E: run test krl.sh ... 891s 14:26:25.878784506 O: key revocation lists: generating test keys 892s 14:26:26.601303845 O: key revocation lists: generating KRLs 892s 14:26:26.734066240 O: key revocation lists: checking revocations for revoked keys 893s 14:26:27.206223345 O: key revocation lists: checking revocations for unrevoked keys 893s 14:26:27.591792326 O: key revocation lists: checking revocations for revoked certs 894s 14:26:28.369120225 O: key revocation lists: checking revocations for unrevoked certs 895s 14:26:29.102895273 O: key revocation lists: testing KRL update 896s 14:26:30.262101105 O: key revocation lists: checking revocations for revoked keys 896s 14:26:30.762183123 O: key revocation lists: checking revocations for unrevoked keys 897s 14:26:31.157363921 O: key revocation lists: checking revocations for revoked certs 897s 14:26:31.945314530 O: key revocation lists: checking revocations for unrevoked certs 898s 14:26:32.636320476 O: ok key revocation lists 898s 14:26:32.637068640 E: run test multipubkey.sh ... 901s 14:26:35.424056969 E: run test limit-keytype.sh ... 901s 14:26:35.423255850 O: ok multiple pubkey 903s 14:26:37.541830282 O: allow rsa,ed25519 904s 14:26:38.462581309 O: allow ed25519 905s 14:26:39.348711677 O: allow cert only 906s 14:26:40.252216496 O: match w/ no match 907s 14:26:41.158203676 O: match w/ matching 908s 14:26:42.153980832 E: run test hostkey-agent.sh ... 908s 14:26:42.153243513 O: ok restrict pubkey type 908s 14:26:42.768140958 O: key type ssh-ed25519 908s 14:26:42.916947408 O: key type sk-ssh-ed25519@openssh.com 909s 14:26:43.063001892 O: key type ecdsa-sha2-nistp256 909s 14:26:43.214080366 O: key type ecdsa-sha2-nistp384 909s 14:26:43.388567198 O: key type ecdsa-sha2-nistp521 909s 14:26:43.589542930 O: key type sk-ecdsa-sha2-nistp256@openssh.com 909s 14:26:43.747661271 O: key type ssh-rsa 909s 14:26:43.902934019 O: cert type ssh-ed25519-cert-v01@openssh.com 910s 14:26:44.136265006 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 910s 14:26:44.375925653 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 910s 14:26:44.616983356 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 910s 14:26:44.865604920 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 911s 14:26:45.143284872 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 911s 14:26:45.394480564 O: cert type ssh-rsa-cert-v01@openssh.com 911s 14:26:45.635578607 O: cert type rsa-sha2-256-cert-v01@openssh.com 911s 14:26:45.874185220 O: cert type rsa-sha2-512-cert-v01@openssh.com 912s 14:26:46.115960718 O: ok hostkey agent 912s 14:26:46.116624095 E: run test hostkey-rotate.sh ... 913s 14:26:47.160775691 O: learn hostkey with StrictHostKeyChecking=no 913s 14:26:47.410091604 O: learn additional hostkeys 913s 14:26:47.712170024 O: learn additional hostkeys, type=ssh-ed25519 914s 14:26:47.998876375 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 914s 14:26:48.299896434 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 914s 14:26:48.594803146 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 914s 14:26:48.899916421 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 915s 14:26:49.216636303 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 915s 14:26:49.512732681 O: learn additional hostkeys, type=ssh-rsa 915s 14:26:49.809950211 O: learn changed non-primary hostkey type=ssh-rsa 917s 14:26:51.071314988 O: learn new primary hostkey 917s 14:26:51.383649879 O: rotate primary hostkey 917s 14:26:51.704408828 O: check rotate primary hostkey 918s 14:26:52.024919042 E: run test principals-command.sh ... 918s 14:26:52.024410198 O: ok hostkey rotate 919s 14:26:53.370574125 O: SKIPPED: /var/run/principals_command_openssh-tests.59417 not executable (/var/run mounted noexec?) 919s 14:26:53.374588776 E: run test cert-file.sh ... 919s 14:26:53.544132674 O: identity cert with no plain public file 919s 14:26:53.864363070 O: CertificateFile with no plain public file 920s 14:26:54.177623380 O: plain keys 920s 14:26:54.507174456 O: untrusted cert 920s 14:26:54.826631812 O: good cert, bad key 921s 14:26:55.176358072 O: single trusted 921s 14:26:55.487024024 O: multiple trusted 922s 14:26:56.805596107 E: run test cfginclude.sh ... 922s 14:26:56.805081193 O: ok ssh with certificates 922s 14:26:56.945085274 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 14:26:56.950624597 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 14:26:56.956095887 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 14:26:56.961518372 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 14:26:56.966973564 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:56.972532412 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:56.978715992 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:56.984231255 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:56.989774308 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.001964679 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.006894429 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.011778435 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.021065083 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.027284917 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.032917707 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.038577657 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.044002578 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.049593662 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.055043622 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.060424485 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.065822684 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.077965187 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.082547357 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.087842857 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.097751132 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.103171159 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 923s 14:26:57.106151915 O: ok config include 923s 14:26:57.106279296 E: run test servcfginclude.sh ... 923s 14:26:57.383159738 E: run test allow-deny-users.sh ... 923s 14:26:57.383630787 O: ok server config include 926s 14:27:00.068661230 O: ok AllowUsers/DenyUsers 926s 14:27:00.068731397 E: run test authinfo.sh ... 926s 14:27:00.201907147 O: ExposeAuthInfo=no 926s 14:27:00.510836407 O: ExposeAuthInfo=yes 926s 14:27:00.821509957 O: ok authinfo 926s 14:27:00.822031268 E: run test sshsig.sh ... 927s 14:27:00.970415974 O: sshsig: make certificates 927s 14:27:01.011211813 O: sshsig: check signature for ssh-ed25519 927s 14:27:01.243691021 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 927s 14:27:01.493989548 O: sshsig: check signature for ecdsa-sha2-nistp256 927s 14:27:01.845232244 O: sshsig: check signature for ecdsa-sha2-nistp384 928s 14:27:02.401193263 O: sshsig: check signature for ecdsa-sha2-nistp521 929s 14:27:03.280425144 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 929s 14:27:03.698632777 O: sshsig: check signature for ssh-rsa 929s 14:27:03.932260322 O: sshsig: check signature for ssh-ed25519-cert.pub 930s 14:27:04.471631058 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 931s 14:27:05.034033448 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 931s 14:27:05.717516005 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 932s 14:27:06.629224034 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 933s 14:27:07.948919416 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 934s 14:27:08.717194836 O: sshsig: check signature for ssh-rsa-cert.pub 935s 14:27:09.246876355 O: sshsig: match principals 935s 14:27:09.265817890 O: sshsig: nomatch principals 935s 14:27:09.277207631 O: ok sshsig 935s 14:27:09.277789629 E: run test knownhosts.sh ... 937s 14:27:11.184870226 O: ok known hosts 937s 14:27:11.185590404 E: run test knownhosts-command.sh ... 937s 14:27:11.326652134 O: simple connection 937s 14:27:11.609974211 O: no keys 937s 14:27:11.820076373 O: bad exit status 938s 14:27:11.972635692 O: keytype ssh-ed25519 938s 14:27:12.222122836 O: keytype sk-ssh-ed25519@openssh.com 938s 14:27:12.475946213 O: keytype ecdsa-sha2-nistp256 938s 14:27:12.728498740 O: keytype ecdsa-sha2-nistp384 939s 14:27:12.993577647 O: keytype ecdsa-sha2-nistp521 939s 14:27:13.278371538 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 939s 14:27:13.535604233 O: keytype ssh-rsa 939s 14:27:13.792994149 O: ok known hosts command 939s 14:27:13.793094356 E: run test agent-restrict.sh ... 939s 14:27:13.928427769 O: generate keys 940s 14:27:13.981705924 O: prepare client config 940s 14:27:13.988845269 O: prepare known_hosts 940s 14:27:13.991837097 O: prepare server configs 940s 14:27:14.001699682 O: authentication w/o agent 941s 14:27:15.443637081 O: start agent 945s 14:27:19.450763129 O: authentication with agent (no restrict) 946s 14:27:20.914738293 O: unrestricted keylist 947s 14:27:21.679678192 O: authentication with agent (basic restrict) 948s 14:27:22.427992065 O: authentication with agent incorrect key (basic restrict) 949s 14:27:22.983535301 O: keylist (basic restrict) 949s 14:27:23.713130548 O: username 950s 14:27:24.431259021 O: username wildcard 951s 14:27:25.155978995 O: username incorrect 951s 14:27:25.209061739 O: agent restriction honours certificate principal 951s 14:27:25.234888260 O: multihop without agent 952s 14:27:26.049802192 O: multihop agent unrestricted 952s 14:27:26.873628067 O: multihop restricted 953s 14:27:27.708414154 O: multihop username 954s 14:27:28.542197681 O: multihop wildcard username 955s 14:27:29.383332571 O: multihop wrong username 956s 14:27:29.973592701 O: multihop cycle no agent 957s 14:27:31.155418389 O: multihop cycle agent unrestricted 958s 14:27:32.343228653 O: multihop cycle restricted deny 958s 14:27:32.809157814 O: multihop cycle restricted allow 960s 14:27:34.039546799 O: ok agent restrictions 960s 14:27:34.040156001 E: run test hostbased.sh ... 960s 14:27:34.168374197 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 960s 14:27:34.168992638 E: run test channel-timeout.sh ... 960s 14:27:34.297070021 O: no timeout 965s 14:27:39.600738036 O: command timeout 966s 14:27:40.380538890 O: command long timeout 966s 14:27:40.709270068 O: command wildcard timeout 967s 14:27:41.380435627 O: command irrelevant timeout 972s 14:27:46.692147562 O: multiplexed command timeout 983s 14:27:57.401871492 O: irrelevant multiplexed command timeout 998s 14:28:12.725862390 O: global command timeout 1020s 14:28:34.412951705 O: sftp no timeout 1025s 14:28:39.734906771 O: sftp timeout 1026s 14:28:40.379607825 E: Connection closed 1026s 14:28:40.380967309 O: sftp irrelevant timeout 1031s 14:28:45.689968961 O: ok channel timeout 1031s 14:28:45.690636390 E: run test connection-timeout.sh ... 1031s 14:28:45.821218077 O: no timeout 1038s 14:28:52.126245800 O: timeout 1046s 14:29:00.412617768 O: session inhibits timeout 1054s 14:29:08.714726288 O: timeout after session 1062s 14:29:16.720003156 O: timeout with listeners 1071s 14:29:25.018914806 O: ok unused connection timeout 1071s 14:29:25.019441909 E: run test match-subsystem.sh ... 1074s 14:29:28.065130006 O: ok sshd_config match subsystem 1074s 14:29:28.065799691 E: run test agent-pkcs11-restrict.sh ... 1074s 14:29:28.197664114 O: SKIPPED: No PKCS#11 library found 1074s 14:29:28.198177502 E: run test agent-pkcs11-cert.sh ... 1074s 14:29:28.329144301 O: SKIPPED: No PKCS#11 library found 1074s 14:29:28.329621048 E: run test penalty.sh ... 1074s 14:29:28.603702053 O: test connect 1074s 14:29:28.921096960 O: penalty for authentication failure 1082s 14:29:36.410677509 O: penalty for no authentication 1084s 14:29:38.548436522 O: ok penalties 1084s 14:29:38.548914180 E: run test penalty-expire.sh ... 1084s 14:29:38.814076508 O: test connect 1085s 14:29:39.130467725 O: penalty expiry 1098s 14:29:52.820953443 O: ok penalties 1098s 14:29:52.821528175 O: set -e ; if test -z "" ; then \ 1098s 14:29:52.822015257 O: V="" ; \ 1098s 14:29:52.822496357 O: test "x" = "x" || \ 1098s 14:29:52.822974338 O: V=/tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1098s 14:29:52.823485397 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1098s 14:29:52.823989190 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1098s 14:29:52.824495692 O: -d /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1098s 14:29:52.824976811 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1098s 14:29:52.825481436 O: -d /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1098s 14:29:52.825986009 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1098s 14:29:52.826477007 O: -d /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1098s 14:29:52.826969252 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1098s 14:29:52.827451926 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1098s 14:29:52.827947100 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1098s 14:29:52.828444226 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1098s 14:29:52.828933044 O: -d /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1098s 14:29:52.829420071 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1098s 14:29:52.829899641 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1098s 14:29:52.830362297 O: if test "x" = "xyes" ; then \ 1098s 14:29:52.830859057 O: $V /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1098s 14:29:52.831330314 O: fi \ 1098s 14:29:52.831795798 O: fi 1114s 14:30:08.462277003 O: test_sshbuf: ...................................................................................................... 103 tests ok 1206s 14:31:40.692709050 O: test_sshkey: .................................................................................... 84 tests ok 1206s 14:31:40.711324875 O: test_sshsig: ....... 7 tests ok 1206s 14:31:40.964589328 O: test_authopt: .................................................................................................................................................. 146 tests ok 1221s 14:31:55.925661184 O: test_bitmap: .. 2 tests ok 1221s 14:31:55.928523913 O: test_conversion: . 1 tests ok 1232s 14:32:06.339203584 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1232s 14:32:06.783129294 O: test_hostkeys: .................. 18 tests ok 1232s 14:32:06.785966902 O: test_match: ...... 6 tests ok 1232s 14:32:06.788499923 O: test_misc: ........................................... 43 tests ok 1232s 14:32:06.789723297 E: run test putty-transfer.sh ... 1236s 14:32:09.999530474 O: plink version 0.83 major 0 minor 83 1236s 14:32:10.005091765 O: putty transfer data: compression 0 1238s 14:32:12.215871703 O: putty transfer data: compression 1 1240s 14:32:14.429397593 O: ok putty transfer data 1240s 14:32:14.430055286 E: run test putty-ciphers.sh ... 1241s 14:32:15.049924747 O: plink version 0.83 major 0 minor 83 1241s 14:32:15.295810657 O: putty ciphers: cipher default mac default 1241s 14:32:15.600993199 O: putty ciphers: cipher default mac hmac-sha1 1241s 14:32:15.871205446 O: putty ciphers: cipher default mac hmac-sha1-96 1242s 14:32:16.147212181 O: putty ciphers: cipher default mac hmac-sha2-256 1242s 14:32:16.427367267 O: putty ciphers: cipher default mac hmac-sha2-512 1242s 14:32:16.700088216 O: putty ciphers: cipher default mac hmac-md5 1243s 14:32:16.981357313 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1243s 14:32:17.260069234 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1243s 14:32:17.552227098 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1243s 14:32:17.837404685 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1244s 14:32:18.115652522 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1244s 14:32:18.389163739 O: putty ciphers: cipher 3des-cbc mac default 1244s 14:32:18.662776387 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1244s 14:32:18.933896072 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1245s 14:32:19.207278140 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1245s 14:32:19.494548260 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1245s 14:32:19.764514400 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1246s 14:32:20.039517272 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1246s 14:32:20.311021391 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1246s 14:32:20.583552068 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1246s 14:32:20.861046633 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1247s 14:32:21.144286319 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1247s 14:32:21.419244680 O: putty ciphers: cipher aes128-cbc mac default 1247s 14:32:21.702266128 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1248s 14:32:21.985762026 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1248s 14:32:22.284403268 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1248s 14:32:22.569692826 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1248s 14:32:22.845294532 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1249s 14:32:23.118812324 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1249s 14:32:23.388675937 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1249s 14:32:23.659372802 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1249s 14:32:23.940404120 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1250s 14:32:24.221117289 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1250s 14:32:24.508172782 O: putty ciphers: cipher aes192-cbc mac default 1250s 14:32:24.783052891 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1251s 14:32:25.057041294 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1251s 14:32:25.331368773 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1251s 14:32:25.609794883 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1251s 14:32:25.881291091 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1252s 14:32:26.160200607 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1252s 14:32:26.432784114 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1252s 14:32:26.712685014 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1253s 14:32:26.986271054 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1253s 14:32:27.266637242 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1253s 14:32:27.539173403 O: putty ciphers: cipher aes256-cbc mac default 1253s 14:32:27.810545718 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1254s 14:32:28.079589127 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1254s 14:32:28.351793794 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1254s 14:32:28.633513680 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1254s 14:32:28.912060601 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1255s 14:32:29.201344326 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1255s 14:32:29.486659851 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1255s 14:32:29.767586186 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1256s 14:32:30.047197919 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1256s 14:32:30.336250368 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1256s 14:32:30.611121503 O: putty ciphers: cipher aes128-ctr mac default 1256s 14:32:30.885155642 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1257s 14:32:31.169768913 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1257s 14:32:31.454876844 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1257s 14:32:31.749349608 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1258s 14:32:32.023427756 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1258s 14:32:32.299257699 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1258s 14:32:32.576750162 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1258s 14:32:32.852303920 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1259s 14:32:33.130404790 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1259s 14:32:33.407713667 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1259s 14:32:33.699611439 O: putty ciphers: cipher aes192-ctr mac default 1260s 14:32:33.988942972 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1260s 14:32:34.268689210 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1260s 14:32:34.544410341 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1260s 14:32:34.822020971 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1261s 14:32:35.094665764 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1261s 14:32:35.368470030 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1261s 14:32:35.645220344 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1261s 14:32:35.932075313 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1262s 14:32:36.207336698 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1262s 14:32:36.481983072 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1262s 14:32:36.758052107 O: putty ciphers: cipher aes256-ctr mac default 1263s 14:32:37.029512909 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1263s 14:32:37.303383688 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1263s 14:32:37.586302339 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1263s 14:32:37.871395664 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1264s 14:32:38.144596918 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1264s 14:32:38.420014649 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1264s 14:32:38.699035087 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1265s 14:32:38.970553896 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1265s 14:32:39.244971386 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1265s 14:32:39.526960004 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1265s 14:32:39.802472442 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1266s 14:32:40.084042715 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1266s 14:32:40.355685868 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1266s 14:32:40.627372885 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1266s 14:32:40.909101183 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1267s 14:32:41.182541010 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1267s 14:32:41.454731880 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1267s 14:32:41.727764101 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1268s 14:32:42.006396436 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1268s 14:32:42.287609336 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1268s 14:32:42.565896986 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1268s 14:32:42.840288082 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1269s 14:32:43.109323696 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1269s 14:32:43.381101639 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1269s 14:32:43.654453619 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1269s 14:32:43.927488655 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1270s 14:32:44.194737554 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1270s 14:32:44.465202763 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1270s 14:32:44.741702015 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1271s 14:32:45.019664638 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1271s 14:32:45.291741427 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1271s 14:32:45.567868322 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1271s 14:32:45.845563231 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1272s 14:32:46.114357463 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1272s 14:32:46.381429155 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1272s 14:32:46.651825707 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1272s 14:32:46.932494806 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1273s 14:32:47.221606205 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1273s 14:32:47.514037939 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1273s 14:32:47.801011099 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1274s 14:32:48.077940421 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1274s 14:32:48.356949159 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1274s 14:32:48.632784294 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1274s 14:32:48.909943184 O: ok putty ciphers 1274s 14:32:48.910625938 E: run test putty-kex.sh ... 1275s 14:32:49.751346450 O: plink version 0.83 major 0 minor 83 1275s 14:32:49.891447365 O: putty KEX: kex diffie-hellman-group1-sha1 1276s 14:32:50.128225054 O: putty KEX: kex diffie-hellman-group14-sha1 1276s 14:32:50.495087371 O: putty KEX: kex diffie-hellman-group14-sha256 1276s 14:32:50.859515357 O: putty KEX: kex diffie-hellman-group16-sha512 1278s 14:32:52.135970598 O: putty KEX: kex diffie-hellman-group18-sha512 1285s 14:32:59.353924295 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1285s 14:32:59.728587895 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1287s 14:33:01.046242935 O: putty KEX: kex ecdh-sha2-nistp256 1287s 14:33:01.267440174 O: putty KEX: kex ecdh-sha2-nistp384 1287s 14:33:01.509964269 O: putty KEX: kex ecdh-sha2-nistp521 1287s 14:33:01.819820398 O: putty KEX: kex curve25519-sha256 1288s 14:33:02.037565432 O: putty KEX: kex curve25519-sha256@libssh.org 1288s 14:33:02.260338050 O: putty KEX: kex sntrup761x25519-sha512 1288s 14:33:02.515013270 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1288s 14:33:02.774659833 O: putty KEX: kex mlkem768x25519-sha256 1289s 14:33:02.995265076 O: ok putty KEX 1289s 14:33:02.996102939 E: run test conch-ciphers.sh ... 1289s 14:33:03.263720184 O: conch ciphers: cipher aes256-ctr 1289s 14:33:03.793294195 O: conch ciphers: cipher aes256-cbc 1290s 14:33:04.313719600 O: conch ciphers: cipher aes192-ctr 1290s 14:33:04.830610508 O: conch ciphers: cipher aes192-cbc 1291s 14:33:05.358821531 O: conch ciphers: cipher aes128-ctr 1291s 14:33:05.878293549 O: conch ciphers: cipher aes128-cbc 1292s 14:33:06.411375407 O: conch ciphers: cipher cast128-cbc 1292s 14:33:06.934198160 O: conch ciphers: cipher blowfish 1293s 14:33:07.462243043 O: conch ciphers: cipher 3des-cbc 1294s 14:33:08.003441150 O: ok conch ciphers 1294s 14:33:08.004051725 E: run test dropbear-ciphers.sh ... 1294s 14:33:08.084553260 O: Create dropbear key type ed25519 1294s 14:33:08.095847603 O: Create dropbear key type rsa 1295s 14:33:09.018680982 O: Create dropbear key type ecdsa 1295s 14:33:09.110364539 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1295s 14:33:09.415982255 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1295s 14:33:09.681223855 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1295s 14:33:09.955927921 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1296s 14:33:10.262564300 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1296s 14:33:10.531732267 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1296s 14:33:10.807093988 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1297s 14:33:11.115356314 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1297s 14:33:11.386101813 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1297s 14:33:11.663993492 O: ok dropbear ciphers 1297s 14:33:11.664775058 E: run test dropbear-kex.sh ... 1297s 14:33:11.829341821 O: diffie-hellman-group14-sha1 1297s 14:33:11.829979367 O: dropbear kex: kex curve25519-sha256 1298s 14:33:12.087832826 O: dropbear kex: kex curve25519-sha256@libssh.org 1298s 14:33:12.326895125 O: dropbear kex: kex diffie-hellman-group14-sha256 1298s 14:33:12.596940651 O: dropbear kex: kex diffie-hellman-group14-sha1 1298s 14:33:12.735477134 O: ssh cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data failed 1298s 14:33:12.735884510 E: cmp: EOF on /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/copy which is empty 1298s 14:33:12.756417340 O: corrupted copy 1298s 14:33:12.756966020 O: failed dropbear kex 1298s 14:33:12.757426928 E: make: *** [Makefile:277: t-exec-interop] Error 1 1298s 14:33:12.757904678 O: make: Leaving directory '/tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress' 1298s 14:33:12.758465650 O: ==> /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/failed-regress.log <== 1298s 14:33:12.758987439 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.676126.ssh.73842.log 1298s 14:33:12.759478752 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.681137.ssh.73849.log 1298s 14:33:12.759954312 O: trace: using cached key type ssh-ed25519 1298s 14:33:12.760488587 O: trace: using cached key type sk-ssh-ed25519@openssh.com 1298s 14:33:12.760964406 O: trace: using cached key type ecdsa-sha2-nistp256 1298s 14:33:12.761442596 O: trace: using cached key type ecdsa-sha2-nistp384 1298s 14:33:12.762195713 O: trace: using cached key type ecdsa-sha2-nistp521 1298s 14:33:12.762863384 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com 1298s 14:33:12.763512603 O: trace: using cached key type ssh-rsa 1298s 14:33:12.764051322 O: trace: Create dropbear keys and add to authorized_keys 1298s 14:33:12.764576079 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.726856.ssh.73895.log 1298s 14:33:12.765080245 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.731837.ssh.73901.log 1298s 14:33:12.765595999 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.736534.ssh.73906.log 1298s 14:33:12.766417699 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.824857.ssh.73927.log 1298s 14:33:12.767150087 O: trace: dropbear kex: kex curve25519-sha256 1298s 14:33:12.767785817 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.839842.sshd.73941.log 1298s 14:33:12.768315476 O: trace: dropbear kex: kex curve25519-sha256@libssh.org 1298s 14:33:12.768934154 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143312.095364.sshd.73973.log 1298s 14:33:12.769485967 O: trace: dropbear kex: kex diffie-hellman-group14-sha256 1298s 14:33:12.770085040 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143312.335698.sshd.74005.log 1298s 14:33:12.770604496 O: trace: dropbear kex: kex diffie-hellman-group14-sha1 1298s 14:33:12.771142032 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143312.604939.sshd.74037.log 1298s 14:33:12.771651710 O: FAIL: ssh cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data failed 1298s 14:33:12.772165255 O: Saving debug logs to /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 1298s 14:33:12.772665033 O: 1298s 14:33:12.773181904 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.676126.ssh.73842.log 1298s 14:33:12.773699453 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.681137.ssh.73849.log 1298s 14:33:12.774378259 O: trace: using cached key type ssh-ed25519 1298s 14:33:12.774904249 O: trace: using cached key type sk-ssh-ed25519@openssh.com 1298s 14:33:12.775481781 O: trace: using cached key type ecdsa-sha2-nistp256 1298s 14:33:12.776108990 O: trace: using cached key type ecdsa-sha2-nistp384 1298s 14:33:12.776713286 O: trace: using cached key type ecdsa-sha2-nistp521 1298s 14:33:12.777554355 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com 1298s 14:33:12.778046294 O: trace: using cached key type ssh-rsa 1298s 14:33:12.778508180 O: trace: Create dropbear keys and add to authorized_keys 1298s 14:33:12.779065503 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.726856.ssh.73895.log 1298s 14:33:12.779567657 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.731837.ssh.73901.log 1298s 14:33:12.780045647 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.736534.ssh.73906.log 1298s 14:33:12.780512538 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.824857.ssh.73927.log 1298s 14:33:12.781029914 O: trace: dropbear kex: kex curve25519-sha256 1298s 14:33:12.781522283 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143311.839842.sshd.73941.log 1298s 14:33:12.781986421 O: trace: dropbear kex: kex curve25519-sha256@libssh.org 1298s 14:33:12.782489656 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143312.095364.sshd.73973.log 1298s 14:33:12.782982513 O: trace: dropbear kex: kex diffie-hellman-group14-sha256 1298s 14:33:12.783479369 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143312.335698.sshd.74005.log 1298s 14:33:12.783963555 O: trace: dropbear kex: kex diffie-hellman-group14-sha1 1298s 14:33:12.784455337 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/log/20250505T143312.604939.sshd.74037.log 1298s 14:33:12.784923541 O: FAIL: ssh cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data failed 1298s 14:33:12.785391857 O: Saving debug logs to /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 1298s 14:33:12.785843277 O: FAIL: corrupted copy 1298s 14:33:12.786315879 O: Saving debug logs to /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 1298s 14:33:12.786786941 O: 1298s 14:33:12.787318999 O: ==> /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/failed-ssh.log <== 1298s 14:33:12.787809560 O: FAIL: ssh cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data failed 1298s 14:33:12.788263522 O: 1298s 14:33:12.788728809 O: FAIL: ssh cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data failed 1298s 14:33:12.789190376 O: FAIL: corrupted copy 1298s 14:33:12.789786003 O: 1298s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1298s 14:33:12.790314824 O: ==> /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/failed-sshd.log <== 1298s 14:33:12.790955719 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy 1298s 14:33:12.791494202 O: debug3: send_rexec_state: entering fd = 8 config len 1183 1298s 14:33:12.792006552 O: debug3: ssh_msg_send: type 0 len 4943 1298s 14:33:12.792510930 O: debug3: ssh_msg_send: done 1298s 14:33:12.793048984 O: debug3: send_rexec_state: done 1298s 14:33:12.793638468 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9 1298s 14:33:12.794239837 O: FAIL: ssh cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data failed 1298s 14:33:12.794756719 O: 1298s 14:33:12.795275981 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/sshd_proxy 1298s 14:33:12.795794295 O: debug3: send_rexec_state: entering fd = 8 config len 1183 1298s 14:33:12.796300126 O: debug3: ssh_msg_send: type 0 len 4943 1298s 14:33:12.796817187 O: debug3: ssh_msg_send: done 1298s 14:33:12.797383013 O: debug3: send_rexec_state: done 1298s 14:33:12.797852917 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9 1298s 14:33:12.798323643 O: FAIL: ssh cat /tmp/autopkgtest.4Yp25n/autopkgtest_tmp/user/regress/data failed 1298s 14:33:12.798786558 O: FAIL: corrupted copy 1298s 14:33:12.799385222 O: 1298s 14:33:12.800365695 I: Finished with exitcode 2 1299s info: Looking for files to backup/remove ... 1299s info: Removing files ... 1299s info: Removing crontab ... 1299s info: Removing user `openssh-tests' ... 1299s autopkgtest [14:33:13]: test regress: -----------------------] 1300s regress FAIL non-zero exit status 2 1300s autopkgtest [14:33:14]: test regress: - - - - - - - - - - results - - - - - - - - - - 1300s autopkgtest [14:33:14]: test ssh-gssapi: preparing testbed 1384s autopkgtest [14:34:38]: testbed dpkg architecture: s390x 1384s autopkgtest [14:34:38]: testbed apt version: 3.0.0 1384s autopkgtest [14:34:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1384s autopkgtest [14:34:38]: testbed release detected to be: questing 1385s autopkgtest [14:34:39]: updating testbed package index (apt update) 1385s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1385s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1385s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1386s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1386s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 1386s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 1386s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 1386s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [116 kB] 1386s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [888 kB] 1386s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [17.0 kB] 1386s Fetched 2163 kB in 1s (2191 kB/s) 1387s Reading package lists... 1387s autopkgtest [14:34:41]: upgrading testbed (apt dist-upgrade and autopurge) 1387s Reading package lists... 1387s Building dependency tree... 1387s Reading state information... 1388s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1388s Starting 2 pkgProblemResolver with broken count: 0 1388s Done 1388s Entering ResolveByKeep 1388s 1388s Calculating upgrade... 1388s The following packages will be upgraded: 1388s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 ucf 1388s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1388s Need to get 5438 kB of archives. 1388s After this operation, 259 kB disk space will be freed. 1388s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x ucf all 3.0051 [43.6 kB] 1388s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12t64 s390x 3.12.10-1 [2509 kB] 1389s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-stdlib s390x 3.12.10-1 [2049 kB] 1389s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-minimal s390x 3.12.10-1 [836 kB] 1389s Preconfiguring packages ... 1389s Fetched 5438 kB in 1s (7166 kB/s) 1389s (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 ... 81479 files and directories currently installed.) 1389s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 1389s Unpacking ucf (3.0051) over (3.0050) ... 1389s Preparing to unpack .../libpython3.12t64_3.12.10-1_s390x.deb ... 1389s Unpacking libpython3.12t64:s390x (3.12.10-1) over (3.12.8-3) ... 1389s Preparing to unpack .../libpython3.12-stdlib_3.12.10-1_s390x.deb ... 1389s Unpacking libpython3.12-stdlib:s390x (3.12.10-1) over (3.12.8-3) ... 1389s Preparing to unpack .../libpython3.12-minimal_3.12.10-1_s390x.deb ... 1389s Unpacking libpython3.12-minimal:s390x (3.12.10-1) over (3.12.8-3) ... 1389s Setting up libpython3.12-minimal:s390x (3.12.10-1) ... 1389s Setting up ucf (3.0051) ... 1389s Setting up libpython3.12-stdlib:s390x (3.12.10-1) ... 1389s Setting up libpython3.12t64:s390x (3.12.10-1) ... 1389s Processing triggers for man-db (2.13.1-1) ... 1390s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1390s Reading package lists... 1390s Building dependency tree... 1390s Reading state information... 1391s Starting pkgProblemResolver with broken count: 0 1391s Starting 2 pkgProblemResolver with broken count: 0 1391s Done 1391s Solving dependencies... 1391s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1393s Reading package lists... 1393s Building dependency tree... 1393s Reading state information... 1393s Starting pkgProblemResolver with broken count: 0 1393s Starting 2 pkgProblemResolver with broken count: 0 1393s Done 1394s The following NEW packages will be installed: 1394s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1394s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1394s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1394s openssh-server-gssapi 1394s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1394s Need to get 796 kB of archives. 1394s After this operation, 2750 kB of additional disk space will be used. 1394s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x krb5-config all 2.7 [22.0 kB] 1394s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x libgssrpc4t64 s390x 1.21.3-4ubuntu2 [59.1 kB] 1394s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x libkadm5clnt-mit12 s390x 1.21.3-4ubuntu2 [40.6 kB] 1394s Get:4 http://ftpmaster.internal/ubuntu questing/main s390x libkdb5-10t64 s390x 1.21.3-4ubuntu2 [42.0 kB] 1394s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x libkadm5srv-mit12 s390x 1.21.3-4ubuntu2 [55.4 kB] 1394s Get:6 http://ftpmaster.internal/ubuntu questing/universe s390x krb5-user s390x 1.21.3-4ubuntu2 [110 kB] 1394s Get:7 http://ftpmaster.internal/ubuntu questing/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 1394s Get:8 http://ftpmaster.internal/ubuntu questing/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 1394s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 1394s Get:10 http://ftpmaster.internal/ubuntu questing/universe s390x krb5-kdc s390x 1.21.3-4ubuntu2 [198 kB] 1394s Get:11 http://ftpmaster.internal/ubuntu questing/universe s390x krb5-admin-server s390x 1.21.3-4ubuntu2 [95.7 kB] 1394s Get:12 http://ftpmaster.internal/ubuntu questing/universe s390x openssh-client-gssapi all 1:9.9p1-3ubuntu3 [5022 B] 1394s Get:13 http://ftpmaster.internal/ubuntu questing/universe s390x openssh-server-gssapi all 1:9.9p1-3ubuntu3 [5026 B] 1394s Preconfiguring packages ... 1394s Fetched 796 kB in 1s (1455 kB/s) 1394s Selecting previously unselected package krb5-config. 1394s (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 ... 81479 files and directories currently installed.) 1394s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1394s Unpacking krb5-config (2.7) ... 1394s Selecting previously unselected package libgssrpc4t64:s390x. 1394s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_s390x.deb ... 1394s Unpacking libgssrpc4t64:s390x (1.21.3-4ubuntu2) ... 1394s Selecting previously unselected package libkadm5clnt-mit12:s390x. 1394s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_s390x.deb ... 1394s Unpacking libkadm5clnt-mit12:s390x (1.21.3-4ubuntu2) ... 1395s Selecting previously unselected package libkdb5-10t64:s390x. 1395s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_s390x.deb ... 1395s Unpacking libkdb5-10t64:s390x (1.21.3-4ubuntu2) ... 1395s Selecting previously unselected package libkadm5srv-mit12:s390x. 1395s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_s390x.deb ... 1395s Unpacking libkadm5srv-mit12:s390x (1.21.3-4ubuntu2) ... 1395s Selecting previously unselected package krb5-user. 1395s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_s390x.deb ... 1395s Unpacking krb5-user (1.21.3-4ubuntu2) ... 1395s Selecting previously unselected package libevent-2.1-7t64:s390x. 1395s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 1395s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1395s Selecting previously unselected package libverto1t64:s390x. 1395s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1395s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1395s Selecting previously unselected package libverto-libevent1t64:s390x. 1395s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1395s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1395s Selecting previously unselected package krb5-kdc. 1395s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_s390x.deb ... 1395s Unpacking krb5-kdc (1.21.3-4ubuntu2) ... 1395s Selecting previously unselected package krb5-admin-server. 1395s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_s390x.deb ... 1395s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ... 1395s Selecting previously unselected package openssh-client-gssapi. 1395s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 1395s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 1395s Selecting previously unselected package openssh-server-gssapi. 1395s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 1395s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 1395s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1395s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 1395s Setting up libgssrpc4t64:s390x (1.21.3-4ubuntu2) ... 1395s Setting up krb5-config (2.7) ... 1395s Setting up libkadm5clnt-mit12:s390x (1.21.3-4ubuntu2) ... 1395s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 1395s Setting up libkdb5-10t64:s390x (1.21.3-4ubuntu2) ... 1395s Setting up libkadm5srv-mit12:s390x (1.21.3-4ubuntu2) ... 1395s Setting up krb5-user (1.21.3-4ubuntu2) ... 1395s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1395s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1395s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1395s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1395s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1395s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1395s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1395s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1395s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1395s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1395s Setting up krb5-kdc (1.21.3-4ubuntu2) ... 1395s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1395s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1395s Setting up krb5-admin-server (1.21.3-4ubuntu2) ... 1396s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1396s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1396s Processing triggers for man-db (2.13.1-1) ... 1401s autopkgtest [14:34:55]: test ssh-gssapi: [----------------------- 1401s Generating public/private ed25519 key pair. 1401s Your identification has been saved in /root/.ssh/id_ed25519 1401s Your public key has been saved in /root/.ssh/id_ed25519.pub 1401s The key fingerprint is: 1401s SHA256:XuygDkaKBeF9F315KwRxAoVCuUmRurCmQZeyw1tvMNA root@autopkgtest 1401s The key's randomart image is: 1401s +--[ED25519 256]--+ 1401s |. .o+o==o.. | 1401s |... = ...o+ . | 1401s |....+.+. o . . | 1401s | * E.o. . . . | 1401s |o O o S o . | 1401s |.O B o + | 1401s |+.= * . . . | 1401s |.. . = | 1401s | . . | 1401s +----[SHA256]-----+ 1401s ## Setting up test environment 1401s ## Creating Kerberos realm EXAMPLE.FAKE 1401s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1401s master key name 'K/M@EXAMPLE.FAKE' 1401s ## Creating principals 1401s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1401s Principal "testuser2079@EXAMPLE.FAKE" created. 1401s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1401s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1401s ## Extracting service principal host/sshd-gssapi.example.fake 1401s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1401s 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. 1401s 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. 1401s ## Adjusting /etc/krb5.conf 1401s ## TESTS 1401s 1401s ## TEST test_gssapi_login 1401s ## Configuring sshd for gssapi-with-mic authentication 1401s ## Restarting ssh 1401s ## Obtaining TGT 1401s Password for testuser2079@EXAMPLE.FAKE: 1401s Ticket cache: FILE:/tmp/krb5cc_0 1401s Default principal: testuser2079@EXAMPLE.FAKE 1401s 1401s Valid starting Expires Service principal 1401s 05/05/25 14:34:55 05/06/25 00:34:55 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1401s renew until 05/06/25 14:34:55 1401s 1401s ## ssh'ing into localhost using gssapi-with-mic auth 1401s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1402s Mon May 5 14:34:56 UTC 2025 1402s 1402s ## checking that we got a service ticket for ssh (host/) 1402s 05/05/25 14:34:55 05/06/25 00:34:55 host/sshd-gssapi.example.fake@ 1402s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1402s 1402s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1402s May 05 14:34:55 sshd-gssapi.example.fake sshd-session[2182]: Accepted gssapi-with-mic for testuser2079 from 127.0.0.1 port 47994 ssh2: testuser2079@EXAMPLE.FAKE 1402s ## PASS test_gssapi_login 1402s 1402s ## TEST test_gssapi_keyex_login 1402s ## Configuring sshd for gssapi-keyex authentication 1402s ## Restarting ssh 1402s ## Obtaining TGT 1402s Password for testuser2079@EXAMPLE.FAKE: 1402s Ticket cache: FILE:/tmp/krb5cc_0 1402s Default principal: testuser2079@EXAMPLE.FAKE 1402s 1402s Valid starting Expires Service principal 1402s 05/05/25 14:34:56 05/06/25 00:34:56 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1402s renew until 05/06/25 14:34:56 1402s 1402s ## ssh'ing into localhost using gssapi-keyex auth 1402s Mon May 5 14:34:56 UTC 2025 1402s 1402s ## checking that we got a service ticket for ssh (host/) 1402s 05/05/25 14:34:56 05/06/25 00:34:56 host/sshd-gssapi.example.fake@ 1402s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1402s 1402s ## Checking ssh logs to confirm gssapi-keyex auth was used 1402s May 05 14:34:56 sshd-gssapi.example.fake sshd-session[2237]: Accepted gssapi-keyex for testuser2079 from 127.0.0.1 port 48010 ssh2: testuser2079@EXAMPLE.FAKE 1402s ## PASS test_gssapi_keyex_login 1402s 1402s ## TEST test_gssapi_keyex_pubkey_fallback 1402s ## Configuring sshd for gssapi-keyex authentication 1402s ## Restarting ssh 1402s ## Obtaining TGT 1402s Password for testuser2079@EXAMPLE.FAKE: 1402s Ticket cache: FILE:/tmp/krb5cc_0 1402s Default principal: testuser2079@EXAMPLE.FAKE 1402s 1402s Valid starting Expires Service principal 1402s 05/05/25 14:34:56 05/06/25 00:34:56 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1402s renew until 05/06/25 14:34:56 1402s 1402s ## ssh'ing into localhost using gssapi-keyex auth 1403s Mon May 5 14:34:57 UTC 2025 1403s 1403s ## checking that we got a service ticket for ssh (host/) 1403s 05/05/25 14:34:56 05/06/25 00:34:56 host/sshd-gssapi.example.fake@ 1403s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1403s 1403s ## Checking ssh logs to confirm publickey auth was used 1403s May 05 14:34:56 sshd-gssapi.example.fake sshd-session[2278]: Accepted publickey for testuser2079-2 from 127.0.0.1 port 48022 ssh2: ED25519 SHA256:XuygDkaKBeF9F315KwRxAoVCuUmRurCmQZeyw1tvMNA 1403s ## PASS test_gssapi_keyex_pubkey_fallback 1403s 1403s ## ALL TESTS PASSED 1403s ## Cleaning up 1403s autopkgtest [14:34:57]: test ssh-gssapi: -----------------------] 1403s autopkgtest [14:34:57]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1403s ssh-gssapi PASS 1404s autopkgtest [14:34:58]: test socket-activation: preparing testbed 1479s autopkgtest [14:36:13]: testbed dpkg architecture: s390x 1480s autopkgtest [14:36:14]: testbed apt version: 3.0.0 1480s autopkgtest [14:36:14]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1480s autopkgtest [14:36:14]: testbed release detected to be: questing 1481s autopkgtest [14:36:15]: updating testbed package index (apt update) 1481s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1481s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1481s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1482s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1482s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 1482s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 1482s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 1482s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [116 kB] 1482s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [888 kB] 1482s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [17.0 kB] 1482s Fetched 2163 kB in 1s (2104 kB/s) 1483s Reading package lists... 1483s autopkgtest [14:36:17]: upgrading testbed (apt dist-upgrade and autopurge) 1483s Reading package lists... 1483s Building dependency tree... 1483s Reading state information... 1484s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1484s Starting 2 pkgProblemResolver with broken count: 0 1484s Done 1484s Entering ResolveByKeep 1484s 1484s Calculating upgrade... 1484s The following packages will be upgraded: 1484s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 ucf 1484s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1484s Need to get 5438 kB of archives. 1484s After this operation, 259 kB disk space will be freed. 1484s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x ucf all 3.0051 [43.6 kB] 1484s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12t64 s390x 3.12.10-1 [2509 kB] 1485s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-stdlib s390x 3.12.10-1 [2049 kB] 1485s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-minimal s390x 3.12.10-1 [836 kB] 1485s Preconfiguring packages ... 1485s Fetched 5438 kB in 1s (7194 kB/s) 1485s (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 ... 81479 files and directories currently installed.) 1485s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 1485s Unpacking ucf (3.0051) over (3.0050) ... 1485s Preparing to unpack .../libpython3.12t64_3.12.10-1_s390x.deb ... 1485s Unpacking libpython3.12t64:s390x (3.12.10-1) over (3.12.8-3) ... 1485s Preparing to unpack .../libpython3.12-stdlib_3.12.10-1_s390x.deb ... 1485s Unpacking libpython3.12-stdlib:s390x (3.12.10-1) over (3.12.8-3) ... 1485s Preparing to unpack .../libpython3.12-minimal_3.12.10-1_s390x.deb ... 1485s Unpacking libpython3.12-minimal:s390x (3.12.10-1) over (3.12.8-3) ... 1485s Setting up libpython3.12-minimal:s390x (3.12.10-1) ... 1485s Setting up ucf (3.0051) ... 1485s Setting up libpython3.12-stdlib:s390x (3.12.10-1) ... 1485s Setting up libpython3.12t64:s390x (3.12.10-1) ... 1485s Processing triggers for man-db (2.13.1-1) ... 1486s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1486s Reading package lists... 1486s Building dependency tree... 1486s Reading state information... 1487s Starting pkgProblemResolver with broken count: 0 1487s Starting 2 pkgProblemResolver with broken count: 0 1487s Done 1487s Solving dependencies... 1487s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1489s Reading package lists... 1489s Building dependency tree... 1489s Reading state information... 1490s Starting pkgProblemResolver with broken count: 0 1490s Starting 2 pkgProblemResolver with broken count: 0 1490s Done 1490s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1492s autopkgtest [14:36:26]: test socket-activation: [----------------------- 1492s Generating public/private ed25519 key pair. 1492s Your identification has been saved in /home/testuser1665/.ssh/id_ed25519 1492s Your public key has been saved in /home/testuser1665/.ssh/id_ed25519.pub 1492s The key fingerprint is: 1492s SHA256:yrJ3aG44jE/KYXBfUf16oNWoysesYASuBAx1+2AaaCk testuser1665@autopkgtest 1492s The key's randomart image is: 1492s +--[ED25519 256]--+ 1492s |... . .. | 1492s |o... . . . | 1492s |E+o + . + | 1492s |+. = o . + o | 1492s |..+ . o S+ o | 1492s |.+ o o .o . . | 1492s |. oo*oo= . | 1492s | o.=++B = | 1492s | o.o*o+ | 1492s +----[SHA256]-----+ 1492s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1492s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1493s Disabling 'ssh.service', but its triggering units are still active: 1493s ssh.socket 1493s Stopping 'ssh.service', but its triggering units are still active: 1493s ssh.socket 1493s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1494s Mon May 5 14:36:28 UTC 2025 1494s autopkgtest [14:36:28]: test socket-activation: -----------------------] 1494s socket-activation PASS 1494s autopkgtest [14:36:28]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1495s autopkgtest [14:36:29]: test xinetd: preparing testbed 1495s Reading package lists... 1495s Building dependency tree... 1495s Reading state information... 1495s Starting pkgProblemResolver with broken count: 0 1495s Starting 2 pkgProblemResolver with broken count: 0 1495s Done 1495s The following NEW packages will be installed: 1495s xinetd 1495s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1495s Need to get 118 kB of archives. 1495s After this operation, 316 kB of additional disk space will be used. 1495s Get:1 http://ftpmaster.internal/ubuntu questing/universe s390x xinetd s390x 1:2.3.15.4-5 [118 kB] 1496s Fetched 118 kB in 0s (329 kB/s) 1496s Selecting previously unselected package xinetd. 1496s (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 ... 81479 files and directories currently installed.) 1496s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_s390x.deb ... 1496s Unpacking xinetd (1:2.3.15.4-5) ... 1496s Setting up xinetd (1:2.3.15.4-5) ... 1496s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1496s Processing triggers for man-db (2.13.1-1) ... 1498s autopkgtest [14:36:32]: test xinetd: [----------------------- 1498s Generating public/private ed25519 key pair. 1498s Your identification has been saved in /home/testuser2199/.ssh/id_ed25519 1498s Your public key has been saved in /home/testuser2199/.ssh/id_ed25519.pub 1498s The key fingerprint is: 1498s SHA256:AWe6W2pe3yVOSXreLyB4U47mkIIP2Ah61KsR+lbYT7o testuser2199@autopkgtest 1498s The key's randomart image is: 1498s +--[ED25519 256]--+ 1498s | . o | 1498s | = | 1498s | . . . | 1498s |. o . . . . | 1498s |.+ B o. So +. | 1498s |o * B o++ *oo. | 1498s | o + *+..=.o=.. | 1498s | + .oo. ..* +. | 1498s | . E.. . + .o.| 1498s +----[SHA256]-----+ 1498s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1498s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1499s Disabling 'ssh.service', but its triggering units are still active: 1499s ssh.socket 1499s Stopping 'ssh.service', but its triggering units are still active: 1499s ssh.socket 1499s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1499s Mon May 5 14:36:33 UTC 2025 1499s autopkgtest [14:36:33]: test xinetd: -----------------------] 1500s autopkgtest [14:36:34]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1500s xinetd PASS 1500s autopkgtest [14:36:34]: test systemd-socket-activation: preparing testbed 1577s autopkgtest [14:37:51]: testbed dpkg architecture: s390x 1577s autopkgtest [14:37:51]: testbed apt version: 3.0.0 1577s autopkgtest [14:37:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1577s autopkgtest [14:37:51]: testbed release detected to be: questing 1578s autopkgtest [14:37:52]: updating testbed package index (apt update) 1578s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 1578s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1578s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1579s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1579s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [92.9 kB] 1579s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [906 kB] 1579s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [32.3 kB] 1579s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [116 kB] 1579s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [888 kB] 1579s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [17.0 kB] 1579s Fetched 2163 kB in 1s (1530 kB/s) 1580s Reading package lists... 1581s autopkgtest [14:37:55]: upgrading testbed (apt dist-upgrade and autopurge) 1581s Reading package lists... 1581s Building dependency tree... 1581s Reading state information... 1581s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1581s Starting 2 pkgProblemResolver with broken count: 0 1581s Done 1581s Entering ResolveByKeep 1581s 1581s Calculating upgrade... 1582s The following packages will be upgraded: 1582s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 ucf 1582s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1582s Need to get 5438 kB of archives. 1582s After this operation, 259 kB disk space will be freed. 1582s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x ucf all 3.0051 [43.6 kB] 1582s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12t64 s390x 3.12.10-1 [2509 kB] 1583s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-stdlib s390x 3.12.10-1 [2049 kB] 1583s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-minimal s390x 3.12.10-1 [836 kB] 1584s Preconfiguring packages ... 1584s Fetched 5438 kB in 2s (3094 kB/s) 1584s (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 ... 81479 files and directories currently installed.) 1584s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 1584s Unpacking ucf (3.0051) over (3.0050) ... 1584s Preparing to unpack .../libpython3.12t64_3.12.10-1_s390x.deb ... 1584s Unpacking libpython3.12t64:s390x (3.12.10-1) over (3.12.8-3) ... 1584s Preparing to unpack .../libpython3.12-stdlib_3.12.10-1_s390x.deb ... 1584s Unpacking libpython3.12-stdlib:s390x (3.12.10-1) over (3.12.8-3) ... 1584s Preparing to unpack .../libpython3.12-minimal_3.12.10-1_s390x.deb ... 1584s Unpacking libpython3.12-minimal:s390x (3.12.10-1) over (3.12.8-3) ... 1584s Setting up libpython3.12-minimal:s390x (3.12.10-1) ... 1584s Setting up ucf (3.0051) ... 1584s Setting up libpython3.12-stdlib:s390x (3.12.10-1) ... 1584s Setting up libpython3.12t64:s390x (3.12.10-1) ... 1584s Processing triggers for man-db (2.13.1-1) ... 1584s Processing triggers for libc-bin (2.41-6ubuntu1) ... 1585s Reading package lists... 1585s Building dependency tree... 1585s Reading state information... 1585s Starting pkgProblemResolver with broken count: 0 1585s Starting 2 pkgProblemResolver with broken count: 0 1585s Done 1585s Solving dependencies... 1585s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1588s Reading package lists... 1588s Building dependency tree... 1588s Reading state information... 1588s Starting pkgProblemResolver with broken count: 0 1588s Starting 2 pkgProblemResolver with broken count: 0 1588s Done 1588s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1590s autopkgtest [14:38:04]: test systemd-socket-activation: [----------------------- 1591s Stopping ssh.service... 1591s Checking that ssh.socket is active and listening... 1591s Checking that ssh.service is inactive/dead... 1591s Checking that a connection attempt activates ssh.service... 1591s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1591s Checking that sshd can be re-executed... 1591s Checking sshd can run in debug mode... 1592s debug1: PAM: establishing credentials 1592s debug1: permanently_set_uid: 0/0 1592s debug3: Copy environment: XDG_SESSION_ID=7 1592s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1592s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1592s debug3: Copy environment: XDG_SESSION_TYPE=tty 1592s debug3: Copy environment: XDG_SESSION_CLASS=user 1592s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1592s debug3: Copy environment: http_proxy=http://squid.internal:3128/ 1592s debug3: Copy environment: https_proxy=http://squid.internal:3128/ 1592s 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 1592s debug3: Copy environment: TERM=linux 1592s debug3: Copy environment: LANG=C.UTF-8 1592s Environment: 1592s LANG=C.UTF-8 1592s USER=root 1592s LOGNAME=root 1592s HOME=/root 1592s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1592s SHELL=/bin/bash 1592s XDG_SESSION_ID=7 1592s XDG_RUNTIME_DIR=/run/user/0 1592s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1592s XDG_SESSION_TYPE=tty 1592s XDG_SESSION_CLASS=user 1592s http_proxy=http://squid.internal:3128/ 1592s https_proxy=http://squid.internal:3128/ 1592s 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 1592s TERM=linux 1592s SSH_CLIENT=::1 50450 22 1592s SSH_CONNECTION=::1 50450 ::1 22 1592s Done. 1592s autopkgtest [14:38:06]: test systemd-socket-activation: -----------------------] 1592s systemd-socket-activation PASS 1592s autopkgtest [14:38:06]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1593s autopkgtest [14:38:07]: test sshd-socket-generator: preparing testbed 1593s Reading package lists... 1593s Building dependency tree... 1593s Reading state information... 1593s Starting pkgProblemResolver with broken count: 0 1593s Starting 2 pkgProblemResolver with broken count: 0 1593s Done 1593s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1594s autopkgtest [14:38:08]: test sshd-socket-generator: [----------------------- 1594s test_default...PASS 1594s test_custom_port...PASS 1594s test_default_and_custom_port...PASS 1594s test_mutiple_custom_ports...PASS 1594s test_custom_listenaddress...PASS 1594s test_custom_listenaddress_and_port...PASS 1594s test_custom_ipv6_listenaddress...PASS 1594s test_custom_family_ipv4...PASS 1594s test_custom_family_ipv6...PASS 1594s test_custom_port_and_family_ipv4...PASS 1594s test_custom_port_and_family_ipv6...PASS 1594s test_match_on_port...PASS 1595s autopkgtest [14:38:09]: test sshd-socket-generator: -----------------------] 1595s autopkgtest [14:38:09]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1595s sshd-socket-generator PASS 1595s autopkgtest [14:38:09]: @@@@@@@@@@@@@@@@@@@@ summary 1595s regress FAIL non-zero exit status 2 1595s ssh-gssapi PASS 1595s socket-activation PASS 1595s xinetd PASS 1595s systemd-socket-activation PASS 1595s sshd-socket-generator PASS 1612s nova [W] Using flock in prodstack6-s390x 1612s Creating nova instance adt-questing-s390x-openssh-20250505-141133-juju-7f2275-prod-proposed-migration-environment-23-c0991584-a1b5-4104-ad18-32c88ea04b3b from image adt/ubuntu-questing-s390x-server-20250505.img (UUID 235ae1f0-b461-4906-9827-678093d41c66)... 1612s nova [W] Timed out waiting for 0b55e0ae-ec14-4f3c-a106-dd35097da71f to get deleted. 1612s nova [W] Using flock in prodstack6-s390x 1612s Creating nova instance adt-questing-s390x-openssh-20250505-141133-juju-7f2275-prod-proposed-migration-environment-23-c0991584-a1b5-4104-ad18-32c88ea04b3b from image adt/ubuntu-questing-s390x-server-20250505.img (UUID 235ae1f0-b461-4906-9827-678093d41c66)... 1612s nova [W] Timed out waiting for 52874896-dc64-484b-bc60-70966b73fed2 to get deleted. 1612s nova [W] Using flock in prodstack6-s390x 1612s Creating nova instance adt-questing-s390x-openssh-20250505-141133-juju-7f2275-prod-proposed-migration-environment-23-c0991584-a1b5-4104-ad18-32c88ea04b3b from image adt/ubuntu-questing-s390x-server-20250505.img (UUID 235ae1f0-b461-4906-9827-678093d41c66)... 1612s nova [W] Timed out waiting for 4f4a1011-7810-4aa7-9bb5-e2c78db18507 to get deleted. 1612s nova [W] Using flock in prodstack6-s390x 1612s Creating nova instance adt-questing-s390x-openssh-20250505-141133-juju-7f2275-prod-proposed-migration-environment-23-c0991584-a1b5-4104-ad18-32c88ea04b3b from image adt/ubuntu-questing-s390x-server-20250505.img (UUID 235ae1f0-b461-4906-9827-678093d41c66)... 1612s nova [W] Timed out waiting for f592be60-741a-486d-95ff-29f9211db217 to get deleted.