0s autopkgtest [05:11:05]: starting date and time: 2025-02-22 05:11:05+0000 0s autopkgtest [05:11:05]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [05:11:05]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.xjzp2rum/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glib2.0 --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glib2.0/2.83.4-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-22.secgroup --name adt-plucky-s390x-openssh-20250222-051105-juju-7f2275-prod-proposed-migration-environment-2-222e7a35-cd36-4a48-8597-c7a428885a04 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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/ 131s autopkgtest [05:13:16]: testbed dpkg architecture: s390x 131s autopkgtest [05:13:16]: testbed apt version: 2.9.30ubuntu1 132s autopkgtest [05:13:17]: @@@@@@@@@@@@@@@@@@@@ test bed setup 132s autopkgtest [05:13:17]: testbed release detected to be: None 133s autopkgtest [05:13:18]: updating testbed package index (apt update) 133s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 133s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 133s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 134s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 134s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.1 kB] 134s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 134s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 134s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [508 kB] 134s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [123 kB] 134s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 134s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [423 kB] 134s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 134s Fetched 1265 kB in 1s (1290 kB/s) 135s Reading package lists... 135s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s Calculating upgrade... 136s Calculating upgrade... 136s The following packages were automatically installed and are no longer required: 136s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 136s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 136s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 136s linux-tools-6.11.0-8-generic 136s Use 'sudo apt autoremove' to remove them. 136s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 136s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s Solving dependencies... 136s The following packages will be REMOVED: 136s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 136s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 136s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 136s linux-tools-6.11.0-8-generic* 136s 0 upgraded, 0 newly installed, 9 to remove and 3 not upgraded. 136s After this operation, 167 MB disk space will be freed. 137s (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.) 137s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 137s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 137s Removing libpython3.12t64:s390x (3.12.9-1) ... 137s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 137s Removing libnsl2:s390x (1.3.0-3build3) ... 137s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 137s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 137s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 138s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 138s Processing triggers for libc-bin (2.40-4ubuntu1) ... 138s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 55930 files and directories currently installed.) 138s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 138s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 138s autopkgtest [05:13:23]: upgrading testbed (apt dist-upgrade and autopurge) 138s Reading package lists... 138s Building dependency tree... 138s Reading state information... 138s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 138s Starting 2 pkgProblemResolver with broken count: 0 138s Done 139s Entering ResolveByKeep 139s 139s Calculating upgrade... 139s The following packages will be upgraded: 139s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 139s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 139s Need to get 1819 kB of archives. 139s After this operation, 1024 B disk space will be freed. 139s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x gir1.2-glib-2.0 s390x 2.83.4-1 [182 kB] 139s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-0t64 s390x 2.83.4-1 [1584 kB] 140s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-data all 2.83.4-1 [52.9 kB] 140s Fetched 1819 kB in 1s (2895 kB/s) 140s (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.) 140s Preparing to unpack .../gir1.2-glib-2.0_2.83.4-1_s390x.deb ... 140s Unpacking gir1.2-glib-2.0:s390x (2.83.4-1) over (2.83.3-2) ... 140s Preparing to unpack .../libglib2.0-0t64_2.83.4-1_s390x.deb ... 140s Unpacking libglib2.0-0t64:s390x (2.83.4-1) over (2.83.3-2) ... 140s Preparing to unpack .../libglib2.0-data_2.83.4-1_all.deb ... 140s Unpacking libglib2.0-data (2.83.4-1) over (2.83.3-2) ... 140s Setting up libglib2.0-0t64:s390x (2.83.4-1) ... 140s No schema files found: doing nothing. 140s Setting up libglib2.0-data (2.83.4-1) ... 140s Setting up gir1.2-glib-2.0:s390x (2.83.4-1) ... 140s Processing triggers for libc-bin (2.40-4ubuntu1) ... 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s Starting pkgProblemResolver with broken count: 0 140s Starting 2 pkgProblemResolver with broken count: 0 141s Done 141s Solving dependencies... 141s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s autopkgtest [05:13:26]: rebooting testbed after setup commands that affected boot 160s autopkgtest [05:13:45]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb 4 15:05:57 UTC 2025 166s autopkgtest [05:13:51]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 172s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (dsc) [3480 B] 172s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (tar) [1965 kB] 172s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (asc) [833 B] 172s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (diff) [210 kB] 172s gpgv: Signature made Thu Nov 21 14:24:07 2024 UTC 172s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 172s gpgv: Can't check signature: No public key 172s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu2.dsc: no acceptable signature found 172s autopkgtest [05:13:57]: testing package openssh version 1:9.9p1-3ubuntu2 176s autopkgtest [05:14:01]: build not needed 181s autopkgtest [05:14:06]: test regress: preparing testbed 182s Reading package lists... 182s Building dependency tree... 182s Reading state information... 182s Starting pkgProblemResolver with broken count: 0 182s Starting 2 pkgProblemResolver with broken count: 0 182s Done 182s The following NEW packages will be installed: 182s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 182s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 182s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 182s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 182s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 182s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 182s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 182s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 182s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 182s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 182s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 182s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 182s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 182s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 182s python3-packaging python3-pyasn1 python3-pyasn1-modules 182s python3-service-identity python3-twisted python3-zope.interface wdiff 182s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 182s Need to get 8350 kB of archives. 182s After this operation, 38.2 MB of additional disk space will be used. 182s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 182s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 183s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear-bin s390x 2024.86-2 [151 kB] 183s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear all 2024.86-2 [8664 B] 183s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 183s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 183s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 183s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-touch-perl all 0.12-2 [7498 B] 183s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 183s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 183s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 183s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-xsaccessor-perl s390x 1.19-4build6 [34.2 kB] 183s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 183s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libdynaloader-functions-perl all 0.004-1 [11.4 kB] 183s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libdevel-callchecker-perl s390x 0.009-1build1 [14.2 kB] 183s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 183s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libmodule-runtime-perl all 0.016-2 [16.4 kB] 183s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 183s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 183s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 183s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 183s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 183s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 183s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 183s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 183s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 183s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x liburi-perl all 5.30-1 [94.4 kB] 183s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 183s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 183s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 183s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libio-html-perl all 1.004-3 [15.9 kB] 183s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 183s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 183s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 183s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 183s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x perl-openssl-defaults s390x 7build3 [6628 B] 183s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-ssleay-perl s390x 1.94-3 [320 kB] 183s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x libio-socket-ssl-perl all 2.089-1 [200 kB] 183s Get:39 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-http-perl all 6.23-1 [22.3 kB] 183s Get:40 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 183s Get:41 http://ftpmaster.internal/ubuntu plucky/main s390x libtry-tiny-perl all 0.32-1 [21.2 kB] 183s Get:42 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-robotrules-perl all 6.02-1 [12.6 kB] 183s Get:43 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-perl all 6.77-1 [138 kB] 183s Get:44 http://ftpmaster.internal/ubuntu plucky/main s390x patchutils s390x 0.4.2-1build3 [79.2 kB] 183s Get:45 http://ftpmaster.internal/ubuntu plucky/main s390x wdiff s390x 1.2.2-7 [29.8 kB] 183s Get:46 http://ftpmaster.internal/ubuntu plucky/main s390x devscripts all 2.25.2 [1058 kB] 183s Get:47 http://ftpmaster.internal/ubuntu plucky/universe s390x libhavege2 s390x 1.9.19-9ubuntu1 [27.2 kB] 183s Get:48 http://ftpmaster.internal/ubuntu plucky/universe s390x putty-tools s390x 0.83-2 [741 kB] 183s Get:49 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hamcrest all 2.1.0-1 [28.1 kB] 183s Get:50 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1 all 0.6.0-1 [57.8 kB] 183s Get:51 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1-modules all 0.4.1-1 [80.2 kB] 183s Get:52 http://ftpmaster.internal/ubuntu plucky/main s390x python3-service-identity all 24.1.0-1 [11.2 kB] 183s Get:53 http://ftpmaster.internal/ubuntu plucky/main s390x python3-automat all 24.8.1-1 [35.5 kB] 183s Get:54 http://ftpmaster.internal/ubuntu plucky/main s390x python3-constantly all 23.10.4-2 [13.9 kB] 183s Get:55 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hyperlink all 21.0.0-6 [68.0 kB] 183s Get:56 http://ftpmaster.internal/ubuntu plucky/main s390x python3-packaging all 24.2-1 [51.5 kB] 183s Get:57 http://ftpmaster.internal/ubuntu plucky/main s390x python3-incremental all 24.7.2-3 [18.6 kB] 183s Get:58 http://ftpmaster.internal/ubuntu plucky/main s390x python3-zope.interface s390x 7.2-1 [140 kB] 183s Get:59 http://ftpmaster.internal/ubuntu plucky/main s390x python3-twisted all 24.11.0-1 [2066 kB] 183s Get:60 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-tests s390x 1:9.9p1-3ubuntu2 [1409 kB] 184s Get:61 http://ftpmaster.internal/ubuntu plucky/universe s390x haveged s390x 1.9.19-9ubuntu1 [32.7 kB] 184s Fetched 8350 kB in 1s (6171 kB/s) 184s Selecting previously unselected package libtommath1:s390x. 184s (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.) 184s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 184s Unpacking libtommath1:s390x (1.3.0-1) ... 184s Selecting previously unselected package libtomcrypt1:s390x. 184s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 184s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 184s Selecting previously unselected package dropbear-bin. 184s Preparing to unpack .../02-dropbear-bin_2024.86-2_s390x.deb ... 184s Unpacking dropbear-bin (2024.86-2) ... 184s Selecting previously unselected package dropbear. 184s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 184s Unpacking dropbear (2024.86-2) ... 184s Selecting previously unselected package libfile-dirlist-perl. 184s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 184s Unpacking libfile-dirlist-perl (0.05-3) ... 184s Selecting previously unselected package libfile-which-perl. 184s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 184s Unpacking libfile-which-perl (1.27-2) ... 184s Selecting previously unselected package libfile-homedir-perl. 184s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 184s Unpacking libfile-homedir-perl (1.006-2) ... 184s Selecting previously unselected package libfile-touch-perl. 184s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 184s Unpacking libfile-touch-perl (0.12-2) ... 184s Selecting previously unselected package libio-pty-perl. 184s Preparing to unpack .../08-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 184s Unpacking libio-pty-perl (1:1.20-1build3) ... 184s Selecting previously unselected package libipc-run-perl. 184s Preparing to unpack .../09-libipc-run-perl_20231003.0-2_all.deb ... 184s Unpacking libipc-run-perl (20231003.0-2) ... 184s Selecting previously unselected package libclass-method-modifiers-perl. 184s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 184s Unpacking libclass-method-modifiers-perl (2.15-1) ... 184s Selecting previously unselected package libclass-xsaccessor-perl. 184s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_s390x.deb ... 184s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 184s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 184s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 184s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 184s Selecting previously unselected package libdynaloader-functions-perl. 184s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-1_all.deb ... 184s Unpacking libdynaloader-functions-perl (0.004-1) ... 184s Selecting previously unselected package libdevel-callchecker-perl:s390x. 184s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-1build1_s390x.deb ... 184s Unpacking libdevel-callchecker-perl:s390x (0.009-1build1) ... 184s Selecting previously unselected package libparams-classify-perl:s390x. 184s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_s390x.deb ... 184s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 184s Selecting previously unselected package libmodule-runtime-perl. 184s Preparing to unpack .../16-libmodule-runtime-perl_0.016-2_all.deb ... 184s Unpacking libmodule-runtime-perl (0.016-2) ... 184s Selecting previously unselected package libimport-into-perl. 184s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 184s Unpacking libimport-into-perl (1.002005-2) ... 184s Selecting previously unselected package librole-tiny-perl. 184s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 184s Unpacking librole-tiny-perl (2.002004-1) ... 184s Selecting previously unselected package libsub-quote-perl. 184s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 184s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 184s Selecting previously unselected package libmoo-perl. 184s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 184s Unpacking libmoo-perl (2.005005-1) ... 184s Selecting previously unselected package libencode-locale-perl. 184s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 184s Unpacking libencode-locale-perl (1.05-3) ... 184s Selecting previously unselected package libtimedate-perl. 184s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 184s Unpacking libtimedate-perl (2.3300-2) ... 184s Selecting previously unselected package libhttp-date-perl. 184s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 184s Unpacking libhttp-date-perl (6.06-1) ... 184s Selecting previously unselected package libfile-listing-perl. 184s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 184s Unpacking libfile-listing-perl (6.16-1) ... 184s Selecting previously unselected package libhtml-tagset-perl. 184s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 184s Unpacking libhtml-tagset-perl (3.24-1) ... 184s Selecting previously unselected package liburi-perl. 184s Preparing to unpack .../26-liburi-perl_5.30-1_all.deb ... 184s Unpacking liburi-perl (5.30-1) ... 184s Selecting previously unselected package libhtml-parser-perl:s390x. 184s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_s390x.deb ... 184s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 184s Selecting previously unselected package libhtml-tree-perl. 184s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 184s Unpacking libhtml-tree-perl (5.07-3) ... 184s Selecting previously unselected package libclone-perl:s390x. 184s Preparing to unpack .../29-libclone-perl_0.47-1_s390x.deb ... 184s Unpacking libclone-perl:s390x (0.47-1) ... 184s Selecting previously unselected package libio-html-perl. 184s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 184s Unpacking libio-html-perl (1.004-3) ... 184s Selecting previously unselected package liblwp-mediatypes-perl. 184s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 184s Unpacking liblwp-mediatypes-perl (6.04-2) ... 184s Selecting previously unselected package libhttp-message-perl. 184s Preparing to unpack .../32-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 184s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 184s Selecting previously unselected package libhttp-cookies-perl. 184s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 184s Unpacking libhttp-cookies-perl (6.11-1) ... 184s Selecting previously unselected package libhttp-negotiate-perl. 184s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 184s Unpacking libhttp-negotiate-perl (6.01-2) ... 184s Selecting previously unselected package perl-openssl-defaults:s390x. 184s Preparing to unpack .../35-perl-openssl-defaults_7build3_s390x.deb ... 184s Unpacking perl-openssl-defaults:s390x (7build3) ... 184s Selecting previously unselected package libnet-ssleay-perl:s390x. 184s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_s390x.deb ... 184s Unpacking libnet-ssleay-perl:s390x (1.94-3) ... 184s Selecting previously unselected package libio-socket-ssl-perl. 184s Preparing to unpack .../37-libio-socket-ssl-perl_2.089-1_all.deb ... 184s Unpacking libio-socket-ssl-perl (2.089-1) ... 184s Selecting previously unselected package libnet-http-perl. 184s Preparing to unpack .../38-libnet-http-perl_6.23-1_all.deb ... 184s Unpacking libnet-http-perl (6.23-1) ... 184s Selecting previously unselected package liblwp-protocol-https-perl. 184s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 184s Unpacking liblwp-protocol-https-perl (6.14-1) ... 184s Selecting previously unselected package libtry-tiny-perl. 184s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 184s Unpacking libtry-tiny-perl (0.32-1) ... 184s Selecting previously unselected package libwww-robotrules-perl. 184s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1_all.deb ... 184s Unpacking libwww-robotrules-perl (6.02-1) ... 184s Selecting previously unselected package libwww-perl. 184s Preparing to unpack .../42-libwww-perl_6.77-1_all.deb ... 184s Unpacking libwww-perl (6.77-1) ... 184s Selecting previously unselected package patchutils. 184s Preparing to unpack .../43-patchutils_0.4.2-1build3_s390x.deb ... 184s Unpacking patchutils (0.4.2-1build3) ... 185s Selecting previously unselected package wdiff. 185s Preparing to unpack .../44-wdiff_1.2.2-7_s390x.deb ... 185s Unpacking wdiff (1.2.2-7) ... 185s Selecting previously unselected package devscripts. 185s Preparing to unpack .../45-devscripts_2.25.2_all.deb ... 185s Unpacking devscripts (2.25.2) ... 185s Selecting previously unselected package libhavege2:s390x. 185s Preparing to unpack .../46-libhavege2_1.9.19-9ubuntu1_s390x.deb ... 185s Unpacking libhavege2:s390x (1.9.19-9ubuntu1) ... 185s Selecting previously unselected package putty-tools. 185s Preparing to unpack .../47-putty-tools_0.83-2_s390x.deb ... 185s Unpacking putty-tools (0.83-2) ... 185s Selecting previously unselected package python3-hamcrest. 185s Preparing to unpack .../48-python3-hamcrest_2.1.0-1_all.deb ... 185s Unpacking python3-hamcrest (2.1.0-1) ... 185s Selecting previously unselected package python3-pyasn1. 185s Preparing to unpack .../49-python3-pyasn1_0.6.0-1_all.deb ... 185s Unpacking python3-pyasn1 (0.6.0-1) ... 185s Selecting previously unselected package python3-pyasn1-modules. 185s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-1_all.deb ... 185s Unpacking python3-pyasn1-modules (0.4.1-1) ... 185s Selecting previously unselected package python3-service-identity. 185s Preparing to unpack .../51-python3-service-identity_24.1.0-1_all.deb ... 185s Unpacking python3-service-identity (24.1.0-1) ... 185s Selecting previously unselected package python3-automat. 185s Preparing to unpack .../52-python3-automat_24.8.1-1_all.deb ... 185s Unpacking python3-automat (24.8.1-1) ... 185s Selecting previously unselected package python3-constantly. 185s Preparing to unpack .../53-python3-constantly_23.10.4-2_all.deb ... 185s Unpacking python3-constantly (23.10.4-2) ... 185s Selecting previously unselected package python3-hyperlink. 185s Preparing to unpack .../54-python3-hyperlink_21.0.0-6_all.deb ... 185s Unpacking python3-hyperlink (21.0.0-6) ... 185s Selecting previously unselected package python3-packaging. 185s Preparing to unpack .../55-python3-packaging_24.2-1_all.deb ... 185s Unpacking python3-packaging (24.2-1) ... 185s Selecting previously unselected package python3-incremental. 185s Preparing to unpack .../56-python3-incremental_24.7.2-3_all.deb ... 185s Unpacking python3-incremental (24.7.2-3) ... 185s Selecting previously unselected package python3-zope.interface. 185s Preparing to unpack .../57-python3-zope.interface_7.2-1_s390x.deb ... 185s Unpacking python3-zope.interface (7.2-1) ... 185s Selecting previously unselected package python3-twisted. 185s Preparing to unpack .../58-python3-twisted_24.11.0-1_all.deb ... 185s Unpacking python3-twisted (24.11.0-1) ... 185s Selecting previously unselected package openssh-tests. 185s Preparing to unpack .../59-openssh-tests_1%3a9.9p1-3ubuntu2_s390x.deb ... 185s Unpacking openssh-tests (1:9.9p1-3ubuntu2) ... 185s Selecting previously unselected package haveged. 185s Preparing to unpack .../60-haveged_1.9.19-9ubuntu1_s390x.deb ... 185s Unpacking haveged (1.9.19-9ubuntu1) ... 185s Setting up wdiff (1.2.2-7) ... 185s Setting up libfile-which-perl (1.27-2) ... 185s Setting up libdynaloader-functions-perl (0.004-1) ... 185s Setting up libclass-method-modifiers-perl (2.15-1) ... 185s Setting up libio-pty-perl (1:1.20-1build3) ... 185s Setting up python3-zope.interface (7.2-1) ... 185s Setting up libclone-perl:s390x (0.47-1) ... 185s Setting up libtommath1:s390x (1.3.0-1) ... 185s Setting up libhtml-tagset-perl (3.24-1) ... 185s Setting up python3-automat (24.8.1-1) ... 185s Setting up liblwp-mediatypes-perl (6.04-2) ... 185s Setting up libtry-tiny-perl (0.32-1) ... 185s Setting up perl-openssl-defaults:s390x (7build3) ... 185s Setting up libencode-locale-perl (1.05-3) ... 185s Setting up python3-hamcrest (2.1.0-1) ... 185s Setting up putty-tools (0.83-2) ... 185s Setting up libhavege2:s390x (1.9.19-9ubuntu1) ... 185s Setting up patchutils (0.4.2-1build3) ... 185s Setting up python3-packaging (24.2-1) ... 185s Setting up python3-incremental (24.7.2-3) ... 185s Setting up python3-hyperlink (21.0.0-6) ... 186s Setting up libio-html-perl (1.004-3) ... 186s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 186s Setting up libipc-run-perl (20231003.0-2) ... 186s Setting up libtimedate-perl (2.3300-2) ... 186s Setting up librole-tiny-perl (2.002004-1) ... 186s Setting up python3-pyasn1 (0.6.0-1) ... 186s Setting up python3-constantly (23.10.4-2) ... 186s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 186s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 186s Setting up libfile-dirlist-perl (0.05-3) ... 186s Setting up libfile-homedir-perl (1.006-2) ... 186s Setting up liburi-perl (5.30-1) ... 186s Setting up libfile-touch-perl (0.12-2) ... 186s Setting up libnet-ssleay-perl:s390x (1.94-3) ... 186s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 186s Setting up libhttp-date-perl (6.06-1) ... 186s Setting up haveged (1.9.19-9ubuntu1) ... 186s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 186s Setting up dropbear-bin (2024.86-2) ... 186s Setting up libfile-listing-perl (6.16-1) ... 186s Setting up libnet-http-perl (6.23-1) ... 186s Setting up libdevel-callchecker-perl:s390x (0.009-1build1) ... 186s Setting up dropbear (2024.86-2) ... 186s Converting existing OpenSSH RSA host key to Dropbear format. 186s Key is a ssh-rsa key 186s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 186s 3072 SHA256:TQ1l+MAWcI+6wqJ2EsR65FJew77nrIu6HwLGrjx8oog /etc/dropbear/dropbear_rsa_host_key (RSA) 186s +---[RSA 3072]----+ 186s | .o++o | 186s | .=* | 186s | . . .ooo | 186s |. = + + . | 186s |.X o . S . | 186s |* = .. . | 186s |o+....o . | 186s |==o=oo.. | 186s |E*Boo=o | 186s +----[SHA256]-----+ 186s Converting existing OpenSSH ECDSA host key to Dropbear format. 186s Key is a ecdsa-sha2-nistp256 key 186s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 186s 256 SHA256:sk+2GKZUozvrV+84bzBtUOtMQFCv1KcxsxZn6Jsa0GU /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 186s +---[ECDSA 256]---+ 186s | .+o | 186s | .o.. | 186s | .oE.+ | 186s | o.=o@ | 186s | = S*= | 186s | o =+.=o | 186s | o +.+=o | 186s | ..+.*.=o | 186s | .=+. ==o | 186s +----[SHA256]-----+ 186s Converting existing OpenSSH ED25519 host key to Dropbear format. 186s Key is a ssh-ed25519 key 186s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 186s 256 SHA256:zbDH+HlbXTKcQsLmN63cNo89EsLhuM/Fw8jBHG08ank /etc/dropbear/dropbear_ed25519_host_key (ED25519) 186s +--[ED25519 256]--+ 186s | | 186s | . o | 186s | . +..= | 186s | Xo+=o.. | 186s | S XB=E* .| 186s | +oB** +.| 186s | +o==* .| 186s | ....=.* | 186s | .o. o +| 186s +----[SHA256]-----+ 186s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 187s Setting up python3-pyasn1-modules (0.4.1-1) ... 187s Setting up python3-service-identity (24.1.0-1) ... 187s Setting up libwww-robotrules-perl (6.02-1) ... 187s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 187s Setting up libio-socket-ssl-perl (2.089-1) ... 187s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 187s Setting up libhttp-negotiate-perl (6.01-2) ... 187s Setting up libhttp-cookies-perl (6.11-1) ... 187s Setting up libhtml-tree-perl (5.07-3) ... 187s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 187s Setting up libmodule-runtime-perl (0.016-2) ... 187s Setting up python3-twisted (24.11.0-1) ... 189s Setting up libimport-into-perl (1.002005-2) ... 189s Setting up libmoo-perl (2.005005-1) ... 189s Setting up openssh-tests (1:9.9p1-3ubuntu2) ... 189s Setting up liblwp-protocol-https-perl (6.14-1) ... 189s Setting up libwww-perl (6.77-1) ... 189s Setting up devscripts (2.25.2) ... 189s Processing triggers for install-info (7.1.1-1) ... 189s Processing triggers for libc-bin (2.40-4ubuntu1) ... 189s Processing triggers for man-db (2.13.0-1) ... 191s autopkgtest [05:14:16]: test regress: [----------------------- 192s info: Adding user `openssh-tests' ... 192s info: Selecting UID/GID from range 1000 to 59999 ... 192s info: Adding new group `openssh-tests' (1001) ... 192s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 192s info: Creating home directory `/home/openssh-tests' ... 192s info: Copying files from `/etc/skel' ... 192s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 192s info: Adding user `openssh-tests' to group `users' ... 192s I: annotate-output 2.25.2 192s I: prefix='%H:%M:%S.%N ' 192s 05:14:17.268146828 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user 192s 05:14:17.286058809 O: make: Entering directory '/tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress' 192s 05:14:17.286915429 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/valgrind-out 192s 05:14:17.287521066 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 192s 05:14:17.287987943 E: + /usr/bin/ssh -Q key 192s 05:14:17.288466137 O: ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 192s 05:14:17.288579872 E: + grep -q ^ssh-rsa 192s 05:14:17.289068801 O: tr '\n' '\r' /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 192s 05:14:17.289680298 O: ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 192s 05:14:17.290375660 O: awk '{print $0 "\r"}' /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 192s 05:14:17.292671431 O: ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 192s 05:14:17.293328957 O: fi 192s 05:14:17.294188956 E: + + diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv 192s 05:14:17.294811252 E: ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2.prv 192s 05:14:17.300115481 E: + tr \n \r 192s 05:14:17.300683356 E: + ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 192s 05:14:17.301169019 E: + diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv 192s 05:14:17.303917010 E: + awk {print $0 "\r"} /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2.prv 192s 05:14:17.305606339 E: + ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 192s 05:14:17.306133242 E: + diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv 192s 05:14:17.310297594 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 192s 05:14:17.310889405 O: cat /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t2.out ; \ 192s 05:14:17.311116493 E: + /usr/bin/ssh -Q key 192s 05:14:17.312055061 O: chmod 600 /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t2.out ; \ 192s 05:14:17.312570401 E: + grep -q ^ssh-rsa 192s 05:14:17.313076358 O: ssh-keygen -yf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 192s 05:14:17.313687180 O: fi 192s 05:14:17.314548287 E: + cat /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.prv 192s 05:14:17.315162362 E: + chmod 600 /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t2.out 192s 05:14:17.315973828 E: + ssh-keygen -yf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t2.out 192s 05:14:17.317126044 E: + diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub 192s 05:14:17.319656839 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 192s 05:14:17.320179239 O: ssh-keygen -ef /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t3.out ; \ 192s 05:14:17.320416873 E: + /usr/bin/ssh -Q key 192s 05:14:17.321472845 E: + grep -q ^ssh-rsa 192s 05:14:17.320858973 O: ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 192s 05:14:17.322042113 O: fi 192s 05:14:17.323116491 E: + ssh-keygen -ef /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub 192s 05:14:17.327969242 E: + ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t3.out 192s 05:14:17.328468435 E: + diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub 192s 05:14:17.328514203 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 192s 05:14:17.329141137 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 192s 05:14:17.329739367 E: + grep -q ^ssh-rsa 192s 05:14:17.329753816 O: awk '{print $2}' | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t4.ok ; \ 192s 05:14:17.330473172 O: fi 192s 05:14:17.331020046 E: + /usr/bin/ssh -Q key 192s 05:14:17.337104421 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub 192s 05:14:17.338059433 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 192s 05:14:17.338122768 E: + diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t4.ok 192s 05:14:17.339069375 O: ssh-keygen -Bf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 192s 05:14:17.339545706 O: awk '{print $2}' | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t5.ok ; \ 192s 05:14:17.340113665 O: fi 192s 05:14:17.339654453 E: + awk {print $2} 192s 05:14:17.340716244 E: + grep -q ^ssh-rsa 192s 05:14:17.341267880 E: + /usr/bin/ssh -Q key 192s 05:14:17.343140582 E: + ssh-keygen -Bf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub 192s 05:14:17.343889206 E: + awk {print $2} 192s 05:14:17.344421642 E: + diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t5.ok 192s 05:14:17.347536258 E: + grep -q ^ssh-dss 192s 05:14:17.348056685 E: + /usr/bin/ssh -Q key 192s 05:14:17.350158805 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 192s 05:14:17.350747492 O: ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t6.out1 ; \ 192s 05:14:17.351233546 O: ssh-keygen -if /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t6.out2 ; \ 192s 05:14:17.351921498 O: chmod 600 /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t6.out1 ; \ 192s 05:14:17.352461815 O: ssh-keygen -yf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t6.out2 ; \ 192s 05:14:17.352997313 O: fi 192s 05:14:17.353478269 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 192s 05:14:17.353953798 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t7.out ; \ 192s 05:14:17.354415855 O: fi 192s 05:14:17.354945155 E: + /usr/bin/ssh -Q key 192s 05:14:17.355637668 E: + grep -q ^ssh-dss 192s 05:14:17.356801046 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 192s 05:14:17.357314467 O: ssh-keygen -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 192s 05:14:17.357811575 O: ssh-keygen -Bf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 192s 05:14:17.358272248 O: fi 192s 05:14:17.358761700 E: + /usr/bin/ssh -Q key 192s 05:14:17.359231893 E: + grep -q ^ssh-dss 192s 05:14:17.361721844 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 192s 05:14:17.362189922 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t8.out ; \ 192s 05:14:17.362635697 O: fi 192s 05:14:17.363118871 E: + ssh -Q key 192s 05:14:17.363674103 E: + grep -q ^ssh-dss 192s 05:14:17.366457576 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 192s 05:14:17.366949129 O: ssh-keygen -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 192s 05:14:17.368108301 E: + ssh -Q key 192s 05:14:17.367711914 O: ssh-keygen -Bf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 192s 05:14:17.368745233 O: fi 192s 05:14:17.368790689 E: + grep -q ^ssh-dss 192s 05:14:17.371955803 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 192s 05:14:17.372886360 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t9.out 192s 05:14:17.378901207 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 192s 05:14:17.379397918 O: ssh-keygen -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t9.out > /dev/null 192s 05:14:17.389075283 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 192s 05:14:17.389567914 O: ssh-keygen -Bf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t9.out > /dev/null 192s 05:14:17.396704035 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t10.out 192s 05:14:17.400589368 O: ssh-keygen -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t10.out > /dev/null 192s 05:14:17.403462742 O: ssh-keygen -Bf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t10.out > /dev/null 192s 05:14:17.406470787 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 192s 05:14:17.406956542 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 192s 05:14:17.407483358 O: awk '{print $2}' | diff - /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t11.ok ; \ 192s 05:14:17.407935850 E: + /usr/bin/ssh -Q key 192s 05:14:17.408408373 O: fi 192s 05:14:17.409449498 E: + grep -q ^ssh-dss 192s 05:14:17.410138941 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t12.out 192s 05:14:17.413348320 O: ssh-keygen -lf /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 192s 05:14:17.417581303 E: run test connect.sh ... 194s 05:14:19.458308128 E: run test proxy-connect.sh ... 194s 05:14:19.458405570 O: ok simple connect 194s 05:14:19.595322964 O: plain username comp=no 194s 05:14:19.902692994 O: plain username comp=yes 195s 05:14:20.207329617 O: username with style 195s 05:14:20.502069951 E: run test sshfp-connect.sh ... 195s 05:14:20.502669206 O: ok proxy connect 195s 05:14:20.635960167 E: run test connect-privsep.sh ... 195s 05:14:20.636285560 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 199s 05:14:24.657914715 O: ok proxy connect with privsep 199s 05:14:24.658423408 E: run test connect-uri.sh ... 199s 05:14:24.935644096 O: uri connect: no trailing slash 200s 05:14:25.275430168 O: uri connect: trailing slash 200s 05:14:25.614932594 O: uri connect: with path name 200s 05:14:25.633128676 O: ok uri connect 200s 05:14:25.633231283 E: run test proto-version.sh ... 200s 05:14:25.890779152 O: ok sshd version with different protocol combinations 200s 05:14:25.891300638 E: run test proto-mismatch.sh ... 201s 05:14:26.149518185 O: ok protocol version mismatch 201s 05:14:26.149984631 E: run test exit-status.sh ... 201s 05:14:26.291773851 O: test remote exit status: status 0 206s 05:14:31.901947342 O: test remote exit status: status 1 212s 05:14:37.526324439 O: test remote exit status: status 4 218s 05:14:43.145250287 O: test remote exit status: status 5 223s 05:14:48.762351210 O: test remote exit status: status 44 229s 05:14:54.356430427 O: ok remote exit status 229s 05:14:54.357083657 E: run test exit-status-signal.sh ... 230s 05:14:55.489879452 O: ok exit status on signal 230s 05:14:55.490526562 E: run test envpass.sh ... 230s 05:14:55.627391326 O: test environment passing: pass env, don't accept 230s 05:14:55.925571975 O: test environment passing: setenv, don't accept 231s 05:14:56.218368440 O: test environment passing: don't pass env, accept 231s 05:14:56.509487023 O: test environment passing: pass single env, accept single env 231s 05:14:56.808599779 O: test environment passing: pass multiple env, accept multiple env 232s 05:14:57.103723477 O: test environment passing: setenv, accept 232s 05:14:57.405035830 O: test environment passing: setenv, first match wins 232s 05:14:57.717986716 O: test environment passing: server setenv wins 233s 05:14:58.014538535 O: test environment passing: server setenv wins 233s 05:14:58.315268716 O: ok environment passing 233s 05:14:58.315972837 E: run test transfer.sh ... 235s 05:15:00.891309177 O: ok transfer data 235s 05:15:00.891732488 E: run test banner.sh ... 236s 05:15:01.028922327 O: test banner: missing banner file 236s 05:15:01.350212849 O: test banner: size 0 236s 05:15:01.663184841 O: test banner: size 10 237s 05:15:01.981004762 O: test banner: size 100 237s 05:15:02.318062948 O: test banner: size 1000 237s 05:15:02.633819786 O: test banner: size 10000 237s 05:15:02.943452668 O: test banner: size 100000 238s 05:15:03.249109219 O: test banner: suppress banner (-q) 238s 05:15:03.548920915 O: ok banner 238s 05:15:03.548829794 E: run test rekey.sh ... 238s 05:15:03.696456886 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 238s 05:15:03.937353695 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 239s 05:15:04.213917528 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 239s 05:15:04.488325953 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 239s 05:15:04.875605716 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 240s 05:15:05.650487538 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 241s 05:15:06.460475561 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 242s 05:15:07.260494980 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 242s 05:15:07.499446193 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 242s 05:15:07.761457389 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 243s 05:15:08.045048211 O: client rekey KexAlgorithms=curve25519-sha256 243s 05:15:08.318548800 O: client rekey KexAlgorithms=sntrup761x25519-sha512 243s 05:15:08.799078172 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 244s 05:15:09.279992123 O: client rekey KexAlgorithms=mlkem768x25519-sha256 244s 05:15:09.560025845 O: client rekey Ciphers=3des-cbc 244s 05:15:09.840271731 O: client rekey Ciphers=aes128-cbc 245s 05:15:10.111476127 O: client rekey Ciphers=aes192-cbc 245s 05:15:10.392566683 O: client rekey Ciphers=aes256-cbc 245s 05:15:10.667240151 O: client rekey Ciphers=aes128-ctr 245s 05:15:10.946682962 O: client rekey Ciphers=aes192-ctr 246s 05:15:11.235667650 O: client rekey Ciphers=aes256-ctr 246s 05:15:11.517561182 O: client rekey Ciphers=aes128-gcm@openssh.com 251s 05:15:15.994891757 O: client rekey Ciphers=aes256-gcm@openssh.com 255s 05:15:20.903305473 O: client rekey Ciphers=chacha20-poly1305@openssh.com 261s 05:15:26.819206430 O: client rekey MACs=hmac-sha1 262s 05:15:27.108528950 O: client rekey MACs=hmac-sha1-96 262s 05:15:27.382475411 O: client rekey MACs=hmac-sha2-256 262s 05:15:27.678023051 O: client rekey MACs=hmac-sha2-512 263s 05:15:27.972228190 O: client rekey MACs=hmac-md5 263s 05:15:28.243681092 O: client rekey MACs=hmac-md5-96 263s 05:15:28.510440321 O: client rekey MACs=umac-64@openssh.com 263s 05:15:28.794226228 O: client rekey MACs=umac-128@openssh.com 264s 05:15:29.068710022 O: client rekey MACs=hmac-sha1-etm@openssh.com 264s 05:15:29.348213027 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 264s 05:15:29.623742465 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 264s 05:15:29.895590436 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 265s 05:15:30.170695489 O: client rekey MACs=hmac-md5-etm@openssh.com 265s 05:15:30.451985694 O: client rekey MACs=hmac-md5-96-etm@openssh.com 265s 05:15:30.759821079 O: client rekey MACs=umac-64-etm@openssh.com 266s 05:15:31.042961066 O: client rekey MACs=umac-128-etm@openssh.com 266s 05:15:31.320728728 O: client rekeylimit 16 266s 05:15:31.618127718 O: client rekeylimit 1k 266s 05:15:31.897115455 O: client rekeylimit 128k 267s 05:15:32.202802239 O: client rekeylimit 256k 267s 05:15:32.575538227 O: client rekeylimit default 5 282s 05:15:47.829471080 O: client rekeylimit default 10 303s 05:16:08.095833488 O: client rekeylimit default 5 no data 318s 05:16:23.331153294 O: client rekeylimit default 10 no data 338s 05:16:43.559341930 O: server rekeylimit 16 338s 05:16:43.847847389 O: server rekeylimit 1k 339s 05:16:44.123824017 O: server rekeylimit 128k 339s 05:16:44.414100992 O: server rekeylimit 256k 339s 05:16:44.771389639 O: server rekeylimit default 5 no data 355s 05:17:00.014588155 O: server rekeylimit default 10 no data 375s 05:17:20.263907985 O: rekeylimit parsing: bytes 375s 05:17:20.313004191 O: rekeylimit parsing: time 375s 05:17:20.360595690 O: ok rekey 375s 05:17:20.361054259 E: run test dhgex.sh ... 375s 05:17:20.501842032 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 375s 05:17:20.757322394 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 376s 05:17:21.001940197 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 376s 05:17:21.246039045 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 376s 05:17:21.487625067 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 376s 05:17:21.729104375 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 376s 05:17:21.972756175 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 377s 05:17:22.220178071 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 377s 05:17:22.466982572 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 377s 05:17:22.785585400 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 378s 05:17:23.107430843 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 378s 05:17:23.426665207 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 378s 05:17:23.746681072 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 379s 05:17:24.057909053 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 379s 05:17:24.388057641 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 379s 05:17:24.704884760 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 380s 05:17:25.034610357 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 380s 05:17:25.347667934 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 380s 05:17:25.664053228 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 381s 05:17:26.073475924 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 381s 05:17:26.473703561 O: ok dhgex 381s 05:17:26.473709916 E: run test stderr-data.sh ... 381s 05:17:26.606375302 O: test stderr data transfer: () 388s 05:17:33.242296081 O: test stderr data transfer: (-n) 394s 05:17:39.861089503 O: ok stderr data transfer 394s 05:17:39.861548731 E: run test stderr-after-eof.sh ... 397s 05:17:42.295669048 O: ok stderr data after eof 397s 05:17:42.296372817 E: run test broken-pipe.sh ... 397s 05:17:42.442870367 O: ok broken pipe test 397s 05:17:42.443499614 E: run test try-ciphers.sh ... 397s 05:17:42.588163280 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 397s 05:17:42.890126377 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 398s 05:17:43.195978329 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 398s 05:17:43.503218408 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 398s 05:17:43.810811526 O: test try ciphers: cipher 3des-cbc mac hmac-md5 399s 05:17:44.115219607 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 399s 05:17:44.416157389 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 399s 05:17:44.726569150 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 400s 05:17:45.032620236 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 400s 05:17:45.336355735 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 400s 05:17:45.641861629 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 400s 05:17:45.948746191 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 401s 05:17:46.257119169 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 401s 05:17:46.556828799 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 401s 05:17:46.864162735 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 402s 05:17:47.162326406 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 402s 05:17:47.472466014 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 402s 05:17:47.778503379 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 403s 05:17:48.083853787 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 403s 05:17:48.392770989 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 403s 05:17:48.693985120 O: test try ciphers: cipher aes128-cbc mac hmac-md5 404s 05:17:48.996988957 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 404s 05:17:49.305987324 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 404s 05:17:49.610088513 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 404s 05:17:49.924914699 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 405s 05:17:50.225790266 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 405s 05:17:50.529880985 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 405s 05:17:50.833226337 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 406s 05:17:51.144165965 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 406s 05:17:51.447363303 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 406s 05:17:51.753529492 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 407s 05:17:52.061229543 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 407s 05:17:52.364120079 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 407s 05:17:52.674128417 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 408s 05:17:52.986809744 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 408s 05:17:53.289545543 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 408s 05:17:53.600163333 O: test try ciphers: cipher aes192-cbc mac hmac-md5 408s 05:17:53.898340692 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 409s 05:17:54.206606489 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 409s 05:17:54.512137750 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 409s 05:17:54.819521040 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 410s 05:17:55.134716331 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 410s 05:17:55.441623796 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 410s 05:17:55.749366465 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 411s 05:17:56.062121315 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 411s 05:17:56.364920151 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 411s 05:17:56.671968831 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 411s 05:17:56.972858310 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 412s 05:17:57.284127756 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 412s 05:17:57.593319765 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 412s 05:17:57.908108426 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 413s 05:17:58.217851844 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 413s 05:17:58.537378015 O: test try ciphers: cipher aes256-cbc mac hmac-md5 413s 05:17:58.859899210 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 414s 05:17:59.180136098 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 414s 05:17:59.507500315 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 414s 05:17:59.812325404 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 415s 05:18:00.115419055 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 415s 05:18:00.425438713 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 415s 05:18:00.736733622 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 416s 05:18:01.043538764 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 416s 05:18:01.346664707 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 416s 05:18:01.656231663 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 417s 05:18:01.987715525 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 417s 05:18:02.298940837 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 417s 05:18:02.601462213 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 417s 05:18:02.899926543 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 418s 05:18:03.203843326 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 418s 05:18:03.504604459 O: test try ciphers: cipher aes128-ctr mac hmac-md5 418s 05:18:03.810745880 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 419s 05:18:04.105543162 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 419s 05:18:04.407070224 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 419s 05:18:04.710911268 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 420s 05:18:05.014421025 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 420s 05:18:05.317482875 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 420s 05:18:05.630488683 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 420s 05:18:05.932230465 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 421s 05:18:06.235884288 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 421s 05:18:06.542164565 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 421s 05:18:06.850700839 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 422s 05:18:07.164594694 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 422s 05:18:07.481844235 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 422s 05:18:07.791313696 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 423s 05:18:08.105580433 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 423s 05:18:08.418841576 O: test try ciphers: cipher aes192-ctr mac hmac-md5 423s 05:18:08.719988433 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 424s 05:18:09.032591440 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 424s 05:18:09.342975787 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 424s 05:18:09.653320961 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 425s 05:18:09.981603304 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 425s 05:18:10.298697378 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 425s 05:18:10.610150287 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 425s 05:18:10.910612180 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 426s 05:18:11.209243542 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 426s 05:18:11.518615820 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 426s 05:18:11.830288581 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 427s 05:18:12.136037043 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 427s 05:18:12.448775852 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 427s 05:18:12.756870572 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 428s 05:18:13.063636878 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 428s 05:18:13.371188398 O: test try ciphers: cipher aes256-ctr mac hmac-md5 428s 05:18:13.683792628 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 429s 05:18:14.000326671 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 429s 05:18:14.313808983 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 429s 05:18:14.621714861 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 429s 05:18:14.928478821 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 430s 05:18:15.237585470 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 430s 05:18:15.559226903 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 430s 05:18:15.872480355 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 431s 05:18:16.181987589 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 431s 05:18:16.491363735 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 431s 05:18:16.808995738 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 432s 05:18:17.122180525 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 432s 05:18:17.444569131 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 432s 05:18:17.768400160 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 433s 05:18:18.071695744 E: run test yes-head.sh ... 433s 05:18:18.071167036 O: ok try ciphers 436s 05:18:21.204528674 O: ok yes pipe head 436s 05:18:21.205239273 E: run test login-timeout.sh ... 451s 05:18:36.859201066 O: ok connect after login grace timeout 451s 05:18:36.859964633 E: run test agent.sh ... 462s 05:18:47.277880478 O: ok simple agent test 462s 05:18:47.278595933 E: run test agent-getpeereid.sh ... 462s 05:18:47.445823277 O: ok disallow agent attach from other uid 462s 05:18:47.446364578 E: run test agent-timeout.sh ... 482s 05:19:07.671958607 O: ok agent timeout test 482s 05:19:07.672660782 E: run test agent-ptrace.sh ... 482s 05:19:07.801940633 O: skipped (gdb not found) 482s 05:19:07.802488459 E: run test agent-subprocess.sh ... 492s 05:19:17.945061823 O: ok agent subprocess 492s 05:19:17.945751717 E: run test keyscan.sh ... 495s 05:19:20.364509180 O: ok keyscan 495s 05:19:20.365193500 E: run test keygen-change.sh ... 499s 05:19:24.258139336 O: ok change passphrase for key 499s 05:19:24.258832571 E: run test keygen-comment.sh ... 501s 05:19:26.782328274 O: ok Comment extraction from private key 501s 05:19:26.783014059 E: run test keygen-convert.sh ... 505s 05:19:30.185843192 O: ok convert keys 505s 05:19:30.186586774 E: run test keygen-knownhosts.sh ... 505s 05:19:30.407581788 O: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts updated. 505s 05:19:30.408465586 O: Original contents retained as /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts.old 505s 05:19:30.418861872 O: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts updated. 505s 05:19:30.419504052 O: Original contents retained as /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts.old 505s 05:19:30.423448598 O: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts updated. 505s 05:19:30.424121169 O: Original contents retained as /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts.old 505s 05:19:30.431870096 O: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts updated. 505s 05:19:30.432562102 O: Original contents retained as /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hosts.old 505s 05:19:30.446548126 O: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hashed updated. 505s 05:19:30.447270115 O: Original contents retained as /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/kh.hashed.old 505s 05:19:30.450149170 O: ok ssh-keygen known_hosts 505s 05:19:30.450831897 E: run test keygen-moduli.sh ... 510s 05:19:35.578757038 E: run test keygen-sshfp.sh ... 510s 05:19:35.579485327 O: ok keygen moduli 510s 05:19:35.736464340 E: run test key-options.sh ... 510s 05:19:35.736336465 O: ok keygen-sshfp 510s 05:19:35.868758486 O: key option command="echo bar" 511s 05:19:36.166116877 O: key option no-pty,command="echo bar" 511s 05:19:36.458802228 O: key option pty default 511s 05:19:36.762817500 O: key option pty no-pty 512s 05:19:37.063413459 O: key option pty restrict 512s 05:19:37.362756853 O: key option pty restrict,pty 512s 05:19:37.664377203 O: key option environment 513s 05:19:38.396683297 O: key option from="127.0.0.1" 514s 05:19:39.387160504 O: key option from="127.0.0.0/8" 515s 05:19:40.116229084 O: key option expiry-time default 515s 05:19:40.424273435 O: key option expiry-time invalid 515s 05:19:40.827520218 O: key option expiry-time expired 516s 05:19:41.230688303 O: key option expiry-time valid 516s 05:19:41.555802641 O: ok key options 516s 05:19:41.556289312 E: run test scp.sh ... 516s 05:19:41.691390600 O: scp: scp mode: simple copy local file to local file 516s 05:19:41.696609919 O: scp: scp mode: simple copy local file to remote file 516s 05:19:41.701229674 O: scp: scp mode: simple copy remote file to local file 516s 05:19:41.706265098 O: scp: scp mode: copy local file to remote file in place 516s 05:19:41.711895385 O: scp: scp mode: copy remote file to local file in place 516s 05:19:41.717158832 O: scp: scp mode: copy local file to remote file clobber 516s 05:19:41.722646413 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 Feb 22 05:19 /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/copy 516s 05:19:41.723197246 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 Feb 22 05:19 /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/data 516s 05:19:41.723985240 O: scp: scp mode: copy remote file to local file clobber 516s 05:19:41.730195670 O: scp: scp mode: simple copy local file to remote dir 516s 05:19:41.735636240 O: scp: scp mode: simple copy local file to local dir 516s 05:19:41.741459317 O: scp: scp mode: simple copy remote file to local dir 516s 05:19:41.747501363 O: scp: scp mode: recursive local dir to remote dir 516s 05:19:41.758655357 O: scp: scp mode: recursive local dir to local dir 516s 05:19:41.769494965 O: scp: scp mode: recursive remote dir to local dir 516s 05:19:41.782315854 O: scp: scp mode: unmatched glob file local->remote 516s 05:19:41.787852523 O: scp: scp mode: unmatched glob file remote->local 516s 05:19:41.791184220 O: scp: scp mode: unmatched glob dir recursive local->remote 516s 05:19:41.799324261 O: scp: scp mode: unmatched glob dir recursive remote->local 516s 05:19:41.804072797 O: scp: scp mode: shell metacharacters 516s 05:19:41.808648094 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 516s 05:19:41.829375617 O: scp: scp mode: disallow bad server #0 516s 05:19:41.841587927 O: scp: scp mode: disallow bad server #1 516s 05:19:41.854029020 O: scp: scp mode: disallow bad server #2 516s 05:19:41.866463553 O: scp: scp mode: disallow bad server #3 516s 05:19:41.880411907 O: scp: scp mode: disallow bad server #4 516s 05:19:41.893841321 O: scp: scp mode: disallow bad server #5 516s 05:19:41.906333843 O: scp: scp mode: disallow bad server #6 516s 05:19:41.918581014 O: scp: scp mode: disallow bad server #7 516s 05:19:41.931456607 O: scp: scp mode: detect non-directory target 516s 05:19:41.933902176 E: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/copy2: Not a directory 517s 05:19:41.934832790 O: scp: sftp mode: simple copy local file to local file 517s 05:19:41.939574130 O: scp: sftp mode: simple copy local file to remote file 517s 05:19:41.946616871 O: scp: sftp mode: simple copy remote file to local file 517s 05:19:41.952390868 O: scp: sftp mode: copy local file to remote file in place 517s 05:19:41.959098844 O: scp: sftp mode: copy remote file to local file in place 517s 05:19:41.965260863 O: scp: sftp mode: copy local file to remote file clobber 517s 05:19:41.971504224 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 Feb 22 05:19 /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/copy 517s 05:19:41.972062899 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 Feb 22 05:19 /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/data 517s 05:19:41.972969204 O: scp: sftp mode: copy remote file to local file clobber 517s 05:19:41.979678712 O: scp: sftp mode: simple copy local file to remote dir 517s 05:19:41.986443062 O: scp: sftp mode: simple copy local file to local dir 517s 05:19:41.992024670 O: scp: sftp mode: simple copy remote file to local dir 517s 05:19:41.998318562 O: scp: sftp mode: recursive local dir to remote dir 517s 05:19:42.011563627 O: scp: sftp mode: recursive local dir to local dir 517s 05:19:42.021764870 O: scp: sftp mode: recursive remote dir to local dir 517s 05:19:42.036405541 O: scp: sftp mode: unmatched glob file local->remote 517s 05:19:42.042619952 O: scp: sftp mode: unmatched glob file remote->local 517s 05:19:42.047543386 O: scp: sftp mode: unmatched glob dir recursive local->remote 517s 05:19:42.056246225 O: scp: sftp mode: unmatched glob dir recursive remote->local 517s 05:19:42.063228128 O: scp: sftp mode: shell metacharacters 517s 05:19:42.067993581 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 517s 05:19:42.089508097 O: scp: sftp mode: disallow bad server #0 517s 05:19:42.107514019 O: scp: sftp mode: disallow bad server #1 517s 05:19:42.125519007 O: scp: sftp mode: disallow bad server #2 517s 05:19:42.143200557 O: scp: sftp mode: disallow bad server #3 517s 05:19:42.162044178 O: scp: sftp mode: disallow bad server #4 517s 05:19:42.179343338 O: scp: sftp mode: disallow bad server #5 517s 05:19:42.198130921 O: scp: sftp mode: disallow bad server #6 517s 05:19:42.216291839 O: scp: sftp mode: disallow bad server #7 517s 05:19:42.236046210 O: scp: sftp mode: detect non-directory target 517s 05:19:42.238507528 E: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/copy2: Not a directory 517s 05:19:42.242078523 O: ok scp 517s 05:19:42.242613919 E: run test scp3.sh ... 517s 05:19:42.378043279 O: scp3: scp mode: simple copy remote file to remote file 517s 05:19:42.732683794 O: scp3: scp mode: simple copy remote file to remote dir 518s 05:19:43.062810867 O: scp3: scp mode: recursive remote dir to remote dir 518s 05:19:43.411576903 O: scp3: scp mode: detect non-directory target 519s 05:19:44.048803886 O: scp3: sftp mode: simple copy remote file to remote file 519s 05:19:44.055933559 O: scp3: sftp mode: simple copy remote file to remote dir 519s 05:19:44.063047700 O: scp3: sftp mode: recursive remote dir to remote dir 519s 05:19:44.079360573 O: scp3: sftp mode: detect non-directory target 519s 05:19:44.082984939 E: scp: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/copy2: destination is not a directory 519s 05:19:44.083626852 E: scp: /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/copy2: destination is not a directory 519s 05:19:44.087231610 O: ok scp3 519s 05:19:44.087723479 E: run test scp-uri.sh ... 519s 05:19:44.218665877 O: scp-uri: scp mode: simple copy local file to remote file 519s 05:19:44.222875375 O: scp-uri: scp mode: simple copy remote file to local file 519s 05:19:44.226806765 O: scp-uri: scp mode: simple copy local file to remote dir 519s 05:19:44.231248585 O: scp-uri: scp mode: simple copy remote file to local dir 519s 05:19:44.235971540 O: scp-uri: scp mode: recursive local dir to remote dir 519s 05:19:44.242009126 O: scp-uri: scp mode: recursive remote dir to local dir 519s 05:19:44.247828910 O: scp-uri: sftp mode: simple copy local file to remote file 519s 05:19:44.252950897 O: scp-uri: sftp mode: simple copy remote file to local file 519s 05:19:44.257098045 O: scp-uri: sftp mode: simple copy local file to remote dir 519s 05:19:44.263306561 O: scp-uri: sftp mode: simple copy remote file to local dir 519s 05:19:44.268875201 O: scp-uri: sftp mode: recursive local dir to remote dir 519s 05:19:44.276167643 O: scp-uri: sftp mode: recursive remote dir to local dir 519s 05:19:44.283768304 O: ok scp-uri 519s 05:19:44.284260454 E: run test sftp.sh ... 519s 05:19:44.414951089 O: test basic sftp put/get: buffer_size 5 num_requests 1 523s 05:19:48.333580596 O: test basic sftp put/get: buffer_size 5 num_requests 2 525s 05:19:50.092364783 O: test basic sftp put/get: buffer_size 5 num_requests 10 526s 05:19:51.276310049 O: test basic sftp put/get: buffer_size 1000 num_requests 1 526s 05:19:51.301703521 O: test basic sftp put/get: buffer_size 1000 num_requests 2 526s 05:19:51.314735995 O: test basic sftp put/get: buffer_size 1000 num_requests 10 526s 05:19:51.325900690 O: test basic sftp put/get: buffer_size 32000 num_requests 1 526s 05:19:51.332728893 O: test basic sftp put/get: buffer_size 32000 num_requests 2 526s 05:19:51.338688772 O: test basic sftp put/get: buffer_size 32000 num_requests 10 526s 05:19:51.344506562 O: test basic sftp put/get: buffer_size 64000 num_requests 1 526s 05:19:51.350951626 O: test basic sftp put/get: buffer_size 64000 num_requests 2 526s 05:19:51.357115721 O: test basic sftp put/get: buffer_size 64000 num_requests 10 526s 05:19:51.364489250 E: run test sftp-chroot.sh ... 526s 05:19:51.365044828 O: ok basic sftp put/get 526s 05:19:51.667688006 O: test sftp in chroot: get 527s 05:19:52.013480497 O: test sftp in chroot: match 527s 05:19:52.504213807 O: ok sftp in chroot 527s 05:19:52.508167305 E: run test sftp-cmds.sh ... 527s 05:19:52.641646671 O: sftp commands: lls 527s 05:19:52.646073176 O: sftp commands: lls w/path 527s 05:19:52.649814005 O: sftp commands: ls 527s 05:19:52.654810754 O: sftp commands: shell 527s 05:19:52.657566653 O: sftp commands: pwd 527s 05:19:52.659542064 O: sftp commands: lpwd 527s 05:19:52.661277954 O: sftp commands: quit 527s 05:19:52.663008891 O: sftp commands: help 527s 05:19:52.665076218 O: sftp commands: get 527s 05:19:52.669756487 O: sftp commands: get quoted 527s 05:19:52.675253384 O: sftp commands: get filename with quotes 527s 05:19:52.681217812 O: sftp commands: get filename with spaces 527s 05:19:52.686590676 O: sftp commands: get filename with glob metacharacters 527s 05:19:52.691153585 O: sftp commands: get to directory 527s 05:19:52.695619821 O: sftp commands: glob get to directory 527s 05:19:52.727067079 O: sftp commands: get to local dir 527s 05:19:52.731701952 O: sftp commands: glob get to local dir 527s 05:19:52.755075050 O: sftp commands: put 527s 05:19:52.760235682 O: sftp commands: put filename with quotes 527s 05:19:52.765169282 O: sftp commands: put filename with spaces 527s 05:19:52.771435975 O: sftp commands: put to directory 527s 05:19:52.776149992 O: sftp commands: glob put to directory 527s 05:19:52.781938734 O: sftp commands: put to local dir 527s 05:19:52.786797655 O: sftp commands: glob put to local dir 527s 05:19:52.801904684 O: sftp commands: rename 527s 05:19:52.804250201 O: sftp commands: rename directory 527s 05:19:52.805776245 O: sftp commands: ln 527s 05:19:52.807710829 O: sftp commands: ln -s 527s 05:19:52.809791106 O: sftp commands: cp 527s 05:19:52.813216491 O: sftp commands: mkdir 527s 05:19:52.814685887 O: sftp commands: chdir 527s 05:19:52.816245977 O: sftp commands: rmdir 527s 05:19:52.817815126 O: sftp commands: lmkdir 527s 05:19:52.819479021 O: sftp commands: lchdir 527s 05:19:52.824025023 E: run test sftp-badcmds.sh ... 527s 05:19:52.823552884 O: ok sftp commands 528s 05:19:52.958794032 O: sftp invalid commands: get nonexistent 528s 05:19:52.961916386 O: sftp invalid commands: glob get to nonexistent directory 528s 05:19:52.971365443 O: sftp invalid commands: put nonexistent 528s 05:19:52.973560263 O: sftp invalid commands: glob put to nonexistent directory 528s 05:19:52.976344127 O: sftp invalid commands: rename nonexistent 528s 05:19:52.980553139 O: sftp invalid commands: rename target exists (directory) 528s 05:19:52.984713808 O: sftp invalid commands: glob put files to local file 528s 05:19:52.988688562 O: ok sftp invalid commands 528s 05:19:52.989249275 E: run test sftp-batch.sh ... 528s 05:19:53.128986042 O: sftp batchfile: good commands 528s 05:19:53.133461889 O: sftp batchfile: bad commands 528s 05:19:53.138606482 O: sftp batchfile: comments and blanks 528s 05:19:53.142038586 O: sftp batchfile: junk command 528s 05:19:53.144292489 O: ok sftp batchfile 528s 05:19:53.144720101 E: run test sftp-glob.sh ... 528s 05:19:53.284211352 O: sftp glob: file glob 528s 05:19:53.288827109 O: sftp glob: dir glob 528s 05:19:53.292661635 O: sftp glob: quoted glob 528s 05:19:53.297206442 O: sftp glob: escaped glob 528s 05:19:53.301541689 O: sftp glob: escaped quote 528s 05:19:53.305034704 O: sftp glob: quoted quote 528s 05:19:53.308574412 O: sftp glob: single-quoted quote 528s 05:19:53.311798722 O: sftp glob: escaped space 528s 05:19:53.314905126 O: sftp glob: quoted space 528s 05:19:53.318221827 O: sftp glob: escaped slash 528s 05:19:53.322006342 O: sftp glob: quoted slash 528s 05:19:53.324992743 O: sftp glob: escaped slash at EOL 528s 05:19:53.328553355 O: sftp glob: quoted slash at EOL 528s 05:19:53.332338294 O: sftp glob: escaped slash+quote 528s 05:19:53.335655858 O: sftp glob: quoted slash+quote 528s 05:19:53.339605102 O: ok sftp glob 528s 05:19:53.340312975 E: run test sftp-perm.sh ... 528s 05:19:53.483236260 O: sftp permissions: read-only upload 528s 05:19:53.492911749 O: sftp permissions: read-only setstat 528s 05:19:53.502340961 O: sftp permissions: read-only rm 528s 05:19:53.510996264 O: sftp permissions: read-only mkdir 528s 05:19:53.519103405 O: sftp permissions: read-only rmdir 528s 05:19:53.527116877 O: sftp permissions: read-only posix-rename 528s 05:19:53.535103876 O: sftp permissions: read-only oldrename 528s 05:19:53.544265262 O: sftp permissions: read-only symlink 528s 05:19:53.552844539 O: sftp permissions: read-only hardlink 528s 05:19:53.561236366 O: sftp permissions: explicit open 528s 05:19:53.581503634 O: sftp permissions: explicit read 528s 05:19:53.602087896 O: sftp permissions: explicit write 528s 05:19:53.622631928 O: sftp permissions: explicit lstat 528s 05:19:53.641540005 O: sftp permissions: explicit opendir 528s 05:19:53.661007616 O: sftp permissions: explicit readdir 528s 05:19:53.680495991 O: sftp permissions: explicit setstat 528s 05:19:53.697861739 O: sftp permissions: explicit remove 528s 05:19:53.715716297 O: sftp permissions: explicit mkdir 528s 05:19:53.731356644 O: sftp permissions: explicit rmdir 528s 05:19:53.748632147 O: sftp permissions: explicit rename 528s 05:19:53.764246944 O: sftp permissions: explicit symlink 528s 05:19:53.780081937 O: sftp permissions: explicit hardlink 528s 05:19:53.795272800 O: sftp permissions: explicit statvfs 528s 05:19:53.807375447 O: ok sftp permissions 528s 05:19:53.807908280 E: run test sftp-uri.sh ... 529s 05:19:54.094935659 O: sftp-uri: non-interactive fetch to local file 529s 05:19:54.430378720 O: sftp-uri: non-interactive fetch to local dir 529s 05:19:54.771645800 O: sftp-uri: put to remote directory (trailing slash) 530s 05:19:55.110958328 O: sftp-uri: put to remote directory (no slash) 530s 05:19:55.464635578 O: ok sftp-uri 530s 05:19:55.465298803 E: run test reconfigure.sh ... 544s 05:20:09.204375518 O: ok simple connect after reconfigure 544s 05:20:09.204825156 E: run test dynamic-forward.sh ... 544s 05:20:09.482097188 O: test -D forwarding 546s 05:20:11.027666981 O: test -R forwarding 547s 05:20:12.628166969 O: PermitRemoteOpen=any 550s 05:20:15.290898993 O: PermitRemoteOpen=none 550s 05:20:15.787113801 O: PermitRemoteOpen=explicit 553s 05:20:18.549470338 O: PermitRemoteOpen=disallowed 554s 05:20:19.046908081 E: run test forwarding.sh ... 554s 05:20:19.046395658 O: ok dynamic forwarding 560s 05:20:25.765404845 O: ok local and remote forwarding 560s 05:20:25.766123771 E: run test multiplex.sh ... 562s 05:20:27.065792981 O: test connection multiplexing: setenv 562s 05:20:27.075038634 O: test connection multiplexing: envpass 562s 05:20:27.083633621 O: test connection multiplexing: transfer 562s 05:20:27.110531222 O: test connection multiplexing: transfer -Oproxy 562s 05:20:27.172520296 O: test connection multiplexing: forward 564s 05:20:29.208571502 O: test connection multiplexing: status 0 () 569s 05:20:34.228798532 O: test connection multiplexing: status 0 (-Oproxy) 574s 05:20:39.248641604 O: test connection multiplexing: status 1 () 579s 05:20:44.268572217 O: test connection multiplexing: status 1 (-Oproxy) 584s 05:20:49.291308703 O: test connection multiplexing: status 4 () 589s 05:20:54.311458336 O: test connection multiplexing: status 4 (-Oproxy) 594s 05:20:59.336272660 O: test connection multiplexing: status 5 () 599s 05:21:04.359370744 O: test connection multiplexing: status 5 (-Oproxy) 604s 05:21:09.380402441 O: test connection multiplexing: status 44 () 609s 05:21:14.401242241 O: test connection multiplexing: status 44 (-Oproxy) 614s 05:21:19.420507700 O: test connection multiplexing: cmd check 614s 05:21:19.426689458 O: test connection multiplexing: cmd forward local (TCP) 615s 05:21:20.776137640 O: test connection multiplexing: cmd forward remote (TCP) 617s 05:21:22.128014165 O: test connection multiplexing: cmd forward local (UNIX) 618s 05:21:23.203526108 O: test connection multiplexing: cmd forward remote (UNIX) 619s 05:21:24.266859796 O: test connection multiplexing: cmd exit 619s 05:21:24.273109276 O: test connection multiplexing: cmd stop 630s 05:21:35.311279943 E: run test reexec.sh ... 630s 05:21:35.311101994 O: ok connection multiplexing 630s 05:21:35.459176415 O: test config passing 630s 05:21:35.963519523 O: test reexec fallback 630s 05:21:35.964108141 E: ln: failed to create hard link '/tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 631s 05:21:36.482333335 O: ok reexec tests 631s 05:21:36.482765957 E: run test brokenkeys.sh ... 632s 05:21:37.097273072 O: ok broken keys 632s 05:21:37.097637712 E: run test sshcfgparse.sh ... 632s 05:21:37.236967653 O: reparse minimal config 632s 05:21:37.248703039 O: ssh -W opts 632s 05:21:37.282809142 O: user first match 632s 05:21:37.305777094 O: pubkeyacceptedalgorithms 632s 05:21:37.341598090 O: agentforwarding 632s 05:21:37.370382639 O: command line override 632s 05:21:37.385501694 O: ok ssh config parse 632s 05:21:37.385518806 E: run test cfgparse.sh ... 632s 05:21:37.527638133 O: reparse minimal config 632s 05:21:37.671965834 O: reparse regress config 632s 05:21:37.814388951 O: listenaddress order 632s 05:21:37.961950445 E: run test cfgmatch.sh ... 632s 05:21:37.961822814 O: ok sshd config parse 640s 05:21:45.922622259 O: ok sshd_config match 640s 05:21:45.923271501 E: run test cfgmatchlisten.sh ... 653s 05:21:58.014076502 O: ok sshd_config matchlisten 653s 05:21:58.014520061 E: run test percent.sh ... 653s 05:21:58.157077793 O: percent expansions matchexec percent 657s 05:22:02.237703619 O: percent expansions localcommand percent 660s 05:22:05.947591643 O: percent expansions remotecommand percent 661s 05:22:06.040871700 O: percent expansions controlpath percent 661s 05:22:06.129387121 O: percent expansions identityagent percent 661s 05:22:06.220359413 O: percent expansions forwardagent percent 661s 05:22:06.308913127 O: percent expansions localforward percent 661s 05:22:06.398399438 O: percent expansions remoteforward percent 661s 05:22:06.486685219 O: percent expansions revokedhostkeys percent 661s 05:22:06.578759085 O: percent expansions userknownhostsfile percent 664s 05:22:09.403880320 O: percent expansions controlpath dollar 664s 05:22:09.412408462 O: percent expansions identityagent dollar 664s 05:22:09.420213243 O: percent expansions forwardagent dollar 664s 05:22:09.428165206 O: percent expansions localforward dollar 664s 05:22:09.436093227 O: percent expansions remoteforward dollar 664s 05:22:09.443805214 O: percent expansions userknownhostsfile dollar 664s 05:22:09.767653252 O: percent expansions controlpath tilde 664s 05:22:09.783542500 O: percent expansions identityagent tilde 664s 05:22:09.798697577 O: percent expansions forwardagent tilde 664s 05:22:09.814790733 O: ok percent expansions 664s 05:22:09.815419990 E: run test addrmatch.sh ... 664s 05:22:09.951749489 O: test first entry for user 192.168.0.1 somehost 665s 05:22:10.019941044 O: test negative match for user 192.168.30.1 somehost 665s 05:22:10.087692245 O: test no match for user 19.0.0.1 somehost 665s 05:22:10.154345534 O: test list middle for user 10.255.255.254 somehost 665s 05:22:10.220815456 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 665s 05:22:10.287289176 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 665s 05:22:10.353633894 O: test localaddress for user 19.0.0.1 somehost 665s 05:22:10.420702345 O: test localport for user 19.0.0.1 somehost 665s 05:22:10.487080614 O: test bare IP6 address for user ::1 somehost.example.com 665s 05:22:10.553350560 O: test deny IPv6 for user ::2 somehost.example.com 665s 05:22:10.620310214 O: test IP6 negated for user ::3 somehost 665s 05:22:10.687149158 O: test IP6 no match for user ::4 somehost 665s 05:22:10.754594504 O: test IP6 network for user 2000::1 somehost 665s 05:22:10.821541969 O: test IP6 network for user 2001::1 somehost 665s 05:22:10.888775643 O: test IP6 localaddress for user ::5 somehost 665s 05:22:10.954267265 O: test IP6 localport for user ::5 somehost 666s 05:22:11.019521696 O: test invalid Match address 10.0.1.0/8 666s 05:22:11.027423886 O: test invalid Match localaddress 10.0.1.0/8 666s 05:22:11.034340882 O: test invalid Match address 10.0.0.1/24 666s 05:22:11.041535292 O: test invalid Match localaddress 10.0.0.1/24 666s 05:22:11.048782445 O: test invalid Match address 2000:aa:bb:01::/56 666s 05:22:11.056175074 O: test invalid Match localaddress 2000:aa:bb:01::/56 666s 05:22:11.064405554 E: run test localcommand.sh ... 666s 05:22:11.065034959 O: ok address match 666s 05:22:11.202593745 O: test localcommand: proto localcommand 666s 05:22:11.499841455 E: run test forcecommand.sh ... 666s 05:22:11.499105258 O: ok localcommand 667s 05:22:12.881535328 E: Connection closed. 667s 05:22:12.882780872 E: Connection closed 668s 05:22:13.512432888 E: Connection closed. 668s 05:22:13.514010206 E: Connection closed 668s 05:22:13.826088616 O: ok forced command 668s 05:22:13.826550223 E: run test portnum.sh ... 668s 05:22:13.966517242 O: port number parsing: invalid port 0 668s 05:22:13.972266592 O: port number parsing: invalid port 65536 669s 05:22:13.977496320 O: port number parsing: invalid port 131073 669s 05:22:13.982855774 O: port number parsing: invalid port 2000blah 669s 05:22:13.988207994 O: port number parsing: invalid port blah2000 669s 05:22:13.993522925 O: port number parsing: valid port 1 669s 05:22:14.293714062 O: port number parsing: valid port 22 669s 05:22:14.591534301 O: port number parsing: valid port 2222 669s 05:22:14.886415482 O: port number parsing: valid port 22222 670s 05:22:15.188430833 O: port number parsing: valid port 65535 670s 05:22:15.482685122 O: ok port number parsing 670s 05:22:15.483340474 E: run test keytype.sh ... 670s 05:22:15.625636276 O: keygen ed25519, 512 bits 670s 05:22:15.633511184 O: keygen ed25519-sk, n/a bits 670s 05:22:15.643136199 O: keygen ecdsa, 256 bits 670s 05:22:15.649581755 O: keygen ecdsa, 384 bits 670s 05:22:15.656414788 O: keygen ecdsa, 521 bits 670s 05:22:15.663013855 O: keygen ecdsa-sk, n/a bits 670s 05:22:15.675728027 O: keygen rsa, 2048 bits 670s 05:22:15.803982057 O: keygen rsa, 3072 bits 671s 05:22:16.443948310 O: userkey ed25519-512, hostkey ed25519-512 671s 05:22:16.586151215 O: userkey ed25519-512, hostkey ed25519-512 671s 05:22:16.733928447 O: userkey ed25519-512, hostkey ed25519-512 671s 05:22:16.884411906 O: userkey ed25519-sk, hostkey ed25519-sk 672s 05:22:17.027021513 O: userkey ed25519-sk, hostkey ed25519-sk 672s 05:22:17.175882883 O: userkey ed25519-sk, hostkey ed25519-sk 672s 05:22:17.327142546 O: userkey ecdsa-256, hostkey ecdsa-256 672s 05:22:17.485808876 O: userkey ecdsa-256, hostkey ecdsa-256 672s 05:22:17.648763870 O: userkey ecdsa-256, hostkey ecdsa-256 672s 05:22:17.818108078 O: userkey ecdsa-384, hostkey ecdsa-384 673s 05:22:18.048138833 O: userkey ecdsa-384, hostkey ecdsa-384 673s 05:22:18.281333104 O: userkey ecdsa-384, hostkey ecdsa-384 673s 05:22:18.514802814 O: userkey ecdsa-521, hostkey ecdsa-521 673s 05:22:18.812842399 O: userkey ecdsa-521, hostkey ecdsa-521 674s 05:22:19.105474746 O: userkey ecdsa-521, hostkey ecdsa-521 674s 05:22:19.419650581 O: userkey ecdsa-sk, hostkey ecdsa-sk 674s 05:22:19.591621071 O: userkey ecdsa-sk, hostkey ecdsa-sk 674s 05:22:19.771007529 O: userkey ecdsa-sk, hostkey ecdsa-sk 674s 05:22:19.942175282 O: userkey rsa-2048, hostkey rsa-2048 675s 05:22:20.077047449 O: userkey rsa-2048, hostkey rsa-2048 675s 05:22:20.215555531 O: userkey rsa-2048, hostkey rsa-2048 675s 05:22:20.361797935 O: userkey rsa-3072, hostkey rsa-3072 675s 05:22:20.508528943 O: userkey rsa-3072, hostkey rsa-3072 675s 05:22:20.664312748 O: userkey rsa-3072, hostkey rsa-3072 675s 05:22:20.825267376 O: ok login with different key types 675s 05:22:20.826017446 E: run test kextype.sh ... 676s 05:22:20.980667273 O: kex diffie-hellman-group1-sha1 676s 05:22:21.876629998 O: kex diffie-hellman-group14-sha1 677s 05:22:22.823377687 O: kex diffie-hellman-group14-sha256 678s 05:22:23.763430897 O: kex diffie-hellman-group16-sha512 679s 05:22:24.873675789 O: kex diffie-hellman-group18-sha512 681s 05:22:26.456248315 O: kex diffie-hellman-group-exchange-sha1 683s 05:22:28.096221050 O: kex diffie-hellman-group-exchange-sha256 684s 05:22:29.762646577 O: kex ecdh-sha2-nistp256 685s 05:22:30.659389939 O: kex ecdh-sha2-nistp384 686s 05:22:31.605796990 O: kex ecdh-sha2-nistp521 687s 05:22:32.547196541 O: kex curve25519-sha256 688s 05:22:33.454902887 O: kex curve25519-sha256@libssh.org 689s 05:22:34.355360078 O: kex sntrup761x25519-sha512 690s 05:22:35.545652229 O: kex sntrup761x25519-sha512@openssh.com 691s 05:22:36.734815427 O: kex mlkem768x25519-sha256 692s 05:22:37.649822666 O: ok login with different key exchange algorithms 692s 05:22:37.650453874 E: run test cert-hostkey.sh ... 693s 05:22:38.184859575 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/host_ca_key.pub 693s 05:22:38.185512738 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/host_ca_key2.pub 693s 05:22:38.185999729 O: certified host keys: sign host ed25519 cert 693s 05:22:38.191910497 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 693s 05:22:38.200563977 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 693s 05:22:38.201960895 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 693s 05:22:38.209977390 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 693s 05:22:38.218833313 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 693s 05:22:38.220688022 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 693s 05:22:38.227937028 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 693s 05:22:38.239881225 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 693s 05:22:38.241346403 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 693s 05:22:38.250583025 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 693s 05:22:38.267331298 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 693s 05:22:38.268950825 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 693s 05:22:38.281783230 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 693s 05:22:38.304922823 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 693s 05:22:38.306771182 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 693s 05:22:38.320003843 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 693s 05:22:38.332061686 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 693s 05:22:38.333645485 O: certified host keys: sign host rsa cert 694s 05:22:39.801026591 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 694s 05:22:39.811252123 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 694s 05:22:39.813967919 O: certified host keys: sign host rsa-sha2-256 cert 695s 05:22:40.168713372 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 695s 05:22:40.184150449 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 695s 05:22:40.186174318 O: certified host keys: sign host rsa-sha2-512 cert 695s 05:22:40.624779697 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 695s 05:22:40.640531094 O: Revoking from /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 695s 05:22:40.642843722 O: certified host keys: host ed25519 cert connect 695s 05:22:40.644548987 O: certified host keys: ed25519 basic connect expect success yes 695s 05:22:40.901145831 O: certified host keys: ed25519 empty KRL expect success yes 696s 05:22:41.153204042 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 696s 05:22:41.367772121 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 696s 05:22:41.584435609 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 696s 05:22:41.793653373 O: certified host keys: ed25519 empty plaintext revocation expect success yes 697s 05:22:42.046539940 O: certified host keys: ed25519 plain key plaintext revocation expect success no 697s 05:22:42.262269356 O: certified host keys: ed25519 cert plaintext revocation expect success no 697s 05:22:42.476527754 O: certified host keys: ed25519 CA plaintext revocation expect success no 697s 05:22:42.686908849 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 697s 05:22:42.687833015 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 697s 05:22:42.947203538 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 698s 05:22:43.209833324 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 698s 05:22:43.432453404 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 698s 05:22:43.654873907 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 698s 05:22:43.893013010 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 699s 05:22:44.143604275 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 699s 05:22:44.379327129 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 699s 05:22:44.605313858 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 699s 05:22:44.848245614 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 699s 05:22:44.849335016 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 700s 05:22:45.118402551 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 700s 05:22:45.391169954 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 700s 05:22:45.620867482 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 700s 05:22:45.847817217 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 701s 05:22:46.075668281 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 701s 05:22:46.348400432 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 701s 05:22:46.578362927 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 701s 05:22:46.816067327 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 702s 05:22:47.051862884 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 702s 05:22:47.053420903 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 702s 05:22:47.355007642 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 702s 05:22:47.656504051 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 702s 05:22:47.906115133 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 703s 05:22:48.158515966 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 703s 05:22:48.406049175 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 703s 05:22:48.710431425 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 703s 05:22:48.967422249 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 704s 05:22:49.230806731 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 704s 05:22:49.477591812 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 704s 05:22:49.478119477 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 704s 05:22:49.816701665 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 705s 05:22:50.157640211 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 705s 05:22:50.459118022 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 705s 05:22:50.765071566 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 706s 05:22:51.071790776 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 706s 05:22:51.470676088 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 706s 05:22:51.769305196 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 707s 05:22:52.081960116 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 707s 05:22:52.380408166 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 707s 05:22:52.382484883 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 707s 05:22:52.664092671 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 707s 05:22:52.947576327 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 708s 05:22:53.182631196 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 708s 05:22:53.421562161 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 708s 05:22:53.671655697 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 708s 05:22:53.957198947 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 709s 05:22:54.210346981 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 709s 05:22:54.472910188 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 709s 05:22:54.713003223 O: certified host keys: host rsa cert connect 709s 05:22:54.713528136 O: certified host keys: rsa basic connect expect success yes 710s 05:22:54.978977541 O: certified host keys: rsa empty KRL expect success yes 710s 05:22:55.244280772 O: certified host keys: rsa KRL w/ plain key revoked expect success no 710s 05:22:55.474013680 O: certified host keys: rsa KRL w/ cert revoked expect success no 710s 05:22:55.698849228 O: certified host keys: rsa KRL w/ CA revoked expect success no 710s 05:22:55.926265703 O: certified host keys: rsa empty plaintext revocation expect success yes 711s 05:22:56.190610573 O: certified host keys: rsa plain key plaintext revocation expect success no 711s 05:22:56.426812183 O: certified host keys: rsa cert plaintext revocation expect success no 711s 05:22:56.685517298 O: certified host keys: rsa CA plaintext revocation expect success no 711s 05:22:56.917913886 O: certified host keys: host rsa-sha2-256 cert connect 711s 05:22:56.918472822 O: certified host keys: rsa-sha2-256 basic connect expect success yes 712s 05:22:57.174217029 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 712s 05:22:57.436571877 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 712s 05:22:57.651778957 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 712s 05:22:57.869412677 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 713s 05:22:58.085660636 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 713s 05:22:58.339844747 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 713s 05:22:58.577195199 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 713s 05:22:58.829851200 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 714s 05:22:59.064126230 O: certified host keys: host rsa-sha2-512 cert connect 714s 05:22:59.065724911 O: certified host keys: rsa-sha2-512 basic connect expect success yes 714s 05:22:59.333675992 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 714s 05:22:59.606137358 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 714s 05:22:59.838089397 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 715s 05:23:00.063222194 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 715s 05:23:00.288662472 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 715s 05:23:00.560132406 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 715s 05:23:00.802304801 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 716s 05:23:01.055750660 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 716s 05:23:01.289109369 O: certified host keys: host ed25519 revoked cert 716s 05:23:01.539128886 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 716s 05:23:01.794822169 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 717s 05:23:02.064920125 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 717s 05:23:02.351253430 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 718s 05:23:02.671197937 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 718s 05:23:02.950737527 O: certified host keys: host rsa revoked cert 718s 05:23:03.210458245 O: certified host keys: host rsa-sha2-256 revoked cert 718s 05:23:03.462525461 O: certified host keys: host rsa-sha2-512 revoked cert 718s 05:23:03.719456339 O: certified host keys: host ed25519 revoked cert 718s 05:23:03.936246599 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 719s 05:23:04.160466519 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 719s 05:23:04.395736015 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 719s 05:23:04.655136517 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 719s 05:23:04.950564373 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 720s 05:23:05.195718496 O: certified host keys: host rsa revoked cert 720s 05:23:05.427677603 O: certified host keys: host rsa-sha2-256 revoked cert 720s 05:23:05.652717470 O: certified host keys: host rsa-sha2-512 revoked cert 737s 05:23:22.084256170 O: certified host keys: host ed25519 cert downgrade to raw key 737s 05:23:22.603712997 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 738s 05:23:23.132743622 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 738s 05:23:23.722360654 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 739s 05:23:24.353731769 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 740s 05:23:25.059079401 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 740s 05:23:25.636484859 O: certified host keys: host rsa cert downgrade to raw key 741s 05:23:26.384654581 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 742s 05:23:27.773657969 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 743s 05:23:28.463829884 O: certified host keys: host ed25519 connect wrong cert 743s 05:23:28.687745573 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 743s 05:23:28.912896400 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 744s 05:23:29.153590137 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 744s 05:23:29.424985894 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 744s 05:23:29.751744690 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 745s 05:23:30.006538104 O: certified host keys: host rsa connect wrong cert 746s 05:23:31.891291107 O: certified host keys: host rsa-sha2-256 connect wrong cert 747s 05:23:32.353986879 O: certified host keys: host rsa-sha2-512 connect wrong cert 750s 05:23:35.442069575 O: ok certified host keys 750s 05:23:35.442615896 E: run test cert-userkey.sh ... 751s 05:23:36.736775097 O: certified user keys: sign user ed25519 cert 751s 05:23:36.758227637 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 751s 05:23:36.779024847 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 751s 05:23:36.800834866 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 751s 05:23:36.825931993 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 751s 05:23:36.850242342 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 751s 05:23:36.873877099 O: certified user keys: sign user rsa cert 752s 05:23:37.077595218 O: certified user keys: sign user rsa-sha2-256 cert 752s 05:23:37.536774479 O: certified user keys: sign user rsa-sha2-512 cert 752s 05:23:37.815285700 O: certified user keys: ed25519 missing authorized_principals 753s 05:23:38.144760301 O: certified user keys: ed25519 empty authorized_principals 753s 05:23:38.531652234 O: certified user keys: ed25519 wrong authorized_principals 753s 05:23:38.844957276 O: certified user keys: ed25519 correct authorized_principals 754s 05:23:39.167678816 O: certified user keys: ed25519 authorized_principals bad key opt 754s 05:23:39.484108594 O: certified user keys: ed25519 authorized_principals command=false 754s 05:23:39.809444639 O: certified user keys: ed25519 authorized_principals command=true 755s 05:23:40.129221576 O: certified user keys: ed25519 wrong principals key option 755s 05:23:40.451830960 O: certified user keys: ed25519 correct principals key option 755s 05:23:40.764336180 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 756s 05:23:41.072041013 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 756s 05:23:41.384589726 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 756s 05:23:41.697089505 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 757s 05:23:42.012779182 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 757s 05:23:42.316969740 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 757s 05:23:42.626073944 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 757s 05:23:42.945836647 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 758s 05:23:43.248388510 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 758s 05:23:43.559138857 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 758s 05:23:43.897192423 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 759s 05:23:44.225119956 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 759s 05:23:44.555344513 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 759s 05:23:44.881249155 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 760s 05:23:45.210929871 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 760s 05:23:45.540571486 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 760s 05:23:45.860898871 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 761s 05:23:46.186705857 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 761s 05:23:46.505562329 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 761s 05:23:46.830972997 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 762s 05:23:47.155734566 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 762s 05:23:47.480661294 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 762s 05:23:47.836740330 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 763s 05:23:48.190723203 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 763s 05:23:48.559138051 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 763s 05:23:48.974429273 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 764s 05:23:49.401190139 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 764s 05:23:49.823068842 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 765s 05:23:50.263984977 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 765s 05:23:50.640124216 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 766s 05:23:51.029858055 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 766s 05:23:51.466483504 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 766s 05:23:51.838085499 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 767s 05:23:52.220044754 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 767s 05:23:52.604092134 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 768s 05:23:52.974446699 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 768s 05:23:53.368959149 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 768s 05:23:53.681431072 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 769s 05:23:53.993105984 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 769s 05:23:54.304523897 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 769s 05:23:54.632105238 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 769s 05:23:54.941151157 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 770s 05:23:55.265972781 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 770s 05:23:55.592430823 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 770s 05:23:55.914831096 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 771s 05:23:56.238491667 O: certified user keys: rsa missing authorized_principals 771s 05:23:56.531621872 O: certified user keys: rsa empty authorized_principals 771s 05:23:56.824918633 O: certified user keys: rsa wrong authorized_principals 772s 05:23:57.116932619 O: certified user keys: rsa correct authorized_principals 772s 05:23:57.423103870 O: certified user keys: rsa authorized_principals bad key opt 772s 05:23:57.712591701 O: certified user keys: rsa authorized_principals command=false 773s 05:23:58.025019231 O: certified user keys: rsa authorized_principals command=true 773s 05:23:58.343644981 O: certified user keys: rsa wrong principals key option 773s 05:23:58.662716562 O: certified user keys: rsa correct principals key option 774s 05:23:59.021740155 O: certified user keys: rsa-sha2-256 missing authorized_principals 774s 05:23:59.371362830 O: certified user keys: rsa-sha2-256 empty authorized_principals 774s 05:23:59.712745078 O: certified user keys: rsa-sha2-256 wrong authorized_principals 775s 05:24:00.092066150 O: certified user keys: rsa-sha2-256 correct authorized_principals 775s 05:24:00.510690750 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 775s 05:24:00.867743518 O: certified user keys: rsa-sha2-256 authorized_principals command=false 776s 05:24:01.205951038 O: certified user keys: rsa-sha2-256 authorized_principals command=true 776s 05:24:01.529420118 O: certified user keys: rsa-sha2-256 wrong principals key option 776s 05:24:01.835181885 O: certified user keys: rsa-sha2-256 correct principals key option 777s 05:24:02.162452885 O: certified user keys: rsa-sha2-512 missing authorized_principals 777s 05:24:02.489189643 O: certified user keys: rsa-sha2-512 empty authorized_principals 777s 05:24:02.792617012 O: certified user keys: rsa-sha2-512 wrong authorized_principals 778s 05:24:03.096714191 O: certified user keys: rsa-sha2-512 correct authorized_principals 778s 05:24:03.448760978 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 778s 05:24:03.782866280 O: certified user keys: rsa-sha2-512 authorized_principals command=false 779s 05:24:04.107138429 O: certified user keys: rsa-sha2-512 authorized_principals command=true 779s 05:24:04.422513516 O: certified user keys: rsa-sha2-512 wrong principals key option 779s 05:24:04.729956724 O: certified user keys: rsa-sha2-512 correct principals key option 780s 05:24:05.172776833 O: certified user keys: ed25519 authorized_keys connect 780s 05:24:05.543442594 O: certified user keys: ed25519 authorized_keys revoked key 780s 05:24:05.866686441 O: certified user keys: ed25519 authorized_keys revoked via KRL 781s 05:24:06.177846255 O: certified user keys: ed25519 authorized_keys empty KRL 781s 05:24:06.527532076 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 781s 05:24:06.854429685 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 782s 05:24:07.193781902 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 782s 05:24:07.518847999 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 782s 05:24:07.840739461 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 783s 05:24:08.247193580 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 783s 05:24:08.608269268 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 783s 05:24:08.963039482 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 784s 05:24:09.288188103 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 784s 05:24:09.640208236 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 785s 05:24:10.081114109 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 785s 05:24:10.529767255 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 785s 05:24:10.877429731 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 786s 05:24:11.270682441 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 786s 05:24:11.668744040 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 787s 05:24:12.036500077 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 787s 05:24:12.441806587 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 787s 05:24:12.764787825 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 788s 05:24:13.082721953 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 788s 05:24:13.408090925 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 788s 05:24:13.738644594 O: certified user keys: rsa authorized_keys connect 789s 05:24:14.071807649 O: certified user keys: rsa authorized_keys revoked key 789s 05:24:14.398791395 O: certified user keys: rsa authorized_keys revoked via KRL 789s 05:24:14.710656360 O: certified user keys: rsa authorized_keys empty KRL 790s 05:24:15.028897812 O: certified user keys: rsa-sha2-256 authorized_keys connect 790s 05:24:15.359055070 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 790s 05:24:15.662048923 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 790s 05:24:15.973627612 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 791s 05:24:16.287325541 O: certified user keys: rsa-sha2-512 authorized_keys connect 791s 05:24:16.601379151 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 791s 05:24:16.903325857 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 792s 05:24:17.215640216 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 792s 05:24:17.537140302 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 792s 05:24:17.852168293 O: certified user keys: authorized_keys CA does not authenticate 792s 05:24:17.852897242 O: certified user keys: ensure CA key does not authenticate user 793s 05:24:18.180661623 O: certified user keys: ed25519 TrustedUserCAKeys connect 793s 05:24:18.506117867 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 793s 05:24:18.824785680 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 794s 05:24:19.142904025 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 794s 05:24:19.480691581 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 794s 05:24:19.797597206 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 795s 05:24:20.118316277 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 795s 05:24:20.434368966 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 795s 05:24:20.760889980 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 796s 05:24:21.084002876 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 796s 05:24:21.425841835 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 796s 05:24:21.773280571 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 797s 05:24:22.103345079 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 797s 05:24:22.448750521 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 797s 05:24:22.793564982 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 798s 05:24:23.139620771 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 798s 05:24:23.491939428 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 798s 05:24:23.880203967 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 799s 05:24:24.303748303 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 799s 05:24:24.674337425 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 800s 05:24:25.095044807 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 800s 05:24:25.442141961 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 800s 05:24:25.765152833 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 801s 05:24:26.074113240 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 801s 05:24:26.416221922 O: certified user keys: rsa TrustedUserCAKeys connect 801s 05:24:26.801066037 O: certified user keys: rsa TrustedUserCAKeys revoked key 802s 05:24:27.121998432 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 802s 05:24:27.494873054 O: certified user keys: rsa TrustedUserCAKeys empty KRL 802s 05:24:27.960264817 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 803s 05:24:28.303086234 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 803s 05:24:28.604393501 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 803s 05:24:28.920575872 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 804s 05:24:29.364212260 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 804s 05:24:29.737544604 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 805s 05:24:30.053884573 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 805s 05:24:30.370578816 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 805s 05:24:30.690383743 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 806s 05:24:30.997735341 O: certified user keys: TrustedUserCAKeys CA does not authenticate 806s 05:24:30.999661501 O: certified user keys: ensure CA key does not authenticate user 806s 05:24:31.296517304 O: certified user keys: correct principal auth authorized_keys expect success rsa 806s 05:24:31.624347488 O: certified user keys: correct principal auth authorized_keys expect success ed25519 806s 05:24:31.944661187 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 807s 05:24:32.280413191 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 807s 05:24:32.603826326 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 807s 05:24:32.914029473 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 808s 05:24:33.223516526 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 808s 05:24:33.556017769 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 808s 05:24:33.867995360 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 809s 05:24:34.182957667 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 809s 05:24:34.492298136 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 809s 05:24:34.822186661 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 810s 05:24:35.139315275 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 810s 05:24:35.456637780 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 810s 05:24:35.774186565 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 811s 05:24:36.091544717 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 811s 05:24:36.411879306 O: certified user keys: cert expired auth authorized_keys expect failure rsa 811s 05:24:36.726894527 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 812s 05:24:37.041433544 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 812s 05:24:37.358373833 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 812s 05:24:37.689013028 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 813s 05:24:38.019510466 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 813s 05:24:38.350587323 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 813s 05:24:38.682168982 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 814s 05:24:39.022622556 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 814s 05:24:39.339908065 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 814s 05:24:39.654936813 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 814s 05:24:39.972555056 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 815s 05:24:40.292006736 O: certified user keys: force-command auth authorized_keys expect failure rsa 815s 05:24:40.621919295 O: certified user keys: force-command auth authorized_keys expect failure ed25519 815s 05:24:40.947241655 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 816s 05:24:41.287141438 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 816s 05:24:41.606881771 O: certified user keys: empty principals auth authorized_keys expect success rsa 816s 05:24:41.938653411 O: certified user keys: empty principals auth authorized_keys expect success ed25519 817s 05:24:42.291273974 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 817s 05:24:42.639326885 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 818s 05:24:42.995760350 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 818s 05:24:43.352212610 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 818s 05:24:43.679380613 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 819s 05:24:44.001945423 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 819s 05:24:44.339438799 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 819s 05:24:44.673934939 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 820s 05:24:44.992473737 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 820s 05:24:45.324391461 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 820s 05:24:45.665936511 O: certified user keys: force-command match true auth authorized_keys expect success rsa 821s 05:24:46.044034866 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 821s 05:24:46.500229330 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 821s 05:24:46.845946253 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 822s 05:24:47.171370123 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 822s 05:24:47.547205697 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 822s 05:24:47.872951080 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 823s 05:24:48.184670091 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 823s 05:24:48.501087268 O: certified user keys: user ed25519 connect wrong cert 823s 05:24:48.851261654 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 824s 05:24:49.144057674 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 824s 05:24:49.444834250 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 824s 05:24:49.768030918 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 825s 05:24:50.075611093 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 825s 05:24:50.383111074 O: certified user keys: user rsa connect wrong cert 825s 05:24:50.706141695 O: certified user keys: user rsa-sha2-256 connect wrong cert 826s 05:24:51.023453775 O: certified user keys: user rsa-sha2-512 connect wrong cert 826s 05:24:51.330578878 O: ok certified user keys 826s 05:24:51.331318439 E: run test host-expand.sh ... 826s 05:24:51.785834916 O: ok expand %h and %n 826s 05:24:51.786477598 E: run test keys-command.sh ... 826s 05:24:51.954069309 O: SKIPPED: /var/run/keycommand_openssh-tests.42924 not executable (/var/run mounted noexec?) 827s 05:24:51.958685078 E: run test forward-control.sh ... 828s 05:24:53.869905681 O: check_lfwd done (expecting Y): default configuration 830s 05:24:55.513339644 O: check_rfwd done (expecting Y): default configuration 831s 05:24:56.149432861 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 832s 05:24:57.793752899 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 834s 05:24:59.137807435 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 835s 05:25:00.803544074 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 837s 05:25:02.450051621 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 839s 05:25:04.093115950 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 840s 05:25:05.421412321 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 842s 05:25:07.055604645 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 842s 05:25:07.717763876 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 844s 05:25:09.372688540 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 844s 05:25:09.697292958 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 845s 05:25:10.033734041 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 846s 05:25:11.379248824 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 846s 05:25:11.692216634 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 848s 05:25:13.340556385 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 850s 05:25:14.993258589 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 851s 05:25:16.661306622 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 852s 05:25:16.977440654 O: check_rfwd done (expecting N): AllowTcpForwarding=local 852s 05:25:17.312806989 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 852s 05:25:17.625262208 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 854s 05:25:19.261747235 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 854s 05:25:19.576317214 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 855s 05:25:20.917419252 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 856s 05:25:21.227008798 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 857s 05:25:22.881889171 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 858s 05:25:23.197670796 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 859s 05:25:24.547565167 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 859s 05:25:24.870920374 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 861s 05:25:26.212450406 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 861s 05:25:26.534314264 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 863s 05:25:28.200896825 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 863s 05:25:28.516802529 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 864s 05:25:29.850724055 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 866s 05:25:31.503768429 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 867s 05:25:32.844997495 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 869s 05:25:34.544918556 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 869s 05:25:34.875045438 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 871s 05:25:36.536610209 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 871s 05:25:36.887907457 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 873s 05:25:38.543220736 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 873s 05:25:38.897813525 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 875s 05:25:40.566810137 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 877s 05:25:41.906268265 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 877s 05:25:42.226208571 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 878s 05:25:43.564193911 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 878s 05:25:43.883298799 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 879s 05:25:44.213539735 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 880s 05:25:45.874376341 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 882s 05:25:47.208306790 O: check_lfwd done (expecting N): AllowTcpForwarding=no 882s 05:25:47.529785403 O: check_rfwd done (expecting N): AllowTcpForwarding=no 882s 05:25:47.859081727 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 883s 05:25:48.168680874 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 884s 05:25:49.510490518 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 884s 05:25:49.818656677 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 886s 05:25:51.147683355 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 886s 05:25:51.460987324 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 886s 05:25:51.799816224 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 887s 05:25:52.120118564 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 888s 05:25:53.447166463 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 888s 05:25:53.755716340 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 890s 05:25:55.079589338 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 890s 05:25:55.388732321 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 890s 05:25:55.713685747 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 891s 05:25:56.021547478 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 891s 05:25:56.669047429 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 891s 05:25:56.973452793 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 893s 05:25:58.609551409 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 895s 05:26:00.253092097 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 896s 05:26:01.888889408 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 897s 05:26:02.196431434 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 898s 05:26:03.831634521 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 899s 05:26:04.138867423 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 900s 05:26:05.459388140 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 900s 05:26:05.764722772 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 901s 05:26:06.083104153 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 902s 05:26:07.734350951 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 904s 05:26:09.060180178 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 904s 05:26:09.364241002 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 904s 05:26:09.690470379 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 905s 05:26:09.993429621 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 905s 05:26:10.003648742 O: ok sshd control of local and remote forwarding 905s 05:26:10.004234351 E: run test integrity.sh ... 905s 05:26:10.160658717 O: test integrity: hmac-sha1 @2900 905s 05:26:10.474007657 O: test integrity: hmac-sha1 @2901 905s 05:26:10.778249073 O: test integrity: hmac-sha1 @2902 906s 05:26:11.089166091 O: test integrity: hmac-sha1 @2903 906s 05:26:11.413715330 O: test integrity: hmac-sha1 @2904 906s 05:26:11.729688022 O: test integrity: hmac-sha1 @2905 907s 05:26:12.035974141 O: test integrity: hmac-sha1 @2906 907s 05:26:12.349889283 O: test integrity: hmac-sha1 @2907 907s 05:26:12.654380429 O: test integrity: hmac-sha1 @2908 907s 05:26:12.970497247 O: test integrity: hmac-sha1 @2909 908s 05:26:13.269876886 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 908s 05:26:13.279587632 O: test integrity: hmac-sha1-96 @2900 908s 05:26:13.583137096 O: test integrity: hmac-sha1-96 @2901 908s 05:26:13.887232254 O: test integrity: hmac-sha1-96 @2902 909s 05:26:14.191928821 O: test integrity: hmac-sha1-96 @2903 909s 05:26:14.497187713 O: test integrity: hmac-sha1-96 @2904 909s 05:26:14.804343656 O: test integrity: hmac-sha1-96 @2905 910s 05:26:15.116358469 O: test integrity: hmac-sha1-96 @2906 910s 05:26:15.437987866 O: test integrity: hmac-sha1-96 @2907 910s 05:26:15.780988009 O: test integrity: hmac-sha1-96 @2908 911s 05:26:16.092842414 O: test integrity: hmac-sha1-96 @2909 911s 05:26:16.389751275 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 911s 05:26:16.399223909 O: test integrity: hmac-sha2-256 @2900 911s 05:26:16.709929197 O: test integrity: hmac-sha2-256 @2901 912s 05:26:17.029525443 O: test integrity: hmac-sha2-256 @2902 912s 05:26:17.349995172 O: test integrity: hmac-sha2-256 @2903 912s 05:26:17.667063077 O: test integrity: hmac-sha2-256 @2904 913s 05:26:17.985220627 O: test integrity: hmac-sha2-256 @2905 913s 05:26:18.308406716 O: test integrity: hmac-sha2-256 @2906 913s 05:26:18.633430342 O: test integrity: hmac-sha2-256 @2907 913s 05:26:18.951625902 O: test integrity: hmac-sha2-256 @2908 914s 05:26:19.261285022 O: test integrity: hmac-sha2-256 @2909 914s 05:26:19.573780944 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 914s 05:26:19.583755581 O: test integrity: hmac-sha2-512 @2900 914s 05:26:19.877088420 O: test integrity: hmac-sha2-512 @2901 915s 05:26:20.176881573 O: test integrity: hmac-sha2-512 @2902 915s 05:26:20.460575657 O: test integrity: hmac-sha2-512 @2903 915s 05:26:20.745830842 O: test integrity: hmac-sha2-512 @2904 916s 05:26:21.045432150 O: test integrity: hmac-sha2-512 @2905 916s 05:26:21.327442899 O: test integrity: hmac-sha2-512 @2906 916s 05:26:21.610374191 O: test integrity: hmac-sha2-512 @2907 916s 05:26:21.913851953 O: test integrity: hmac-sha2-512 @2908 917s 05:26:22.205298275 O: test integrity: hmac-sha2-512 @2909 917s 05:26:22.479336660 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 917s 05:26:22.489221621 O: test integrity: hmac-md5 @2900 917s 05:26:22.799018728 O: test integrity: hmac-md5 @2901 918s 05:26:23.108316534 O: test integrity: hmac-md5 @2902 918s 05:26:23.419825272 O: test integrity: hmac-md5 @2903 918s 05:26:23.733749725 O: test integrity: hmac-md5 @2904 919s 05:26:24.065173311 O: test integrity: hmac-md5 @2905 919s 05:26:24.382038027 O: test integrity: hmac-md5 @2906 919s 05:26:24.708026281 O: test integrity: hmac-md5 @2907 920s 05:26:25.030974683 O: test integrity: hmac-md5 @2908 920s 05:26:25.344576729 O: test integrity: hmac-md5 @2909 920s 05:26:25.663151299 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 920s 05:26:25.673277913 O: test integrity: hmac-md5-96 @2900 921s 05:26:25.999475523 O: test integrity: hmac-md5-96 @2901 921s 05:26:26.301686198 O: test integrity: hmac-md5-96 @2902 921s 05:26:26.607591242 O: test integrity: hmac-md5-96 @2903 921s 05:26:26.937196792 O: test integrity: hmac-md5-96 @2904 922s 05:26:27.250616335 O: test integrity: hmac-md5-96 @2905 922s 05:26:27.569167168 O: test integrity: hmac-md5-96 @2906 922s 05:26:27.875282625 O: test integrity: hmac-md5-96 @2907 923s 05:26:28.199111264 O: test integrity: hmac-md5-96 @2908 923s 05:26:28.503883009 O: test integrity: hmac-md5-96 @2909 923s 05:26:28.819014941 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 923s 05:26:28.828735770 O: test integrity: umac-64@openssh.com @2900 924s 05:26:29.157446883 O: test integrity: umac-64@openssh.com @2901 924s 05:26:29.469646653 O: test integrity: umac-64@openssh.com @2902 924s 05:26:29.781613052 O: test integrity: umac-64@openssh.com @2903 925s 05:26:30.093296036 O: test integrity: umac-64@openssh.com @2904 925s 05:26:30.418597834 O: test integrity: umac-64@openssh.com @2905 925s 05:26:30.747281647 O: test integrity: umac-64@openssh.com @2906 926s 05:26:31.066247316 O: test integrity: umac-64@openssh.com @2907 926s 05:26:31.378012511 O: test integrity: umac-64@openssh.com @2908 926s 05:26:31.689883090 O: test integrity: umac-64@openssh.com @2909 927s 05:26:31.996025975 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 927s 05:26:32.005378372 O: test integrity: umac-128@openssh.com @2900 927s 05:26:32.325665208 O: test integrity: umac-128@openssh.com @2901 927s 05:26:32.642632030 O: test integrity: umac-128@openssh.com @2902 927s 05:26:32.949224464 O: test integrity: umac-128@openssh.com @2903 928s 05:26:33.256708340 O: test integrity: umac-128@openssh.com @2904 928s 05:26:33.554100128 O: test integrity: umac-128@openssh.com @2905 928s 05:26:33.855413938 O: test integrity: umac-128@openssh.com @2906 929s 05:26:34.178783270 O: test integrity: umac-128@openssh.com @2907 929s 05:26:34.514991179 O: test integrity: umac-128@openssh.com @2908 929s 05:26:34.831373074 O: test integrity: umac-128@openssh.com @2909 930s 05:26:35.132623780 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 930s 05:26:35.142147670 O: test integrity: hmac-sha1-etm@openssh.com @2900 930s 05:26:35.449915914 O: test integrity: hmac-sha1-etm@openssh.com @2901 930s 05:26:35.773580195 O: test integrity: hmac-sha1-etm@openssh.com @2902 931s 05:26:36.092716161 O: test integrity: hmac-sha1-etm@openssh.com @2903 931s 05:26:36.408782429 O: test integrity: hmac-sha1-etm@openssh.com @2904 931s 05:26:36.725971230 O: test integrity: hmac-sha1-etm@openssh.com @2905 932s 05:26:37.042044933 O: test integrity: hmac-sha1-etm@openssh.com @2906 932s 05:26:37.362159480 O: test integrity: hmac-sha1-etm@openssh.com @2907 932s 05:26:37.676256091 O: test integrity: hmac-sha1-etm@openssh.com @2908 933s 05:26:37.988541186 O: test integrity: hmac-sha1-etm@openssh.com @2909 933s 05:26:38.297349011 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 933s 05:26:38.306531158 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 933s 05:26:38.611609135 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 933s 05:26:38.918730499 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 934s 05:26:39.224277085 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 934s 05:26:39.537993838 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 934s 05:26:39.846488304 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 935s 05:26:40.148939346 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 935s 05:26:40.458839929 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 935s 05:26:40.771541244 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 936s 05:26:41.082637251 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 936s 05:26:41.387973406 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 936s 05:26:41.397217808 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 936s 05:26:41.710430733 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 937s 05:26:42.028899154 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 937s 05:26:42.340002368 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 937s 05:26:42.654842634 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 937s 05:26:42.972882585 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 938s 05:26:43.286512484 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 938s 05:26:43.592943470 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 938s 05:26:43.931657119 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 939s 05:26:44.268534718 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 939s 05:26:44.569115835 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 939s 05:26:44.578478519 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 939s 05:26:44.873128313 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 940s 05:26:45.162896303 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 940s 05:26:45.447569162 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 940s 05:26:45.738355291 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 941s 05:26:46.029814871 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 941s 05:26:46.321174986 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 941s 05:26:46.601435213 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 941s 05:26:46.886832064 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 942s 05:26:47.182781263 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 942s 05:26:47.467756377 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 942s 05:26:47.476629441 O: test integrity: hmac-md5-etm@openssh.com @2900 942s 05:26:47.786890682 O: test integrity: hmac-md5-etm@openssh.com @2901 943s 05:26:48.105148143 O: test integrity: hmac-md5-etm@openssh.com @2902 943s 05:26:48.426662086 O: test integrity: hmac-md5-etm@openssh.com @2903 943s 05:26:48.739687892 O: test integrity: hmac-md5-etm@openssh.com @2904 944s 05:26:49.057761111 O: test integrity: hmac-md5-etm@openssh.com @2905 944s 05:26:49.372374644 O: test integrity: hmac-md5-etm@openssh.com @2906 944s 05:26:49.693711964 O: test integrity: hmac-md5-etm@openssh.com @2907 944s 05:26:50.004195788 O: test integrity: hmac-md5-etm@openssh.com @2908 945s 05:26:50.317665534 O: test integrity: hmac-md5-etm@openssh.com @2909 945s 05:26:50.621213546 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 945s 05:26:50.630137828 O: test integrity: hmac-md5-96-etm@openssh.com @2900 945s 05:26:50.945366848 O: test integrity: hmac-md5-96-etm@openssh.com @2901 946s 05:26:51.260316409 O: test integrity: hmac-md5-96-etm@openssh.com @2902 946s 05:26:51.575020992 O: test integrity: hmac-md5-96-etm@openssh.com @2903 946s 05:26:51.897825577 O: test integrity: hmac-md5-96-etm@openssh.com @2904 947s 05:26:52.210245290 O: test integrity: hmac-md5-96-etm@openssh.com @2905 947s 05:26:52.523426470 O: test integrity: hmac-md5-96-etm@openssh.com @2906 947s 05:26:52.851851246 O: test integrity: hmac-md5-96-etm@openssh.com @2907 948s 05:26:53.177403728 O: test integrity: hmac-md5-96-etm@openssh.com @2908 948s 05:26:53.496301812 O: test integrity: hmac-md5-96-etm@openssh.com @2909 948s 05:26:53.800540910 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 948s 05:26:53.810145714 O: test integrity: umac-64-etm@openssh.com @2900 949s 05:26:54.132026520 O: test integrity: umac-64-etm@openssh.com @2901 949s 05:26:54.445123880 O: test integrity: umac-64-etm@openssh.com @2902 949s 05:26:54.754594884 O: test integrity: umac-64-etm@openssh.com @2903 950s 05:26:55.067077585 O: test integrity: umac-64-etm@openssh.com @2904 950s 05:26:55.390251058 O: test integrity: umac-64-etm@openssh.com @2905 950s 05:26:55.706817072 O: test integrity: umac-64-etm@openssh.com @2906 951s 05:26:56.023562185 O: test integrity: umac-64-etm@openssh.com @2907 951s 05:26:56.334088309 O: test integrity: umac-64-etm@openssh.com @2908 951s 05:26:56.663538456 O: test integrity: umac-64-etm@openssh.com @2909 951s 05:26:56.978392951 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 952s 05:26:56.988156757 O: test integrity: umac-128-etm@openssh.com @2900 952s 05:26:57.305471102 O: test integrity: umac-128-etm@openssh.com @2901 952s 05:26:57.624633055 O: test integrity: umac-128-etm@openssh.com @2902 952s 05:26:57.964478348 O: test integrity: umac-128-etm@openssh.com @2903 953s 05:26:58.280505890 O: test integrity: umac-128-etm@openssh.com @2904 953s 05:26:58.613204323 O: test integrity: umac-128-etm@openssh.com @2905 953s 05:26:58.954763945 O: test integrity: umac-128-etm@openssh.com @2906 954s 05:26:59.294318360 O: test integrity: umac-128-etm@openssh.com @2907 954s 05:26:59.626949168 O: test integrity: umac-128-etm@openssh.com @2908 954s 05:26:59.945540491 O: test integrity: umac-128-etm@openssh.com @2909 955s 05:27:00.255811217 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 955s 05:27:00.264900672 O: test integrity: aes128-gcm@openssh.com @2900 955s 05:27:00.555156115 O: test integrity: aes128-gcm@openssh.com @2901 955s 05:27:00.844107946 O: test integrity: aes128-gcm@openssh.com @2902 956s 05:27:01.142362841 O: test integrity: aes128-gcm@openssh.com @2903 956s 05:27:01.431391776 O: test integrity: aes128-gcm@openssh.com @2904 956s 05:27:01.732685592 O: test integrity: aes128-gcm@openssh.com @2905 957s 05:27:02.022446558 O: test integrity: aes128-gcm@openssh.com @2906 957s 05:27:02.310626834 O: test integrity: aes128-gcm@openssh.com @2907 957s 05:27:02.597403590 O: test integrity: aes128-gcm@openssh.com @2908 957s 05:27:02.882010447 O: test integrity: aes128-gcm@openssh.com @2909 958s 05:27:03.159956681 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 958s 05:27:03.169362242 O: test integrity: aes256-gcm@openssh.com @2900 958s 05:27:03.455326470 O: test integrity: aes256-gcm@openssh.com @2901 958s 05:27:03.740313916 O: test integrity: aes256-gcm@openssh.com @2902 959s 05:27:04.034609036 O: test integrity: aes256-gcm@openssh.com @2903 959s 05:27:04.321438016 O: test integrity: aes256-gcm@openssh.com @2904 959s 05:27:04.605736228 O: test integrity: aes256-gcm@openssh.com @2905 959s 05:27:04.891499227 O: test integrity: aes256-gcm@openssh.com @2906 960s 05:27:05.172877020 O: test integrity: aes256-gcm@openssh.com @2907 960s 05:27:05.452366849 O: test integrity: aes256-gcm@openssh.com @2908 960s 05:27:05.733720037 O: test integrity: aes256-gcm@openssh.com @2909 961s 05:27:06.017112877 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 961s 05:27:06.026841098 O: test integrity: chacha20-poly1305@openssh.com @2900 961s 05:27:06.313650978 O: test integrity: chacha20-poly1305@openssh.com @2901 961s 05:27:06.595418504 O: test integrity: chacha20-poly1305@openssh.com @2902 961s 05:27:06.880478955 O: test integrity: chacha20-poly1305@openssh.com @2903 962s 05:27:07.165565771 O: test integrity: chacha20-poly1305@openssh.com @2904 962s 05:27:07.445442855 O: test integrity: chacha20-poly1305@openssh.com @2905 962s 05:27:07.725642767 O: test integrity: chacha20-poly1305@openssh.com @2906 963s 05:27:08.009279385 O: test integrity: chacha20-poly1305@openssh.com @2907 963s 05:27:08.314027034 O: test integrity: chacha20-poly1305@openssh.com @2908 963s 05:27:08.603860890 O: test integrity: chacha20-poly1305@openssh.com @2909 963s 05:27:08.886038925 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 963s 05:27:08.887262321 E: run test krl.sh ... 963s 05:27:08.887838212 O: ok integrity 964s 05:27:09.041762355 O: key revocation lists: generating test keys 964s 05:27:09.458425644 O: key revocation lists: generating KRLs 964s 05:27:09.588904989 O: key revocation lists: checking revocations for revoked keys 965s 05:27:10.061283176 O: key revocation lists: checking revocations for unrevoked keys 965s 05:27:10.448515727 O: key revocation lists: checking revocations for revoked certs 966s 05:27:11.330230444 O: key revocation lists: checking revocations for unrevoked certs 967s 05:27:12.046772007 O: key revocation lists: testing KRL update 968s 05:27:13.150798940 O: key revocation lists: checking revocations for revoked keys 968s 05:27:13.731002135 O: key revocation lists: checking revocations for unrevoked keys 969s 05:27:14.131457047 O: key revocation lists: checking revocations for revoked certs 969s 05:27:14.901093326 O: key revocation lists: checking revocations for unrevoked certs 970s 05:27:15.670465681 E: run test multipubkey.sh ... 970s 05:27:15.671214748 O: ok key revocation lists 973s 05:27:18.530714757 E: run test limit-keytype.sh ... 973s 05:27:18.531223485 O: ok multiple pubkey 975s 05:27:20.476984668 O: allow rsa,ed25519 976s 05:27:21.411118673 O: allow ed25519 977s 05:27:22.266415313 O: allow cert only 978s 05:27:23.181651833 O: match w/ no match 979s 05:27:24.049116011 O: match w/ matching 979s 05:27:24.939058076 O: ok restrict pubkey type 979s 05:27:24.939695372 E: run test hostkey-agent.sh ... 980s 05:27:25.334677855 O: key type ssh-ed25519 980s 05:27:25.479439839 O: key type sk-ssh-ed25519@openssh.com 980s 05:27:25.629232100 O: key type ecdsa-sha2-nistp256 980s 05:27:25.778691072 O: key type ecdsa-sha2-nistp384 980s 05:27:25.958867546 O: key type ecdsa-sha2-nistp521 981s 05:27:26.164862086 O: key type sk-ecdsa-sha2-nistp256@openssh.com 981s 05:27:26.309050824 O: key type ssh-rsa 981s 05:27:26.466836181 O: cert type ssh-ed25519-cert-v01@openssh.com 981s 05:27:26.704076218 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 981s 05:27:26.930649519 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 982s 05:27:27.169237198 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 982s 05:27:27.415053222 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 982s 05:27:27.688040825 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 982s 05:27:27.931136313 O: cert type ssh-rsa-cert-v01@openssh.com 983s 05:27:28.167322446 O: cert type rsa-sha2-256-cert-v01@openssh.com 983s 05:27:28.400523412 O: cert type rsa-sha2-512-cert-v01@openssh.com 983s 05:27:28.634934303 O: ok hostkey agent 983s 05:27:28.635552936 E: run test hostkey-rotate.sh ... 984s 05:27:29.326911075 O: learn hostkey with StrictHostKeyChecking=no 984s 05:27:29.644709662 O: learn additional hostkeys 984s 05:27:29.960630530 O: learn additional hostkeys, type=ssh-ed25519 985s 05:27:30.261172357 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 985s 05:27:30.596863246 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 985s 05:27:30.907690972 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 986s 05:27:31.239384063 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 986s 05:27:31.558977508 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 986s 05:27:31.861469941 O: learn additional hostkeys, type=ssh-rsa 987s 05:27:32.164725399 O: learn changed non-primary hostkey type=ssh-rsa 988s 05:27:33.280042353 O: learn new primary hostkey 988s 05:27:33.601997744 O: rotate primary hostkey 988s 05:27:33.933679887 O: check rotate primary hostkey 989s 05:27:34.259145078 O: ok hostkey rotate 989s 05:27:34.259872952 E: run test principals-command.sh ... 990s 05:27:34.989614205 O: SKIPPED: /var/run/principals_command_openssh-tests.58969 not executable (/var/run mounted noexec?) 990s 05:27:34.994295918 E: run test cert-file.sh ... 990s 05:27:35.181065965 O: identity cert with no plain public file 990s 05:27:35.488548472 O: CertificateFile with no plain public file 990s 05:27:35.795603279 O: plain keys 991s 05:27:36.124341517 O: untrusted cert 991s 05:27:36.443428380 O: good cert, bad key 991s 05:27:36.789881758 O: single trusted 992s 05:27:37.100223157 O: multiple trusted 993s 05:27:38.404480201 O: ok ssh with certificates 993s 05:27:38.405468727 E: run test cfginclude.sh ... 993s 05:27:38.584601601 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.591377638 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.597451331 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.603865404 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.610314168 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.616046316 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.623287209 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.629658389 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.635968506 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.659726373 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.669366048 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.675752838 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.690718238 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.703418493 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.715344684 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.728216017 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.739509224 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.747320289 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.753133864 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.758569991 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.764455568 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.776784118 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.782177284 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.787040376 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.796017958 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.801982505 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 993s 05:27:38.804809568 O: ok config include 993s 05:27:38.805331836 E: run test servcfginclude.sh ... 994s 05:27:39.077933200 O: ok server config include 994s 05:27:39.078669034 E: run test allow-deny-users.sh ... 996s 05:27:41.712938588 O: ok AllowUsers/DenyUsers 996s 05:27:41.713591277 E: run test authinfo.sh ... 996s 05:27:41.856259726 O: ExposeAuthInfo=no 997s 05:27:42.170202093 O: ExposeAuthInfo=yes 997s 05:27:42.473001248 E: run test sshsig.sh ... 997s 05:27:42.472498313 O: ok authinfo 997s 05:27:42.623075262 O: sshsig: make certificates 997s 05:27:42.662449303 O: sshsig: check signature for ssh-ed25519 997s 05:27:42.886279907 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 998s 05:27:43.126424081 O: sshsig: check signature for ecdsa-sha2-nistp256 998s 05:27:43.481436768 O: sshsig: check signature for ecdsa-sha2-nistp384 999s 05:27:44.042988353 O: sshsig: check signature for ecdsa-sha2-nistp521 999s 05:27:44.932455955 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 1000s 05:27:45.361650984 O: sshsig: check signature for ssh-rsa 1000s 05:27:45.612448898 O: sshsig: check signature for ssh-ed25519-cert.pub 1001s 05:27:46.154424259 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 1001s 05:27:46.721814375 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 1002s 05:27:47.400747853 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 1003s 05:27:48.366379288 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 1004s 05:27:49.706023501 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1005s 05:27:50.475325185 O: sshsig: check signature for ssh-rsa-cert.pub 1006s 05:27:51.015152285 O: sshsig: match principals 1006s 05:27:51.033243246 O: sshsig: nomatch principals 1006s 05:27:51.045395299 O: ok sshsig 1006s 05:27:51.045933072 E: run test knownhosts.sh ... 1007s 05:27:52.923810271 O: ok known hosts 1007s 05:27:52.924392142 E: run test knownhosts-command.sh ... 1008s 05:27:53.061423816 O: simple connection 1008s 05:27:53.335093817 O: no keys 1008s 05:27:53.537903942 O: bad exit status 1008s 05:27:53.681793304 O: keytype ssh-ed25519 1008s 05:27:53.932204257 O: keytype sk-ssh-ed25519@openssh.com 1009s 05:27:54.178003807 O: keytype ecdsa-sha2-nistp256 1009s 05:27:54.423598792 O: keytype ecdsa-sha2-nistp384 1009s 05:27:54.679867916 O: keytype ecdsa-sha2-nistp521 1009s 05:27:54.964022949 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1010s 05:27:55.225402219 O: keytype ssh-rsa 1010s 05:27:55.484083007 O: ok known hosts command 1010s 05:27:55.484741179 E: run test agent-restrict.sh ... 1010s 05:27:55.629511245 O: generate keys 1010s 05:27:55.692251476 O: prepare client config 1010s 05:27:55.700716529 O: prepare known_hosts 1010s 05:27:55.704214827 O: prepare server configs 1010s 05:27:55.715394969 O: authentication w/o agent 1012s 05:27:57.190823334 O: start agent 1016s 05:28:01.201521383 O: authentication with agent (no restrict) 1017s 05:28:02.682956045 O: unrestricted keylist 1018s 05:28:03.444385196 O: authentication with agent (basic restrict) 1019s 05:28:04.181389034 O: authentication with agent incorrect key (basic restrict) 1019s 05:28:04.746781353 O: keylist (basic restrict) 1020s 05:28:05.512872409 O: username 1021s 05:28:06.255800683 O: username wildcard 1022s 05:28:06.994692430 O: username incorrect 1022s 05:28:07.047093841 O: agent restriction honours certificate principal 1022s 05:28:07.070287461 O: multihop without agent 1022s 05:28:07.901651179 O: multihop agent unrestricted 1023s 05:28:08.739422032 O: multihop restricted 1024s 05:28:09.590166004 O: multihop username 1025s 05:28:10.431826810 O: multihop wildcard username 1026s 05:28:11.271514772 O: multihop wrong username 1026s 05:28:11.891323469 O: multihop cycle no agent 1028s 05:28:13.126049978 O: multihop cycle agent unrestricted 1029s 05:28:14.371817916 O: multihop cycle restricted deny 1029s 05:28:14.829323401 O: multihop cycle restricted allow 1031s 05:28:16.073305871 O: ok agent restrictions 1031s 05:28:16.073825803 E: run test hostbased.sh ... 1031s 05:28:16.206464445 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1031s 05:28:16.206945964 E: run test channel-timeout.sh ... 1031s 05:28:16.342402751 O: no timeout 1036s 05:28:21.641185063 O: command timeout 1037s 05:28:22.893961564 O: command long timeout 1038s 05:28:23.195191056 O: command wildcard timeout 1038s 05:28:23.893617386 O: command irrelevant timeout 1044s 05:28:29.196272885 O: multiplexed command timeout 1054s 05:28:39.914097436 O: irrelevant multiplexed command timeout 1070s 05:28:55.292249432 O: global command timeout 1092s 05:29:16.924655466 O: sftp no timeout 1097s 05:29:22.247383099 O: sftp timeout 1097s 05:29:22.893181437 E: Connection closed 1097s 05:29:22.894848523 O: sftp irrelevant timeout 1103s 05:29:28.218528496 O: ok channel timeout 1103s 05:29:28.219226400 E: run test connection-timeout.sh ... 1103s 05:29:28.357914667 O: no timeout 1109s 05:29:34.658762739 O: timeout 1117s 05:29:42.970730777 O: session inhibits timeout 1126s 05:29:51.291901038 O: timeout after session 1134s 05:29:59.296935165 O: timeout with listeners 1142s 05:30:07.633973348 O: ok unused connection timeout 1142s 05:30:07.634666851 E: run test match-subsystem.sh ... 1145s 05:30:10.895068789 O: ok sshd_config match subsystem 1145s 05:30:10.895634971 E: run test agent-pkcs11-restrict.sh ... 1146s 05:30:11.025332299 O: SKIPPED: No PKCS#11 library found 1146s 05:30:11.025949338 E: run test agent-pkcs11-cert.sh ... 1146s 05:30:11.154690790 O: SKIPPED: No PKCS#11 library found 1146s 05:30:11.155484801 E: run test penalty.sh ... 1146s 05:30:11.418957413 O: test connect 1146s 05:30:11.733850024 O: penalty for authentication failure 1154s 05:30:19.205113718 O: penalty for no authentication 1156s 05:30:21.343938344 O: ok penalties 1156s 05:30:21.344641046 E: run test penalty-expire.sh ... 1156s 05:30:21.619682247 O: test connect 1156s 05:30:21.944130053 O: penalty expiry 1170s 05:30:35.613350363 O: ok penalties 1170s 05:30:35.613916520 O: set -e ; if test -z "" ; then \ 1170s 05:30:35.614376827 O: V="" ; \ 1170s 05:30:35.614856341 O: test "x" = "x" || \ 1170s 05:30:35.615337392 O: V=/tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1170s 05:30:35.615807324 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1170s 05:30:35.616277334 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1170s 05:30:35.616756649 O: -d /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1170s 05:30:35.617232665 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1170s 05:30:35.617702741 O: -d /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1170s 05:30:35.618169967 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1170s 05:30:35.618639127 O: -d /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1170s 05:30:35.619107238 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1170s 05:30:35.619579329 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1170s 05:30:35.620053729 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1170s 05:30:35.620525246 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1170s 05:30:35.621001322 O: -d /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1170s 05:30:35.621463640 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1170s 05:30:35.621943519 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1170s 05:30:35.622404078 O: if test "x" = "xyes" ; then \ 1170s 05:30:35.622870669 O: $V /tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1170s 05:30:35.623338897 O: fi \ 1170s 05:30:35.623796897 O: fi 1186s 05:30:51.819267464 O: test_sshbuf: ...................................................................................................... 103 tests ok 1280s 05:32:25.353438909 O: test_sshkey: .................................................................................... 84 tests ok 1280s 05:32:25.372750711 O: test_sshsig: ....... 7 tests ok 1280s 05:32:25.622572302 O: test_authopt: .................................................................................................................................................. 146 tests ok 1295s 05:32:40.196804793 O: test_bitmap: .. 2 tests ok 1295s 05:32:40.199604088 O: test_conversion: . 1 tests ok 1304s 05:32:49.883968043 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1305s 05:32:50.335209495 O: test_hostkeys: .................. 18 tests ok 1305s 05:32:50.338075346 O: test_match: ...... 6 tests ok 1305s 05:32:50.340599600 O: test_misc: ........................................... 43 tests ok 1305s 05:32:50.342330893 E: run test putty-transfer.sh ... 1307s 05:32:52.556438410 O: plink version 0.83 major 0 minor 83 1307s 05:32:52.562174750 O: putty transfer data: compression 0 1309s 05:32:54.850804809 O: putty transfer data: compression 1 1312s 05:32:57.091152348 O: ok putty transfer data 1312s 05:32:57.091824969 E: run test putty-ciphers.sh ... 1314s 05:32:59.659054177 O: plink version 0.83 major 0 minor 83 1314s 05:32:59.909655544 O: putty ciphers: cipher default mac default 1315s 05:33:00.183519193 O: putty ciphers: cipher default mac hmac-sha1 1315s 05:33:00.485011256 O: putty ciphers: cipher default mac hmac-sha1-96 1315s 05:33:00.782891690 O: putty ciphers: cipher default mac hmac-sha2-256 1316s 05:33:01.057432627 O: putty ciphers: cipher default mac hmac-sha2-512 1316s 05:33:01.331718259 O: putty ciphers: cipher default mac hmac-md5 1316s 05:33:01.601074299 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1316s 05:33:01.871342219 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1317s 05:33:02.146717289 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1317s 05:33:02.426221501 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1317s 05:33:02.700453165 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1317s 05:33:02.971887484 O: putty ciphers: cipher 3des-cbc mac default 1318s 05:33:03.245469357 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1318s 05:33:03.529824560 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1318s 05:33:03.802122097 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1319s 05:33:04.077684940 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1319s 05:33:04.359183682 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1319s 05:33:04.628445077 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1319s 05:33:04.898530458 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1320s 05:33:05.171750151 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1320s 05:33:05.448867996 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1320s 05:33:05.725047500 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1321s 05:33:05.996959831 O: putty ciphers: cipher aes128-cbc mac default 1321s 05:33:06.275296071 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1321s 05:33:06.553520605 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1321s 05:33:06.830316319 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1322s 05:33:07.109702486 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1322s 05:33:07.388161952 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1322s 05:33:07.656566282 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1322s 05:33:07.938136252 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1323s 05:33:08.224889283 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1323s 05:33:08.497803608 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1323s 05:33:08.778077990 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1324s 05:33:09.053676272 O: putty ciphers: cipher aes192-cbc mac default 1324s 05:33:09.329872233 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1324s 05:33:09.602535888 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1324s 05:33:09.893581047 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1325s 05:33:10.175094687 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1325s 05:33:10.453908636 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1325s 05:33:10.721951980 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1326s 05:33:10.990165292 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1326s 05:33:11.270291298 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1326s 05:33:11.544953060 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1326s 05:33:11.816889485 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1327s 05:33:12.098272256 O: putty ciphers: cipher aes256-cbc mac default 1327s 05:33:12.380724032 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1327s 05:33:12.652597778 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1327s 05:33:12.921688209 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1328s 05:33:13.193400755 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1328s 05:33:13.465068100 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1328s 05:33:13.734991356 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1329s 05:33:14.002630700 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1329s 05:33:14.276430353 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1329s 05:33:14.555032922 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1329s 05:33:14.825534620 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1330s 05:33:15.100245131 O: putty ciphers: cipher aes128-ctr mac default 1330s 05:33:15.387214584 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1330s 05:33:15.660724006 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1331s 05:33:15.946145922 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1331s 05:33:16.222772370 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1331s 05:33:16.496426858 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1331s 05:33:16.767629091 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1332s 05:33:17.031668341 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1332s 05:33:17.302813782 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1332s 05:33:17.573138061 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1332s 05:33:17.845498882 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1333s 05:33:18.115912000 O: putty ciphers: cipher aes192-ctr mac default 1333s 05:33:18.385918916 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1333s 05:33:18.653474017 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1333s 05:33:18.926043102 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1334s 05:33:19.203687315 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1334s 05:33:19.476337773 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1334s 05:33:19.744005192 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1335s 05:33:20.011653806 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1335s 05:33:20.287702731 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1335s 05:33:20.559413065 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1335s 05:33:20.831291354 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1336s 05:33:21.099526600 O: putty ciphers: cipher aes256-ctr mac default 1336s 05:33:21.375293714 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1336s 05:33:21.648707103 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1336s 05:33:21.915532671 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1337s 05:33:22.192864730 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1337s 05:33:22.470660592 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1337s 05:33:22.747434118 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1338s 05:33:23.043227145 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1338s 05:33:23.326898669 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1338s 05:33:23.603372673 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1338s 05:33:23.875710228 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1339s 05:33:24.147514307 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1339s 05:33:24.419402761 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1339s 05:33:24.693919568 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1339s 05:33:24.960433070 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1340s 05:33:25.231316251 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1340s 05:33:25.502477012 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1340s 05:33:25.770447004 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1341s 05:33:26.037689174 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1341s 05:33:26.311055598 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1341s 05:33:26.584555517 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1341s 05:33:26.858354092 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1342s 05:33:27.134976284 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1342s 05:33:27.408535349 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1342s 05:33:27.683661714 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1342s 05:33:27.963327247 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1343s 05:33:28.238990578 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1343s 05:33:28.517504298 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1343s 05:33:28.786911260 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1344s 05:33:29.061990528 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1344s 05:33:29.336698888 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1344s 05:33:29.610755229 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1344s 05:33:29.888089016 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1345s 05:33:30.162045922 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1345s 05:33:30.438513041 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1345s 05:33:30.717217024 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1346s 05:33:30.998048198 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1346s 05:33:31.276654790 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1346s 05:33:31.551289552 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1346s 05:33:31.823744939 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1347s 05:33:32.094799476 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1347s 05:33:32.380957278 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1347s 05:33:32.654989500 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1347s 05:33:32.927774561 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1348s 05:33:33.202899950 O: ok putty ciphers 1348s 05:33:33.203610701 E: run test putty-kex.sh ... 1349s 05:33:33.981704586 O: plink version 0.83 major 0 minor 83 1349s 05:33:34.123597308 O: putty KEX: kex diffie-hellman-group1-sha1 1349s 05:33:34.355310888 O: putty KEX: kex diffie-hellman-group14-sha1 1349s 05:33:34.723416642 O: putty KEX: kex diffie-hellman-group14-sha256 1350s 05:33:35.091137811 O: putty KEX: kex diffie-hellman-group16-sha512 1351s 05:33:36.356169569 O: putty KEX: kex diffie-hellman-group18-sha512 1359s 05:33:44.130814609 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1359s 05:33:44.530960871 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1360s 05:33:45.884763930 O: putty KEX: kex ecdh-sha2-nistp256 1361s 05:33:46.106249970 O: putty KEX: kex ecdh-sha2-nistp384 1361s 05:33:46.356177370 O: putty KEX: kex ecdh-sha2-nistp521 1361s 05:33:46.661764600 O: putty KEX: kex curve25519-sha256 1361s 05:33:46.880685650 O: putty KEX: kex curve25519-sha256@libssh.org 1362s 05:33:47.100481168 O: putty KEX: kex sntrup761x25519-sha512 1362s 05:33:47.358746319 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1362s 05:33:47.616280796 O: putty KEX: kex mlkem768x25519-sha256 1362s 05:33:47.844019627 O: ok putty KEX 1362s 05:33:47.844728882 E: run test conch-ciphers.sh ... 1363s 05:33:48.113772459 O: conch ciphers: cipher aes256-ctr 1363s 05:33:48.693117652 O: conch ciphers: cipher aes256-cbc 1364s 05:33:49.258179252 O: conch ciphers: cipher aes192-ctr 1364s 05:33:49.835156070 O: conch ciphers: cipher aes192-cbc 1365s 05:33:50.395526400 O: conch ciphers: cipher aes128-ctr 1366s 05:33:50.975183206 O: conch ciphers: cipher aes128-cbc 1366s 05:33:51.571640358 O: conch ciphers: cipher cast128-cbc 1367s 05:33:52.172900454 O: conch ciphers: cipher blowfish 1367s 05:33:52.752963182 O: conch ciphers: cipher 3des-cbc 1368s 05:33:53.351543313 O: ok conch ciphers 1368s 05:33:53.352422737 E: run test dropbear-ciphers.sh ... 1368s 05:33:53.441872811 O: Create dropbear key type ed25519 1368s 05:33:53.454815319 O: Create dropbear key type rsa 1368s 05:33:53.881803888 O: Create dropbear key type ecdsa 1369s 05:33:53.976915866 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 1369s 05:33:54.273968558 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 1369s 05:33:54.519154000 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 1369s 05:33:54.777071176 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1370s 05:33:55.074025227 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1370s 05:33:55.327375223 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1370s 05:33:55.587595134 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 1370s 05:33:55.887520605 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 1371s 05:33:56.138099420 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 1371s 05:33:56.394731592 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1371s 05:33:56.690019487 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1371s 05:33:56.942000537 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1372s 05:33:57.205293924 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 1372s 05:33:57.503643444 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 1372s 05:33:57.755714386 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 1373s 05:33:58.011709408 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1373s 05:33:58.306222559 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1373s 05:33:58.550246592 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1373s 05:33:58.802670755 O: ok dropbear ciphers 1373s 05:33:58.803165959 E: run test dropbear-kex.sh ... 1373s 05:33:58.973772563 O: diffie-hellman-group14-sha1 1373s 05:33:58.974896893 O: dropbear kex: kex curve25519-sha256 1374s 05:33:59.235203365 O: dropbear kex: kex curve25519-sha256@libssh.org 1374s 05:33:59.482916928 O: dropbear kex: kex diffie-hellman-group14-sha256 1374s 05:33:59.787693428 O: dropbear kex: kex diffie-hellman-group14-sha1 1375s 05:34:00.063656033 O: ok dropbear kex 1375s 05:34:00.064328224 O: make: Leaving directory '/tmp/autopkgtest.V1KQl1/autopkgtest_tmp/user/regress' 1375s 05:34:00.065143217 I: Finished with exitcode 0 1375s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1375s info: Looking for files to backup/remove ... 1375s info: Removing files ... 1375s info: Removing crontab ... 1375s info: Removing user `openssh-tests' ... 1375s autopkgtest [05:34:00]: test regress: -----------------------] 1376s regress PASS 1376s autopkgtest [05:34:01]: test regress: - - - - - - - - - - results - - - - - - - - - - 1376s autopkgtest [05:34:01]: test ssh-gssapi: preparing testbed 1449s autopkgtest [05:35:14]: testbed dpkg architecture: s390x 1449s autopkgtest [05:35:14]: testbed apt version: 2.9.30ubuntu1 1450s autopkgtest [05:35:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1450s autopkgtest [05:35:15]: testbed release detected to be: plucky 1451s autopkgtest [05:35:16]: updating testbed package index (apt update) 1451s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1451s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1451s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1451s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1451s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.1 kB] 1451s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [508 kB] 1452s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1452s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1452s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [123 kB] 1452s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 1452s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [423 kB] 1452s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 1452s Fetched 1265 kB in 1s (1064 kB/s) 1453s Reading package lists... 1453s Reading package lists... 1453s Building dependency tree... 1453s Reading state information... 1453s Calculating upgrade... 1453s Calculating upgrade... 1454s The following packages were automatically installed and are no longer required: 1454s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 1454s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 1454s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 1454s linux-tools-6.11.0-8-generic 1454s Use 'sudo apt autoremove' to remove them. 1454s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1454s Reading package lists... 1454s Building dependency tree... 1454s Reading state information... 1454s Solving dependencies... 1454s The following packages will be REMOVED: 1454s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 1454s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 1454s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 1454s linux-tools-6.11.0-8-generic* 1454s 0 upgraded, 0 newly installed, 9 to remove and 3 not upgraded. 1454s After this operation, 167 MB disk space will be freed. 1454s (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.) 1454s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 1454s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 1454s Removing libpython3.12t64:s390x (3.12.9-1) ... 1454s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 1454s Removing libnsl2:s390x (1.3.0-3build3) ... 1454s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 1454s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 1454s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 1455s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1455s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1455s (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.) 1455s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 1455s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1456s autopkgtest [05:35:21]: upgrading testbed (apt dist-upgrade and autopurge) 1456s Reading package lists... 1456s Building dependency tree... 1456s Reading state information... 1456s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1456s Starting 2 pkgProblemResolver with broken count: 0 1456s Done 1456s Entering ResolveByKeep 1456s 1456s Calculating upgrade... 1457s The following packages will be upgraded: 1457s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 1457s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1457s Need to get 1819 kB of archives. 1457s After this operation, 1024 B disk space will be freed. 1457s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x gir1.2-glib-2.0 s390x 2.83.4-1 [182 kB] 1457s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-0t64 s390x 2.83.4-1 [1584 kB] 1458s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-data all 2.83.4-1 [52.9 kB] 1458s Fetched 1819 kB in 1s (1774 kB/s) 1458s (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.) 1458s Preparing to unpack .../gir1.2-glib-2.0_2.83.4-1_s390x.deb ... 1458s Unpacking gir1.2-glib-2.0:s390x (2.83.4-1) over (2.83.3-2) ... 1458s Preparing to unpack .../libglib2.0-0t64_2.83.4-1_s390x.deb ... 1458s Unpacking libglib2.0-0t64:s390x (2.83.4-1) over (2.83.3-2) ... 1458s Preparing to unpack .../libglib2.0-data_2.83.4-1_all.deb ... 1458s Unpacking libglib2.0-data (2.83.4-1) over (2.83.3-2) ... 1458s Setting up libglib2.0-0t64:s390x (2.83.4-1) ... 1458s No schema files found: doing nothing. 1458s Setting up libglib2.0-data (2.83.4-1) ... 1458s Setting up gir1.2-glib-2.0:s390x (2.83.4-1) ... 1458s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1458s Reading package lists... 1458s Building dependency tree... 1458s Reading state information... 1459s Starting pkgProblemResolver with broken count: 0 1459s Starting 2 pkgProblemResolver with broken count: 0 1459s Done 1459s Solving dependencies... 1459s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1459s autopkgtest [05:35:24]: rebooting testbed after setup commands that affected boot 1480s Reading package lists... 1480s Building dependency tree... 1480s Reading state information... 1480s Starting pkgProblemResolver with broken count: 0 1480s Starting 2 pkgProblemResolver with broken count: 0 1480s Done 1480s The following NEW packages will be installed: 1480s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1480s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1480s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1480s openssh-server-gssapi 1481s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1481s Need to get 796 kB of archives. 1481s After this operation, 2750 kB of additional disk space will be used. 1481s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-config all 2.7 [22.0 kB] 1481s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-4ubuntu1 [59.1 kB] 1481s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-4ubuntu1 [40.5 kB] 1481s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-4ubuntu1 [41.9 kB] 1481s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-4ubuntu1 [55.6 kB] 1481s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-user s390x 1.21.3-4ubuntu1 [110 kB] 1481s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 1481s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 1481s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 1481s Get:10 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-kdc s390x 1.21.3-4ubuntu1 [198 kB] 1481s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-admin-server s390x 1.21.3-4ubuntu1 [95.7 kB] 1481s Get:12 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-client-gssapi all 1:9.9p1-3ubuntu2 [5028 B] 1481s Get:13 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-server-gssapi all 1:9.9p1-3ubuntu2 [5036 B] 1481s Preconfiguring packages ... 1481s Fetched 796 kB in 1s (1192 kB/s) 1481s Selecting previously unselected package krb5-config. 1481s (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.) 1481s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1481s Unpacking krb5-config (2.7) ... 1482s Selecting previously unselected package libgssrpc4t64:s390x. 1482s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu1_s390x.deb ... 1482s Unpacking libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 1482s Selecting previously unselected package libkadm5clnt-mit12:s390x. 1482s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu1_s390x.deb ... 1482s Unpacking libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 1482s Selecting previously unselected package libkdb5-10t64:s390x. 1482s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu1_s390x.deb ... 1482s Unpacking libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 1482s Selecting previously unselected package libkadm5srv-mit12:s390x. 1482s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu1_s390x.deb ... 1482s Unpacking libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 1482s Selecting previously unselected package krb5-user. 1482s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu1_s390x.deb ... 1482s Unpacking krb5-user (1.21.3-4ubuntu1) ... 1482s Selecting previously unselected package libevent-2.1-7t64:s390x. 1482s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 1482s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1482s Selecting previously unselected package libverto1t64:s390x. 1482s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1482s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1482s Selecting previously unselected package libverto-libevent1t64:s390x. 1482s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1482s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1482s Selecting previously unselected package krb5-kdc. 1482s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu1_s390x.deb ... 1482s Unpacking krb5-kdc (1.21.3-4ubuntu1) ... 1482s Selecting previously unselected package krb5-admin-server. 1482s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu1_s390x.deb ... 1482s Unpacking krb5-admin-server (1.21.3-4ubuntu1) ... 1482s Selecting previously unselected package openssh-client-gssapi. 1482s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 1482s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 1482s Selecting previously unselected package openssh-server-gssapi. 1482s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 1482s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1482s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1482s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 1482s Setting up libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 1482s Setting up krb5-config (2.7) ... 1482s Setting up libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 1482s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1482s Setting up libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 1482s Setting up libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 1482s Setting up krb5-user (1.21.3-4ubuntu1) ... 1482s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1482s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1482s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1482s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1482s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1482s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1482s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1482s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1482s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1482s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1482s Setting up krb5-kdc (1.21.3-4ubuntu1) ... 1482s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1482s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1482s Setting up krb5-admin-server (1.21.3-4ubuntu1) ... 1483s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1483s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1483s Processing triggers for man-db (2.13.0-1) ... 1489s autopkgtest [05:35:54]: test ssh-gssapi: [----------------------- 1489s Generating public/private ed25519 key pair. 1489s Your identification has been saved in /root/.ssh/id_ed25519 1489s Your public key has been saved in /root/.ssh/id_ed25519.pub 1489s The key fingerprint is: 1489s SHA256:UV7w1+GOJGlIKLS9YIgsNnmQ03qNMmHYxjWNC8UaHeE root@autopkgtest 1489s The key's randomart image is: 1489s +--[ED25519 256]--+ 1489s |.+o=B* ..o.. . | 1489s |o=Xo=o+..o.o. ...| 1489s |oB+*E=.....+.....| 1489s |o++oo.. ... o.o | 1489s | + .S . . | 1489s | | 1489s | | 1489s | | 1489s | | 1489s +----[SHA256]-----+ 1489s ## Setting up test environment 1489s ## Creating Kerberos realm EXAMPLE.FAKE 1489s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1489s master key name 'K/M@EXAMPLE.FAKE' 1489s ## Creating principals 1489s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1489s Principal "testuser1696@EXAMPLE.FAKE" created. 1489s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1489s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1489s ## Extracting service principal host/sshd-gssapi.example.fake 1489s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1489s 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. 1489s 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. 1489s ## Adjusting /etc/krb5.conf 1489s ## TESTS 1489s 1489s ## TEST test_gssapi_login 1489s ## Configuring sshd for gssapi-with-mic authentication 1489s ## Restarting ssh 1489s ## Obtaining TGT 1489s Password for testuser1696@EXAMPLE.FAKE: 1489s Ticket cache: FILE:/tmp/krb5cc_0 1489s Default principal: testuser1696@EXAMPLE.FAKE 1489s 1489s Valid starting Expires Service principal 1489s 02/22/25 05:37:20 02/22/25 15:37:20 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1489s renew until 02/23/25 05:37:20 1489s 1489s ## ssh'ing into localhost using gssapi-with-mic auth 1490s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1490s Sat Feb 22 05:37:20 UTC 2025 1490s 1490s ## checking that we got a service ticket for ssh (host/) 1490s 02/22/25 05:37:20 02/22/25 15:37:20 host/sshd-gssapi.example.fake@ 1490s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1490s 1490s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1490s Feb 22 05:37:20 sshd-gssapi.example.fake sshd-session[1799]: Accepted gssapi-with-mic for testuser1696 from 127.0.0.1 port 50422 ssh2: testuser1696@EXAMPLE.FAKE 1490s ## PASS test_gssapi_login 1490s 1490s ## TEST test_gssapi_keyex_login 1490s ## Configuring sshd for gssapi-keyex authentication 1490s ## Restarting ssh 1490s ## Obtaining TGT 1490s Password for testuser1696@EXAMPLE.FAKE: 1490s Ticket cache: FILE:/tmp/krb5cc_0 1490s Default principal: testuser1696@EXAMPLE.FAKE 1490s 1490s Valid starting Expires Service principal 1490s 02/22/25 05:37:21 02/22/25 15:37:21 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1490s renew until 02/23/25 05:37:21 1490s 1490s ## ssh'ing into localhost using gssapi-keyex auth 1490s Sat Feb 22 05:37:21 UTC 2025 1490s 1490s ## checking that we got a service ticket for ssh (host/) 1490s 02/22/25 05:37:21 02/22/25 15:37:21 host/sshd-gssapi.example.fake@ 1490s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1490s 1490s ## Checking ssh logs to confirm gssapi-keyex auth was used 1490s Feb 22 05:37:21 sshd-gssapi.example.fake sshd-session[1854]: Accepted gssapi-keyex for testuser1696 from 127.0.0.1 port 50432 ssh2: testuser1696@EXAMPLE.FAKE 1490s ## PASS test_gssapi_keyex_login 1490s 1490s ## TEST test_gssapi_keyex_pubkey_fallback 1490s ## Configuring sshd for gssapi-keyex authentication 1490s ## Restarting ssh 1490s ## Obtaining TGT 1490s Password for testuser1696@EXAMPLE.FAKE: 1490s Ticket cache: FILE:/tmp/krb5cc_0 1490s Default principal: testuser1696@EXAMPLE.FAKE 1490s 1490s Valid starting Expires Service principal 1490s 02/22/25 05:37:21 02/22/25 15:37:21 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1490s renew until 02/23/25 05:37:21 1490s 1490s ## ssh'ing into localhost using gssapi-keyex auth 1491s Sat Feb 22 05:37:21 UTC 2025 1491s 1491s ## checking that we got a service ticket for ssh (host/) 1491s 02/22/25 05:37:21 02/22/25 15:37:21 host/sshd-gssapi.example.fake@ 1491s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1491s 1491s ## Checking ssh logs to confirm publickey auth was used 1491s Feb 22 05:37:21 sshd-gssapi.example.fake sshd-session[1895]: Accepted publickey for testuser1696-2 from 127.0.0.1 port 50444 ssh2: ED25519 SHA256:UV7w1+GOJGlIKLS9YIgsNnmQ03qNMmHYxjWNC8UaHeE 1491s ## PASS test_gssapi_keyex_pubkey_fallback 1491s 1491s ## ALL TESTS PASSED 1491s ## Cleaning up 1491s autopkgtest [05:35:56]: test ssh-gssapi: -----------------------] 1491s autopkgtest [05:35:56]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1491s ssh-gssapi PASS 1492s autopkgtest [05:35:57]: test socket-activation: preparing testbed 1575s autopkgtest [05:37:20]: testbed dpkg architecture: s390x 1575s autopkgtest [05:37:20]: testbed apt version: 2.9.30ubuntu1 1575s autopkgtest [05:37:20]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1575s autopkgtest [05:37:20]: testbed release detected to be: plucky 1576s autopkgtest [05:37:21]: updating testbed package index (apt update) 1576s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1577s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1577s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1577s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1577s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1577s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.1 kB] 1577s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [508 kB] 1577s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1577s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [123 kB] 1577s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 1577s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [423 kB] 1577s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 1577s Fetched 1265 kB in 1s (1519 kB/s) 1578s Reading package lists... 1578s Reading package lists... 1578s Building dependency tree... 1578s Reading state information... 1579s Calculating upgrade... 1579s Calculating upgrade... 1579s The following packages were automatically installed and are no longer required: 1579s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 1579s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 1579s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 1579s linux-tools-6.11.0-8-generic 1579s Use 'sudo apt autoremove' to remove them. 1579s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1579s Reading package lists... 1579s Building dependency tree... 1579s Reading state information... 1579s Solving dependencies... 1579s The following packages will be REMOVED: 1579s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 1579s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 1579s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 1579s linux-tools-6.11.0-8-generic* 1579s 0 upgraded, 0 newly installed, 9 to remove and 3 not upgraded. 1579s After this operation, 167 MB disk space will be freed. 1579s (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.) 1579s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 1579s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 1579s Removing libpython3.12t64:s390x (3.12.9-1) ... 1579s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 1579s Removing libnsl2:s390x (1.3.0-3build3) ... 1579s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 1579s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 1580s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 1580s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1580s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1580s (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.) 1580s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 1580s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1580s autopkgtest [05:37:25]: upgrading testbed (apt dist-upgrade and autopurge) 1581s Reading package lists... 1581s Building dependency tree... 1581s Reading state information... 1581s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1581s Starting 2 pkgProblemResolver with broken count: 0 1581s Done 1581s Entering ResolveByKeep 1581s 1581s Calculating upgrade... 1581s The following packages will be upgraded: 1581s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 1582s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1582s Need to get 1819 kB of archives. 1582s After this operation, 1024 B disk space will be freed. 1582s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x gir1.2-glib-2.0 s390x 2.83.4-1 [182 kB] 1582s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-0t64 s390x 2.83.4-1 [1584 kB] 1582s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-data all 2.83.4-1 [52.9 kB] 1582s Fetched 1819 kB in 1s (2977 kB/s) 1582s (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.) 1582s Preparing to unpack .../gir1.2-glib-2.0_2.83.4-1_s390x.deb ... 1582s Unpacking gir1.2-glib-2.0:s390x (2.83.4-1) over (2.83.3-2) ... 1582s Preparing to unpack .../libglib2.0-0t64_2.83.4-1_s390x.deb ... 1582s Unpacking libglib2.0-0t64:s390x (2.83.4-1) over (2.83.3-2) ... 1582s Preparing to unpack .../libglib2.0-data_2.83.4-1_all.deb ... 1582s Unpacking libglib2.0-data (2.83.4-1) over (2.83.3-2) ... 1582s Setting up libglib2.0-0t64:s390x (2.83.4-1) ... 1582s No schema files found: doing nothing. 1582s Setting up libglib2.0-data (2.83.4-1) ... 1582s Setting up gir1.2-glib-2.0:s390x (2.83.4-1) ... 1582s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1583s Reading package lists... 1583s Building dependency tree... 1583s Reading state information... 1583s Starting pkgProblemResolver with broken count: 0 1583s Starting 2 pkgProblemResolver with broken count: 0 1583s Done 1583s Solving dependencies... 1583s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1583s autopkgtest [05:37:28]: rebooting testbed after setup commands that affected boot 1605s Reading package lists... 1605s Building dependency tree... 1605s Reading state information... 1605s Starting pkgProblemResolver with broken count: 0 1606s Starting 2 pkgProblemResolver with broken count: 0 1606s Done 1606s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1608s autopkgtest [05:37:53]: test socket-activation: [----------------------- 1609s Generating public/private ed25519 key pair. 1609s Your identification has been saved in /home/testuser1236/.ssh/id_ed25519 1609s Your public key has been saved in /home/testuser1236/.ssh/id_ed25519.pub 1609s The key fingerprint is: 1609s SHA256:yDAoOHHYZo82wxb31hYvEiR0ewj0P4bgEOj391NlVIs testuser1236@autopkgtest 1609s The key's randomart image is: 1609s +--[ED25519 256]--+ 1609s |.+oo=.o .. | 1609s |+o=o.=.o. .. .| 1609s |=+o=+.+o.o .E . | 1609s | +B+.=+=+ . o | 1609s | o.oo.+oS. o | 1609s | . o . . | 1609s | . . . | 1609s | o | 1609s | . | 1609s +----[SHA256]-----+ 1609s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1609s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1609s Disabling 'ssh.service', but its triggering units are still active: 1609s ssh.socket 1609s Stopping 'ssh.service', but its triggering units are still active: 1609s ssh.socket 1610s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1610s Sat Feb 22 05:39:21 UTC 2025 1610s autopkgtest [05:37:55]: test socket-activation: -----------------------] 1611s socket-activation PASS 1611s autopkgtest [05:37:56]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1611s autopkgtest [05:37:56]: test xinetd: preparing testbed 1611s Reading package lists... 1611s Building dependency tree... 1611s Reading state information... 1612s Starting pkgProblemResolver with broken count: 0 1612s Starting 2 pkgProblemResolver with broken count: 0 1612s Done 1612s The following NEW packages will be installed: 1612s xinetd 1612s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1612s Need to get 116 kB of archives. 1612s After this operation, 316 kB of additional disk space will be used. 1612s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x xinetd s390x 1:2.3.15.4-4 [116 kB] 1612s Fetched 116 kB in 0s (332 kB/s) 1612s Selecting previously unselected package xinetd. 1612s (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.) 1612s Preparing to unpack .../xinetd_1%3a2.3.15.4-4_s390x.deb ... 1612s Unpacking xinetd (1:2.3.15.4-4) ... 1612s Setting up xinetd (1:2.3.15.4-4) ... 1613s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1613s Processing triggers for man-db (2.13.0-1) ... 1614s autopkgtest [05:37:59]: test xinetd: [----------------------- 1615s Generating public/private ed25519 key pair. 1615s Your identification has been saved in /home/testuser1764/.ssh/id_ed25519 1615s Your public key has been saved in /home/testuser1764/.ssh/id_ed25519.pub 1615s The key fingerprint is: 1615s SHA256:Kh2wlIqCQA0F3Qx0TZVKYRQoL7P87otRBGFxo5ia72Y testuser1764@autopkgtest 1615s The key's randomart image is: 1615s +--[ED25519 256]--+ 1615s | +BO*+=*+.. | 1615s |. ++==oo . | 1615s |.o .*.. . | 1615s |+o =.+ . | 1615s |* o =.. S | 1615s |.. o.. o | 1615s | ..o o | 1615s | .E oo | 1615s | o..o+. | 1615s +----[SHA256]-----+ 1615s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1615s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1616s Disabling 'ssh.service', but its triggering units are still active: 1616s ssh.socket 1616s Stopping 'ssh.service', but its triggering units are still active: 1616s ssh.socket 1616s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1616s Sat Feb 22 05:39:27 UTC 2025 1616s autopkgtest [05:38:01]: test xinetd: -----------------------] 1617s autopkgtest [05:38:02]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1617s xinetd PASS 1617s autopkgtest [05:38:02]: test systemd-socket-activation: preparing testbed 1673s autopkgtest [05:38:58]: testbed dpkg architecture: s390x 1673s autopkgtest [05:38:58]: testbed apt version: 2.9.30ubuntu1 1674s autopkgtest [05:38:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1674s autopkgtest [05:38:59]: testbed release detected to be: plucky 1675s autopkgtest [05:39:00]: updating testbed package index (apt update) 1675s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1675s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1675s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1675s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1675s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1675s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [508 kB] 1676s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1676s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.1 kB] 1676s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [123 kB] 1676s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 1676s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [423 kB] 1676s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 1676s Fetched 1265 kB in 1s (1536 kB/s) 1676s Reading package lists... 1677s Reading package lists... 1677s Building dependency tree... 1677s Reading state information... 1677s Calculating upgrade... 1677s Calculating upgrade... 1677s The following packages were automatically installed and are no longer required: 1677s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 1677s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 1677s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 1677s linux-tools-6.11.0-8-generic 1677s Use 'sudo apt autoremove' to remove them. 1677s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1677s Reading package lists... 1677s Building dependency tree... 1677s Reading state information... 1677s Solving dependencies... 1678s The following packages will be REMOVED: 1678s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 1678s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 1678s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 1678s linux-tools-6.11.0-8-generic* 1678s 0 upgraded, 0 newly installed, 9 to remove and 3 not upgraded. 1678s After this operation, 167 MB disk space will be freed. 1678s (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.) 1678s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 1678s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 1678s Removing libpython3.12t64:s390x (3.12.9-1) ... 1678s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 1678s Removing libnsl2:s390x (1.3.0-3build3) ... 1678s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 1678s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 1678s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 1679s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1679s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1679s (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.) 1679s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 1679s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 1679s autopkgtest [05:39:04]: upgrading testbed (apt dist-upgrade and autopurge) 1679s Reading package lists... 1679s Building dependency tree... 1679s Reading state information... 1680s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1680s Starting 2 pkgProblemResolver with broken count: 0 1680s Done 1680s Entering ResolveByKeep 1680s 1680s Calculating upgrade... 1680s The following packages will be upgraded: 1680s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 1680s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1680s Need to get 1819 kB of archives. 1680s After this operation, 1024 B disk space will be freed. 1680s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x gir1.2-glib-2.0 s390x 2.83.4-1 [182 kB] 1680s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-0t64 s390x 2.83.4-1 [1584 kB] 1681s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libglib2.0-data all 2.83.4-1 [52.9 kB] 1681s Fetched 1819 kB in 1s (3038 kB/s) 1681s (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.) 1681s Preparing to unpack .../gir1.2-glib-2.0_2.83.4-1_s390x.deb ... 1681s Unpacking gir1.2-glib-2.0:s390x (2.83.4-1) over (2.83.3-2) ... 1681s Preparing to unpack .../libglib2.0-0t64_2.83.4-1_s390x.deb ... 1681s Unpacking libglib2.0-0t64:s390x (2.83.4-1) over (2.83.3-2) ... 1681s Preparing to unpack .../libglib2.0-data_2.83.4-1_all.deb ... 1681s Unpacking libglib2.0-data (2.83.4-1) over (2.83.3-2) ... 1681s Setting up libglib2.0-0t64:s390x (2.83.4-1) ... 1681s No schema files found: doing nothing. 1681s Setting up libglib2.0-data (2.83.4-1) ... 1681s Setting up gir1.2-glib-2.0:s390x (2.83.4-1) ... 1681s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1681s Reading package lists... 1681s Building dependency tree... 1681s Reading state information... 1682s Starting pkgProblemResolver with broken count: 0 1682s Starting 2 pkgProblemResolver with broken count: 0 1682s Done 1682s Solving dependencies... 1682s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1682s autopkgtest [05:39:07]: rebooting testbed after setup commands that affected boot 1702s Reading package lists... 1702s Building dependency tree... 1702s Reading state information... 1702s Starting pkgProblemResolver with broken count: 0 1702s Starting 2 pkgProblemResolver with broken count: 0 1702s Done 1703s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1705s autopkgtest [05:39:30]: test systemd-socket-activation: [----------------------- 1705s Stopping ssh.service... 1705s Checking that ssh.socket is active and listening... 1705s Checking that ssh.service is inactive/dead... 1705s Checking that a connection attempt activates ssh.service... 1705s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1706s Checking that sshd can be re-executed... 1707s Checking sshd can run in debug mode... 1707s debug1: PAM: establishing credentials 1707s debug1: permanently_set_uid: 0/0 1707s debug3: Copy environment: XDG_SESSION_ID=5 1707s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1707s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1707s debug3: Copy environment: XDG_SESSION_TYPE=tty 1707s debug3: Copy environment: XDG_SESSION_CLASS=user 1707s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1707s debug3: Copy environment: TERM=linux 1707s debug3: Copy environment: http_proxy=http://squid.internal:3128 1707s debug3: Copy environment: https_proxy=http://squid.internal:3128 1707s 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 1707s debug3: Copy environment: LANG=C.UTF-8 1707s Environment: 1707s LANG=C.UTF-8 1707s USER=root 1707s LOGNAME=root 1707s HOME=/root 1707s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1707s SHELL=/bin/bash 1707s XDG_SESSION_ID=5 1707s XDG_RUNTIME_DIR=/run/user/0 1707s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1707s XDG_SESSION_TYPE=tty 1707s XDG_SESSION_CLASS=user 1707s TERM=linux 1707s http_proxy=http://squid.internal:3128 1707s https_proxy=http://squid.internal:3128 1707s 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 1707s SSH_CLIENT=::1 38698 22 1707s SSH_CONNECTION=::1 38698 ::1 22 1707s Done. 1707s autopkgtest [05:39:32]: test systemd-socket-activation: -----------------------] 1708s autopkgtest [05:39:33]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1708s systemd-socket-activation PASS 1708s autopkgtest [05:39:33]: test sshd-socket-generator: preparing testbed 1708s Reading package lists... 1709s Building dependency tree... 1709s Reading state information... 1709s Starting pkgProblemResolver with broken count: 0 1709s Starting 2 pkgProblemResolver with broken count: 0 1709s Done 1709s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1710s autopkgtest [05:39:35]: test sshd-socket-generator: [----------------------- 1710s test_default...PASS 1710s test_custom_port...PASS 1710s test_default_and_custom_port...PASS 1710s test_mutiple_custom_ports...PASS 1710s test_custom_listenaddress...PASS 1710s test_custom_listenaddress_and_port...PASS 1710s test_custom_ipv6_listenaddress...PASS 1710s test_custom_family_ipv4...PASS 1710s test_custom_family_ipv6...PASS 1710s test_custom_port_and_family_ipv4...PASS 1710s test_custom_port_and_family_ipv6...PASS 1710s test_match_on_port...PASS 1710s autopkgtest [05:39:35]: test sshd-socket-generator: -----------------------] 1711s sshd-socket-generator PASS 1711s autopkgtest [05:39:36]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1711s autopkgtest [05:39:36]: @@@@@@@@@@@@@@@@@@@@ summary 1711s regress PASS 1711s ssh-gssapi PASS 1711s socket-activation PASS 1711s xinetd PASS 1711s systemd-socket-activation PASS 1711s sshd-socket-generator PASS 1729s nova [W] Using flock in prodstack6-s390x 1729s Creating nova instance adt-plucky-s390x-openssh-20250222-051105-juju-7f2275-prod-proposed-migration-environment-2-222e7a35-cd36-4a48-8597-c7a428885a04 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1729s nova [W] Timed out waiting for 87b8985d-14b9-401f-850e-b0d5460895ae to get deleted. 1729s nova [W] Using flock in prodstack6-s390x 1729s Creating nova instance adt-plucky-s390x-openssh-20250222-051105-juju-7f2275-prod-proposed-migration-environment-2-222e7a35-cd36-4a48-8597-c7a428885a04 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1729s nova [W] Timed out waiting for 588239a2-f4e9-4cd2-b476-e1a6bcbefa9a to get deleted. 1729s nova [W] Using flock in prodstack6-s390x 1729s Creating nova instance adt-plucky-s390x-openssh-20250222-051105-juju-7f2275-prod-proposed-migration-environment-2-222e7a35-cd36-4a48-8597-c7a428885a04 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1729s nova [W] Timed out waiting for 3ec2ca2e-112f-42d4-9ce8-89fb618699a0 to get deleted. 1729s nova [W] Using flock in prodstack6-s390x 1729s Creating nova instance adt-plucky-s390x-openssh-20250222-051105-juju-7f2275-prod-proposed-migration-environment-2-222e7a35-cd36-4a48-8597-c7a428885a04 from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 1729s nova [W] Timed out waiting for 4262de20-ae79-49de-9c1a-40021e3d363c to get deleted.