0s autopkgtest [06:24:10]: starting date and time: 2025-02-22 06:24:10+0000 0s autopkgtest [06:24:10]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [06:24:10]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.lavfvc8w/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:devscripts --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=devscripts/2.25.3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-s390x-8.secgroup --name adt-plucky-s390x-openssh-20250222-062410-juju-7f2275-prod-proposed-migration-environment-20-3f958b6b-45a0-4850-947f-c15c50a7a093 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-s390x -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 89s autopkgtest [06:25:39]: testbed dpkg architecture: s390x 89s autopkgtest [06:25:39]: testbed apt version: 2.9.30ubuntu1 90s autopkgtest [06:25:40]: @@@@@@@@@@@@@@@@@@@@ test bed setup 90s autopkgtest [06:25:40]: testbed release detected to be: None 91s autopkgtest [06:25:41]: updating testbed package index (apt update) 91s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 91s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 91s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 91s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 91s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 91s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 91s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 91s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 91s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [125 kB] 91s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 91s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [424 kB] 92s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 92s Fetched 1264 kB in 1s (1455 kB/s) 92s Reading package lists... 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 93s Calculating upgrade... 93s Calculating upgrade... 93s The following packages were automatically installed and are no longer required: 93s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 93s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 93s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 93s linux-tools-6.11.0-8-generic 93s Use 'sudo apt autoremove' to remove them. 93s The following packages will be upgraded: 93s curl libcurl3t64-gnutls libcurl4t64 93s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 93s Need to get 1041 kB of archives. 93s After this operation, 7168 B disk space will be freed. 93s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x curl s390x 8.12.1-2ubuntu1 [251 kB] 94s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl4t64 s390x 8.12.1-2ubuntu1 [397 kB] 94s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl3t64-gnutls s390x 8.12.1-2ubuntu1 [393 kB] 94s Fetched 1041 kB in 1s (1844 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 ... 81030 files and directories currently installed.) 94s Preparing to unpack .../curl_8.12.1-2ubuntu1_s390x.deb ... 94s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 94s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_s390x.deb ... 94s Unpacking libcurl4t64:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 94s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_s390x.deb ... 94s Unpacking libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 94s Setting up libcurl4t64:s390x (8.12.1-2ubuntu1) ... 94s Setting up libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) ... 94s Setting up curl (8.12.1-2ubuntu1) ... 94s Processing triggers for man-db (2.13.0-1) ... 95s Processing triggers for libc-bin (2.40-4ubuntu1) ... 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Solving dependencies... 95s The following packages will be REMOVED: 95s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 95s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 95s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 95s linux-tools-6.11.0-8-generic* 95s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 95s After this operation, 167 MB disk space will be freed. 95s (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 ... 81030 files and directories currently installed.) 95s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 95s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 95s Removing libpython3.12t64:s390x (3.12.9-1) ... 95s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 95s Removing libnsl2:s390x (1.3.0-3build3) ... 95s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 95s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 95s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 96s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 96s Processing triggers for libc-bin (2.40-4ubuntu1) ... 96s (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 ... 55930 files and directories currently installed.) 96s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 96s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 96s autopkgtest [06:25:46]: upgrading testbed (apt dist-upgrade and autopurge) 97s Reading package lists... 97s Building dependency tree... 97s Reading state information... 97s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 97s Starting 2 pkgProblemResolver with broken count: 0 97s Done 97s Entering ResolveByKeep 97s 97s Calculating upgrade... 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 97s Reading package lists... 98s Building dependency tree... 98s Reading state information... 98s Starting pkgProblemResolver with broken count: 0 98s Starting 2 pkgProblemResolver with broken count: 0 98s Done 98s Solving dependencies... 98s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 98s autopkgtest [06:25:48]: rebooting testbed after setup commands that affected boot 116s autopkgtest [06:26:06]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb 4 15:05:57 UTC 2025 119s autopkgtest [06:26:09]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 124s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (dsc) [3480 B] 124s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (tar) [1965 kB] 124s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (asc) [833 B] 124s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (diff) [210 kB] 124s gpgv: Signature made Thu Nov 21 14:24:07 2024 UTC 124s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 124s gpgv: Can't check signature: No public key 124s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu2.dsc: no acceptable signature found 124s autopkgtest [06:26:14]: testing package openssh version 1:9.9p1-3ubuntu2 126s autopkgtest [06:26:16]: build not needed 128s autopkgtest [06:26:18]: test regress: preparing testbed 128s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s Starting pkgProblemResolver with broken count: 0 129s Starting 2 pkgProblemResolver with broken count: 0 129s Done 129s The following NEW packages will be installed: 129s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 129s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 129s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 129s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 129s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 129s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 129s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 129s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 129s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 129s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 129s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 129s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 129s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 129s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 129s python3-packaging python3-pyasn1 python3-pyasn1-modules 129s python3-service-identity python3-twisted python3-zope.interface wdiff 129s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 129s Need to get 8350 kB of archives. 129s After this operation, 38.2 MB of additional disk space will be used. 129s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 129s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 129s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear-bin s390x 2024.86-2 [151 kB] 129s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear all 2024.86-2 [8664 B] 129s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 129s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 129s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 129s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-touch-perl all 0.12-2 [7498 B] 129s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 129s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 129s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 129s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-xsaccessor-perl s390x 1.19-4build6 [34.2 kB] 129s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 129s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libdynaloader-functions-perl all 0.004-1 [11.4 kB] 129s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libdevel-callchecker-perl s390x 0.009-1build1 [14.2 kB] 129s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 129s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libmodule-runtime-perl all 0.016-2 [16.4 kB] 129s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 129s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 129s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 129s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 130s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 130s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 130s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 130s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 130s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 130s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x liburi-perl all 5.30-1 [94.4 kB] 130s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 130s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 130s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 130s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libio-html-perl all 1.004-3 [15.9 kB] 130s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 130s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 130s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 130s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 130s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x perl-openssl-defaults s390x 7build3 [6628 B] 130s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-ssleay-perl s390x 1.94-3 [320 kB] 130s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x libio-socket-ssl-perl all 2.089-1 [200 kB] 130s Get:39 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-http-perl all 6.23-1 [22.3 kB] 130s Get:40 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 130s Get:41 http://ftpmaster.internal/ubuntu plucky/main s390x libtry-tiny-perl all 0.32-1 [21.2 kB] 130s Get:42 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-robotrules-perl all 6.02-1 [12.6 kB] 130s Get:43 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-perl all 6.77-1 [138 kB] 130s Get:44 http://ftpmaster.internal/ubuntu plucky/main s390x patchutils s390x 0.4.2-1build3 [79.2 kB] 130s Get:45 http://ftpmaster.internal/ubuntu plucky/main s390x wdiff s390x 1.2.2-7 [29.8 kB] 130s Get:46 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x devscripts all 2.25.3 [1058 kB] 130s Get:47 http://ftpmaster.internal/ubuntu plucky/universe s390x libhavege2 s390x 1.9.19-9ubuntu1 [27.2 kB] 130s Get:48 http://ftpmaster.internal/ubuntu plucky/universe s390x putty-tools s390x 0.83-2 [741 kB] 130s Get:49 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hamcrest all 2.1.0-1 [28.1 kB] 130s Get:50 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1 all 0.6.0-1 [57.8 kB] 130s Get:51 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1-modules all 0.4.1-1 [80.2 kB] 130s Get:52 http://ftpmaster.internal/ubuntu plucky/main s390x python3-service-identity all 24.1.0-1 [11.2 kB] 130s Get:53 http://ftpmaster.internal/ubuntu plucky/main s390x python3-automat all 24.8.1-1 [35.5 kB] 130s Get:54 http://ftpmaster.internal/ubuntu plucky/main s390x python3-constantly all 23.10.4-2 [13.9 kB] 130s Get:55 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hyperlink all 21.0.0-6 [68.0 kB] 130s Get:56 http://ftpmaster.internal/ubuntu plucky/main s390x python3-packaging all 24.2-1 [51.5 kB] 130s Get:57 http://ftpmaster.internal/ubuntu plucky/main s390x python3-incremental all 24.7.2-3 [18.6 kB] 130s Get:58 http://ftpmaster.internal/ubuntu plucky/main s390x python3-zope.interface s390x 7.2-1 [140 kB] 130s Get:59 http://ftpmaster.internal/ubuntu plucky/main s390x python3-twisted all 24.11.0-1 [2066 kB] 130s Get:60 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-tests s390x 1:9.9p1-3ubuntu2 [1409 kB] 131s Get:61 http://ftpmaster.internal/ubuntu plucky/universe s390x haveged s390x 1.9.19-9ubuntu1 [32.7 kB] 131s Fetched 8350 kB in 2s (5037 kB/s) 131s Selecting previously unselected package libtommath1:s390x. 131s (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 ... 55928 files and directories currently installed.) 131s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 131s Unpacking libtommath1:s390x (1.3.0-1) ... 131s Selecting previously unselected package libtomcrypt1:s390x. 131s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 131s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 131s Selecting previously unselected package dropbear-bin. 131s Preparing to unpack .../02-dropbear-bin_2024.86-2_s390x.deb ... 131s Unpacking dropbear-bin (2024.86-2) ... 131s Selecting previously unselected package dropbear. 131s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 131s Unpacking dropbear (2024.86-2) ... 131s Selecting previously unselected package libfile-dirlist-perl. 131s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 131s Unpacking libfile-dirlist-perl (0.05-3) ... 131s Selecting previously unselected package libfile-which-perl. 131s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 131s Unpacking libfile-which-perl (1.27-2) ... 131s Selecting previously unselected package libfile-homedir-perl. 131s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 131s Unpacking libfile-homedir-perl (1.006-2) ... 131s Selecting previously unselected package libfile-touch-perl. 131s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 131s Unpacking libfile-touch-perl (0.12-2) ... 131s Selecting previously unselected package libio-pty-perl. 131s Preparing to unpack .../08-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 131s Unpacking libio-pty-perl (1:1.20-1build3) ... 131s Selecting previously unselected package libipc-run-perl. 131s Preparing to unpack .../09-libipc-run-perl_20231003.0-2_all.deb ... 131s Unpacking libipc-run-perl (20231003.0-2) ... 131s Selecting previously unselected package libclass-method-modifiers-perl. 131s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 131s Unpacking libclass-method-modifiers-perl (2.15-1) ... 131s Selecting previously unselected package libclass-xsaccessor-perl. 131s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_s390x.deb ... 131s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 131s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 131s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 131s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 131s Selecting previously unselected package libdynaloader-functions-perl. 131s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-1_all.deb ... 131s Unpacking libdynaloader-functions-perl (0.004-1) ... 131s Selecting previously unselected package libdevel-callchecker-perl:s390x. 131s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-1build1_s390x.deb ... 131s Unpacking libdevel-callchecker-perl:s390x (0.009-1build1) ... 131s Selecting previously unselected package libparams-classify-perl:s390x. 131s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_s390x.deb ... 131s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 131s Selecting previously unselected package libmodule-runtime-perl. 131s Preparing to unpack .../16-libmodule-runtime-perl_0.016-2_all.deb ... 131s Unpacking libmodule-runtime-perl (0.016-2) ... 131s Selecting previously unselected package libimport-into-perl. 131s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 131s Unpacking libimport-into-perl (1.002005-2) ... 131s Selecting previously unselected package librole-tiny-perl. 131s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 131s Unpacking librole-tiny-perl (2.002004-1) ... 131s Selecting previously unselected package libsub-quote-perl. 131s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 131s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 131s Selecting previously unselected package libmoo-perl. 131s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 131s Unpacking libmoo-perl (2.005005-1) ... 131s Selecting previously unselected package libencode-locale-perl. 131s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 131s Unpacking libencode-locale-perl (1.05-3) ... 131s Selecting previously unselected package libtimedate-perl. 131s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 131s Unpacking libtimedate-perl (2.3300-2) ... 131s Selecting previously unselected package libhttp-date-perl. 131s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 131s Unpacking libhttp-date-perl (6.06-1) ... 131s Selecting previously unselected package libfile-listing-perl. 131s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 131s Unpacking libfile-listing-perl (6.16-1) ... 131s Selecting previously unselected package libhtml-tagset-perl. 131s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 131s Unpacking libhtml-tagset-perl (3.24-1) ... 131s Selecting previously unselected package liburi-perl. 131s Preparing to unpack .../26-liburi-perl_5.30-1_all.deb ... 131s Unpacking liburi-perl (5.30-1) ... 131s Selecting previously unselected package libhtml-parser-perl:s390x. 131s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_s390x.deb ... 131s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 131s Selecting previously unselected package libhtml-tree-perl. 131s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 131s Unpacking libhtml-tree-perl (5.07-3) ... 131s Selecting previously unselected package libclone-perl:s390x. 131s Preparing to unpack .../29-libclone-perl_0.47-1_s390x.deb ... 131s Unpacking libclone-perl:s390x (0.47-1) ... 131s Selecting previously unselected package libio-html-perl. 131s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 131s Unpacking libio-html-perl (1.004-3) ... 131s Selecting previously unselected package liblwp-mediatypes-perl. 131s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 131s Unpacking liblwp-mediatypes-perl (6.04-2) ... 131s Selecting previously unselected package libhttp-message-perl. 131s Preparing to unpack .../32-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 131s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 131s Selecting previously unselected package libhttp-cookies-perl. 131s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 131s Unpacking libhttp-cookies-perl (6.11-1) ... 131s Selecting previously unselected package libhttp-negotiate-perl. 131s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 131s Unpacking libhttp-negotiate-perl (6.01-2) ... 131s Selecting previously unselected package perl-openssl-defaults:s390x. 131s Preparing to unpack .../35-perl-openssl-defaults_7build3_s390x.deb ... 131s Unpacking perl-openssl-defaults:s390x (7build3) ... 131s Selecting previously unselected package libnet-ssleay-perl:s390x. 131s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_s390x.deb ... 131s Unpacking libnet-ssleay-perl:s390x (1.94-3) ... 131s Selecting previously unselected package libio-socket-ssl-perl. 131s Preparing to unpack .../37-libio-socket-ssl-perl_2.089-1_all.deb ... 131s Unpacking libio-socket-ssl-perl (2.089-1) ... 131s Selecting previously unselected package libnet-http-perl. 131s Preparing to unpack .../38-libnet-http-perl_6.23-1_all.deb ... 131s Unpacking libnet-http-perl (6.23-1) ... 131s Selecting previously unselected package liblwp-protocol-https-perl. 131s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 131s Unpacking liblwp-protocol-https-perl (6.14-1) ... 131s Selecting previously unselected package libtry-tiny-perl. 131s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 131s Unpacking libtry-tiny-perl (0.32-1) ... 131s Selecting previously unselected package libwww-robotrules-perl. 131s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1_all.deb ... 131s Unpacking libwww-robotrules-perl (6.02-1) ... 131s Selecting previously unselected package libwww-perl. 131s Preparing to unpack .../42-libwww-perl_6.77-1_all.deb ... 131s Unpacking libwww-perl (6.77-1) ... 131s Selecting previously unselected package patchutils. 131s Preparing to unpack .../43-patchutils_0.4.2-1build3_s390x.deb ... 131s Unpacking patchutils (0.4.2-1build3) ... 131s Selecting previously unselected package wdiff. 131s Preparing to unpack .../44-wdiff_1.2.2-7_s390x.deb ... 131s Unpacking wdiff (1.2.2-7) ... 131s Selecting previously unselected package devscripts. 131s Preparing to unpack .../45-devscripts_2.25.3_all.deb ... 131s Unpacking devscripts (2.25.3) ... 132s Selecting previously unselected package libhavege2:s390x. 132s Preparing to unpack .../46-libhavege2_1.9.19-9ubuntu1_s390x.deb ... 132s Unpacking libhavege2:s390x (1.9.19-9ubuntu1) ... 132s Selecting previously unselected package putty-tools. 132s Preparing to unpack .../47-putty-tools_0.83-2_s390x.deb ... 132s Unpacking putty-tools (0.83-2) ... 132s Selecting previously unselected package python3-hamcrest. 132s Preparing to unpack .../48-python3-hamcrest_2.1.0-1_all.deb ... 132s Unpacking python3-hamcrest (2.1.0-1) ... 132s Selecting previously unselected package python3-pyasn1. 132s Preparing to unpack .../49-python3-pyasn1_0.6.0-1_all.deb ... 132s Unpacking python3-pyasn1 (0.6.0-1) ... 132s Selecting previously unselected package python3-pyasn1-modules. 132s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-1_all.deb ... 132s Unpacking python3-pyasn1-modules (0.4.1-1) ... 132s Selecting previously unselected package python3-service-identity. 132s Preparing to unpack .../51-python3-service-identity_24.1.0-1_all.deb ... 132s Unpacking python3-service-identity (24.1.0-1) ... 132s Selecting previously unselected package python3-automat. 132s Preparing to unpack .../52-python3-automat_24.8.1-1_all.deb ... 132s Unpacking python3-automat (24.8.1-1) ... 132s Selecting previously unselected package python3-constantly. 132s Preparing to unpack .../53-python3-constantly_23.10.4-2_all.deb ... 132s Unpacking python3-constantly (23.10.4-2) ... 132s Selecting previously unselected package python3-hyperlink. 132s Preparing to unpack .../54-python3-hyperlink_21.0.0-6_all.deb ... 132s Unpacking python3-hyperlink (21.0.0-6) ... 132s Selecting previously unselected package python3-packaging. 132s Preparing to unpack .../55-python3-packaging_24.2-1_all.deb ... 132s Unpacking python3-packaging (24.2-1) ... 132s Selecting previously unselected package python3-incremental. 132s Preparing to unpack .../56-python3-incremental_24.7.2-3_all.deb ... 132s Unpacking python3-incremental (24.7.2-3) ... 132s Selecting previously unselected package python3-zope.interface. 132s Preparing to unpack .../57-python3-zope.interface_7.2-1_s390x.deb ... 132s Unpacking python3-zope.interface (7.2-1) ... 132s Selecting previously unselected package python3-twisted. 132s Preparing to unpack .../58-python3-twisted_24.11.0-1_all.deb ... 132s Unpacking python3-twisted (24.11.0-1) ... 132s Selecting previously unselected package openssh-tests. 132s Preparing to unpack .../59-openssh-tests_1%3a9.9p1-3ubuntu2_s390x.deb ... 132s Unpacking openssh-tests (1:9.9p1-3ubuntu2) ... 132s Selecting previously unselected package haveged. 132s Preparing to unpack .../60-haveged_1.9.19-9ubuntu1_s390x.deb ... 132s Unpacking haveged (1.9.19-9ubuntu1) ... 132s Setting up wdiff (1.2.2-7) ... 132s Setting up libfile-which-perl (1.27-2) ... 132s Setting up libdynaloader-functions-perl (0.004-1) ... 132s Setting up libclass-method-modifiers-perl (2.15-1) ... 132s Setting up libio-pty-perl (1:1.20-1build3) ... 132s Setting up python3-zope.interface (7.2-1) ... 132s Setting up libclone-perl:s390x (0.47-1) ... 132s Setting up libtommath1:s390x (1.3.0-1) ... 132s Setting up libhtml-tagset-perl (3.24-1) ... 132s Setting up python3-automat (24.8.1-1) ... 132s Setting up liblwp-mediatypes-perl (6.04-2) ... 132s Setting up libtry-tiny-perl (0.32-1) ... 132s Setting up perl-openssl-defaults:s390x (7build3) ... 132s Setting up libencode-locale-perl (1.05-3) ... 132s Setting up python3-hamcrest (2.1.0-1) ... 132s Setting up putty-tools (0.83-2) ... 132s Setting up libhavege2:s390x (1.9.19-9ubuntu1) ... 132s Setting up patchutils (0.4.2-1build3) ... 132s Setting up python3-packaging (24.2-1) ... 132s Setting up python3-incremental (24.7.2-3) ... 132s Setting up python3-hyperlink (21.0.0-6) ... 132s Setting up libio-html-perl (1.004-3) ... 132s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 132s Setting up libipc-run-perl (20231003.0-2) ... 132s Setting up libtimedate-perl (2.3300-2) ... 132s Setting up librole-tiny-perl (2.002004-1) ... 132s Setting up python3-pyasn1 (0.6.0-1) ... 133s Setting up python3-constantly (23.10.4-2) ... 133s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 133s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 133s Setting up libfile-dirlist-perl (0.05-3) ... 133s Setting up libfile-homedir-perl (1.006-2) ... 133s Setting up liburi-perl (5.30-1) ... 133s Setting up libfile-touch-perl (0.12-2) ... 133s Setting up libnet-ssleay-perl:s390x (1.94-3) ... 133s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 133s Setting up libhttp-date-perl (6.06-1) ... 133s Setting up haveged (1.9.19-9ubuntu1) ... 133s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 133s Setting up dropbear-bin (2024.86-2) ... 133s Setting up libfile-listing-perl (6.16-1) ... 133s Setting up libnet-http-perl (6.23-1) ... 133s Setting up libdevel-callchecker-perl:s390x (0.009-1build1) ... 133s Setting up dropbear (2024.86-2) ... 133s Converting existing OpenSSH RSA host key to Dropbear format. 133s Key is a ssh-rsa key 133s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 133s 3072 SHA256:Q7PRck0PLXKaKZfH0prYqbUfremem7fJMk1mcDrIHDU /etc/dropbear/dropbear_rsa_host_key (RSA) 133s +---[RSA 3072]----+ 133s | o. | 133s | ..oEo. | 133s | = o@.o. | 133s | ..*O = . | 133s | SB O + | 133s | ..X o.+ | 133s | o ..*. | 133s | . . oBo. | 133s | +X=+. | 133s +----[SHA256]-----+ 133s Converting existing OpenSSH ECDSA host key to Dropbear format. 133s Key is a ecdsa-sha2-nistp256 key 133s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 133s 256 SHA256:qJzpsdTONVzk8bosJQp9a/pNDd/dPwRZCrH59e1/L9Y /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 133s +---[ECDSA 256]---+ 133s | .. | 133s | .o . | 133s | oo. +. | 133s | . o o.+. o| 133s | .. S + ... o| 133s | ..=..o..= . +.| 133s | B..o++o o o.+| 133s | o =..+= . o.E| 133s | o ++..+ . .B| 133s +----[SHA256]-----+ 133s Converting existing OpenSSH ED25519 host key to Dropbear format. 133s Key is a ssh-ed25519 key 133s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 133s 256 SHA256:OJ2UVsv6UwgfaSAz6i9UTF0LJ8PpSkWEFrPW/WJUW8Q /etc/dropbear/dropbear_ed25519_host_key (ED25519) 133s +--[ED25519 256]--+ 133s | BBBo+ .oo | 133s | +oB=@ = oE | 133s | ..=o* X . | 133s | . o.=.B + | 133s | o.o.S = o | 133s | . ... o o | 133s | . . o | 133s | . . | 133s | | 133s +----[SHA256]-----+ 133s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 134s Setting up python3-pyasn1-modules (0.4.1-1) ... 134s Setting up python3-service-identity (24.1.0-1) ... 134s Setting up libwww-robotrules-perl (6.02-1) ... 134s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 134s Setting up libio-socket-ssl-perl (2.089-1) ... 134s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 134s Setting up libhttp-negotiate-perl (6.01-2) ... 134s Setting up libhttp-cookies-perl (6.11-1) ... 134s Setting up libhtml-tree-perl (5.07-3) ... 134s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 134s Setting up libmodule-runtime-perl (0.016-2) ... 134s Setting up python3-twisted (24.11.0-1) ... 136s Setting up libimport-into-perl (1.002005-2) ... 136s Setting up libmoo-perl (2.005005-1) ... 136s Setting up openssh-tests (1:9.9p1-3ubuntu2) ... 136s Setting up liblwp-protocol-https-perl (6.14-1) ... 136s Setting up libwww-perl (6.77-1) ... 136s Setting up devscripts (2.25.3) ... 136s Processing triggers for install-info (7.1.1-1) ... 136s Processing triggers for libc-bin (2.40-4ubuntu1) ... 136s Processing triggers for man-db (2.13.0-1) ... 138s autopkgtest [06:26:28]: test regress: [----------------------- 138s info: Adding user `openssh-tests' ... 138s info: Selecting UID/GID from range 1000 to 59999 ... 138s info: Adding new group `openssh-tests' (1001) ... 138s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 138s info: Creating home directory `/home/openssh-tests' ... 138s info: Copying files from `/etc/skel' ... 138s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 138s info: Adding user `openssh-tests' to group `users' ... 138s I: annotate-output 2.25.3 138s I: prefix='%H:%M:%S.%N ' 138s 06:27:54.613397215 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user 138s 06:27:54.628189137 O: make: Entering directory '/tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress' 138s 06:27:54.628729818 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/valgrind-out 138s 06:27:54.629346131 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 138s 06:27:54.629855795 E: + grep -q ^ssh-rsa 138s 06:27:54.630309286 O: ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 138s 06:27:54.630817804 E: + /usr/bin/ssh -Q key 138s 06:27:54.631229747 O: tr '\n' '\r' /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 138s 06:27:54.631807213 O: ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 138s 06:27:54.632329944 O: awk '{print $0 "\r"}' /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 138s 06:27:54.634441625 O: ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 138s 06:27:54.634925752 O: fi 138s 06:27:54.635554058 E: + ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2.prv 138s 06:27:54.636060617 E: + diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv 139s 06:27:54.640072607 E: + tr \n \r 139s 06:27:54.641090188 E: + ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 139s 06:27:54.641571225 E: + diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv 139s 06:27:54.644358752 E: + awk {print $0 "\r"} /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2.prv 139s 06:27:54.645888550 E: + ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 139s 06:27:54.646409001 E: + diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv 139s 06:27:54.649768230 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 139s 06:27:54.650239353 O: cat /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t2.out ; \ 139s 06:27:54.650688984 O: chmod 600 /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t2.out ; \ 139s 06:27:54.651147898 O: ssh-keygen -yf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 139s 06:27:54.651584773 O: fi 139s 06:27:54.649583878 E: + + grep -q ^ssh-rsa 139s 06:27:54.652487420 E: /usr/bin/ssh -Q key 139s 06:27:54.653177420 E: + cat /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.prv 139s 06:27:54.653660667 E: + chmod 600 /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t2.out 139s 06:27:54.654533619 E: + ssh-keygen -yf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t2.out 139s 06:27:54.655023734 E: + diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub 139s 06:27:54.657325600 E: + /usr/bin/ssh -Q key 139s 06:27:54.656864934 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 139s 06:27:54.658307629 E: + grep -q ^ssh-rsa 139s 06:27:54.658734403 O: ssh-keygen -ef /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t3.out ; \ 139s 06:27:54.659315147 O: ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 139s 06:27:54.661331974 E: + ssh-keygen -ef /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub 139s 06:27:54.661717892 O: fi 139s 06:27:54.664448692 E: + ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t3.out 139s 06:27:54.664958663 E: + diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub 139s 06:27:54.666963051 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 139s 06:27:54.667528535 E: + /usr/bin/ssh -Q key 139s 06:27:54.668394120 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 139s 06:27:54.668892375 O: awk '{print $2}' | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t4.ok ; \ 139s 06:27:54.669347679 O: fi 139s 06:27:54.668305737 E: + grep -q ^ssh-rsa 139s 06:27:54.670949177 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub 139s 06:27:54.671947812 E: + awk {print $2} 139s 06:27:54.672409529 E: + diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t4.ok 139s 06:27:54.674667162 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 139s 06:27:54.675632852 O: ssh-keygen -Bf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 139s 06:27:54.676125284 O: awk '{print $2}' | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t5.ok ; \ 139s 06:27:54.676579369 O: fi 139s 06:27:54.677775182 E: + /usr/bin/ssh -Q key 139s 06:27:54.678985000 E: + grep -q ^ssh-rsa 139s 06:27:54.679491567 E: + ssh-keygen -Bf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub 139s 06:27:54.679970133 E: + awk+ {print $2} 139s 06:27:54.680454673 E: diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t5.ok 139s 06:27:54.681915592 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 139s 06:27:54.682360856 E: + /usr/bin/ssh -Q+ key 139s 06:27:54.683365473 E: grep -q ^ssh-dss 139s 06:27:54.683759091 O: ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t6.out1 ; \ 139s 06:27:54.684289307 O: ssh-keygen -if /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t6.out2 ; \ 139s 06:27:54.684808295 O: chmod 600 /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t6.out1 ; \ 139s 06:27:54.685310439 O: ssh-keygen -yf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t6.out2 ; \ 139s 06:27:54.685787166 O: fi 139s 06:27:54.686470590 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 139s 06:27:54.686894376 E: + /usr/bin/ssh+ -Q key 139s 06:27:54.688316534 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t7.out ; \ 139s 06:27:54.688797028 O: fi 139s 06:27:54.688942210 E: grep -q ^ssh-dss 139s 06:27:54.691372942 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 139s 06:27:54.691940710 O: ssh-keygen -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 139s 06:27:54.691967010 E: + + /usr/bin/ssh -Q key 139s 06:27:54.693012499 O: ssh-keygen -Bf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 139s 06:27:54.693109843 E: grep -q ^ssh-dss 139s 06:27:54.693491781 O: fi 139s 06:27:54.695907812 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 139s 06:27:54.696342170 E: + ssh+ -Q key 139s 06:27:54.696838798 E: grep -q ^ssh-dss 139s 06:27:54.697278386 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t8.out ; \ 139s 06:27:54.697731165 O: fi 139s 06:27:54.700083799 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 139s 06:27:54.701000624 O: ssh-keygen -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 139s 06:27:54.701470621 O: ssh-keygen -Bf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 139s 06:27:54.701931224 O: fi 139s 06:27:54.700472219 E: + ssh+ -Q key 139s 06:27:54.702472941 E: grep -q ^ssh-dss 139s 06:27:54.703978238 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 139s 06:27:54.704447868 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t9.out 139s 06:27:54.710051729 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 139s 06:27:54.710569504 O: ssh-keygen -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t9.out > /dev/null 139s 06:27:54.717601401 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 139s 06:27:54.718571235 O: ssh-keygen -Bf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t9.out > /dev/null 139s 06:27:54.725249305 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t10.out 139s 06:27:54.728911839 O: ssh-keygen -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t10.out > /dev/null 139s 06:27:54.732011534 O: ssh-keygen -Bf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t10.out > /dev/null 139s 06:27:54.735420075 E: + /usr/bin/ssh -Q key 139s 06:27:54.735555072 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 139s 06:27:54.736021492 E: + grep -q ^ssh-dss 139s 06:27:54.736457731 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 139s 06:27:54.737325972 O: awk '{print $2}' | diff - /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t11.ok ; \ 139s 06:27:54.737999166 O: fi 139s 06:27:54.739220974 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t12.out 139s 06:27:54.742540725 O: ssh-keygen -lf /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 139s 06:27:54.746974753 E: run test connect.sh ... 140s 06:27:56.151173934 O: ok simple connect 140s 06:27:56.151257242 E: run test proxy-connect.sh ... 140s 06:27:56.282473217 O: plain username comp=no 140s 06:27:56.578478147 O: plain username comp=yes 141s 06:27:56.882373753 O: username with style 141s 06:27:57.194726931 O: ok proxy connect 141s 06:27:57.195226563 E: run test sshfp-connect.sh ... 141s 06:27:57.331869426 E: run test connect-privsep.sh ... 141s 06:27:57.332326732 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 145s 06:26:35.649377080 O: ok proxy connect with privsep 145s 06:26:35.649871636 E: run test connect-uri.sh ... 145s 06:26:35.913663576 O: uri connect: no trailing slash 146s 06:26:36.225000757 O: uri connect: trailing slash 146s 06:26:36.535190070 O: uri connect: with path name 146s 06:26:36.549979782 O: ok uri connect 146s 06:26:36.550472137 E: run test proto-version.sh ... 146s 06:26:36.789642283 E: run test proto-mismatch.sh ... 146s 06:26:36.789345875 O: ok sshd version with different protocol combinations 147s 06:26:37.033617924 O: ok protocol version mismatch 147s 06:26:37.034163668 E: run test exit-status.sh ... 147s 06:26:37.163022442 O: test remote exit status: status 0 152s 06:26:42.759546166 O: test remote exit status: status 1 158s 06:26:48.368982471 O: test remote exit status: status 4 163s 06:26:53.958524843 O: test remote exit status: status 5 169s 06:26:59.554937427 O: test remote exit status: status 44 175s 06:27:05.152139992 O: ok remote exit status 175s 06:27:05.152639394 E: run test exit-status-signal.sh ... 176s 06:27:06.283333570 O: ok exit status on signal 176s 06:27:06.283238691 E: run test envpass.sh ... 176s 06:27:06.413978491 O: test environment passing: pass env, don't accept 176s 06:27:06.733476877 O: test environment passing: setenv, don't accept 177s 06:27:07.028088474 O: test environment passing: don't pass env, accept 177s 06:27:07.323205895 O: test environment passing: pass single env, accept single env 177s 06:27:07.613631518 O: test environment passing: pass multiple env, accept multiple env 177s 06:27:07.903320724 O: test environment passing: setenv, accept 178s 06:27:08.196565362 O: test environment passing: setenv, first match wins 178s 06:27:08.491142643 O: test environment passing: server setenv wins 178s 06:27:08.779677527 O: test environment passing: server setenv wins 179s 06:27:09.076738005 E: run test transfer.sh ... 179s 06:27:09.076202864 O: ok environment passing 181s 06:27:11.661694866 E: run test banner.sh ... 181s 06:27:11.662346411 O: ok transfer data 181s 06:27:11.790349655 O: test banner: missing banner file 182s 06:27:12.088014448 O: test banner: size 0 182s 06:27:12.384066472 O: test banner: size 10 182s 06:27:12.691488780 O: test banner: size 100 183s 06:27:12.997446266 O: test banner: size 1000 183s 06:27:13.298985738 O: test banner: size 10000 183s 06:27:13.608053077 O: test banner: size 100000 183s 06:27:13.916303510 O: test banner: suppress banner (-q) 184s 06:27:14.212558690 O: ok banner 184s 06:27:14.213174734 E: run test rekey.sh ... 184s 06:27:14.357502078 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 184s 06:27:14.601803769 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 184s 06:27:14.874441863 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 185s 06:27:15.146425703 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 185s 06:27:15.520614006 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 186s 06:27:16.276794991 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 187s 06:27:17.052409364 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 187s 06:27:17.834497512 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 188s 06:27:18.078058206 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 188s 06:27:18.328991963 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 188s 06:27:18.622114354 O: client rekey KexAlgorithms=curve25519-sha256 188s 06:27:18.909375092 O: client rekey KexAlgorithms=sntrup761x25519-sha512 189s 06:27:19.408214215 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 189s 06:27:19.882616024 O: client rekey KexAlgorithms=mlkem768x25519-sha256 190s 06:27:20.153281843 O: client rekey Ciphers=3des-cbc 190s 06:27:20.425102042 O: client rekey Ciphers=aes128-cbc 190s 06:27:20.689105412 O: client rekey Ciphers=aes192-cbc 190s 06:27:20.952775165 O: client rekey Ciphers=aes256-cbc 191s 06:27:21.218666400 O: client rekey Ciphers=aes128-ctr 191s 06:27:21.492857286 O: client rekey Ciphers=aes192-ctr 191s 06:27:21.762025378 O: client rekey Ciphers=aes256-ctr 192s 06:27:22.031611327 O: client rekey Ciphers=aes128-gcm@openssh.com 196s 06:27:26.334060977 O: client rekey Ciphers=aes256-gcm@openssh.com 201s 06:27:31.160947563 O: client rekey Ciphers=chacha20-poly1305@openssh.com 206s 06:27:36.817742714 O: client rekey MACs=hmac-sha1 207s 06:27:37.078751055 O: client rekey MACs=hmac-sha1-96 207s 06:27:37.344417085 O: client rekey MACs=hmac-sha2-256 207s 06:27:37.611801709 O: client rekey MACs=hmac-sha2-512 207s 06:27:37.871377691 O: client rekey MACs=hmac-md5 208s 06:27:38.134060972 O: client rekey MACs=hmac-md5-96 208s 06:27:38.400190271 O: client rekey MACs=umac-64@openssh.com 208s 06:27:38.663960251 O: client rekey MACs=umac-128@openssh.com 208s 06:27:38.924613718 O: client rekey MACs=hmac-sha1-etm@openssh.com 209s 06:27:39.190710249 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 209s 06:27:39.455746622 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 209s 06:27:39.717934226 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 209s 06:27:39.977599771 O: client rekey MACs=hmac-md5-etm@openssh.com 210s 06:27:40.238714835 O: client rekey MACs=hmac-md5-96-etm@openssh.com 210s 06:27:40.501795095 O: client rekey MACs=umac-64-etm@openssh.com 210s 06:27:40.761660165 O: client rekey MACs=umac-128-etm@openssh.com 211s 06:27:41.024867567 O: client rekeylimit 16 211s 06:27:41.312484579 O: client rekeylimit 1k 211s 06:27:41.585839773 O: client rekeylimit 128k 211s 06:27:41.875816201 O: client rekeylimit 256k 212s 06:27:42.233214391 O: client rekeylimit default 5 227s 06:27:57.486477160 O: client rekeylimit default 10 247s 06:28:17.726432077 O: client rekeylimit default 5 no data 262s 06:28:32.959392394 O: client rekeylimit default 10 no data 283s 06:28:53.206667732 O: server rekeylimit 16 283s 06:28:53.496802854 O: server rekeylimit 1k 283s 06:28:53.771451086 O: server rekeylimit 128k 284s 06:28:54.060279743 O: server rekeylimit 256k 284s 06:28:54.415054261 O: server rekeylimit default 5 no data 299s 06:29:09.663151676 O: server rekeylimit default 10 no data 320s 06:29:29.906349097 O: rekeylimit parsing: bytes 320s 06:29:29.952111835 O: rekeylimit parsing: time 320s 06:29:29.997410969 E: run test dhgex.sh ... 320s 06:29:29.996958213 O: ok rekey 320s 06:29:30.126408701 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 320s 06:29:30.359314075 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 320s 06:29:30.595432887 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 320s 06:29:30.831275979 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 321s 06:29:31.068058084 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 321s 06:29:31.296957853 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 321s 06:29:31.535316380 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 321s 06:29:31.768839774 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 322s 06:29:32.021453395 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 322s 06:29:32.333257686 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 322s 06:29:32.651838319 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 322s 06:29:32.962434494 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 323s 06:29:33.281132828 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 323s 06:29:33.590143151 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 323s 06:29:33.902408096 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 324s 06:29:34.218497308 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 324s 06:29:34.527545457 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 324s 06:29:34.835332774 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 325s 06:29:35.147572742 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 325s 06:29:35.550005561 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 325s 06:29:35.952834638 O: ok dhgex 325s 06:29:35.953449255 E: run test stderr-data.sh ... 326s 06:29:36.079489907 O: test stderr data transfer: () 332s 06:29:42.702149529 O: test stderr data transfer: (-n) 339s 06:29:49.320052062 O: ok stderr data transfer 339s 06:29:49.320707981 E: run test stderr-after-eof.sh ... 341s 06:29:51.756924429 O: ok stderr data after eof 341s 06:29:51.757202183 E: run test broken-pipe.sh ... 341s 06:29:51.900456632 E: run test try-ciphers.sh ... 341s 06:29:51.900991707 O: ok broken pipe test 342s 06:29:52.034264320 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 342s 06:29:52.342067067 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 342s 06:29:52.656383609 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 342s 06:29:52.955338686 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 343s 06:29:53.257268396 O: test try ciphers: cipher 3des-cbc mac hmac-md5 343s 06:29:53.560823938 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 343s 06:29:53.868441469 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 344s 06:29:54.162169190 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 344s 06:29:54.463772888 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 344s 06:29:54.765510472 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 345s 06:29:55.067438648 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 345s 06:29:55.375644112 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 345s 06:29:55.677970405 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 346s 06:29:56.011348448 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 346s 06:29:56.313674220 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 346s 06:29:56.604813941 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 346s 06:29:56.900603978 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 347s 06:29:57.195692437 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 347s 06:29:57.491060015 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 347s 06:29:57.791231801 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 348s 06:29:58.086100247 O: test try ciphers: cipher aes128-cbc mac hmac-md5 348s 06:29:58.380909758 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 348s 06:29:58.679929056 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 349s 06:29:58.985868200 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 349s 06:29:59.280745028 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 349s 06:29:59.588922901 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 349s 06:29:59.900196636 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 350s 06:30:00.243909435 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 350s 06:30:00.544376949 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 350s 06:30:00.846757157 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 351s 06:30:01.140579213 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 351s 06:30:01.448435972 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 351s 06:30:01.748384730 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 352s 06:30:02.051323353 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 352s 06:30:02.351477059 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 352s 06:30:02.657091121 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 352s 06:30:02.955302073 O: test try ciphers: cipher aes192-cbc mac hmac-md5 353s 06:30:03.255789410 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 353s 06:30:03.559578030 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 353s 06:30:03.861467789 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 354s 06:30:04.157083361 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 354s 06:30:04.458673313 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 354s 06:30:04.757440939 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 355s 06:30:05.052205910 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 355s 06:30:05.367036897 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 355s 06:30:05.674378119 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 356s 06:30:05.981382692 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 356s 06:30:06.281419245 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 356s 06:30:06.590970402 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 356s 06:30:06.890441110 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 357s 06:30:07.191062788 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 357s 06:30:07.496136081 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 357s 06:30:07.795608291 O: test try ciphers: cipher aes256-cbc mac hmac-md5 358s 06:30:08.102392908 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 358s 06:30:08.404844204 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 358s 06:30:08.711949337 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 359s 06:30:09.015122051 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 359s 06:30:09.313116119 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 359s 06:30:09.616521760 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 359s 06:30:09.916093347 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 360s 06:30:10.214837032 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 360s 06:30:10.517396303 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 360s 06:30:10.811602551 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 361s 06:30:11.115497482 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 361s 06:30:11.411432389 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 361s 06:30:11.709956315 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 362s 06:30:12.008270347 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 362s 06:30:12.308786757 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 362s 06:30:12.607591046 O: test try ciphers: cipher aes128-ctr mac hmac-md5 362s 06:30:12.917523515 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 363s 06:30:13.208078784 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 363s 06:30:13.504565763 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 363s 06:30:13.801328349 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 364s 06:30:14.100257937 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 364s 06:30:14.403383236 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 364s 06:30:14.698579329 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 365s 06:30:14.996833541 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 365s 06:30:15.307160530 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 365s 06:30:15.611245551 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 365s 06:30:15.920805295 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 366s 06:30:16.226112735 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 366s 06:30:16.529054141 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 366s 06:30:16.834843317 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 367s 06:30:17.137016375 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 367s 06:30:17.443719745 O: test try ciphers: cipher aes192-ctr mac hmac-md5 367s 06:30:17.738026823 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 368s 06:30:18.041394224 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 368s 06:30:18.336257017 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 368s 06:30:18.635938703 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 368s 06:30:18.937876806 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 369s 06:30:19.234323883 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 369s 06:30:19.538897359 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 369s 06:30:19.845216100 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 370s 06:30:20.148618159 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 370s 06:30:20.455610502 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 370s 06:30:20.757905564 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 371s 06:30:21.063577664 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 371s 06:30:21.362498850 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 371s 06:30:21.662704745 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 371s 06:30:21.955961945 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 372s 06:30:22.253195155 O: test try ciphers: cipher aes256-ctr mac hmac-md5 372s 06:30:22.547688265 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 372s 06:30:22.857889570 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 373s 06:30:23.162460433 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 373s 06:30:23.469080824 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 373s 06:30:23.775234098 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 374s 06:30:24.082374115 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 374s 06:30:24.377899180 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 374s 06:30:24.678328563 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 375s 06:30:24.977984240 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 375s 06:30:25.282164469 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 375s 06:30:25.595124291 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 375s 06:30:25.901291623 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 376s 06:30:26.207503107 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 376s 06:30:26.513935199 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 376s 06:30:26.815272187 O: ok try ciphers 376s 06:30:26.815766929 E: run test yes-head.sh ... 379s 06:30:29.946761839 O: ok yes pipe head 379s 06:30:29.946751410 E: run test login-timeout.sh ... 395s 06:30:45.542925673 O: ok connect after login grace timeout 395s 06:30:45.543428053 E: run test agent.sh ... 405s 06:30:55.511138998 O: ok simple agent test 405s 06:30:55.511559893 E: run test agent-getpeereid.sh ... 405s 06:30:55.655121486 E: run test agent-timeout.sh ... 405s 06:30:55.655805798 O: ok disallow agent attach from other uid 425s 06:31:15.874020449 O: ok agent timeout test 425s 06:31:15.874504136 E: run test agent-ptrace.sh ... 426s 06:31:16.001572723 O: skipped (gdb not found) 426s 06:31:16.002132477 E: run test agent-subprocess.sh ... 436s 06:31:26.145292572 E: run test keyscan.sh ... 436s 06:31:26.145943465 O: ok agent subprocess 438s 06:31:28.453792991 O: ok keyscan 438s 06:31:28.454393665 E: run test keygen-change.sh ... 442s 06:31:32.087270048 O: ok change passphrase for key 442s 06:31:32.087768953 E: run test keygen-comment.sh ... 445s 06:31:35.061831283 O: ok Comment extraction from private key 445s 06:31:35.062479357 E: run test keygen-convert.sh ... 448s 06:31:37.782083454 O: ok convert keys 448s 06:31:37.782735388 E: run test keygen-knownhosts.sh ... 448s 06:31:37.995735704 O: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts updated. 448s 06:31:37.996267799 O: Original contents retained as /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts.old 448s 06:31:38.006717929 O: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts updated. 448s 06:31:38.007210559 O: Original contents retained as /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts.old 448s 06:31:38.010685291 O: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts updated. 448s 06:31:38.011261586 O: Original contents retained as /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts.old 448s 06:31:38.018912624 O: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts updated. 448s 06:31:38.019397112 O: Original contents retained as /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hosts.old 448s 06:31:38.032208944 O: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hashed updated. 448s 06:31:38.032695173 O: Original contents retained as /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/kh.hashed.old 448s 06:31:38.035341188 E: run test keygen-moduli.sh ... 448s 06:31:38.035762237 O: ok ssh-keygen known_hosts 453s 06:31:43.129882434 E: run test keygen-sshfp.sh ... 453s 06:31:43.129343787 O: ok keygen moduli 453s 06:31:43.283965792 O: ok keygen-sshfp 453s 06:31:43.284219320 E: run test key-options.sh ... 453s 06:31:43.413781552 O: key option command="echo bar" 453s 06:31:43.712493954 O: key option no-pty,command="echo bar" 454s 06:31:44.012033752 O: key option pty default 454s 06:31:44.314781619 O: key option pty no-pty 454s 06:31:44.611816928 O: key option pty restrict 454s 06:31:44.913612279 O: key option pty restrict,pty 455s 06:31:45.209530847 O: key option environment 456s 06:31:45.964158410 O: key option from="127.0.0.1" 457s 06:31:46.988575467 O: key option from="127.0.0.0/8" 457s 06:31:47.705724781 O: key option expiry-time default 458s 06:31:48.009545879 O: key option expiry-time invalid 458s 06:31:48.414748651 O: key option expiry-time expired 458s 06:31:48.816397267 O: key option expiry-time valid 459s 06:31:49.131557136 O: ok key options 459s 06:31:49.132183642 E: run test scp.sh ... 459s 06:31:49.263739171 O: scp: scp mode: simple copy local file to local file 459s 06:31:49.268794511 O: scp: scp mode: simple copy local file to remote file 459s 06:31:49.273519335 O: scp: scp mode: simple copy remote file to local file 459s 06:31:49.278301424 O: scp: scp mode: copy local file to remote file in place 459s 06:31:49.284020978 O: scp: scp mode: copy remote file to local file in place 459s 06:31:49.289509802 O: scp: scp mode: copy local file to remote file clobber 459s 06:31:49.294754165 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 Feb 22 06:31 /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/copy 459s 06:31:49.295278716 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 Feb 22 06:31 /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/data 459s 06:31:49.296065947 O: scp: scp mode: copy remote file to local file clobber 459s 06:31:49.301940828 O: scp: scp mode: simple copy local file to remote dir 459s 06:31:49.307749398 O: scp: scp mode: simple copy local file to local dir 459s 06:31:49.312953233 O: scp: scp mode: simple copy remote file to local dir 459s 06:31:49.318463694 O: scp: scp mode: recursive local dir to remote dir 459s 06:31:49.328909228 O: scp: scp mode: recursive local dir to local dir 459s 06:31:49.339111214 O: scp: scp mode: recursive remote dir to local dir 459s 06:31:49.350825296 O: scp: scp mode: unmatched glob file local->remote 459s 06:31:49.355978665 O: scp: scp mode: unmatched glob file remote->local 459s 06:31:49.359035547 O: scp: scp mode: unmatched glob dir recursive local->remote 459s 06:31:49.367020585 O: scp: scp mode: unmatched glob dir recursive remote->local 459s 06:31:49.371158415 O: scp: scp mode: shell metacharacters 459s 06:31:49.375529757 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 459s 06:31:49.395071426 O: scp: scp mode: disallow bad server #0 459s 06:31:49.406960791 O: scp: scp mode: disallow bad server #1 459s 06:31:49.418092525 O: scp: scp mode: disallow bad server #2 459s 06:31:49.429434227 O: scp: scp mode: disallow bad server #3 459s 06:31:49.442060355 O: scp: scp mode: disallow bad server #4 459s 06:31:49.453409471 O: scp: scp mode: disallow bad server #5 459s 06:31:49.464765452 O: scp: scp mode: disallow bad server #6 459s 06:31:49.475853016 O: scp: scp mode: disallow bad server #7 459s 06:31:49.487505076 O: scp: scp mode: detect non-directory target 459s 06:31:49.489466833 E: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/copy2: Not a directory 459s 06:31:49.490377342 O: scp: sftp mode: simple copy local file to local file 459s 06:31:49.495093484 O: scp: sftp mode: simple copy local file to remote file 459s 06:31:49.501120535 O: scp: sftp mode: simple copy remote file to local file 459s 06:31:49.506311334 O: scp: sftp mode: copy local file to remote file in place 459s 06:31:49.513144110 O: scp: sftp mode: copy remote file to local file in place 459s 06:31:49.519222703 O: scp: sftp mode: copy local file to remote file clobber 459s 06:31:49.525661689 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 Feb 22 06:31 /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/copy 459s 06:31:49.526137890 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 Feb 22 06:31 /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/data 459s 06:31:49.527514658 O: scp: sftp mode: copy remote file to local file clobber 459s 06:31:49.533270306 O: scp: sftp mode: simple copy local file to remote dir 459s 06:31:49.539781308 O: scp: sftp mode: simple copy local file to local dir 459s 06:31:49.545351866 O: scp: sftp mode: simple copy remote file to local dir 459s 06:31:49.551712472 O: scp: sftp mode: recursive local dir to remote dir 459s 06:31:49.563748580 O: scp: sftp mode: recursive local dir to local dir 459s 06:31:49.573737696 O: scp: sftp mode: recursive remote dir to local dir 459s 06:31:49.587483682 O: scp: sftp mode: unmatched glob file local->remote 459s 06:31:49.593477384 O: scp: sftp mode: unmatched glob file remote->local 459s 06:31:49.598252405 O: scp: sftp mode: unmatched glob dir recursive local->remote 459s 06:31:49.607122018 O: scp: sftp mode: unmatched glob dir recursive remote->local 459s 06:31:49.613676818 O: scp: sftp mode: shell metacharacters 459s 06:31:49.618073397 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 459s 06:31:49.639346440 O: scp: sftp mode: disallow bad server #0 459s 06:31:49.656982029 O: scp: sftp mode: disallow bad server #1 459s 06:31:49.674211013 O: scp: sftp mode: disallow bad server #2 459s 06:31:49.691834836 O: scp: sftp mode: disallow bad server #3 459s 06:31:49.708865265 O: scp: sftp mode: disallow bad server #4 459s 06:31:49.726710938 O: scp: sftp mode: disallow bad server #5 459s 06:31:49.743734987 O: scp: sftp mode: disallow bad server #6 459s 06:31:49.760762428 O: scp: sftp mode: disallow bad server #7 459s 06:31:49.778368229 O: scp: sftp mode: detect non-directory target 459s 06:31:49.780725111 E: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/copy2: Not a directory 459s 06:31:49.783764734 O: ok scp 459s 06:31:49.783933381 E: run test scp3.sh ... 459s 06:31:49.938203481 O: scp3: scp mode: simple copy remote file to remote file 460s 06:31:50.286558862 O: scp3: scp mode: simple copy remote file to remote dir 460s 06:31:50.633656762 O: scp3: scp mode: recursive remote dir to remote dir 461s 06:31:51.010171274 O: scp3: scp mode: detect non-directory target 461s 06:31:51.667502273 O: scp3: sftp mode: simple copy remote file to remote file 461s 06:31:51.674441822 O: scp3: sftp mode: simple copy remote file to remote dir 461s 06:31:51.682007917 O: scp3: sftp mode: recursive remote dir to remote dir 461s 06:31:51.698524829 O: scp3: sftp mode: detect non-directory target 461s 06:31:51.701805862 E: scp: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/copy2: destination is not a directory 461s 06:31:51.702396518 E: scp: /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/copy2: destination is not a directory 461s 06:31:51.705684708 O: ok scp3 461s 06:31:51.706231617 E: run test scp-uri.sh ... 461s 06:31:51.836791248 O: scp-uri: scp mode: simple copy local file to remote file 461s 06:31:51.840713489 O: scp-uri: scp mode: simple copy remote file to local file 461s 06:31:51.844559179 O: scp-uri: scp mode: simple copy local file to remote dir 461s 06:31:51.849233617 O: scp-uri: scp mode: simple copy remote file to local dir 461s 06:31:51.853973727 O: scp-uri: scp mode: recursive local dir to remote dir 461s 06:31:51.859436612 O: scp-uri: scp mode: recursive remote dir to local dir 461s 06:31:51.864893751 O: scp-uri: sftp mode: simple copy local file to remote file 461s 06:31:51.869858044 O: scp-uri: sftp mode: simple copy remote file to local file 461s 06:31:51.874172636 O: scp-uri: sftp mode: simple copy local file to remote dir 461s 06:31:51.879974918 O: scp-uri: sftp mode: simple copy remote file to local dir 461s 06:31:51.885311095 O: scp-uri: sftp mode: recursive local dir to remote dir 461s 06:31:51.891918251 O: scp-uri: sftp mode: recursive remote dir to local dir 461s 06:31:51.900007767 E: run test sftp.sh ... 461s 06:31:51.899506122 O: ok scp-uri 462s 06:31:52.027063842 O: test basic sftp put/get: buffer_size 5 num_requests 1 466s 06:31:56.676537773 O: test basic sftp put/get: buffer_size 5 num_requests 2 469s 06:31:59.335124196 O: test basic sftp put/get: buffer_size 5 num_requests 10 470s 06:32:00.768043509 O: test basic sftp put/get: buffer_size 1000 num_requests 1 470s 06:32:00.783056980 O: test basic sftp put/get: buffer_size 1000 num_requests 2 470s 06:32:00.799795206 O: test basic sftp put/get: buffer_size 1000 num_requests 10 470s 06:32:00.811390684 O: test basic sftp put/get: buffer_size 32000 num_requests 1 470s 06:32:00.817364472 O: test basic sftp put/get: buffer_size 32000 num_requests 2 470s 06:32:00.823037760 O: test basic sftp put/get: buffer_size 32000 num_requests 10 470s 06:32:00.828137787 O: test basic sftp put/get: buffer_size 64000 num_requests 1 470s 06:32:00.833682434 O: test basic sftp put/get: buffer_size 64000 num_requests 2 470s 06:32:00.838872450 O: test basic sftp put/get: buffer_size 64000 num_requests 10 470s 06:32:00.845271975 O: ok basic sftp put/get 470s 06:32:00.845823597 E: run test sftp-chroot.sh ... 471s 06:32:01.115430373 O: test sftp in chroot: get 471s 06:32:01.438773993 O: test sftp in chroot: match 471s 06:32:01.915400796 O: ok sftp in chroot 471s 06:32:01.919562354 E: run test sftp-cmds.sh ... 472s 06:32:02.049711214 O: sftp commands: lls 472s 06:32:02.052671020 O: sftp commands: lls w/path 472s 06:32:02.056423318 O: sftp commands: ls 472s 06:32:02.060504764 O: sftp commands: shell 472s 06:32:02.063956151 O: sftp commands: pwd 472s 06:32:02.065643350 O: sftp commands: lpwd 472s 06:32:02.067369378 O: sftp commands: quit 472s 06:32:02.069064001 O: sftp commands: help 472s 06:32:02.071200323 O: sftp commands: get 472s 06:32:02.075694719 O: sftp commands: get quoted 472s 06:32:02.080733225 O: sftp commands: get filename with quotes 472s 06:32:02.086509392 O: sftp commands: get filename with spaces 472s 06:32:02.091820481 O: sftp commands: get filename with glob metacharacters 472s 06:32:02.095957483 O: sftp commands: get to directory 472s 06:32:02.100148024 O: sftp commands: glob get to directory 472s 06:32:02.135262578 O: sftp commands: get to local dir 472s 06:32:02.139846546 O: sftp commands: glob get to local dir 472s 06:32:02.155657908 O: sftp commands: put 472s 06:32:02.160071348 O: sftp commands: put filename with quotes 472s 06:32:02.164712520 O: sftp commands: put filename with spaces 472s 06:32:02.170250599 O: sftp commands: put to directory 472s 06:32:02.174706479 O: sftp commands: glob put to directory 472s 06:32:02.179567204 O: sftp commands: put to local dir 472s 06:32:02.184215023 O: sftp commands: glob put to local dir 472s 06:32:02.197924898 O: sftp commands: rename 472s 06:32:02.200579647 O: sftp commands: rename directory 472s 06:32:02.202196498 O: sftp commands: ln 472s 06:32:02.204077999 O: sftp commands: ln -s 472s 06:32:02.206292118 O: sftp commands: cp 472s 06:32:02.209947180 O: sftp commands: mkdir 472s 06:32:02.211765298 O: sftp commands: chdir 472s 06:32:02.213294149 O: sftp commands: rmdir 472s 06:32:02.214881809 O: sftp commands: lmkdir 472s 06:32:02.216479290 O: sftp commands: lchdir 472s 06:32:02.220973222 E: run test sftp-badcmds.sh ... 472s 06:32:02.220446354 O: ok sftp commands 472s 06:32:02.349491050 O: sftp invalid commands: get nonexistent 472s 06:32:02.351664851 O: sftp invalid commands: glob get to nonexistent directory 472s 06:32:02.359760066 O: sftp invalid commands: put nonexistent 472s 06:32:02.361879201 O: sftp invalid commands: glob put to nonexistent directory 472s 06:32:02.364584115 O: sftp invalid commands: rename nonexistent 472s 06:32:02.368068561 O: sftp invalid commands: rename target exists (directory) 472s 06:32:02.371950059 O: sftp invalid commands: glob put files to local file 472s 06:32:02.375030505 O: ok sftp invalid commands 472s 06:32:02.375589623 E: run test sftp-batch.sh ... 472s 06:32:02.505977585 O: sftp batchfile: good commands 472s 06:32:02.510097268 O: sftp batchfile: bad commands 472s 06:32:02.515390251 O: sftp batchfile: comments and blanks 472s 06:32:02.518744898 O: sftp batchfile: junk command 472s 06:32:02.521338400 E: run test sftp-glob.sh ... 472s 06:32:02.520830608 O: ok sftp batchfile 472s 06:32:02.650451193 O: sftp glob: file glob 472s 06:32:02.654724316 O: sftp glob: dir glob 472s 06:32:02.657816228 O: sftp glob: quoted glob 472s 06:32:02.661557422 O: sftp glob: escaped glob 472s 06:32:02.665280194 O: sftp glob: escaped quote 472s 06:32:02.668331787 O: sftp glob: quoted quote 472s 06:32:02.671343369 O: sftp glob: single-quoted quote 472s 06:32:02.674390846 O: sftp glob: escaped space 472s 06:32:02.677408641 O: sftp glob: quoted space 472s 06:32:02.680444093 O: sftp glob: escaped slash 472s 06:32:02.683499513 O: sftp glob: quoted slash 472s 06:32:02.686594500 O: sftp glob: escaped slash at EOL 472s 06:32:02.689745570 O: sftp glob: quoted slash at EOL 472s 06:32:02.692877052 O: sftp glob: escaped slash+quote 472s 06:32:02.695847012 O: sftp glob: quoted slash+quote 472s 06:32:02.699681691 E: run test sftp-perm.sh ... 472s 06:32:02.699184349 O: ok sftp glob 472s 06:32:02.828028231 O: sftp permissions: read-only upload 472s 06:32:02.836803416 O: sftp permissions: read-only setstat 472s 06:32:02.844998935 O: sftp permissions: read-only rm 472s 06:32:02.852445943 O: sftp permissions: read-only mkdir 472s 06:32:02.859286700 O: sftp permissions: read-only rmdir 472s 06:32:02.867016471 O: sftp permissions: read-only posix-rename 472s 06:32:02.874927577 O: sftp permissions: read-only oldrename 472s 06:32:02.883266457 O: sftp permissions: read-only symlink 472s 06:32:02.891320745 O: sftp permissions: read-only hardlink 472s 06:32:02.898967789 O: sftp permissions: explicit open 472s 06:32:02.915824161 O: sftp permissions: explicit read 472s 06:32:02.933510133 O: sftp permissions: explicit write 472s 06:32:02.952185329 O: sftp permissions: explicit lstat 473s 06:32:02.968838097 O: sftp permissions: explicit opendir 473s 06:32:02.985913368 O: sftp permissions: explicit readdir 473s 06:32:03.002829713 O: sftp permissions: explicit setstat 473s 06:32:03.018677271 O: sftp permissions: explicit remove 473s 06:32:03.033265237 O: sftp permissions: explicit mkdir 473s 06:32:03.045855923 O: sftp permissions: explicit rmdir 473s 06:32:03.061407440 O: sftp permissions: explicit rename 473s 06:32:03.076276318 O: sftp permissions: explicit symlink 473s 06:32:03.090556039 O: sftp permissions: explicit hardlink 473s 06:32:03.104793296 O: sftp permissions: explicit statvfs 473s 06:32:03.115769088 O: ok sftp permissions 473s 06:32:03.116311680 E: run test sftp-uri.sh ... 473s 06:32:03.382155649 O: sftp-uri: non-interactive fetch to local file 473s 06:32:03.712096612 O: sftp-uri: non-interactive fetch to local dir 474s 06:32:04.041540453 O: sftp-uri: put to remote directory (trailing slash) 474s 06:32:04.360259721 O: sftp-uri: put to remote directory (no slash) 474s 06:32:04.695828090 O: ok sftp-uri 474s 06:32:04.696469395 E: run test reconfigure.sh ... 488s 06:32:18.324136394 E: run test dynamic-forward.sh ... 488s 06:32:18.323967096 O: ok simple connect after reconfigure 488s 06:32:18.591851685 O: test -D forwarding 490s 06:32:20.119506392 O: test -R forwarding 492s 06:32:22.818530044 O: PermitRemoteOpen=any 495s 06:32:25.491466570 O: PermitRemoteOpen=none 496s 06:32:25.976767530 O: PermitRemoteOpen=explicit 497s 06:32:27.670878765 O: PermitRemoteOpen=disallowed 498s 06:32:28.168639825 E: run test forwarding.sh ... 498s 06:32:28.168084278 O: ok dynamic forwarding 504s 06:32:34.839326263 O: ok local and remote forwarding 504s 06:32:34.839963095 E: run test multiplex.sh ... 506s 06:32:36.125818433 O: test connection multiplexing: setenv 506s 06:32:36.134236170 O: test connection multiplexing: envpass 506s 06:32:36.141898000 O: test connection multiplexing: transfer 506s 06:32:36.167721753 O: test connection multiplexing: transfer -Oproxy 506s 06:32:36.226404594 O: test connection multiplexing: forward 508s 06:32:38.261651450 O: test connection multiplexing: status 0 () 513s 06:32:43.281725183 O: test connection multiplexing: status 0 (-Oproxy) 518s 06:32:48.302276913 O: test connection multiplexing: status 1 () 523s 06:32:53.321489702 O: test connection multiplexing: status 1 (-Oproxy) 528s 06:32:58.340650775 O: test connection multiplexing: status 4 () 533s 06:33:03.358681796 O: test connection multiplexing: status 4 (-Oproxy) 538s 06:33:08.377490637 O: test connection multiplexing: status 5 () 543s 06:33:13.395556964 O: test connection multiplexing: status 5 (-Oproxy) 548s 06:33:18.414861729 O: test connection multiplexing: status 44 () 553s 06:33:23.432536756 O: test connection multiplexing: status 44 (-Oproxy) 558s 06:33:28.450969185 O: test connection multiplexing: cmd check 558s 06:33:28.456519952 O: test connection multiplexing: cmd forward local (TCP) 559s 06:33:29.793501805 O: test connection multiplexing: cmd forward remote (TCP) 561s 06:33:31.133425920 O: test connection multiplexing: cmd forward local (UNIX) 562s 06:33:32.193276814 O: test connection multiplexing: cmd forward remote (UNIX) 563s 06:33:33.253920887 O: test connection multiplexing: cmd exit 563s 06:33:33.260328490 O: test connection multiplexing: cmd stop 574s 06:33:44.295111960 E: run test reexec.sh ... 574s 06:33:44.294629817 O: ok connection multiplexing 574s 06:33:44.420696775 O: test config passing 574s 06:33:44.889164485 E: ln: failed to create hard link '/tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 574s 06:33:44.888768172 O: test reexec fallback 575s 06:33:45.347588042 O: ok reexec tests 575s 06:33:45.348094293 E: run test brokenkeys.sh ... 575s 06:33:45.935034677 O: ok broken keys 575s 06:33:45.935627021 E: run test sshcfgparse.sh ... 576s 06:33:46.068948853 O: reparse minimal config 576s 06:33:46.081946752 O: ssh -W opts 576s 06:33:46.116406757 O: user first match 576s 06:33:46.138304153 O: pubkeyacceptedalgorithms 576s 06:33:46.168898594 O: agentforwarding 576s 06:33:46.193633645 O: command line override 576s 06:33:46.207542339 E: run test cfgparse.sh ... 576s 06:33:46.207058036 O: ok ssh config parse 576s 06:33:46.337780826 O: reparse minimal config 576s 06:33:46.475084437 O: reparse regress config 576s 06:33:46.610569003 O: listenaddress order 576s 06:33:46.749384799 O: ok sshd config parse 576s 06:33:46.749926196 E: run test cfgmatch.sh ... 584s 06:33:54.601118181 O: ok sshd_config match 584s 06:33:54.601789373 E: run test cfgmatchlisten.sh ... 596s 06:34:06.421813010 O: ok sshd_config matchlisten 596s 06:34:06.422300392 E: run test percent.sh ... 596s 06:34:06.548954858 O: percent expansions matchexec percent 600s 06:34:10.412342594 O: percent expansions localcommand percent 604s 06:34:13.953057092 O: percent expansions remotecommand percent 604s 06:34:14.029596067 O: percent expansions controlpath percent 604s 06:34:14.108632867 O: percent expansions identityagent percent 604s 06:34:14.187582306 O: percent expansions forwardagent percent 604s 06:34:14.267170389 O: percent expansions localforward percent 604s 06:34:14.354583299 O: percent expansions remoteforward percent 604s 06:34:14.435740304 O: percent expansions revokedhostkeys percent 604s 06:34:14.516880672 O: percent expansions userknownhostsfile percent 607s 06:34:17.233751956 O: percent expansions controlpath dollar 607s 06:34:17.241281519 O: percent expansions identityagent dollar 607s 06:34:17.248152925 O: percent expansions forwardagent dollar 607s 06:34:17.255162755 O: percent expansions localforward dollar 607s 06:34:17.261977077 O: percent expansions remoteforward dollar 607s 06:34:17.268648176 O: percent expansions userknownhostsfile dollar 607s 06:34:17.531525153 O: percent expansions controlpath tilde 607s 06:34:17.545315003 O: percent expansions identityagent tilde 607s 06:34:17.558392815 O: percent expansions forwardagent tilde 607s 06:34:17.571435565 E: run test addrmatch.sh ... 607s 06:34:17.571930825 O: ok percent expansions 607s 06:34:17.698262216 O: test first entry for user 192.168.0.1 somehost 607s 06:34:17.763071474 O: test negative match for user 192.168.30.1 somehost 607s 06:34:17.827632887 O: test no match for user 19.0.0.1 somehost 607s 06:34:17.891808689 O: test list middle for user 10.255.255.254 somehost 608s 06:34:17.956052979 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 608s 06:34:18.020217664 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 608s 06:34:18.084395495 O: test localaddress for user 19.0.0.1 somehost 608s 06:34:18.149642483 O: test localport for user 19.0.0.1 somehost 608s 06:34:18.213926818 O: test bare IP6 address for user ::1 somehost.example.com 608s 06:34:18.278315667 O: test deny IPv6 for user ::2 somehost.example.com 608s 06:34:18.342865970 O: test IP6 negated for user ::3 somehost 608s 06:34:18.407378069 O: test IP6 no match for user ::4 somehost 608s 06:34:18.471976891 O: test IP6 network for user 2000::1 somehost 608s 06:34:18.536474820 O: test IP6 network for user 2001::1 somehost 608s 06:34:18.600819359 O: test IP6 localaddress for user ::5 somehost 608s 06:34:18.665284763 O: test IP6 localport for user ::5 somehost 608s 06:34:18.729963240 O: test invalid Match address 10.0.1.0/8 608s 06:34:18.736178225 O: test invalid Match localaddress 10.0.1.0/8 608s 06:34:18.742080899 O: test invalid Match address 10.0.0.1/24 608s 06:34:18.747941053 O: test invalid Match localaddress 10.0.0.1/24 608s 06:34:18.753746189 O: test invalid Match address 2000:aa:bb:01::/56 608s 06:34:18.759586214 O: test invalid Match localaddress 2000:aa:bb:01::/56 608s 06:34:18.766524391 O: ok address match 608s 06:34:18.767042629 E: run test localcommand.sh ... 608s 06:34:18.891053443 O: test localcommand: proto localcommand 609s 06:34:19.182945869 E: run test forcecommand.sh ... 609s 06:34:19.183411090 O: ok localcommand 610s 06:34:20.488078420 E: Connection closed. 610s 06:34:20.488534070 E: Connection closed 611s 06:34:21.071073068 E: Connection closed 611s 06:34:21.368298809 E: run test portnum.sh ... 611s 06:34:21.367849025 O: ok forced command 611s 06:34:21.491739670 O: port number parsing: invalid port 0 611s 06:34:21.496285648 O: port number parsing: invalid port 65536 611s 06:34:21.500816730 O: port number parsing: invalid port 131073 611s 06:34:21.505341776 O: port number parsing: invalid port 2000blah 611s 06:34:21.509855740 O: port number parsing: invalid port blah2000 611s 06:34:21.514512146 O: port number parsing: valid port 1 611s 06:34:21.803674859 O: port number parsing: valid port 22 612s 06:34:22.092284051 O: port number parsing: valid port 2222 612s 06:34:22.380054884 O: port number parsing: valid port 22222 612s 06:34:22.669087463 O: port number parsing: valid port 65535 613s 06:34:22.959075855 E: run test keytype.sh ... 613s 06:34:22.959556802 O: ok port number parsing 613s 06:34:23.088273745 O: keygen ed25519, 512 bits 613s 06:34:23.094394624 O: keygen ed25519-sk, n/a bits 613s 06:34:23.102662865 O: keygen ecdsa, 256 bits 613s 06:34:23.108106109 O: keygen ecdsa, 384 bits 613s 06:34:23.113594027 O: keygen ecdsa, 521 bits 613s 06:34:23.119181773 O: keygen ecdsa-sk, n/a bits 613s 06:34:23.130635605 O: keygen rsa, 2048 bits 613s 06:34:23.170698658 O: keygen rsa, 3072 bits 614s 06:34:24.381183573 O: userkey ed25519-512, hostkey ed25519-512 614s 06:34:24.510506956 O: userkey ed25519-512, hostkey ed25519-512 614s 06:34:24.638402373 O: userkey ed25519-512, hostkey ed25519-512 614s 06:34:24.765498044 O: userkey ed25519-sk, hostkey ed25519-sk 614s 06:34:24.894914412 O: userkey ed25519-sk, hostkey ed25519-sk 615s 06:34:25.023871184 O: userkey ed25519-sk, hostkey ed25519-sk 615s 06:34:25.156497688 O: userkey ecdsa-256, hostkey ecdsa-256 615s 06:34:25.313270869 O: userkey ecdsa-256, hostkey ecdsa-256 615s 06:34:25.465492229 O: userkey ecdsa-256, hostkey ecdsa-256 615s 06:34:25.628857641 O: userkey ecdsa-384, hostkey ecdsa-384 615s 06:34:25.840742772 O: userkey ecdsa-384, hostkey ecdsa-384 616s 06:34:26.051316736 O: userkey ecdsa-384, hostkey ecdsa-384 616s 06:34:26.261871829 O: userkey ecdsa-521, hostkey ecdsa-521 616s 06:34:26.584998361 O: userkey ecdsa-521, hostkey ecdsa-521 616s 06:34:26.904089916 O: userkey ecdsa-521, hostkey ecdsa-521 617s 06:34:27.235141277 O: userkey ecdsa-sk, hostkey ecdsa-sk 617s 06:34:27.403568601 O: userkey ecdsa-sk, hostkey ecdsa-sk 617s 06:34:27.573546043 O: userkey ecdsa-sk, hostkey ecdsa-sk 617s 06:34:27.793109422 O: userkey rsa-2048, hostkey rsa-2048 617s 06:34:27.943227681 O: userkey rsa-2048, hostkey rsa-2048 618s 06:34:28.090536604 O: userkey rsa-2048, hostkey rsa-2048 618s 06:34:28.236484336 O: userkey rsa-3072, hostkey rsa-3072 618s 06:34:28.394749457 O: userkey rsa-3072, hostkey rsa-3072 618s 06:34:28.549632775 O: userkey rsa-3072, hostkey rsa-3072 618s 06:34:28.691239459 E: run test kextype.sh ... 618s 06:34:28.690678795 O: ok login with different key types 618s 06:34:28.841372696 O: kex diffie-hellman-group1-sha1 619s 06:34:29.746817158 O: kex diffie-hellman-group14-sha1 620s 06:34:30.643071779 O: kex diffie-hellman-group14-sha256 621s 06:34:31.567375745 O: kex diffie-hellman-group16-sha512 622s 06:34:32.612553969 O: kex diffie-hellman-group18-sha512 624s 06:34:34.222591487 O: kex diffie-hellman-group-exchange-sha1 625s 06:34:35.821776462 O: kex diffie-hellman-group-exchange-sha256 627s 06:34:37.399714503 O: kex ecdh-sha2-nistp256 628s 06:34:38.256929947 O: kex ecdh-sha2-nistp384 629s 06:34:39.144470983 O: kex ecdh-sha2-nistp521 630s 06:34:40.049538969 O: kex curve25519-sha256 631s 06:34:40.960545391 O: kex curve25519-sha256@libssh.org 631s 06:34:41.899789561 O: kex sntrup761x25519-sha512 633s 06:34:43.097870929 O: kex sntrup761x25519-sha512@openssh.com 634s 06:34:44.277036212 O: kex mlkem768x25519-sha256 635s 06:34:45.167130508 O: ok login with different key exchange algorithms 635s 06:34:45.167380675 E: run test cert-hostkey.sh ... 636s 06:34:46.453709855 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/host_ca_key.pub 636s 06:34:46.454335679 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/host_ca_key2.pub 636s 06:34:46.454815363 O: certified host keys: sign host ed25519 cert 636s 06:34:46.460174892 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 636s 06:34:46.468638814 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 636s 06:34:46.470190268 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 636s 06:34:46.478201420 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 636s 06:34:46.486592944 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 636s 06:34:46.487905301 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 636s 06:34:46.494655196 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 636s 06:34:46.506535594 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 636s 06:34:46.507862589 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 636s 06:34:46.516852098 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 636s 06:34:46.532848323 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 636s 06:34:46.534136081 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 636s 06:34:46.546537949 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 636s 06:34:46.569953751 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 636s 06:34:46.571646798 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 636s 06:34:46.584706065 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 636s 06:34:46.596350223 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 636s 06:34:46.597742454 O: certified host keys: sign host rsa cert 637s 06:34:47.002190623 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 637s 06:34:47.010703039 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 637s 06:34:47.012090957 O: certified host keys: sign host rsa-sha2-256 cert 637s 06:34:47.478603736 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 637s 06:34:47.493718607 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 637s 06:34:47.495454856 O: certified host keys: sign host rsa-sha2-512 cert 638s 06:34:48.162792505 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 638s 06:34:48.178010106 O: Revoking from /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 638s 06:34:48.179560438 O: certified host keys: host ed25519 cert connect 638s 06:34:48.180496776 O: certified host keys: ed25519 basic connect expect success yes 638s 06:34:48.427101883 O: certified host keys: ed25519 empty KRL expect success yes 638s 06:34:48.671443638 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 638s 06:34:48.874461552 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 639s 06:34:49.077746140 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 639s 06:34:49.281707789 O: certified host keys: ed25519 empty plaintext revocation expect success yes 639s 06:34:49.526711561 O: certified host keys: ed25519 plain key plaintext revocation expect success no 639s 06:34:49.728723425 O: certified host keys: ed25519 cert plaintext revocation expect success no 639s 06:34:49.948702690 O: certified host keys: ed25519 CA plaintext revocation expect success no 640s 06:34:50.165038851 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 640s 06:34:50.166148755 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 640s 06:34:50.422958678 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 640s 06:34:50.686563072 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 640s 06:34:50.908849249 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 641s 06:34:51.115303020 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 641s 06:34:51.321291522 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 641s 06:34:51.569957179 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 641s 06:34:51.784164916 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 642s 06:34:51.996951995 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 642s 06:34:52.203607300 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 642s 06:34:52.204510380 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 642s 06:34:52.477178905 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 642s 06:34:52.748936711 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 643s 06:34:52.977898929 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 643s 06:34:53.205066491 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 643s 06:34:53.436766449 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 643s 06:34:53.704323183 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 643s 06:34:53.934574133 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 644s 06:34:54.164071944 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 644s 06:34:54.392528351 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 644s 06:34:54.393351750 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 644s 06:34:54.677593036 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 645s 06:34:54.967616562 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 645s 06:34:55.209707269 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 645s 06:34:55.460330591 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 645s 06:34:55.711602109 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 646s 06:34:56.001782696 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 646s 06:34:56.259329642 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 646s 06:34:56.520315508 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 646s 06:34:56.788708411 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 646s 06:34:56.789764645 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 647s 06:34:57.123873990 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 647s 06:34:57.458931939 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 647s 06:34:57.747923163 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 648s 06:34:58.029069497 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 648s 06:34:58.319243980 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 648s 06:34:58.661860143 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 649s 06:34:58.957122709 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 649s 06:34:59.277222916 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 649s 06:34:59.556076789 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 649s 06:34:59.556693401 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 649s 06:34:59.818811200 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 650s 06:35:00.082042915 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 650s 06:35:00.310803288 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 650s 06:35:00.538321586 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 650s 06:35:00.771445019 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 651s 06:35:01.059262142 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 651s 06:35:01.310313535 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 651s 06:35:01.574306449 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 651s 06:35:01.808873828 O: certified host keys: host rsa cert connect 651s 06:35:01.809488977 O: certified host keys: rsa basic connect expect success yes 652s 06:35:02.073102793 O: certified host keys: rsa empty KRL expect success yes 652s 06:35:02.335239733 O: certified host keys: rsa KRL w/ plain key revoked expect success no 652s 06:35:02.558068273 O: certified host keys: rsa KRL w/ cert revoked expect success no 652s 06:35:02.782698516 O: certified host keys: rsa KRL w/ CA revoked expect success no 653s 06:35:03.012732640 O: certified host keys: rsa empty plaintext revocation expect success yes 653s 06:35:03.282176667 O: certified host keys: rsa plain key plaintext revocation expect success no 653s 06:35:03.519553210 O: certified host keys: rsa cert plaintext revocation expect success no 653s 06:35:03.772459646 O: certified host keys: rsa CA plaintext revocation expect success no 654s 06:35:04.002885101 O: certified host keys: host rsa-sha2-256 cert connect 654s 06:35:04.003500883 O: certified host keys: rsa-sha2-256 basic connect expect success yes 654s 06:35:04.262298874 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 654s 06:35:04.518701996 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 654s 06:35:04.734895667 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 654s 06:35:04.951213696 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 655s 06:35:05.167081083 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 655s 06:35:05.422180587 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 655s 06:35:05.653349211 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 655s 06:35:05.900548880 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 656s 06:35:06.116694656 O: certified host keys: host rsa-sha2-512 cert connect 656s 06:35:06.117328913 O: certified host keys: rsa-sha2-512 basic connect expect success yes 656s 06:35:06.370732346 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 656s 06:35:06.612325729 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 656s 06:35:06.822602056 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 657s 06:35:07.034882964 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 657s 06:35:07.244295880 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 657s 06:35:07.484680554 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 657s 06:35:07.707255766 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 657s 06:35:07.947696337 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 658s 06:35:08.172343059 O: certified host keys: host ed25519 revoked cert 658s 06:35:08.406151706 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 658s 06:35:08.642856584 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 658s 06:35:08.899770139 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 659s 06:35:09.180595210 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 659s 06:35:09.494600430 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 659s 06:35:09.757356807 O: certified host keys: host rsa revoked cert 660s 06:35:10.010114854 O: certified host keys: host rsa-sha2-256 revoked cert 660s 06:35:10.258984850 O: certified host keys: host rsa-sha2-512 revoked cert 660s 06:35:10.510232908 O: certified host keys: host ed25519 revoked cert 660s 06:35:10.717213996 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 660s 06:35:10.934195245 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 661s 06:35:11.157844671 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 661s 06:35:11.408787924 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 661s 06:35:11.693329004 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 661s 06:35:11.927809158 O: certified host keys: host rsa revoked cert 662s 06:35:12.150785863 O: certified host keys: host rsa-sha2-256 revoked cert 662s 06:35:12.367913766 O: certified host keys: host rsa-sha2-512 revoked cert 678s 06:35:28.311004686 O: certified host keys: host ed25519 cert downgrade to raw key 678s 06:35:28.810149940 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 679s 06:35:29.317194106 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 679s 06:35:29.871288950 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 680s 06:35:30.486207840 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 681s 06:35:31.205195062 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 681s 06:35:31.765497232 O: certified host keys: host rsa cert downgrade to raw key 682s 06:35:32.847896840 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 683s 06:35:33.615959780 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 684s 06:35:34.599991402 O: certified host keys: host ed25519 connect wrong cert 684s 06:35:34.814720173 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 685s 06:35:35.044942791 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 685s 06:35:35.282536390 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 685s 06:35:35.554647697 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 685s 06:35:35.889537685 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 686s 06:35:36.150513794 O: certified host keys: host rsa connect wrong cert 686s 06:35:36.878631863 O: certified host keys: host rsa-sha2-256 connect wrong cert 687s 06:35:37.618897520 O: certified host keys: host rsa-sha2-512 connect wrong cert 688s 06:35:38.104086506 E: run test cert-userkey.sh ... 688s 06:35:38.103597225 O: ok certified host keys 689s 06:35:38.994502429 O: certified user keys: sign user ed25519 cert 689s 06:35:39.010623028 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 689s 06:35:39.028252883 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 689s 06:35:39.044744390 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 689s 06:35:39.063294968 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 689s 06:35:39.085289694 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 689s 06:35:39.107698416 O: certified user keys: sign user rsa cert 689s 06:35:39.448632823 O: certified user keys: sign user rsa-sha2-256 cert 689s 06:35:39.921182065 O: certified user keys: sign user rsa-sha2-512 cert 690s 06:35:40.333499366 O: certified user keys: ed25519 missing authorized_principals 690s 06:35:40.641464499 O: certified user keys: ed25519 empty authorized_principals 690s 06:35:40.941946805 O: certified user keys: ed25519 wrong authorized_principals 691s 06:35:41.250107016 O: certified user keys: ed25519 correct authorized_principals 691s 06:35:41.559922849 O: certified user keys: ed25519 authorized_principals bad key opt 691s 06:35:41.866625780 O: certified user keys: ed25519 authorized_principals command=false 692s 06:35:42.177865545 O: certified user keys: ed25519 authorized_principals command=true 692s 06:35:42.492402527 O: certified user keys: ed25519 wrong principals key option 692s 06:35:42.799970260 O: certified user keys: ed25519 correct principals key option 693s 06:35:43.110854700 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 693s 06:35:43.428947403 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 693s 06:35:43.747786754 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 694s 06:35:44.066088454 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 694s 06:35:44.377251372 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 694s 06:35:44.695134622 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 695s 06:35:45.006678719 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 695s 06:35:45.318880721 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 695s 06:35:45.636737134 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 695s 06:35:45.949429774 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 696s 06:35:46.265074248 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 696s 06:35:46.581058982 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 696s 06:35:46.897125089 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 697s 06:35:47.208151456 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 697s 06:35:47.524756855 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 697s 06:35:47.836808469 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 698s 06:35:48.157365884 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 698s 06:35:48.469623622 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 698s 06:35:48.786543517 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 699s 06:35:49.106017877 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 699s 06:35:49.426028580 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 699s 06:35:49.744473655 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 700s 06:35:50.076972461 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 700s 06:35:50.401897578 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 700s 06:35:50.734579811 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 701s 06:35:51.082491199 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 701s 06:35:51.417642275 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 701s 06:35:51.751397288 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 702s 06:35:52.096024868 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 702s 06:35:52.439132193 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 702s 06:35:52.782404518 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 703s 06:35:53.163563944 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 703s 06:35:53.507222305 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 703s 06:35:53.889851520 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 704s 06:35:54.275644582 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 704s 06:35:54.622917489 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 705s 06:35:55.011698841 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 705s 06:35:55.330459731 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 705s 06:35:55.642977256 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 705s 06:35:55.958851637 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 706s 06:35:56.279520241 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 706s 06:35:56.599050127 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 706s 06:35:56.917277693 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 707s 06:35:57.236900446 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 707s 06:35:57.549326494 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 707s 06:35:57.865596196 O: certified user keys: rsa missing authorized_principals 708s 06:35:58.164174338 O: certified user keys: rsa empty authorized_principals 708s 06:35:58.463523967 O: certified user keys: rsa wrong authorized_principals 708s 06:35:58.762553379 O: certified user keys: rsa correct authorized_principals 709s 06:35:59.074096215 O: certified user keys: rsa authorized_principals bad key opt 709s 06:35:59.375989915 O: certified user keys: rsa authorized_principals command=false 709s 06:35:59.684050299 O: certified user keys: rsa authorized_principals command=true 710s 06:35:59.994165230 O: certified user keys: rsa wrong principals key option 710s 06:36:00.289406121 O: certified user keys: rsa correct principals key option 710s 06:36:00.596171881 O: certified user keys: rsa-sha2-256 missing authorized_principals 710s 06:36:00.907189258 O: certified user keys: rsa-sha2-256 empty authorized_principals 711s 06:36:01.222213009 O: certified user keys: rsa-sha2-256 wrong authorized_principals 711s 06:36:01.537813225 O: certified user keys: rsa-sha2-256 correct authorized_principals 711s 06:36:01.850409545 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 712s 06:36:02.160186889 O: certified user keys: rsa-sha2-256 authorized_principals command=false 712s 06:36:02.469864204 O: certified user keys: rsa-sha2-256 authorized_principals command=true 712s 06:36:02.806314662 O: certified user keys: rsa-sha2-256 wrong principals key option 713s 06:36:03.107081594 O: certified user keys: rsa-sha2-256 correct principals key option 713s 06:36:03.415365495 O: certified user keys: rsa-sha2-512 missing authorized_principals 713s 06:36:03.708370623 O: certified user keys: rsa-sha2-512 empty authorized_principals 714s 06:36:04.010884459 O: certified user keys: rsa-sha2-512 wrong authorized_principals 714s 06:36:04.305607535 O: certified user keys: rsa-sha2-512 correct authorized_principals 714s 06:36:04.608058826 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 714s 06:36:04.908518130 O: certified user keys: rsa-sha2-512 authorized_principals command=false 715s 06:36:05.218041691 O: certified user keys: rsa-sha2-512 authorized_principals command=true 715s 06:36:05.527974194 O: certified user keys: rsa-sha2-512 wrong principals key option 715s 06:36:05.831834258 O: certified user keys: rsa-sha2-512 correct principals key option 716s 06:36:06.140728688 O: certified user keys: ed25519 authorized_keys connect 716s 06:36:06.448191788 O: certified user keys: ed25519 authorized_keys revoked key 716s 06:36:06.744964046 O: certified user keys: ed25519 authorized_keys revoked via KRL 717s 06:36:07.044540994 O: certified user keys: ed25519 authorized_keys empty KRL 717s 06:36:07.345141782 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 717s 06:36:07.637808117 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 717s 06:36:07.934342282 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 718s 06:36:08.232557404 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 718s 06:36:08.528432406 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 718s 06:36:08.829452689 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 719s 06:36:09.131682041 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 719s 06:36:09.426860853 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 719s 06:36:09.728873960 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 720s 06:36:10.052403644 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 720s 06:36:10.387633296 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 720s 06:36:10.706841122 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 721s 06:36:11.040866471 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 721s 06:36:11.399059947 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 721s 06:36:11.789209223 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 722s 06:36:12.137611081 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 722s 06:36:12.509022478 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 722s 06:36:12.818622159 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 723s 06:36:13.131030772 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 723s 06:36:13.439843684 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 723s 06:36:13.758612489 O: certified user keys: rsa authorized_keys connect 724s 06:36:14.063200837 O: certified user keys: rsa authorized_keys revoked key 724s 06:36:14.361629083 O: certified user keys: rsa authorized_keys revoked via KRL 724s 06:36:14.661024842 O: certified user keys: rsa authorized_keys empty KRL 725s 06:36:14.963198755 O: certified user keys: rsa-sha2-256 authorized_keys connect 725s 06:36:15.261981098 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 725s 06:36:15.562990273 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 725s 06:36:15.863642001 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 726s 06:36:16.169250428 O: certified user keys: rsa-sha2-512 authorized_keys connect 726s 06:36:16.469381115 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 726s 06:36:16.772421472 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 727s 06:36:17.077651686 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 727s 06:36:17.382037940 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 727s 06:36:17.675490577 O: certified user keys: authorized_keys CA does not authenticate 727s 06:36:17.676508739 O: certified user keys: ensure CA key does not authenticate user 728s 06:36:17.961296881 O: certified user keys: ed25519 TrustedUserCAKeys connect 728s 06:36:18.262390282 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 728s 06:36:18.557927512 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 728s 06:36:18.856031227 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 729s 06:36:19.164810172 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 729s 06:36:19.470208219 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 729s 06:36:19.776039935 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 730s 06:36:20.083956662 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 730s 06:36:20.387165363 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 730s 06:36:20.693606661 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 731s 06:36:21.008438120 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 731s 06:36:21.335047272 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 731s 06:36:21.652300800 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 732s 06:36:21.984162050 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 732s 06:36:22.325057794 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 732s 06:36:22.644759721 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 733s 06:36:22.974727893 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 733s 06:36:23.352657407 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 733s 06:36:23.739374500 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 734s 06:36:24.110485670 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 734s 06:36:24.493482840 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 734s 06:36:24.811374028 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 735s 06:36:25.125860987 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 735s 06:36:25.444352953 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 735s 06:36:25.764294767 O: certified user keys: rsa TrustedUserCAKeys connect 736s 06:36:26.079456846 O: certified user keys: rsa TrustedUserCAKeys revoked key 736s 06:36:26.388792354 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 736s 06:36:26.693359448 O: certified user keys: rsa TrustedUserCAKeys empty KRL 737s 06:36:27.009622887 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 737s 06:36:27.319631877 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 737s 06:36:27.618126952 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 737s 06:36:27.924452070 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 738s 06:36:28.241089084 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 738s 06:36:28.546594404 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 738s 06:36:28.859570791 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 739s 06:36:29.169664035 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 739s 06:36:29.481200724 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 739s 06:36:29.787723530 O: certified user keys: TrustedUserCAKeys CA does not authenticate 739s 06:36:29.788756375 O: certified user keys: ensure CA key does not authenticate user 740s 06:36:30.084921730 O: certified user keys: correct principal auth authorized_keys expect success rsa 740s 06:36:30.421412677 O: certified user keys: correct principal auth authorized_keys expect success ed25519 740s 06:36:30.742919674 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 741s 06:36:31.071870679 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 741s 06:36:31.397779867 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 741s 06:36:31.699566303 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 742s 06:36:31.999929942 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 742s 06:36:32.313077088 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 742s 06:36:32.630746788 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 742s 06:36:32.950082459 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 743s 06:36:33.262831280 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 743s 06:36:33.585971045 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 743s 06:36:33.921151106 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 744s 06:36:34.234078482 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 744s 06:36:34.544452700 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 744s 06:36:34.860073244 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 745s 06:36:35.177502542 O: certified user keys: cert expired auth authorized_keys expect failure rsa 745s 06:36:35.493119716 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 745s 06:36:35.811015313 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 746s 06:36:36.130171373 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 746s 06:36:36.453340509 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 746s 06:36:36.780059473 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 747s 06:36:37.093699199 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 747s 06:36:37.418172811 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 747s 06:36:37.746003964 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 748s 06:36:38.064263349 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 748s 06:36:38.381700567 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 748s 06:36:38.701847211 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 749s 06:36:39.022509265 O: certified user keys: force-command auth authorized_keys expect failure rsa 749s 06:36:39.348335485 O: certified user keys: force-command auth authorized_keys expect failure ed25519 749s 06:36:39.666668441 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 750s 06:36:39.991657324 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 750s 06:36:40.313242146 O: certified user keys: empty principals auth authorized_keys expect success rsa 750s 06:36:40.627704088 O: certified user keys: empty principals auth authorized_keys expect success ed25519 750s 06:36:40.940495571 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 751s 06:36:41.271608084 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 751s 06:36:41.604377581 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 751s 06:36:41.940820122 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 752s 06:36:42.273468206 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 752s 06:36:42.602709577 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 752s 06:36:42.925917064 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 753s 06:36:43.254466464 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 753s 06:36:43.576268770 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 753s 06:36:43.898007341 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 754s 06:36:44.227506108 O: certified user keys: force-command match true auth authorized_keys expect success rsa 754s 06:36:44.558117722 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 754s 06:36:44.885606550 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 755s 06:36:45.216294639 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 755s 06:36:45.539379120 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 755s 06:36:45.854682051 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 756s 06:36:46.172121549 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 756s 06:36:46.487531759 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 756s 06:36:46.818364053 O: certified user keys: user ed25519 connect wrong cert 757s 06:36:47.134832714 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 757s 06:36:47.433643328 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 757s 06:36:47.733114834 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 758s 06:36:48.039166946 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 758s 06:36:48.348246422 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 758s 06:36:48.653213703 O: certified user keys: user rsa connect wrong cert 759s 06:36:48.969745504 O: certified user keys: user rsa-sha2-256 connect wrong cert 759s 06:36:49.286647951 O: certified user keys: user rsa-sha2-512 connect wrong cert 759s 06:36:49.592317780 E: run test host-expand.sh ... 759s 06:36:49.592791208 O: ok certified user keys 760s 06:36:50.011729543 O: ok expand %h and %n 760s 06:36:50.012369271 E: run test keys-command.sh ... 760s 06:36:50.153311230 O: SKIPPED: /var/run/keycommand_openssh-tests.42925 not executable (/var/run mounted noexec?) 760s 06:36:50.157045925 E: run test forward-control.sh ... 762s 06:36:52.036639157 O: check_lfwd done (expecting Y): default configuration 762s 06:36:52.661164568 O: check_rfwd done (expecting Y): default configuration 763s 06:36:53.285418695 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 763s 06:36:53.911291905 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 765s 06:36:55.224784956 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 766s 06:36:56.842184881 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 768s 06:36:58.455823081 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 770s 06:37:00.072149604 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 771s 06:37:01.391913832 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 773s 06:37:03.032819020 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 774s 06:37:04.647174606 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 776s 06:37:06.281306021 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 777s 06:37:07.600737177 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 777s 06:37:07.898747856 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 779s 06:37:09.224833184 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 779s 06:37:09.524357529 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 780s 06:37:10.138566834 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 781s 06:37:11.772331829 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 783s 06:37:13.398213211 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 783s 06:37:13.694142775 O: check_rfwd done (expecting N): AllowTcpForwarding=local 785s 06:37:15.017372559 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 785s 06:37:15.313106960 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 785s 06:37:15.936302692 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 786s 06:37:16.244268626 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 787s 06:37:17.557832411 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 787s 06:37:17.853162116 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 789s 06:37:19.476770567 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 789s 06:37:19.771340940 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 791s 06:37:21.084297469 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 791s 06:37:21.382004037 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 791s 06:37:21.714088129 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 792s 06:37:22.009417540 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 792s 06:37:22.615640478 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 792s 06:37:22.908262958 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 794s 06:37:24.222054329 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 795s 06:37:25.862397527 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 796s 06:37:26.183249051 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 797s 06:37:27.793351486 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 799s 06:37:29.121845386 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 800s 06:37:30.762774443 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 801s 06:37:31.088049391 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 802s 06:37:32.733045782 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 804s 06:37:34.061948171 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 805s 06:37:35.703970698 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 807s 06:37:37.030811834 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 807s 06:37:37.340920964 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 807s 06:37:37.664147181 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 808s 06:37:37.963598448 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 809s 06:37:39.289162494 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 810s 06:37:40.943003903 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 812s 06:37:42.268904628 O: check_lfwd done (expecting N): AllowTcpForwarding=no 812s 06:37:42.573982930 O: check_rfwd done (expecting N): AllowTcpForwarding=no 812s 06:37:42.895130750 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 813s 06:37:43.196410959 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 814s 06:37:44.524275859 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 814s 06:37:44.831176666 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 816s 06:37:46.161861954 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 816s 06:37:46.469349550 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 816s 06:37:46.805930433 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 817s 06:37:47.121890789 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 817s 06:37:47.451074134 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 817s 06:37:47.753365832 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 819s 06:37:49.079470757 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 819s 06:37:49.392133399 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 820s 06:37:50.724470367 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 821s 06:37:51.034470172 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 821s 06:37:51.657964945 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 821s 06:37:51.956305265 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 822s 06:37:52.576805689 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 824s 06:37:54.203369710 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 825s 06:37:55.838868557 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 826s 06:37:56.144254382 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 827s 06:37:57.758161882 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 828s 06:37:58.067738517 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 829s 06:37:59.396678156 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 829s 06:37:59.707354933 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 831s 06:38:01.038302335 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 833s 06:38:02.695291511 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 833s 06:38:03.027182793 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 833s 06:38:03.336060628 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 834s 06:38:04.664187293 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 835s 06:38:04.973996963 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 835s 06:38:04.983768791 O: ok sshd control of local and remote forwarding 835s 06:38:04.984354520 E: run test integrity.sh ... 835s 06:38:05.130886888 O: test integrity: hmac-sha1 @2900 835s 06:38:05.443149375 O: test integrity: hmac-sha1 @2901 835s 06:38:05.780069149 O: test integrity: hmac-sha1 @2902 836s 06:38:06.095520839 O: test integrity: hmac-sha1 @2903 836s 06:38:06.410004257 O: test integrity: hmac-sha1 @2904 836s 06:38:06.721928980 O: test integrity: hmac-sha1 @2905 837s 06:38:07.035672053 O: test integrity: hmac-sha1 @2906 837s 06:38:07.350448030 O: test integrity: hmac-sha1 @2907 837s 06:38:07.662752719 O: test integrity: hmac-sha1 @2908 838s 06:38:07.970813771 O: test integrity: hmac-sha1 @2909 838s 06:38:08.281100504 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 838s 06:38:08.289924260 O: test integrity: hmac-sha1-96 @2900 838s 06:38:08.601982516 O: test integrity: hmac-sha1-96 @2901 838s 06:38:08.914936371 O: test integrity: hmac-sha1-96 @2902 839s 06:38:09.219357201 O: test integrity: hmac-sha1-96 @2903 839s 06:38:09.522919027 O: test integrity: hmac-sha1-96 @2904 839s 06:38:09.825545614 O: test integrity: hmac-sha1-96 @2905 840s 06:38:10.135824133 O: test integrity: hmac-sha1-96 @2906 840s 06:38:10.452533778 O: test integrity: hmac-sha1-96 @2907 840s 06:38:10.768842853 O: test integrity: hmac-sha1-96 @2908 841s 06:38:11.077138961 O: test integrity: hmac-sha1-96 @2909 841s 06:38:11.374850414 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 841s 06:38:11.385387566 O: test integrity: hmac-sha2-256 @2900 841s 06:38:11.689681159 O: test integrity: hmac-sha2-256 @2901 842s 06:38:12.002297750 O: test integrity: hmac-sha2-256 @2902 842s 06:38:12.301544116 O: test integrity: hmac-sha2-256 @2903 842s 06:38:12.607367978 O: test integrity: hmac-sha2-256 @2904 842s 06:38:12.915289582 O: test integrity: hmac-sha2-256 @2905 843s 06:38:13.225145575 O: test integrity: hmac-sha2-256 @2906 843s 06:38:13.537579775 O: test integrity: hmac-sha2-256 @2907 843s 06:38:13.849858357 O: test integrity: hmac-sha2-256 @2908 844s 06:38:14.164794435 O: test integrity: hmac-sha2-256 @2909 844s 06:38:14.469270515 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 844s 06:38:14.478473474 O: test integrity: hmac-sha2-512 @2900 844s 06:38:14.782407920 O: test integrity: hmac-sha2-512 @2901 845s 06:38:15.067916974 O: test integrity: hmac-sha2-512 @2902 845s 06:38:15.353388441 O: test integrity: hmac-sha2-512 @2903 845s 06:38:15.640837585 O: test integrity: hmac-sha2-512 @2904 845s 06:38:15.929883937 O: test integrity: hmac-sha2-512 @2905 846s 06:38:16.209211997 O: test integrity: hmac-sha2-512 @2906 846s 06:38:16.492330826 O: test integrity: hmac-sha2-512 @2907 846s 06:38:16.786523279 O: test integrity: hmac-sha2-512 @2908 847s 06:38:17.068806375 O: test integrity: hmac-sha2-512 @2909 847s 06:38:17.351679447 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 847s 06:38:17.360725037 O: test integrity: hmac-md5 @2900 847s 06:38:17.671983594 O: test integrity: hmac-md5 @2901 848s 06:38:17.988030317 O: test integrity: hmac-md5 @2902 848s 06:38:18.290468024 O: test integrity: hmac-md5 @2903 848s 06:38:18.597027695 O: test integrity: hmac-md5 @2904 848s 06:38:18.910314309 O: test integrity: hmac-md5 @2905 849s 06:38:19.225018116 O: test integrity: hmac-md5 @2906 849s 06:38:19.543004715 O: test integrity: hmac-md5 @2907 849s 06:38:19.849194112 O: test integrity: hmac-md5 @2908 850s 06:38:20.152494878 O: test integrity: hmac-md5 @2909 850s 06:38:20.460993496 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 850s 06:38:20.469771683 O: test integrity: hmac-md5-96 @2900 850s 06:38:20.775138815 O: test integrity: hmac-md5-96 @2901 851s 06:38:21.076289192 O: test integrity: hmac-md5-96 @2902 851s 06:38:21.375862762 O: test integrity: hmac-md5-96 @2903 851s 06:38:21.682143512 O: test integrity: hmac-md5-96 @2904 852s 06:38:21.990407438 O: test integrity: hmac-md5-96 @2905 852s 06:38:22.304664992 O: test integrity: hmac-md5-96 @2906 852s 06:38:22.607494194 O: test integrity: hmac-md5-96 @2907 852s 06:38:22.906170684 O: test integrity: hmac-md5-96 @2908 853s 06:38:23.223690633 O: test integrity: hmac-md5-96 @2909 853s 06:38:23.518245259 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 853s 06:38:23.527494526 O: test integrity: umac-64@openssh.com @2900 853s 06:38:23.834927408 O: test integrity: umac-64@openssh.com @2901 854s 06:38:24.146409262 O: test integrity: umac-64@openssh.com @2902 854s 06:38:24.445307606 O: test integrity: umac-64@openssh.com @2903 854s 06:38:24.744504829 O: test integrity: umac-64@openssh.com @2904 855s 06:38:25.057599515 O: test integrity: umac-64@openssh.com @2905 855s 06:38:25.364023123 O: test integrity: umac-64@openssh.com @2906 855s 06:38:25.670533863 O: test integrity: umac-64@openssh.com @2907 856s 06:38:25.980028231 O: test integrity: umac-64@openssh.com @2908 856s 06:38:26.293992553 O: test integrity: umac-64@openssh.com @2909 856s 06:38:26.593112280 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 856s 06:38:26.602077536 O: test integrity: umac-128@openssh.com @2900 856s 06:38:26.920543822 O: test integrity: umac-128@openssh.com @2901 857s 06:38:27.229982747 O: test integrity: umac-128@openssh.com @2902 857s 06:38:27.544562617 O: test integrity: umac-128@openssh.com @2903 857s 06:38:27.857872694 O: test integrity: umac-128@openssh.com @2904 858s 06:38:28.167255350 O: test integrity: umac-128@openssh.com @2905 858s 06:38:28.475542157 O: test integrity: umac-128@openssh.com @2906 858s 06:38:28.790570503 O: test integrity: umac-128@openssh.com @2907 859s 06:38:29.102699348 O: test integrity: umac-128@openssh.com @2908 859s 06:38:29.421999375 O: test integrity: umac-128@openssh.com @2909 859s 06:38:29.714652232 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 859s 06:38:29.723619271 O: test integrity: hmac-sha1-etm@openssh.com @2900 860s 06:38:30.075282017 O: test integrity: hmac-sha1-etm@openssh.com @2901 860s 06:38:30.383408759 O: test integrity: hmac-sha1-etm@openssh.com @2902 860s 06:38:30.693849635 O: test integrity: hmac-sha1-etm@openssh.com @2903 861s 06:38:31.006389097 O: test integrity: hmac-sha1-etm@openssh.com @2904 861s 06:38:31.327293546 O: test integrity: hmac-sha1-etm@openssh.com @2905 861s 06:38:31.643604582 O: test integrity: hmac-sha1-etm@openssh.com @2906 861s 06:38:31.955482849 O: test integrity: hmac-sha1-etm@openssh.com @2907 862s 06:38:32.274989826 O: test integrity: hmac-sha1-etm@openssh.com @2908 862s 06:38:32.588139035 O: test integrity: hmac-sha1-etm@openssh.com @2909 862s 06:38:32.895161442 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 862s 06:38:32.904153695 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 863s 06:38:33.215112428 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 863s 06:38:33.518392225 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 863s 06:38:33.831305301 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 864s 06:38:34.149896395 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 864s 06:38:34.464952545 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 864s 06:38:34.775944742 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 865s 06:38:35.088303860 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 865s 06:38:35.393430247 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 865s 06:38:35.697474574 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 866s 06:38:35.997598003 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 866s 06:38:36.006068483 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 866s 06:38:36.315876535 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 866s 06:38:36.626429504 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 866s 06:38:36.926642618 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 867s 06:38:37.238797938 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 867s 06:38:37.542116373 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 867s 06:38:37.846432909 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 868s 06:38:38.156150488 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 868s 06:38:38.467123360 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 868s 06:38:38.795833272 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 869s 06:38:39.090433418 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 869s 06:38:39.099035043 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 869s 06:38:39.383626037 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 869s 06:38:39.671798434 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 870s 06:38:39.964930420 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 870s 06:38:40.257697678 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 870s 06:38:40.544033618 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 870s 06:38:40.834275092 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 871s 06:38:41.126835361 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 871s 06:38:41.418658657 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 871s 06:38:41.705263102 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 872s 06:38:41.996067528 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 872s 06:38:42.005434833 O: test integrity: hmac-md5-etm@openssh.com @2900 872s 06:38:42.322940120 O: test integrity: hmac-md5-etm@openssh.com @2901 872s 06:38:42.635511328 O: test integrity: hmac-md5-etm@openssh.com @2902 873s 06:38:42.967779602 O: test integrity: hmac-md5-etm@openssh.com @2903 873s 06:38:43.275506758 O: test integrity: hmac-md5-etm@openssh.com @2904 873s 06:38:43.585672191 O: test integrity: hmac-md5-etm@openssh.com @2905 873s 06:38:43.911344152 O: test integrity: hmac-md5-etm@openssh.com @2906 874s 06:38:44.218298010 O: test integrity: hmac-md5-etm@openssh.com @2907 874s 06:38:44.528672831 O: test integrity: hmac-md5-etm@openssh.com @2908 874s 06:38:44.838478690 O: test integrity: hmac-md5-etm@openssh.com @2909 875s 06:38:45.143084479 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 875s 06:38:45.151199927 O: test integrity: hmac-md5-96-etm@openssh.com @2900 875s 06:38:45.456785901 O: test integrity: hmac-md5-96-etm@openssh.com @2901 875s 06:38:45.758478219 O: test integrity: hmac-md5-96-etm@openssh.com @2902 876s 06:38:46.063812307 O: test integrity: hmac-md5-96-etm@openssh.com @2903 876s 06:38:46.375279911 O: test integrity: hmac-md5-96-etm@openssh.com @2904 876s 06:38:46.684319756 O: test integrity: hmac-md5-96-etm@openssh.com @2905 877s 06:38:46.991741705 O: test integrity: hmac-md5-96-etm@openssh.com @2906 877s 06:38:47.302116032 O: test integrity: hmac-md5-96-etm@openssh.com @2907 877s 06:38:47.614560259 O: test integrity: hmac-md5-96-etm@openssh.com @2908 877s 06:38:47.930077978 O: test integrity: hmac-md5-96-etm@openssh.com @2909 878s 06:38:48.226142016 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 878s 06:38:48.235135046 O: test integrity: umac-64-etm@openssh.com @2900 878s 06:38:48.545443035 O: test integrity: umac-64-etm@openssh.com @2901 878s 06:38:48.854499012 O: test integrity: umac-64-etm@openssh.com @2902 879s 06:38:49.171089060 O: test integrity: umac-64-etm@openssh.com @2903 879s 06:38:49.483070060 O: test integrity: umac-64-etm@openssh.com @2904 879s 06:38:49.795934416 O: test integrity: umac-64-etm@openssh.com @2905 880s 06:38:50.112596525 O: test integrity: umac-64-etm@openssh.com @2906 880s 06:38:50.423941683 O: test integrity: umac-64-etm@openssh.com @2907 880s 06:38:50.733606886 O: test integrity: umac-64-etm@openssh.com @2908 881s 06:38:51.046010300 O: test integrity: umac-64-etm@openssh.com @2909 881s 06:38:51.349652282 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 881s 06:38:51.358711495 O: test integrity: umac-128-etm@openssh.com @2900 881s 06:38:51.663147456 O: test integrity: umac-128-etm@openssh.com @2901 882s 06:38:51.963692404 O: test integrity: umac-128-etm@openssh.com @2902 882s 06:38:52.273665546 O: test integrity: umac-128-etm@openssh.com @2903 882s 06:38:52.579804949 O: test integrity: umac-128-etm@openssh.com @2904 882s 06:38:52.882216686 O: test integrity: umac-128-etm@openssh.com @2905 883s 06:38:53.183200116 O: test integrity: umac-128-etm@openssh.com @2906 883s 06:38:53.492962158 O: test integrity: umac-128-etm@openssh.com @2907 883s 06:38:53.792034467 O: test integrity: umac-128-etm@openssh.com @2908 884s 06:38:54.096753643 O: test integrity: umac-128-etm@openssh.com @2909 884s 06:38:54.390597587 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 884s 06:38:54.399072493 O: test integrity: aes128-gcm@openssh.com @2900 884s 06:38:54.675926683 O: test integrity: aes128-gcm@openssh.com @2901 884s 06:38:54.953424271 O: test integrity: aes128-gcm@openssh.com @2902 885s 06:38:55.231687098 O: test integrity: aes128-gcm@openssh.com @2903 885s 06:38:55.511047182 O: test integrity: aes128-gcm@openssh.com @2904 885s 06:38:55.797660480 O: test integrity: aes128-gcm@openssh.com @2905 886s 06:38:56.074171933 O: test integrity: aes128-gcm@openssh.com @2906 886s 06:38:56.354127682 O: test integrity: aes128-gcm@openssh.com @2907 886s 06:38:56.636843462 O: test integrity: aes128-gcm@openssh.com @2908 886s 06:38:56.918602841 O: test integrity: aes128-gcm@openssh.com @2909 887s 06:38:57.201941544 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 887s 06:38:57.212117944 O: test integrity: aes256-gcm@openssh.com @2900 887s 06:38:57.491250328 O: test integrity: aes256-gcm@openssh.com @2901 887s 06:38:57.779396826 O: test integrity: aes256-gcm@openssh.com @2902 888s 06:38:58.059665977 O: test integrity: aes256-gcm@openssh.com @2903 888s 06:38:58.342748132 O: test integrity: aes256-gcm@openssh.com @2904 888s 06:38:58.632928213 O: test integrity: aes256-gcm@openssh.com @2905 888s 06:38:58.920209066 O: test integrity: aes256-gcm@openssh.com @2906 889s 06:38:59.210129363 O: test integrity: aes256-gcm@openssh.com @2907 889s 06:38:59.514926293 O: test integrity: aes256-gcm@openssh.com @2908 889s 06:38:59.800789351 O: test integrity: aes256-gcm@openssh.com @2909 890s 06:39:00.078690260 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 890s 06:39:00.087667226 O: test integrity: chacha20-poly1305@openssh.com @2900 890s 06:39:00.365084339 O: test integrity: chacha20-poly1305@openssh.com @2901 890s 06:39:00.642670807 O: test integrity: chacha20-poly1305@openssh.com @2902 890s 06:39:00.927639808 O: test integrity: chacha20-poly1305@openssh.com @2903 891s 06:39:01.204895439 O: test integrity: chacha20-poly1305@openssh.com @2904 891s 06:39:01.490344088 O: test integrity: chacha20-poly1305@openssh.com @2905 891s 06:39:01.776266594 O: test integrity: chacha20-poly1305@openssh.com @2906 892s 06:39:02.060699851 O: test integrity: chacha20-poly1305@openssh.com @2907 892s 06:39:02.338638033 O: test integrity: chacha20-poly1305@openssh.com @2908 892s 06:39:02.617537308 O: test integrity: chacha20-poly1305@openssh.com @2909 892s 06:39:02.889931852 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 892s 06:39:02.890857596 O: ok integrity 892s 06:39:02.891488695 E: run test krl.sh ... 893s 06:39:03.035546490 O: key revocation lists: generating test keys 894s 06:39:04.414178336 O: key revocation lists: generating KRLs 894s 06:39:04.542836639 O: key revocation lists: checking revocations for revoked keys 895s 06:39:04.994658122 O: key revocation lists: checking revocations for unrevoked keys 895s 06:39:05.362657906 O: key revocation lists: checking revocations for revoked certs 896s 06:39:06.090127898 O: key revocation lists: checking revocations for unrevoked certs 896s 06:39:06.735465473 O: key revocation lists: testing KRL update 897s 06:39:07.804617746 O: key revocation lists: checking revocations for revoked keys 898s 06:39:08.255658542 O: key revocation lists: checking revocations for unrevoked keys 898s 06:39:08.619647517 O: key revocation lists: checking revocations for revoked certs 899s 06:39:09.346990521 O: key revocation lists: checking revocations for unrevoked certs 900s 06:39:09.988816672 O: ok key revocation lists 900s 06:39:09.989524412 E: run test multipubkey.sh ... 902s 06:39:12.638855754 E: run test limit-keytype.sh ... 902s 06:39:12.639558099 O: ok multiple pubkey 904s 06:39:14.847360501 O: allow rsa,ed25519 905s 06:39:15.737346248 O: allow ed25519 906s 06:39:16.579296106 O: allow cert only 907s 06:39:17.434221733 O: match w/ no match 908s 06:39:18.291159204 O: match w/ matching 909s 06:39:19.162165361 O: ok restrict pubkey type 909s 06:39:19.162193168 E: run test hostkey-agent.sh ... 910s 06:39:20.120494666 O: key type ssh-ed25519 910s 06:39:20.256368269 O: key type sk-ssh-ed25519@openssh.com 910s 06:39:20.393908538 O: key type ecdsa-sha2-nistp256 910s 06:39:20.534772328 O: key type ecdsa-sha2-nistp384 910s 06:39:20.692862062 O: key type ecdsa-sha2-nistp521 910s 06:39:20.884453589 O: key type sk-ecdsa-sha2-nistp256@openssh.com 911s 06:39:21.033507536 O: key type ssh-rsa 911s 06:39:21.182767534 O: cert type ssh-ed25519-cert-v01@openssh.com 911s 06:39:21.400846440 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 911s 06:39:21.620561001 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 911s 06:39:21.847583484 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 912s 06:39:22.081118501 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 912s 06:39:22.340341535 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 912s 06:39:22.574155762 O: cert type ssh-rsa-cert-v01@openssh.com 912s 06:39:22.806337185 O: cert type rsa-sha2-256-cert-v01@openssh.com 913s 06:39:23.030960902 O: cert type rsa-sha2-512-cert-v01@openssh.com 913s 06:39:23.266878003 O: ok hostkey agent 913s 06:39:23.266945801 E: run test hostkey-rotate.sh ... 913s 06:39:23.662798934 O: learn hostkey with StrictHostKeyChecking=no 913s 06:39:23.900941149 O: learn additional hostkeys 914s 06:39:24.197027732 O: learn additional hostkeys, type=ssh-ed25519 914s 06:39:24.476743302 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 914s 06:39:24.758871059 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 915s 06:39:25.037286202 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 915s 06:39:25.325611445 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 915s 06:39:25.631026769 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 915s 06:39:25.914812197 O: learn additional hostkeys, type=ssh-rsa 916s 06:39:26.198422433 O: learn changed non-primary hostkey type=ssh-rsa 916s 06:39:26.680942503 O: learn new primary hostkey 917s 06:39:26.994462667 O: rotate primary hostkey 917s 06:39:27.312906813 O: check rotate primary hostkey 917s 06:39:27.624672656 E: run test principals-command.sh ... 917s 06:39:27.624520159 O: ok hostkey rotate 919s 06:39:28.597405273 O: SKIPPED: /var/run/principals_command_openssh-tests.58963 not executable (/var/run mounted noexec?) 919s 06:39:28.601008721 E: run test cert-file.sh ... 919s 06:39:28.763365777 O: identity cert with no plain public file 919s 06:39:29.072547072 O: CertificateFile with no plain public file 919s 06:39:29.377584907 O: plain keys 919s 06:39:29.691801112 O: untrusted cert 920s 06:39:30.000197780 O: good cert, bad key 920s 06:39:30.344366831 O: single trusted 920s 06:39:30.649916273 O: multiple trusted 921s 06:39:31.932027659 O: ok ssh with certificates 921s 06:39:31.932454773 E: run test cfginclude.sh ... 922s 06:39:32.063378280 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.068804480 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.074203243 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.079532094 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.084842968 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.090184938 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.096091926 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.101636837 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.106785433 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.118100708 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.123983152 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.127510558 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.135070436 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.140348926 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.145716934 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.151071031 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.156453671 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.162552392 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.167634324 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.173396007 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.178941510 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.190418624 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.195157494 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.199870010 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.208254586 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.213443427 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 922s 06:39:32.216202138 O: ok config include 922s 06:39:32.216551243 E: run test servcfginclude.sh ... 922s 06:39:32.473009405 E: run test allow-deny-users.sh ... 922s 06:39:32.472891483 O: ok server config include 925s 06:39:35.075191197 O: ok AllowUsers/DenyUsers 925s 06:39:35.075949023 E: run test authinfo.sh ... 925s 06:39:35.202059357 O: ExposeAuthInfo=no 925s 06:39:35.507261182 O: ExposeAuthInfo=yes 925s 06:39:35.807541672 O: ok authinfo 925s 06:39:35.808241244 E: run test sshsig.sh ... 925s 06:39:35.945582214 O: sshsig: make certificates 926s 06:39:35.983479025 O: sshsig: check signature for ssh-ed25519 926s 06:39:36.194703877 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 926s 06:39:36.423693604 O: sshsig: check signature for ecdsa-sha2-nistp256 926s 06:39:36.755134267 O: sshsig: check signature for ecdsa-sha2-nistp384 927s 06:39:37.291464008 O: sshsig: check signature for ecdsa-sha2-nistp521 928s 06:39:38.149895931 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 928s 06:39:38.562702894 O: sshsig: check signature for ssh-rsa 928s 06:39:38.795027336 O: sshsig: check signature for ssh-ed25519-cert.pub 929s 06:39:39.310294596 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 929s 06:39:39.846744077 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 930s 06:39:40.492722940 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 931s 06:39:41.390226949 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 932s 06:39:42.689986608 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 933s 06:39:43.431868173 O: sshsig: check signature for ssh-rsa-cert.pub 933s 06:39:43.939422948 O: sshsig: match principals 934s 06:39:43.956226765 O: sshsig: nomatch principals 934s 06:39:43.967544107 E: run test knownhosts.sh ... 934s 06:39:43.967064589 O: ok sshsig 935s 06:39:45.862291829 O: ok known hosts 935s 06:39:45.862891436 E: run test knownhosts-command.sh ... 936s 06:39:45.990142074 O: simple connection 936s 06:39:46.262757886 O: no keys 936s 06:39:46.470501780 O: bad exit status 936s 06:39:46.611419858 O: keytype ssh-ed25519 936s 06:39:46.856587091 O: keytype sk-ssh-ed25519@openssh.com 937s 06:39:47.094680268 O: keytype ecdsa-sha2-nistp256 937s 06:39:47.339571928 O: keytype ecdsa-sha2-nistp384 937s 06:39:47.588191721 O: keytype ecdsa-sha2-nistp521 937s 06:39:47.857508400 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 938s 06:39:48.098578527 O: keytype ssh-rsa 938s 06:39:48.341646169 E: run test agent-restrict.sh ... 938s 06:39:48.342059844 O: ok known hosts command 938s 06:39:48.469476956 O: generate keys 938s 06:39:48.521120588 O: prepare client config 938s 06:39:48.527724415 O: prepare known_hosts 938s 06:39:48.530340098 O: prepare server configs 938s 06:39:48.539077870 O: authentication w/o agent 939s 06:39:49.928994592 O: start agent 943s 06:39:53.933313981 O: authentication with agent (no restrict) 945s 06:39:55.343016185 O: unrestricted keylist 946s 06:39:56.072332855 O: authentication with agent (basic restrict) 946s 06:39:56.782816960 O: authentication with agent incorrect key (basic restrict) 947s 06:39:57.342840479 O: keylist (basic restrict) 948s 06:39:58.069764371 O: username 948s 06:39:58.775912330 O: username wildcard 949s 06:39:59.499381504 O: username incorrect 949s 06:39:59.544656062 O: agent restriction honours certificate principal 949s 06:39:59.565132730 O: multihop without agent 950s 06:40:00.393239342 O: multihop agent unrestricted 951s 06:40:01.204966701 O: multihop restricted 952s 06:40:02.026063396 O: multihop username 952s 06:40:02.853651840 O: multihop wildcard username 953s 06:40:03.683831939 O: multihop wrong username 954s 06:40:04.265949542 O: multihop cycle no agent 955s 06:40:05.430881952 O: multihop cycle agent unrestricted 956s 06:40:06.617241499 O: multihop cycle restricted deny 957s 06:40:07.051050360 O: multihop cycle restricted allow 958s 06:40:08.190141794 E: run test hostbased.sh ... 958s 06:40:08.190120917 O: ok agent restrictions 958s 06:40:08.313794361 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 958s 06:40:08.314197441 E: run test channel-timeout.sh ... 958s 06:40:08.437711358 O: no timeout 963s 06:40:13.728605918 O: command timeout 964s 06:40:14.123685123 O: command long timeout 964s 06:40:14.417461574 O: command wildcard timeout 965s 06:40:15.124043790 O: command irrelevant timeout 970s 06:40:20.418484722 O: multiplexed command timeout 981s 06:40:31.141980811 O: irrelevant multiplexed command timeout 996s 06:40:46.481548540 O: global command timeout 1018s 06:41:08.147421612 O: sftp no timeout 1023s 06:41:13.454666789 O: sftp timeout 1024s 06:41:14.123404847 E: Connection closed 1024s 06:41:14.124705244 O: sftp irrelevant timeout 1029s 06:41:19.425425611 O: ok channel timeout 1029s 06:41:19.425863969 E: run test connection-timeout.sh ... 1029s 06:41:19.553410696 O: no timeout 1034s 06:41:24.851799430 O: timeout 1043s 06:41:33.140770240 O: session inhibits timeout 1051s 06:41:41.446187033 O: timeout after session 1059s 06:41:49.450465598 O: timeout with listeners 1067s 06:41:57.747879204 O: ok unused connection timeout 1067s 06:41:57.748380169 E: run test match-subsystem.sh ... 1070s 06:42:00.771237853 O: ok sshd_config match subsystem 1070s 06:42:00.771405431 E: run test agent-pkcs11-restrict.sh ... 1070s 06:42:00.902018661 O: SKIPPED: No PKCS#11 library found 1070s 06:42:00.902222393 E: run test agent-pkcs11-cert.sh ... 1071s 06:42:01.032469028 E: run test penalty.sh ... 1071s 06:42:01.032134304 O: SKIPPED: No PKCS#11 library found 1071s 06:42:01.300996649 O: test connect 1071s 06:42:01.625718264 O: penalty for authentication failure 1079s 06:42:09.105758379 O: penalty for no authentication 1081s 06:42:11.246108594 O: ok penalties 1081s 06:42:11.246729461 E: run test penalty-expire.sh ... 1081s 06:42:11.516102514 O: test connect 1081s 06:42:11.845975831 O: penalty expiry 1095s 06:42:25.544354783 O: ok penalties 1095s 06:42:25.551574195 O: set -e ; if test -z "" ; then \ 1095s 06:42:25.552068427 O: V="" ; \ 1095s 06:42:25.552534090 O: test "x" = "x" || \ 1095s 06:42:25.553011455 O: V=/tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1095s 06:42:25.553496336 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1095s 06:42:25.553976334 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1095s 06:42:25.554458718 O: -d /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1095s 06:42:25.554934187 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1095s 06:42:25.555406013 O: -d /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1095s 06:42:25.555881858 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1095s 06:42:25.556361960 O: -d /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1095s 06:42:25.556833534 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1095s 06:42:25.557321231 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1095s 06:42:25.557798215 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1095s 06:42:25.558273177 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1095s 06:42:25.558746478 O: -d /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1095s 06:42:25.559220801 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1095s 06:42:25.559686408 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1095s 06:42:25.560139301 O: if test "x" = "xyes" ; then \ 1095s 06:42:25.560603384 O: $V /tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1095s 06:42:25.561068175 O: fi \ 1095s 06:42:25.561530283 O: fi 1111s 06:42:41.616557490 O: test_sshbuf: ...................................................................................................... 103 tests ok 1203s 06:44:13.348573958 O: test_sshkey: .................................................................................... 84 tests ok 1203s 06:44:13.367012405 O: test_sshsig: ....... 7 tests ok 1203s 06:44:13.601708558 O: test_authopt: .................................................................................................................................................. 146 tests ok 1217s 06:44:27.409561564 O: test_bitmap: .. 2 tests ok 1217s 06:44:27.411843972 O: test_conversion: . 1 tests ok 1226s 06:44:36.633424939 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1227s 06:44:37.079057647 O: test_hostkeys: .................. 18 tests ok 1227s 06:44:37.082066815 O: test_match: ...... 6 tests ok 1227s 06:44:37.084641790 O: test_misc: ........................................... 43 tests ok 1227s 06:44:37.085883309 E: run test putty-transfer.sh ... 1229s 06:44:39.365936668 O: plink version 0.83 major 0 minor 83 1229s 06:44:39.371412740 O: putty transfer data: compression 0 1231s 06:44:41.592237509 O: putty transfer data: compression 1 1233s 06:44:43.819002797 O: ok putty transfer data 1233s 06:44:43.819644672 E: run test putty-ciphers.sh ... 1235s 06:44:44.942260097 O: plink version 0.83 major 0 minor 83 1235s 06:44:45.186388444 O: putty ciphers: cipher default mac default 1235s 06:44:45.463173702 O: putty ciphers: cipher default mac hmac-sha1 1235s 06:44:45.736101115 O: putty ciphers: cipher default mac hmac-sha1-96 1236s 06:44:46.009518522 O: putty ciphers: cipher default mac hmac-sha2-256 1236s 06:44:46.280815112 O: putty ciphers: cipher default mac hmac-sha2-512 1236s 06:44:46.553491400 O: putty ciphers: cipher default mac hmac-md5 1236s 06:44:46.824975996 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1237s 06:44:47.105866242 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1237s 06:44:47.403403511 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1237s 06:44:47.675593182 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1237s 06:44:47.948586126 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1238s 06:44:48.231495288 O: putty ciphers: cipher 3des-cbc mac default 1238s 06:44:48.506673654 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1238s 06:44:48.783572022 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1239s 06:44:49.062592684 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1239s 06:44:49.342766503 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1239s 06:44:49.621182724 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1239s 06:44:49.892768322 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1240s 06:44:50.173186055 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1240s 06:44:50.466264326 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1240s 06:44:50.735747018 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1241s 06:44:51.005600466 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1241s 06:44:51.276192555 O: putty ciphers: cipher aes128-cbc mac default 1241s 06:44:51.549958099 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1241s 06:44:51.821204997 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1242s 06:44:52.094422383 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1242s 06:44:52.373706203 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1242s 06:44:52.645175874 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1242s 06:44:52.929869671 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1243s 06:44:53.206698399 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1243s 06:44:53.479992065 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1243s 06:44:53.757250691 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1244s 06:44:54.027150207 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1244s 06:44:54.299870894 O: putty ciphers: cipher aes192-cbc mac default 1244s 06:44:54.573767907 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1244s 06:44:54.850973417 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1245s 06:44:55.124515282 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1245s 06:44:55.395805430 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1245s 06:44:55.670560387 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1245s 06:44:55.946685585 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1246s 06:44:56.222130780 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1246s 06:44:56.494635965 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1246s 06:44:56.767869954 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1247s 06:44:57.038901615 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1247s 06:44:57.307998487 O: putty ciphers: cipher aes256-cbc mac default 1247s 06:44:57.582398232 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1247s 06:44:57.854450439 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1248s 06:44:58.133231609 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1248s 06:44:58.404804102 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1248s 06:44:58.675988489 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1248s 06:44:58.944644040 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1249s 06:44:59.213210211 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1249s 06:44:59.485036099 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1249s 06:44:59.758418494 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1250s 06:45:00.033967292 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1250s 06:45:00.303315029 O: putty ciphers: cipher aes128-ctr mac default 1250s 06:45:00.582650898 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1250s 06:45:00.853647037 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1251s 06:45:01.126222935 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1251s 06:45:01.406990514 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1251s 06:45:01.684515614 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1252s 06:45:01.965857230 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1252s 06:45:02.248869070 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1252s 06:45:02.523545325 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1252s 06:45:02.798448018 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1253s 06:45:03.067203662 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1253s 06:45:03.343331301 O: putty ciphers: cipher aes192-ctr mac default 1253s 06:45:03.617032506 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1253s 06:45:03.891132630 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1254s 06:45:04.163845636 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1254s 06:45:04.444193188 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1254s 06:45:04.713983887 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1255s 06:45:04.984783589 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1255s 06:45:05.254952009 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1255s 06:45:05.527828824 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1255s 06:45:05.801758281 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1256s 06:45:06.073027517 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1256s 06:45:06.343759644 O: putty ciphers: cipher aes256-ctr mac default 1256s 06:45:06.623373125 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1256s 06:45:06.896563183 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1257s 06:45:07.177799805 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1257s 06:45:07.457584138 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1257s 06:45:07.729217456 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1258s 06:45:08.003628673 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1258s 06:45:08.274976713 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1258s 06:45:08.554048817 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1258s 06:45:08.823937170 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1259s 06:45:09.094953837 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1259s 06:45:09.374505668 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1259s 06:45:09.648054020 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1259s 06:45:09.923970285 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1260s 06:45:10.197519250 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1260s 06:45:10.471949215 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1260s 06:45:10.742641968 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1261s 06:45:11.013604610 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1261s 06:45:11.290937458 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1261s 06:45:11.569902180 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1261s 06:45:11.843412871 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1262s 06:45:12.113768875 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1262s 06:45:12.387077225 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1262s 06:45:12.670491484 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1262s 06:45:12.943436940 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1263s 06:45:13.217438419 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1263s 06:45:13.491287794 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1263s 06:45:13.764761826 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1264s 06:45:14.036762581 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1264s 06:45:14.312483222 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1264s 06:45:14.594890227 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1264s 06:45:14.867747503 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1265s 06:45:15.147817685 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1265s 06:45:15.426501725 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1265s 06:45:15.701718726 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1266s 06:45:15.972846943 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1266s 06:45:16.244371525 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1266s 06:45:16.523054399 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1266s 06:45:16.794234319 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1267s 06:45:17.064379196 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1267s 06:45:17.336212965 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1267s 06:45:17.619232073 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1267s 06:45:17.896359021 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1268s 06:45:18.169200106 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1268s 06:45:18.448406346 O: ok putty ciphers 1268s 06:45:18.448866206 E: run test putty-kex.sh ... 1271s 06:45:21.258756612 O: plink version 0.83 major 0 minor 83 1271s 06:45:21.398132687 O: putty KEX: kex diffie-hellman-group1-sha1 1271s 06:45:21.629379060 O: putty KEX: kex diffie-hellman-group14-sha1 1272s 06:45:21.988646592 O: putty KEX: kex diffie-hellman-group14-sha256 1272s 06:45:22.351647136 O: putty KEX: kex diffie-hellman-group16-sha512 1273s 06:45:23.644479839 O: putty KEX: kex diffie-hellman-group18-sha512 1280s 06:45:30.824234746 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1281s 06:45:31.214776979 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1282s 06:45:32.481269116 O: putty KEX: kex ecdh-sha2-nistp256 1282s 06:45:32.702639373 O: putty KEX: kex ecdh-sha2-nistp384 1282s 06:45:32.944647216 O: putty KEX: kex ecdh-sha2-nistp521 1283s 06:45:33.254374789 O: putty KEX: kex curve25519-sha256 1283s 06:45:33.471683298 O: putty KEX: kex curve25519-sha256@libssh.org 1283s 06:45:33.686293371 O: putty KEX: kex sntrup761x25519-sha512 1283s 06:45:33.940141380 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1284s 06:45:34.204954064 O: putty KEX: kex mlkem768x25519-sha256 1284s 06:45:34.426475342 O: ok putty KEX 1284s 06:45:34.427092630 E: run test conch-ciphers.sh ... 1284s 06:45:34.691209142 O: conch ciphers: cipher aes256-ctr 1285s 06:45:35.260454870 O: conch ciphers: cipher aes256-cbc 1285s 06:45:35.827793371 O: conch ciphers: cipher aes192-ctr 1286s 06:45:36.389257643 O: conch ciphers: cipher aes192-cbc 1287s 06:45:36.958025023 O: conch ciphers: cipher aes128-ctr 1287s 06:45:37.539518849 O: conch ciphers: cipher aes128-cbc 1288s 06:45:38.109140965 O: conch ciphers: cipher cast128-cbc 1288s 06:45:38.665460422 O: conch ciphers: cipher blowfish 1289s 06:45:39.237886802 O: conch ciphers: cipher 3des-cbc 1289s 06:45:39.808868957 O: ok conch ciphers 1289s 06:45:39.809329654 E: run test dropbear-ciphers.sh ... 1289s 06:45:39.885655348 O: Create dropbear key type ed25519 1289s 06:45:39.896916013 O: Create dropbear key type rsa 1290s 06:45:40.846514600 O: Create dropbear key type ecdsa 1290s 06:45:40.935992038 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 1291s 06:45:41.206919875 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 1291s 06:45:41.442237541 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 1291s 06:45:41.689178667 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1292s 06:45:41.961894662 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1292s 06:45:42.197525740 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1292s 06:45:42.443482864 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 1292s 06:45:42.713721253 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 1293s 06:45:42.953252057 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 1293s 06:45:43.202227413 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1293s 06:45:43.474241528 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1293s 06:45:43.710325210 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1294s 06:45:43.956674645 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 1294s 06:45:44.231351057 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 1294s 06:45:44.477744860 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 1294s 06:45:44.731710338 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1295s 06:45:45.008782281 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1295s 06:45:45.255270926 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1295s 06:45:45.506234665 E: run test dropbear-kex.sh ... 1295s 06:45:45.506586695 O: ok dropbear ciphers 1295s 06:45:45.672809493 O: diffie-hellman-group14-sha1 1295s 06:45:45.673780877 O: dropbear kex: kex curve25519-sha256 1295s 06:45:45.931263813 O: dropbear kex: kex curve25519-sha256@libssh.org 1297s 06:45:46.177693986 O: dropbear kex: kex diffie-hellman-group14-sha256 1297s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1297s 06:45:46.453668899 O: dropbear kex: kex diffie-hellman-group14-sha1 1297s 06:45:46.732332329 O: ok dropbear kex 1297s 06:45:46.733128009 O: make: Leaving directory '/tmp/autopkgtest.heJEAD/autopkgtest_tmp/user/regress' 1297s 06:45:46.734076060 I: Finished with exitcode 0 1297s info: Looking for files to backup/remove ... 1297s info: Removing files ... 1297s info: Removing crontab ... 1297s info: Removing user `openssh-tests' ... 1297s autopkgtest [06:45:47]: test regress: -----------------------] 1298s regress PASS 1298s autopkgtest [06:45:48]: test regress: - - - - - - - - - - results - - - - - - - - - - 1298s autopkgtest [06:45:48]: test ssh-gssapi: preparing testbed 1363s autopkgtest [06:46:53]: testbed dpkg architecture: s390x 1363s autopkgtest [06:46:53]: testbed apt version: 2.9.30ubuntu1 1364s autopkgtest [06:46:54]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1364s autopkgtest [06:46:54]: testbed release detected to be: plucky 1364s autopkgtest [06:46:54]: updating testbed package index (apt update) 1365s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1365s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1365s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1365s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1365s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 1365s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1365s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 1365s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1365s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [125 kB] 1365s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 1365s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [424 kB] 1365s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 1366s Fetched 1264 kB in 1s (1455 kB/s) 1366s Reading package lists... 1367s Reading package lists... 1367s Building dependency tree... 1367s Reading state information... 1367s Calculating upgrade... 1367s Calculating upgrade... 1367s The following packages were automatically installed and are no longer required: 1367s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 1367s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 1367s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 1367s linux-tools-6.11.0-8-generic 1367s Use 'sudo apt autoremove' to remove them. 1367s The following packages will be upgraded: 1367s curl libcurl3t64-gnutls libcurl4t64 1367s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1367s Need to get 1041 kB of archives. 1367s After this operation, 7168 B disk space will be freed. 1367s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x curl s390x 8.12.1-2ubuntu1 [251 kB] 1368s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl4t64 s390x 8.12.1-2ubuntu1 [397 kB] 1368s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl3t64-gnutls s390x 8.12.1-2ubuntu1 [393 kB] 1368s Fetched 1041 kB in 1s (1955 kB/s) 1368s (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 ... 81030 files and directories currently installed.) 1368s Preparing to unpack .../curl_8.12.1-2ubuntu1_s390x.deb ... 1368s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1368s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_s390x.deb ... 1368s Unpacking libcurl4t64:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1368s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_s390x.deb ... 1368s Unpacking libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1368s Setting up libcurl4t64:s390x (8.12.1-2ubuntu1) ... 1368s Setting up libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) ... 1368s Setting up curl (8.12.1-2ubuntu1) ... 1368s Processing triggers for man-db (2.13.0-1) ... 1368s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1369s Reading package lists... 1369s Building dependency tree... 1369s Reading state information... 1369s Solving dependencies... 1369s The following packages will be REMOVED: 1369s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 1369s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 1369s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 1369s linux-tools-6.11.0-8-generic* 1369s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 1369s After this operation, 167 MB disk space will be freed. 1369s (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 ... 81030 files and directories currently installed.) 1369s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 1369s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 1369s Removing libpython3.12t64:s390x (3.12.9-1) ... 1369s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 1369s Removing libnsl2:s390x (1.3.0-3build3) ... 1369s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 1369s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 1369s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 1370s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1370s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1370s (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 ... 55930 files and directories currently installed.) 1370s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 1370s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1370s autopkgtest [06:47:00]: upgrading testbed (apt dist-upgrade and autopurge) 1371s Reading package lists... 1371s Building dependency tree... 1371s Reading state information... 1371s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1371s Starting 2 pkgProblemResolver with broken count: 0 1371s Done 1371s Entering ResolveByKeep 1371s 1371s Calculating upgrade... 1371s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1372s Reading package lists... 1372s Building dependency tree... 1372s Reading state information... 1372s Starting pkgProblemResolver with broken count: 0 1372s Starting 2 pkgProblemResolver with broken count: 0 1372s Done 1372s Solving dependencies... 1372s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1372s autopkgtest [06:47:02]: rebooting testbed after setup commands that affected boot 1395s Reading package lists... 1395s Building dependency tree... 1395s Reading state information... 1395s Starting pkgProblemResolver with broken count: 0 1395s Starting 2 pkgProblemResolver with broken count: 0 1395s Done 1395s The following NEW packages will be installed: 1395s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1395s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1395s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1395s openssh-server-gssapi 1395s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1395s Need to get 796 kB of archives. 1395s After this operation, 2750 kB of additional disk space will be used. 1395s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-config all 2.7 [22.0 kB] 1395s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-4ubuntu1 [59.1 kB] 1395s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-4ubuntu1 [40.5 kB] 1395s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-4ubuntu1 [41.9 kB] 1396s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-4ubuntu1 [55.6 kB] 1396s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-user s390x 1.21.3-4ubuntu1 [110 kB] 1396s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 1396s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 1396s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 1396s Get:10 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-kdc s390x 1.21.3-4ubuntu1 [198 kB] 1396s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-admin-server s390x 1.21.3-4ubuntu1 [95.7 kB] 1396s Get:12 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-client-gssapi all 1:9.9p1-3ubuntu2 [5028 B] 1396s Get:13 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-server-gssapi all 1:9.9p1-3ubuntu2 [5036 B] 1396s Preconfiguring packages ... 1396s Fetched 796 kB in 1s (1446 kB/s) 1396s Selecting previously unselected package krb5-config. 1396s (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 ... 55928 files and directories currently installed.) 1396s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1396s Unpacking krb5-config (2.7) ... 1396s Selecting previously unselected package libgssrpc4t64:s390x. 1396s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu1_s390x.deb ... 1396s Unpacking libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 1396s Selecting previously unselected package libkadm5clnt-mit12:s390x. 1396s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu1_s390x.deb ... 1396s Unpacking libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 1396s Selecting previously unselected package libkdb5-10t64:s390x. 1396s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu1_s390x.deb ... 1396s Unpacking libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 1396s Selecting previously unselected package libkadm5srv-mit12:s390x. 1396s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu1_s390x.deb ... 1396s Unpacking libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 1396s Selecting previously unselected package krb5-user. 1396s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu1_s390x.deb ... 1396s Unpacking krb5-user (1.21.3-4ubuntu1) ... 1396s Selecting previously unselected package libevent-2.1-7t64:s390x. 1396s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 1396s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1396s Selecting previously unselected package libverto1t64:s390x. 1396s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1396s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1396s Selecting previously unselected package libverto-libevent1t64:s390x. 1396s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1396s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1396s Selecting previously unselected package krb5-kdc. 1396s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu1_s390x.deb ... 1396s Unpacking krb5-kdc (1.21.3-4ubuntu1) ... 1396s Selecting previously unselected package krb5-admin-server. 1396s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu1_s390x.deb ... 1396s Unpacking krb5-admin-server (1.21.3-4ubuntu1) ... 1396s Selecting previously unselected package openssh-client-gssapi. 1396s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 1396s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 1396s Selecting previously unselected package openssh-server-gssapi. 1396s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 1396s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1396s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1396s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 1396s Setting up libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 1396s Setting up krb5-config (2.7) ... 1396s Setting up libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 1396s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1396s Setting up libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 1396s Setting up libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 1396s Setting up krb5-user (1.21.3-4ubuntu1) ... 1396s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1396s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1396s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1396s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1396s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1396s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1396s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1396s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1396s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1396s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1396s Setting up krb5-kdc (1.21.3-4ubuntu1) ... 1397s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1397s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1397s Setting up krb5-admin-server (1.21.3-4ubuntu1) ... 1398s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1398s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1398s Processing triggers for man-db (2.13.0-1) ... 1406s autopkgtest [06:47:36]: test ssh-gssapi: [----------------------- 1406s Generating public/private ed25519 key pair. 1406s Your identification has been saved in /root/.ssh/id_ed25519 1406s Your public key has been saved in /root/.ssh/id_ed25519.pub 1406s The key fingerprint is: 1406s SHA256:iRSiphsnkWQgjQU89sgZqkmjpdiCxqVB9kMyKUhvo3Y root@autopkgtest 1406s The key's randomart image is: 1406s +--[ED25519 256]--+ 1406s |OOo . . | 1406s |B%oo . . | 1406s |X &+ . | 1406s |.@+=.. . . | 1406s |@B*E. . S | 1406s |XOo | 1406s |o. | 1406s | | 1406s | | 1406s +----[SHA256]-----+ 1406s ## Setting up test environment 1406s ## Creating Kerberos realm EXAMPLE.FAKE 1406s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1406s master key name 'K/M@EXAMPLE.FAKE' 1406s ## Creating principals 1406s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1406s Principal "testuser1685@EXAMPLE.FAKE" created. 1406s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1406s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1406s ## Extracting service principal host/sshd-gssapi.example.fake 1406s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1406s 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. 1406s 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. 1406s ## Adjusting /etc/krb5.conf 1406s ## TESTS 1406s 1406s ## TEST test_gssapi_login 1406s ## Configuring sshd for gssapi-with-mic authentication 1406s ## Restarting ssh 1406s ## Obtaining TGT 1406s Password for testuser1685@EXAMPLE.FAKE: 1406s Ticket cache: FILE:/tmp/krb5cc_0 1406s Default principal: testuser1685@EXAMPLE.FAKE 1406s 1406s Valid starting Expires Service principal 1406s 02/22/25 06:49:02 02/22/25 16:49:02 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1406s renew until 02/23/25 06:49:02 1406s 1406s ## ssh'ing into localhost using gssapi-with-mic auth 1407s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1407s Sat Feb 22 06:49:02 UTC 2025 1407s 1407s ## checking that we got a service ticket for ssh (host/) 1407s 02/22/25 06:49:02 02/22/25 16:49:02 host/sshd-gssapi.example.fake@ 1407s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1407s 1407s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1407s Feb 22 06:49:02 sshd-gssapi.example.fake sshd-session[1788]: Accepted gssapi-with-mic for testuser1685 from 127.0.0.1 port 33932 ssh2: testuser1685@EXAMPLE.FAKE 1407s ## PASS test_gssapi_login 1407s 1407s ## TEST test_gssapi_keyex_login 1407s ## Configuring sshd for gssapi-keyex authentication 1407s ## Restarting ssh 1407s ## Obtaining TGT 1407s Password for testuser1685@EXAMPLE.FAKE: 1407s Ticket cache: FILE:/tmp/krb5cc_0 1407s Default principal: testuser1685@EXAMPLE.FAKE 1407s 1407s Valid starting Expires Service principal 1407s 02/22/25 06:49:02 02/22/25 16:49:02 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1407s renew until 02/23/25 06:49:02 1407s 1407s ## ssh'ing into localhost using gssapi-keyex auth 1407s Sat Feb 22 06:49:03 UTC 2025 1407s 1407s ## checking that we got a service ticket for ssh (host/) 1407s 02/22/25 06:49:02 02/22/25 16:49:02 host/sshd-gssapi.example.fake@ 1407s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1407s 1407s ## Checking ssh logs to confirm gssapi-keyex auth was used 1407s Feb 22 06:49:03 sshd-gssapi.example.fake sshd-session[1844]: Accepted gssapi-keyex for testuser1685 from 127.0.0.1 port 33946 ssh2: testuser1685@EXAMPLE.FAKE 1407s ## PASS test_gssapi_keyex_login 1407s 1407s ## TEST test_gssapi_keyex_pubkey_fallback 1407s ## Configuring sshd for gssapi-keyex authentication 1407s ## Restarting ssh 1407s ## Obtaining TGT 1407s Password for testuser1685@EXAMPLE.FAKE: 1407s Ticket cache: FILE:/tmp/krb5cc_0 1407s Default principal: testuser1685@EXAMPLE.FAKE 1407s 1407s Valid starting Expires Service principal 1407s 02/22/25 06:49:03 02/22/25 16:49:03 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1407s renew until 02/23/25 06:49:03 1407s 1407s ## ssh'ing into localhost using gssapi-keyex auth 1407s Sat Feb 22 06:49:03 UTC 2025 1407s 1407s ## checking that we got a service ticket for ssh (host/) 1407s 02/22/25 06:49:03 02/22/25 16:49:03 host/sshd-gssapi.example.fake@ 1407s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1407s 1407s ## Checking ssh logs to confirm publickey auth was used 1407s Feb 22 06:49:03 sshd-gssapi.example.fake sshd-session[1885]: Accepted publickey for testuser1685-2 from 127.0.0.1 port 33960 ssh2: ED25519 SHA256:iRSiphsnkWQgjQU89sgZqkmjpdiCxqVB9kMyKUhvo3Y 1407s ## PASS test_gssapi_keyex_pubkey_fallback 1407s 1407s ## ALL TESTS PASSED 1407s ## Cleaning up 1408s autopkgtest [06:47:38]: test ssh-gssapi: -----------------------] 1408s autopkgtest [06:47:38]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1408s ssh-gssapi PASS 1408s autopkgtest [06:47:38]: test socket-activation: preparing testbed 1514s autopkgtest [06:49:24]: testbed dpkg architecture: s390x 1514s autopkgtest [06:49:24]: testbed apt version: 2.9.30ubuntu1 1514s autopkgtest [06:49:24]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1514s autopkgtest [06:49:24]: testbed release detected to be: plucky 1515s autopkgtest [06:49:25]: updating testbed package index (apt update) 1515s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1516s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1516s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1516s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1516s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 1516s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1516s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 1516s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1516s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [125 kB] 1516s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 1516s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [424 kB] 1516s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 1516s Fetched 1264 kB in 1s (1395 kB/s) 1517s Reading package lists... 1517s Reading package lists... 1517s Building dependency tree... 1517s Reading state information... 1517s Calculating upgrade... 1517s Calculating upgrade... 1518s The following packages were automatically installed and are no longer required: 1518s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 1518s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 1518s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 1518s linux-tools-6.11.0-8-generic 1518s Use 'sudo apt autoremove' to remove them. 1518s The following packages will be upgraded: 1518s curl libcurl3t64-gnutls libcurl4t64 1518s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1518s Need to get 1041 kB of archives. 1518s After this operation, 7168 B disk space will be freed. 1518s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x curl s390x 8.12.1-2ubuntu1 [251 kB] 1518s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl4t64 s390x 8.12.1-2ubuntu1 [397 kB] 1518s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl3t64-gnutls s390x 8.12.1-2ubuntu1 [393 kB] 1518s Fetched 1041 kB in 1s (1783 kB/s) 1519s (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 ... 81030 files and directories currently installed.) 1519s Preparing to unpack .../curl_8.12.1-2ubuntu1_s390x.deb ... 1519s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1519s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_s390x.deb ... 1519s Unpacking libcurl4t64:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1519s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_s390x.deb ... 1519s Unpacking libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1519s Setting up libcurl4t64:s390x (8.12.1-2ubuntu1) ... 1519s Setting up libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) ... 1519s Setting up curl (8.12.1-2ubuntu1) ... 1519s Processing triggers for man-db (2.13.0-1) ... 1519s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1519s Reading package lists... 1519s Building dependency tree... 1519s Reading state information... 1519s Solving dependencies... 1519s The following packages will be REMOVED: 1519s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 1519s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 1519s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 1519s linux-tools-6.11.0-8-generic* 1520s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 1520s After this operation, 167 MB disk space will be freed. 1520s (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 ... 81030 files and directories currently installed.) 1520s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 1520s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 1520s Removing libpython3.12t64:s390x (3.12.9-1) ... 1520s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 1520s Removing libnsl2:s390x (1.3.0-3build3) ... 1520s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 1520s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 1520s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 1521s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1521s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1521s (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 ... 55930 files and directories currently installed.) 1521s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 1521s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1521s autopkgtest [06:49:31]: upgrading testbed (apt dist-upgrade and autopurge) 1521s Reading package lists... 1521s Building dependency tree... 1521s Reading state information... 1521s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1521s Starting 2 pkgProblemResolver with broken count: 0 1521s Done 1522s Entering ResolveByKeep 1522s 1522s Calculating upgrade... 1522s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1522s Reading package lists... 1522s Building dependency tree... 1522s Reading state information... 1522s Starting pkgProblemResolver with broken count: 0 1522s Starting 2 pkgProblemResolver with broken count: 0 1522s Done 1522s Solving dependencies... 1523s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1523s autopkgtest [06:49:33]: rebooting testbed after setup commands that affected boot 1543s Reading package lists... 1544s Building dependency tree... 1544s Reading state information... 1544s Starting pkgProblemResolver with broken count: 0 1544s Starting 2 pkgProblemResolver with broken count: 0 1544s Done 1544s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1548s autopkgtest [06:49:58]: test socket-activation: [----------------------- 1548s Generating public/private ed25519 key pair. 1548s Your identification has been saved in /home/testuser1235/.ssh/id_ed25519 1548s Your public key has been saved in /home/testuser1235/.ssh/id_ed25519.pub 1548s The key fingerprint is: 1548s SHA256:qnRQUbZoMEOSqjhTTwzlLS2aCzOryj1DTIyCxE1z1U0 testuser1235@autopkgtest 1548s The key's randomart image is: 1548s +--[ED25519 256]--+ 1548s |. +**.oo+ oE | 1548s | oo+o* + o . | 1548s |o.oo+ * . | 1548s |+..=o= | 1548s |B.=o. S | 1548s |== +.. . | 1548s |.oo . o | 1548s |o .+ o | 1548s |+. .+ | 1548s +----[SHA256]-----+ 1548s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1548s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1549s Disabling 'ssh.service', but its triggering units are still active: 1549s ssh.socket 1549s Stopping 'ssh.service', but its triggering units are still active: 1549s ssh.socket 1549s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1549s Sat Feb 22 06:51:25 UTC 2025 1550s autopkgtest [06:50:00]: test socket-activation: -----------------------] 1550s socket-activation PASS 1550s autopkgtest [06:50:00]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1550s autopkgtest [06:50:00]: test xinetd: preparing testbed 1551s Reading package lists... 1551s Building dependency tree... 1551s Reading state information... 1551s Starting pkgProblemResolver with broken count: 0 1551s Starting 2 pkgProblemResolver with broken count: 0 1551s Done 1551s The following NEW packages will be installed: 1551s xinetd 1551s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1551s Need to get 116 kB of archives. 1551s After this operation, 316 kB of additional disk space will be used. 1551s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x xinetd s390x 1:2.3.15.4-4 [116 kB] 1552s Fetched 116 kB in 0s (325 kB/s) 1552s Selecting previously unselected package xinetd. 1552s (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 ... 55928 files and directories currently installed.) 1552s Preparing to unpack .../xinetd_1%3a2.3.15.4-4_s390x.deb ... 1552s Unpacking xinetd (1:2.3.15.4-4) ... 1552s Setting up xinetd (1:2.3.15.4-4) ... 1552s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1552s Processing triggers for man-db (2.13.0-1) ... 1554s autopkgtest [06:50:04]: test xinetd: [----------------------- 1554s Generating public/private ed25519 key pair. 1554s Your identification has been saved in /home/testuser1763/.ssh/id_ed25519 1554s Your public key has been saved in /home/testuser1763/.ssh/id_ed25519.pub 1554s The key fingerprint is: 1554s SHA256:5ZVTbxZq7BNQDtE4dMPz5DAIPVVNBfDcopWHmJgK41I testuser1763@autopkgtest 1554s The key's randomart image is: 1554s +--[ED25519 256]--+ 1554s | .+=X*+==| 1554s | O*&o*o| 1554s | E + O*# *| 1554s | o o + .o+.B | 1554s | . . S . .o | 1554s | . . | 1554s | | 1554s | | 1554s | | 1554s +----[SHA256]-----+ 1554s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1554s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1555s Disabling 'ssh.service', but its triggering units are still active: 1555s ssh.socket 1555s Stopping 'ssh.service', but its triggering units are still active: 1555s ssh.socket 1555s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1555s Sat Feb 22 06:51:31 UTC 2025 1555s autopkgtest [06:50:05]: test xinetd: -----------------------] 1556s xinetd PASS 1556s autopkgtest [06:50:06]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1556s autopkgtest [06:50:06]: test systemd-socket-activation: preparing testbed 1622s autopkgtest [06:51:12]: testbed dpkg architecture: s390x 1622s autopkgtest [06:51:12]: testbed apt version: 2.9.30ubuntu1 1622s autopkgtest [06:51:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1622s autopkgtest [06:51:12]: testbed release detected to be: plucky 1623s autopkgtest [06:51:13]: updating testbed package index (apt update) 1623s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1623s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1623s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1624s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1624s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 1624s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1624s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1624s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 1624s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [125 kB] 1624s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 1624s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [424 kB] 1624s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 1624s Fetched 1264 kB in 1s (1399 kB/s) 1625s Reading package lists... 1625s Reading package lists... 1625s Building dependency tree... 1625s Reading state information... 1625s Calculating upgrade... 1625s Calculating upgrade... 1625s The following packages were automatically installed and are no longer required: 1625s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 1625s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 1625s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 1625s linux-tools-6.11.0-8-generic 1625s Use 'sudo apt autoremove' to remove them. 1625s The following packages will be upgraded: 1625s curl libcurl3t64-gnutls libcurl4t64 1626s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1626s Need to get 1041 kB of archives. 1626s After this operation, 7168 B disk space will be freed. 1626s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x curl s390x 8.12.1-2ubuntu1 [251 kB] 1626s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl4t64 s390x 8.12.1-2ubuntu1 [397 kB] 1626s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl3t64-gnutls s390x 8.12.1-2ubuntu1 [393 kB] 1626s Fetched 1041 kB in 1s (1716 kB/s) 1626s (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 ... 81030 files and directories currently installed.) 1626s Preparing to unpack .../curl_8.12.1-2ubuntu1_s390x.deb ... 1626s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1626s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_s390x.deb ... 1626s Unpacking libcurl4t64:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1626s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_s390x.deb ... 1626s Unpacking libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1626s Setting up libcurl4t64:s390x (8.12.1-2ubuntu1) ... 1626s Setting up libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) ... 1626s Setting up curl (8.12.1-2ubuntu1) ... 1626s Processing triggers for man-db (2.13.0-1) ... 1627s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1627s Reading package lists... 1627s Building dependency tree... 1627s Reading state information... 1627s Solving dependencies... 1627s The following packages will be REMOVED: 1627s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 1627s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 1627s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 1627s linux-tools-6.11.0-8-generic* 1627s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 1627s After this operation, 167 MB disk space will be freed. 1628s (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 ... 81030 files and directories currently installed.) 1628s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 1628s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 1628s Removing libpython3.12t64:s390x (3.12.9-1) ... 1628s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 1628s Removing libnsl2:s390x (1.3.0-3build3) ... 1628s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 1628s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 1628s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 1628s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1628s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1629s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 55930 files and directories currently installed.) 1629s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 1629s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1629s autopkgtest [06:51:19]: upgrading testbed (apt dist-upgrade and autopurge) 1629s Reading package lists... 1629s Building dependency tree... 1629s Reading state information... 1629s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1629s Starting 2 pkgProblemResolver with broken count: 0 1629s Done 1629s Entering ResolveByKeep 1629s 1630s Calculating upgrade... 1630s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1630s Reading package lists... 1630s Building dependency tree... 1630s Reading state information... 1630s Starting pkgProblemResolver with broken count: 0 1630s Starting 2 pkgProblemResolver with broken count: 0 1630s Done 1630s Solving dependencies... 1630s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1630s autopkgtest [06:51:20]: rebooting testbed after setup commands that affected boot 1654s Reading package lists... 1654s Building dependency tree... 1654s Reading state information... 1654s Starting pkgProblemResolver with broken count: 0 1654s Starting 2 pkgProblemResolver with broken count: 0 1654s Done 1654s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1658s autopkgtest [06:51:48]: test systemd-socket-activation: [----------------------- 1659s Stopping ssh.service... 1659s Checking that ssh.socket is active and listening... 1659s Checking that ssh.service is inactive/dead... 1659s Checking that a connection attempt activates ssh.service... 1659s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1659s Checking that sshd can be re-executed... 1660s Checking sshd can run in debug mode... 1661s debug1: PAM: establishing credentials 1661s debug1: permanently_set_uid: 0/0 1661s debug3: Copy environment: XDG_SESSION_ID=5 1661s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1661s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1661s debug3: Copy environment: XDG_SESSION_TYPE=tty 1661s debug3: Copy environment: XDG_SESSION_CLASS=user 1661s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1661s debug3: Copy environment: TERM=linux 1661s debug3: Copy environment: http_proxy=http://squid.internal:3128 1661s debug3: Copy environment: https_proxy=http://squid.internal:3128 1661s debug3: Copy environment: no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com 1661s debug3: Copy environment: LANG=C.UTF-8 1661s Environment: 1661s LANG=C.UTF-8 1661s USER=root 1661s LOGNAME=root 1661s HOME=/root 1661s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1661s SHELL=/bin/bash 1661s XDG_SESSION_ID=5 1661s XDG_RUNTIME_DIR=/run/user/0 1661s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1661s XDG_SESSION_TYPE=tty 1661s XDG_SESSION_CLASS=user 1661s TERM=linux 1661s http_proxy=http://squid.internal:3128 1661s https_proxy=http://squid.internal:3128 1661s no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.c 1661s SSH_CLIENT=::1 43180 22 1661s SSH_CONNECTION=::1 43180 ::1 22 1661s Done. 1661s autopkgtest [06:51:51]: test systemd-socket-activation: -----------------------] 1661s autopkgtest [06:51:51]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1661s systemd-socket-activation PASS 1662s autopkgtest [06:51:52]: test sshd-socket-generator: preparing testbed 1662s Reading package lists... 1662s Building dependency tree... 1662s Reading state information... 1662s Starting pkgProblemResolver with broken count: 0 1662s Starting 2 pkgProblemResolver with broken count: 0 1662s Done 1663s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1663s autopkgtest [06:51:53]: test sshd-socket-generator: [----------------------- 1663s test_default...PASS 1663s test_custom_port...PASS 1663s test_default_and_custom_port...PASS 1663s test_mutiple_custom_ports...PASS 1663s test_custom_listenaddress...PASS 1663s test_custom_listenaddress_and_port...PASS 1663s test_custom_ipv6_listenaddress...PASS 1664s test_custom_family_ipv4...PASS 1664s test_custom_family_ipv6...PASS 1664s test_custom_port_and_family_ipv4...PASS 1664s test_custom_port_and_family_ipv6...PASS 1664s test_match_on_port...PASS 1664s autopkgtest [06:51:54]: test sshd-socket-generator: -----------------------] 1664s sshd-socket-generator PASS 1664s autopkgtest [06:51:54]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1665s autopkgtest [06:51:55]: @@@@@@@@@@@@@@@@@@@@ summary 1665s regress PASS 1665s ssh-gssapi PASS 1665s socket-activation PASS 1665s xinetd PASS 1665s systemd-socket-activation PASS 1665s sshd-socket-generator PASS 1669s nova [W] Using flock in prodstack6-s390x 1669s Creating nova instance adt-plucky-s390x-openssh-20250222-062410-juju-7f2275-prod-proposed-migration-environment-20-3f958b6b-45a0-4850-947f-c15c50a7a093 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1669s nova [W] Timed out waiting for d55f71a1-e8d7-4a6a-afe9-184bea356cbd to get deleted. 1669s nova [W] Using flock in prodstack6-s390x 1669s Creating nova instance adt-plucky-s390x-openssh-20250222-062410-juju-7f2275-prod-proposed-migration-environment-20-3f958b6b-45a0-4850-947f-c15c50a7a093 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1669s nova [W] Timed out waiting for ff6c7199-f309-4858-8d15-f5dcc8e47a6d to get deleted. 1669s nova [W] Using flock in prodstack6-s390x 1669s Creating nova instance adt-plucky-s390x-openssh-20250222-062410-juju-7f2275-prod-proposed-migration-environment-20-3f958b6b-45a0-4850-947f-c15c50a7a093 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1669s nova [W] Timed out waiting for 44f6cd60-eac8-41ae-89d9-93aa849fb535 to get deleted. 1669s nova [W] Using flock in prodstack6-s390x 1669s Creating nova instance adt-plucky-s390x-openssh-20250222-062410-juju-7f2275-prod-proposed-migration-environment-20-3f958b6b-45a0-4850-947f-c15c50a7a093 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1669s nova [W] Timed out waiting for 70cf8451-e465-4618-b847-3d200d5418c7 to get deleted.