0s autopkgtest [08:48:09]: starting date and time: 2024-11-07 08:48:09+0000 0s autopkgtest [08:48:09]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [08:48:09]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._3oc3f8b/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pam --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pam/1.5.3-7ubuntu3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-4.secgroup --name adt-plucky-s390x-openssh-20241107-084809-juju-7f2275-prod-proposed-migration-environment-2-a1e7ba71-2c07-4004-bd78-db85934c6914 --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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 101s autopkgtest [08:49:50]: testbed dpkg architecture: s390x 101s autopkgtest [08:49:50]: testbed apt version: 2.9.8 101s autopkgtest [08:49:50]: @@@@@@@@@@@@@@@@@@@@ test bed setup 102s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 102s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1550 kB] 103s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [171 kB] 103s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 103s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [18.8 kB] 103s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [218 kB] 103s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [1191 kB] 103s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [19.0 kB] 103s Fetched 3249 kB in 2s (1748 kB/s) 103s Reading package lists... 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s Calculating upgrade... 106s The following packages will be upgraded: 106s libarchive13t64 libpam-modules libpam-modules-bin libpam-runtime libpam0g 106s python3-jaraco.functools python3-typeguard ssh-import-id ufw 106s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 106s Need to get 1098 kB of archives. 106s After this operation, 3072 B of additional disk space will be used. 106s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam0g s390x 1.5.3-7ubuntu3 [69.9 kB] 106s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules-bin s390x 1.5.3-7ubuntu3 [56.2 kB] 106s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules s390x 1.5.3-7ubuntu3 [294 kB] 106s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 106s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x ufw all 0.36.2-8 [170 kB] 106s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libarchive13t64 s390x 3.7.4-1ubuntu1 [417 kB] 107s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jaraco.functools all 4.1.0-1 [11.8 kB] 107s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x python3-typeguard all 4.4.1-1 [29.0 kB] 107s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x ssh-import-id all 5.11-0ubuntu3 [10.1 kB] 107s Preconfiguring packages ... 107s Fetched 1098 kB in 1s (1382 kB/s) 107s (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 ... 55483 files and directories currently installed.) 107s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_s390x.deb ... 107s Unpacking libpam0g:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 107s Setting up libpam0g:s390x (1.5.3-7ubuntu3) ... 107s (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 ... 55483 files and directories currently installed.) 107s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_s390x.deb ... 107s Unpacking libpam-modules-bin (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 107s Setting up libpam-modules-bin (1.5.3-7ubuntu3) ... 107s pam_namespace.service is a disabled or a static unit not running, not starting it. 107s (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 ... 55483 files and directories currently installed.) 107s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_s390x.deb ... 107s Unpacking libpam-modules:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 107s Setting up libpam-modules:s390x (1.5.3-7ubuntu3) ... 107s (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 ... 55483 files and directories currently installed.) 107s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 107s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 108s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 108s (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 ... 55483 files and directories currently installed.) 108s Preparing to unpack .../archives/ufw_0.36.2-8_all.deb ... 108s Unpacking ufw (0.36.2-8) over (0.36.2-6) ... 108s Preparing to unpack .../libarchive13t64_3.7.4-1ubuntu1_s390x.deb ... 108s Unpacking libarchive13t64:s390x (3.7.4-1ubuntu1) over (3.7.4-1) ... 108s Preparing to unpack .../python3-jaraco.functools_4.1.0-1_all.deb ... 108s Unpacking python3-jaraco.functools (4.1.0-1) over (4.0.2-1) ... 108s Preparing to unpack .../python3-typeguard_4.4.1-1_all.deb ... 108s Unpacking python3-typeguard (4.4.1-1) over (4.3.0-1) ... 108s Preparing to unpack .../ssh-import-id_5.11-0ubuntu3_all.deb ... 108s Unpacking ssh-import-id (5.11-0ubuntu3) over (5.11-0ubuntu2) ... 108s Setting up python3-jaraco.functools (4.1.0-1) ... 108s Setting up ssh-import-id (5.11-0ubuntu3) ... 108s Setting up python3-typeguard (4.4.1-1) ... 108s Setting up ufw (0.36.2-8) ... 109s Setting up libarchive13t64:s390x (3.7.4-1ubuntu1) ... 109s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 109s Processing triggers for man-db (2.12.1-3) ... 110s Processing triggers for libc-bin (2.40-1ubuntu3) ... 110s Reading package lists... 110s Building dependency tree... 110s Reading state information... 110s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 110s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 111s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 111s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 111s Reading package lists... 111s Reading package lists... 111s Building dependency tree... 111s Reading state information... 112s Calculating upgrade... 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s autopkgtest [08:50:01]: rebooting testbed after setup commands that affected boot 116s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 131s autopkgtest [08:50:20]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 133s autopkgtest [08:50:22]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 138s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (dsc) [3327 B] 138s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (tar) [1849 kB] 138s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (asc) [833 B] 138s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (diff) [208 kB] 138s gpgv: Signature made Wed Oct 2 00:50:47 2024 UTC 138s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 138s gpgv: Can't check signature: No public key 138s dpkg-source: warning: cannot verify inline signature for ./openssh_9.7p1-7ubuntu4.dsc: no acceptable signature found 138s autopkgtest [08:50:27]: testing package openssh version 1:9.7p1-7ubuntu4 138s autopkgtest [08:50:27]: build not needed 139s autopkgtest [08:50:28]: test regress: preparing testbed 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 140s Done 141s The following additional packages will be installed: 141s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 141s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 141s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 141s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 141s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 141s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 141s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 141s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 141s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 141s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 141s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 141s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 141s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 141s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 141s python3-incremental python3-pyasn1 python3-pyasn1-modules 141s python3-service-identity python3-twisted python3-zope.interface wdiff 141s Suggested packages: 141s adequate at autopkgtest bls-standalone bsd-mailx | mailx build-essential 141s check-all-the-things cvs-buildpackage debhelper diffoscope disorderfs 141s docker.io dose-extra duck elpa-devscripts faketime gnuplot how-can-i-help 141s libauthen-sasl-perl libdbd-pg-perl libfile-desktopentry-perl 141s libterm-size-perl libyaml-syck-perl mmdebstrap mutt piuparts 141s postgresql-client pristine-lfs python3-pycurl quilt ratt reprotest 141s svn-buildpackage w3m debian-keyring equivs libgitlab-api-v4-perl 141s libsoap-lite-perl pristine-tar dropbear-initramfs runit libdata-dump-perl 141s libio-compress-brotli-perl libcrypt-ssleay-perl libscalar-number-perl 141s libxstring-perl libsub-name-perl libbusiness-isbn-perl libregexp-ipv6-perl 141s libauthen-ntlm-perl putty-doc python3-pampy python3-tk python3-wxgtk4.0 141s wdiff-doc 141s Recommended packages: 141s dctrl-tools dput | dupload libdistro-info-perl libgit-wrapper-perl 141s libjson-perl liblist-compare-perl libstring-shellquote-perl licensecheck 141s lintian python3-debian python3-magic python3-unidiff python3-xdg unzip 141s libhtml-format-perl libnamespace-clean-perl libdata-dump-perl 141s libhtml-form-perl libhttp-daemon-perl libmailtools-perl python3-click 141s The following NEW packages will be installed: 141s autopkgtest-satdep devscripts dropbear dropbear-bin haveged 141s libb-hooks-op-check-perl libclass-method-modifiers-perl 141s libclass-xsaccessor-perl libclone-perl libdevel-callchecker-perl 141s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 141s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 141s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 141s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 141s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 141s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 141s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 141s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 141s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 141s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 141s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 141s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 141s python3-incremental python3-pyasn1 python3-pyasn1-modules 141s python3-service-identity python3-twisted python3-zope.interface wdiff 141s 0 upgraded, 62 newly installed, 0 to remove and 0 not upgraded. 141s Need to get 8492 kB/8493 kB of archives. 141s After this operation, 37.8 MB of additional disk space will be used. 141s Get:1 /tmp/autopkgtest.PyKwRX/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [772 B] 141s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 141s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 141s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear-bin s390x 2024.85-3 [151 kB] 141s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear all 2024.85-3 [8668 B] 141s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x libhavege2 s390x 1.9.14-1ubuntu2 [26.1 kB] 141s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x haveged s390x 1.9.14-1ubuntu2 [33.6 kB] 141s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 141s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 141s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 141s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-touch-perl all 0.12-2 [7498 B] 141s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 141s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 141s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 141s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-xsaccessor-perl s390x 1.19-4build5 [34.2 kB] 141s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 141s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libdynaloader-functions-perl all 0.004-1 [11.4 kB] 141s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libdevel-callchecker-perl s390x 0.009-1build1 [14.2 kB] 141s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 141s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x libmodule-runtime-perl all 0.016-2 [16.4 kB] 141s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 141s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 141s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 141s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 141s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 141s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 141s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 141s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 141s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 142s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x liburi-perl all 5.28-1 [88.1 kB] 142s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 142s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 142s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 142s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x libio-html-perl all 1.004-3 [15.9 kB] 142s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 142s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-message-perl all 6.46-1ubuntu1 [75.9 kB] 142s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 142s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 142s Get:39 http://ftpmaster.internal/ubuntu plucky/main s390x perl-openssl-defaults s390x 7build3 [6628 B] 142s Get:40 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-ssleay-perl s390x 1.94-2 [319 kB] 142s Get:41 http://ftpmaster.internal/ubuntu plucky/main s390x libio-socket-ssl-perl all 2.088-1 [200 kB] 142s Get:42 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-http-perl all 6.23-1 [22.3 kB] 142s Get:43 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 142s Get:44 http://ftpmaster.internal/ubuntu plucky/main s390x libtry-tiny-perl all 0.31-2 [20.8 kB] 142s Get:45 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-robotrules-perl all 6.02-1 [12.6 kB] 142s Get:46 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-perl all 6.77-1 [138 kB] 142s Get:47 http://ftpmaster.internal/ubuntu plucky/main s390x patchutils s390x 0.4.2-1build3 [79.2 kB] 142s Get:48 http://ftpmaster.internal/ubuntu plucky/main s390x wdiff s390x 1.2.2-6build1 [29.3 kB] 142s Get:49 http://ftpmaster.internal/ubuntu plucky/main s390x devscripts all 2.24.1 [1071 kB] 142s Get:50 http://ftpmaster.internal/ubuntu plucky/universe s390x putty-tools s390x 0.81-2 [707 kB] 142s Get:51 http://ftpmaster.internal/ubuntu plucky/main s390x python3-bcrypt s390x 4.2.0-1 [248 kB] 142s Get:52 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hamcrest all 2.1.0-1 [28.1 kB] 142s Get:53 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1 all 0.5.1-1 [57.4 kB] 142s Get:54 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1-modules all 0.3.0-1 [80.2 kB] 142s Get:55 http://ftpmaster.internal/ubuntu plucky/main s390x python3-service-identity all 24.1.0-1 [11.2 kB] 142s Get:56 http://ftpmaster.internal/ubuntu plucky/main s390x python3-automat all 24.8.1-1 [35.5 kB] 142s Get:57 http://ftpmaster.internal/ubuntu plucky/main s390x python3-constantly all 23.10.4-2 [13.9 kB] 142s Get:58 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hyperlink all 21.0.0-6 [68.0 kB] 143s Get:59 http://ftpmaster.internal/ubuntu plucky/main s390x python3-incremental all 24.7.2-2 [18.5 kB] 143s Get:60 http://ftpmaster.internal/ubuntu plucky/main s390x python3-zope.interface s390x 6.4-1 [137 kB] 143s Get:61 http://ftpmaster.internal/ubuntu plucky/main s390x python3-twisted all 24.7.0-2 [2062 kB] 143s Get:62 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-tests s390x 1:9.7p1-7ubuntu4 [1393 kB] 143s Fetched 8492 kB in 2s (3448 kB/s) 143s Selecting previously unselected package libtommath1:s390x. 144s (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 ... 55483 files and directories currently installed.) 144s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 144s Unpacking libtommath1:s390x (1.3.0-1) ... 144s Selecting previously unselected package libtomcrypt1:s390x. 144s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 144s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 144s Selecting previously unselected package dropbear-bin. 144s Preparing to unpack .../02-dropbear-bin_2024.85-3_s390x.deb ... 144s Unpacking dropbear-bin (2024.85-3) ... 144s Selecting previously unselected package dropbear. 144s Preparing to unpack .../03-dropbear_2024.85-3_all.deb ... 144s Unpacking dropbear (2024.85-3) ... 144s Selecting previously unselected package libhavege2:s390x. 144s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_s390x.deb ... 144s Unpacking libhavege2:s390x (1.9.14-1ubuntu2) ... 144s Selecting previously unselected package haveged. 144s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_s390x.deb ... 144s Unpacking haveged (1.9.14-1ubuntu2) ... 144s Selecting previously unselected package libfile-dirlist-perl. 144s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 144s Unpacking libfile-dirlist-perl (0.05-3) ... 144s Selecting previously unselected package libfile-which-perl. 144s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 144s Unpacking libfile-which-perl (1.27-2) ... 144s Selecting previously unselected package libfile-homedir-perl. 144s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 144s Unpacking libfile-homedir-perl (1.006-2) ... 144s Selecting previously unselected package libfile-touch-perl. 144s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 144s Unpacking libfile-touch-perl (0.12-2) ... 144s Selecting previously unselected package libio-pty-perl. 144s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 144s Unpacking libio-pty-perl (1:1.20-1build3) ... 144s Selecting previously unselected package libipc-run-perl. 144s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 144s Unpacking libipc-run-perl (20231003.0-2) ... 144s Selecting previously unselected package libclass-method-modifiers-perl. 144s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 144s Unpacking libclass-method-modifiers-perl (2.15-1) ... 144s Selecting previously unselected package libclass-xsaccessor-perl. 144s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_s390x.deb ... 144s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 144s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 144s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 144s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 144s Selecting previously unselected package libdynaloader-functions-perl. 144s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 144s Unpacking libdynaloader-functions-perl (0.004-1) ... 144s Selecting previously unselected package libdevel-callchecker-perl:s390x. 144s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_s390x.deb ... 144s Unpacking libdevel-callchecker-perl:s390x (0.009-1build1) ... 144s Selecting previously unselected package libparams-classify-perl:s390x. 144s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_s390x.deb ... 144s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 144s Selecting previously unselected package libmodule-runtime-perl. 144s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 144s Unpacking libmodule-runtime-perl (0.016-2) ... 144s Selecting previously unselected package libimport-into-perl. 144s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 144s Unpacking libimport-into-perl (1.002005-2) ... 144s Selecting previously unselected package librole-tiny-perl. 144s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 144s Unpacking librole-tiny-perl (2.002004-1) ... 144s Selecting previously unselected package libsub-quote-perl. 144s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 144s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 144s Selecting previously unselected package libmoo-perl. 144s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 144s Unpacking libmoo-perl (2.005005-1) ... 144s Selecting previously unselected package libencode-locale-perl. 144s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 144s Unpacking libencode-locale-perl (1.05-3) ... 144s Selecting previously unselected package libtimedate-perl. 144s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 144s Unpacking libtimedate-perl (2.3300-2) ... 144s Selecting previously unselected package libhttp-date-perl. 144s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 144s Unpacking libhttp-date-perl (6.06-1) ... 144s Selecting previously unselected package libfile-listing-perl. 144s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 144s Unpacking libfile-listing-perl (6.16-1) ... 144s Selecting previously unselected package libhtml-tagset-perl. 144s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 144s Unpacking libhtml-tagset-perl (3.24-1) ... 144s Selecting previously unselected package liburi-perl. 144s Preparing to unpack .../28-liburi-perl_5.28-1_all.deb ... 144s Unpacking liburi-perl (5.28-1) ... 144s Selecting previously unselected package libhtml-parser-perl:s390x. 144s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_s390x.deb ... 144s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 144s Selecting previously unselected package libhtml-tree-perl. 144s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 144s Unpacking libhtml-tree-perl (5.07-3) ... 144s Selecting previously unselected package libclone-perl:s390x. 144s Preparing to unpack .../31-libclone-perl_0.47-1_s390x.deb ... 144s Unpacking libclone-perl:s390x (0.47-1) ... 144s Selecting previously unselected package libio-html-perl. 144s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 144s Unpacking libio-html-perl (1.004-3) ... 144s Selecting previously unselected package liblwp-mediatypes-perl. 144s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 144s Unpacking liblwp-mediatypes-perl (6.04-2) ... 144s Selecting previously unselected package libhttp-message-perl. 144s Preparing to unpack .../34-libhttp-message-perl_6.46-1ubuntu1_all.deb ... 144s Unpacking libhttp-message-perl (6.46-1ubuntu1) ... 144s Selecting previously unselected package libhttp-cookies-perl. 144s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 144s Unpacking libhttp-cookies-perl (6.11-1) ... 144s Selecting previously unselected package libhttp-negotiate-perl. 144s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 144s Unpacking libhttp-negotiate-perl (6.01-2) ... 144s Selecting previously unselected package perl-openssl-defaults:s390x. 144s Preparing to unpack .../37-perl-openssl-defaults_7build3_s390x.deb ... 144s Unpacking perl-openssl-defaults:s390x (7build3) ... 144s Selecting previously unselected package libnet-ssleay-perl:s390x. 144s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_s390x.deb ... 144s Unpacking libnet-ssleay-perl:s390x (1.94-2) ... 144s Selecting previously unselected package libio-socket-ssl-perl. 144s Preparing to unpack .../39-libio-socket-ssl-perl_2.088-1_all.deb ... 144s Unpacking libio-socket-ssl-perl (2.088-1) ... 144s Selecting previously unselected package libnet-http-perl. 144s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 144s Unpacking libnet-http-perl (6.23-1) ... 144s Selecting previously unselected package liblwp-protocol-https-perl. 144s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 144s Unpacking liblwp-protocol-https-perl (6.14-1) ... 144s Selecting previously unselected package libtry-tiny-perl. 144s Preparing to unpack .../42-libtry-tiny-perl_0.31-2_all.deb ... 144s Unpacking libtry-tiny-perl (0.31-2) ... 144s Selecting previously unselected package libwww-robotrules-perl. 144s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 144s Unpacking libwww-robotrules-perl (6.02-1) ... 144s Selecting previously unselected package libwww-perl. 144s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 144s Unpacking libwww-perl (6.77-1) ... 144s Selecting previously unselected package patchutils. 144s Preparing to unpack .../45-patchutils_0.4.2-1build3_s390x.deb ... 144s Unpacking patchutils (0.4.2-1build3) ... 144s Selecting previously unselected package wdiff. 144s Preparing to unpack .../46-wdiff_1.2.2-6build1_s390x.deb ... 144s Unpacking wdiff (1.2.2-6build1) ... 144s Selecting previously unselected package devscripts. 144s Preparing to unpack .../47-devscripts_2.24.1_all.deb ... 144s Unpacking devscripts (2.24.1) ... 144s Selecting previously unselected package putty-tools. 144s Preparing to unpack .../48-putty-tools_0.81-2_s390x.deb ... 144s Unpacking putty-tools (0.81-2) ... 144s Selecting previously unselected package python3-bcrypt. 144s Preparing to unpack .../49-python3-bcrypt_4.2.0-1_s390x.deb ... 144s Unpacking python3-bcrypt (4.2.0-1) ... 144s Selecting previously unselected package python3-hamcrest. 144s Preparing to unpack .../50-python3-hamcrest_2.1.0-1_all.deb ... 144s Unpacking python3-hamcrest (2.1.0-1) ... 144s Selecting previously unselected package python3-pyasn1. 144s Preparing to unpack .../51-python3-pyasn1_0.5.1-1_all.deb ... 144s Unpacking python3-pyasn1 (0.5.1-1) ... 144s Selecting previously unselected package python3-pyasn1-modules. 144s Preparing to unpack .../52-python3-pyasn1-modules_0.3.0-1_all.deb ... 144s Unpacking python3-pyasn1-modules (0.3.0-1) ... 144s Selecting previously unselected package python3-service-identity. 144s Preparing to unpack .../53-python3-service-identity_24.1.0-1_all.deb ... 144s Unpacking python3-service-identity (24.1.0-1) ... 144s Selecting previously unselected package python3-automat. 144s Preparing to unpack .../54-python3-automat_24.8.1-1_all.deb ... 144s Unpacking python3-automat (24.8.1-1) ... 144s Selecting previously unselected package python3-constantly. 144s Preparing to unpack .../55-python3-constantly_23.10.4-2_all.deb ... 144s Unpacking python3-constantly (23.10.4-2) ... 144s Selecting previously unselected package python3-hyperlink. 144s Preparing to unpack .../56-python3-hyperlink_21.0.0-6_all.deb ... 144s Unpacking python3-hyperlink (21.0.0-6) ... 144s Selecting previously unselected package python3-incremental. 144s Preparing to unpack .../57-python3-incremental_24.7.2-2_all.deb ... 144s Unpacking python3-incremental (24.7.2-2) ... 144s Selecting previously unselected package python3-zope.interface. 144s Preparing to unpack .../58-python3-zope.interface_6.4-1_s390x.deb ... 144s Unpacking python3-zope.interface (6.4-1) ... 144s Selecting previously unselected package python3-twisted. 144s Preparing to unpack .../59-python3-twisted_24.7.0-2_all.deb ... 144s Unpacking python3-twisted (24.7.0-2) ... 144s Selecting previously unselected package openssh-tests. 144s Preparing to unpack .../60-openssh-tests_1%3a9.7p1-7ubuntu4_s390x.deb ... 144s Unpacking openssh-tests (1:9.7p1-7ubuntu4) ... 144s Selecting previously unselected package autopkgtest-satdep. 144s Preparing to unpack .../61-1-autopkgtest-satdep.deb ... 144s Unpacking autopkgtest-satdep (0) ... 144s Setting up wdiff (1.2.2-6build1) ... 144s Setting up libfile-which-perl (1.27-2) ... 144s Setting up libdynaloader-functions-perl (0.004-1) ... 144s Setting up libclass-method-modifiers-perl (2.15-1) ... 144s Setting up libio-pty-perl (1:1.20-1build3) ... 144s Setting up python3-zope.interface (6.4-1) ... 145s Setting up libclone-perl:s390x (0.47-1) ... 145s Setting up libtommath1:s390x (1.3.0-1) ... 145s Setting up libhtml-tagset-perl (3.24-1) ... 145s Setting up python3-bcrypt (4.2.0-1) ... 145s Setting up python3-automat (24.8.1-1) ... 145s Setting up liblwp-mediatypes-perl (6.04-2) ... 145s Setting up libtry-tiny-perl (0.31-2) ... 145s Setting up perl-openssl-defaults:s390x (7build3) ... 145s Setting up libencode-locale-perl (1.05-3) ... 145s Setting up python3-hamcrest (2.1.0-1) ... 145s Setting up putty-tools (0.81-2) ... 145s Setting up libhavege2:s390x (1.9.14-1ubuntu2) ... 145s Setting up patchutils (0.4.2-1build3) ... 145s Setting up python3-incremental (24.7.2-2) ... 145s Setting up python3-hyperlink (21.0.0-6) ... 145s Setting up libio-html-perl (1.004-3) ... 145s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 145s Setting up libipc-run-perl (20231003.0-2) ... 145s Setting up libtimedate-perl (2.3300-2) ... 145s Setting up librole-tiny-perl (2.002004-1) ... 145s Setting up python3-pyasn1 (0.5.1-1) ... 145s Setting up python3-constantly (23.10.4-2) ... 145s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 145s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 145s Setting up libfile-dirlist-perl (0.05-3) ... 145s Setting up libfile-homedir-perl (1.006-2) ... 145s Setting up liburi-perl (5.28-1) ... 145s Setting up libfile-touch-perl (0.12-2) ... 145s Setting up libnet-ssleay-perl:s390x (1.94-2) ... 145s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 145s Setting up libhttp-date-perl (6.06-1) ... 145s Setting up haveged (1.9.14-1ubuntu2) ... 146s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 146s Setting up dropbear-bin (2024.85-3) ... 146s Setting up libfile-listing-perl (6.16-1) ... 146s Setting up libnet-http-perl (6.23-1) ... 146s Setting up libdevel-callchecker-perl:s390x (0.009-1build1) ... 146s Setting up dropbear (2024.85-3) ... 146s Converting existing OpenSSH RSA host key to Dropbear format. 146s Key is a ssh-rsa key 146s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 146s 3072 SHA256:nnx/kvFWJECcenku6aeXqpyYvduFfGDFaR0/IlpdDbk /etc/dropbear/dropbear_rsa_host_key (RSA) 146s +---[RSA 3072]----+ 146s | o...=.| 146s | =.oo+| 146s | + ==oo| 146s | + +oE o| 146s | S. .o+ o | 146s | o . o+o. .| 146s | + ..o=oo | 146s | * ++oB | 146s | o B+=O | 146s +----[SHA256]-----+ 146s Converting existing OpenSSH ECDSA host key to Dropbear format. 146s Key is a ecdsa-sha2-nistp256 key 146s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 146s 256 SHA256:1lg9tdrVgSDeWwal6un6Ek5kk2mD2T3sL6+RvlPwhSk /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 146s +---[ECDSA 256]---+ 146s | . oo.o. | 146s | . o.oo .o| 146s | + =..o=o. o| 146s | o X E.o+= . | 146s | + S.*.o . | 146s | +..oo | 146s | o .=o | 146s | ooo.. | 146s | .+**. | 146s +----[SHA256]-----+ 146s Converting existing OpenSSH ED25519 host key to Dropbear format. 146s Key is a ssh-ed25519 key 146s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 146s 256 SHA256:vNglwizgboaHQabXRMl9OrusNwr71B5AKC6s50BND/Q /etc/dropbear/dropbear_ed25519_host_key (ED25519) 146s +--[ED25519 256]--+ 146s | o.o | 146s | .o+ . . | 146s |.ooooE o | 146s |*oo=ooo. | 146s |++o.+.+oS . | 146s |+* +.+ + | 146s |= B ..+.o | 146s | B + .=. | 146s | o.++.. | 146s +----[SHA256]-----+ 146s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 146s Setting up python3-pyasn1-modules (0.3.0-1) ... 147s Setting up python3-service-identity (24.1.0-1) ... 147s Setting up libwww-robotrules-perl (6.02-1) ... 147s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 147s Setting up libio-socket-ssl-perl (2.088-1) ... 147s Setting up libhttp-message-perl (6.46-1ubuntu1) ... 147s Setting up libhttp-negotiate-perl (6.01-2) ... 147s Setting up libhttp-cookies-perl (6.11-1) ... 147s Setting up libhtml-tree-perl (5.07-3) ... 147s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 147s Setting up libmodule-runtime-perl (0.016-2) ... 147s Setting up python3-twisted (24.7.0-2) ... 149s Setting up libimport-into-perl (1.002005-2) ... 149s Setting up libmoo-perl (2.005005-1) ... 149s Setting up openssh-tests (1:9.7p1-7ubuntu4) ... 149s Setting up liblwp-protocol-https-perl (6.14-1) ... 149s Setting up libwww-perl (6.77-1) ... 149s Setting up devscripts (2.24.1) ... 149s Setting up autopkgtest-satdep (0) ... 149s Processing triggers for libc-bin (2.40-1ubuntu3) ... 149s Processing triggers for man-db (2.12.1-3) ... 150s Processing triggers for install-info (7.1.1-1) ... 152s (Reading database ... 58652 files and directories currently installed.) 152s Removing autopkgtest-satdep (0) ... 153s autopkgtest [08:50:42]: test regress: [----------------------- 153s info: Adding user `openssh-tests' ... 153s info: Selecting UID/GID from range 1000 to 59999 ... 153s info: Adding new group `openssh-tests' (1001) ... 153s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 153s info: Creating home directory `/home/openssh-tests' ... 153s info: Copying files from `/etc/skel' ... 153s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 153s info: Adding user `openssh-tests' to group `users' ... 153s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 153s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 154s I: annotate-output 2.24.1 154s I: prefix='%H:%M:%S.%N ' 154s 08:52:09.413167345 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user 154s 08:52:09.426725618 O: make: Entering directory '/tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress' 154s 08:52:09.427247858 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/valgrind-out 154s 08:52:09.428048608 E: + grep -q ^ssh-rsa 154s 08:52:09.428024070 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 154s 08:52:09.428987754 O: ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 154s 08:52:09.429226582 E: + /usr/bin/ssh -Q key 154s 08:52:09.429891687 O: tr '\n' '\r' /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 154s 08:52:09.430423222 O: ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 154s 08:52:09.432329819 O: awk '{print $0 "\r"}' /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 154s 08:52:09.432938243 O: ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 154s 08:52:09.433551136 O: fi 154s 08:52:09.436344208 E: + ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2.prv 154s 08:52:09.436876523 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv 154s 08:52:09.437771081 E: + tr \n \r 154s 08:52:09.438215207 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv 154s 08:52:09.438655464 E: + ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 154s 08:52:09.441162259 E: + awk {print $0 "\r"} /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2.prv 154s 08:52:09.442222923 E: + ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 154s 08:52:09.445655960 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv 154s 08:52:09.446532959 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 154s 08:52:09.447406574 E: + grep -q ^ssh-rsa 154s 08:52:09.447859571 O: cat /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t2.out ; \ 154s 08:52:09.448305278 E: + /usr/bin/ssh -Q key 154s 08:52:09.448702890 O: chmod 600 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t2.out ; \ 154s 08:52:09.449153241 O: ssh-keygen -yf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 154s 08:52:09.449594042 O: fi 154s 08:52:09.450203631 E: + cat /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.prv 154s 08:52:09.450726735 E: + chmod 600 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t2.out 154s 08:52:09.451307135 E: + ssh-keygen -yf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t2.out 154s 08:52:09.451820923 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub 154s 08:52:09.454672897 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 154s 08:52:09.455677044 E: + /usr/bin/ssh -Q key 154s 08:52:09.456225453 E: + grep -q ^ssh-rsa 154s 08:52:09.456367830 O: ssh-keygen -ef /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t3.out ; \ 154s 08:52:09.458371294 O: ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 154s 08:52:09.458908185 O: fi 154s 08:52:09.459315841 E: + ssh-keygen -ef /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub 154s 08:52:09.463813169 E: + ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t3.out 154s 08:52:09.464393279 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub 154s 08:52:09.464679972 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 154s 08:52:09.465431845 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 154s 08:52:09.465882762 O: awk '{print $2}' | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t4.ok ; \ 154s 08:52:09.466552658 E: + grep -q ^ssh-rsa 154s 08:52:09.466778181 O: fi 154s 08:52:09.467177338 E: + /usr/bin/ssh -Q key 154s 08:52:09.471680843 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub 154s 08:52:09.472088015 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 154s 08:52:09.472967543 O: ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 154s 08:52:09.473400580 O: awk '{print $2}' | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t5.ok ; \ 154s 08:52:09.473819133 O: fi 154s 08:52:09.472504086 E: + awk {print $2} 154s 08:52:09.474839812 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t4.ok 154s 08:52:09.475314879 E: + /usr/bin/ssh -Q key 154s 08:52:09.475764732 E: + grep -q ^ssh-rsa 154s 08:52:09.476260532 E: + ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub 154s 08:52:09.476741895 E: + awk {print $2} 154s 08:52:09.477230219 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t5.ok 154s 08:52:09.479627007 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 154s 08:52:09.480069236 E: + /usr/bin/ssh -Q key 154s 08:52:09.480524925 O: ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out1 ; \ 154s 08:52:09.481016317 O: ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out2 ; \ 154s 08:52:09.481457427 O: chmod 600 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out1 ; \ 154s 08:52:09.481903993 O: ssh-keygen -yf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out2 ; \ 154s 08:52:09.482337312 O: fi 154s 08:52:09.482868419 E: + grep -q ^ssh-dss 154s 08:52:09.483470096 E: + ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/dsa_ssh2.prv 154s 08:52:09.486735608 E: + ssh-keygen -if /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/dsa_ssh2.pub 154s 08:52:09.489330303 E: + chmod 600 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out1 154s 08:52:09.489944210 E: + ssh-keygen -yf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out1 154s 08:52:09.490818623 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t6.out2 154s 08:52:09.493092332 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 154s 08:52:09.493586515 E: + /usr/bin/ssh -Q key 154s 08:52:09.494038455 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t7.out ; \ 154s 08:52:09.494509419 O: fi 154s 08:52:09.495437860 E: + grep -q ^ssh-dss 154s 08:52:09.496416532 E: + ssh-keygen -q -t rsa -N -f /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t7.out 154s 08:52:09.884330070 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 154s 08:52:09.884833158 E: + /usr/bin/ssh -Q key 154s 08:52:09.885331874 O: ssh-keygen -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 154s 08:52:09.885820120 O: ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 154s 08:52:09.886815892 E: + grep -q ^ssh-dss 154s 08:52:09.887223678 O: fi 154s 08:52:09.888026257 E: + ssh-keygen -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t7.out 154s 08:52:09.890795642 E: + ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t7.out 154s 08:52:09.893624583 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 154s 08:52:09.894104443 E: + ssh -Q key 154s 08:52:09.894541254 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t8.out ; \ 154s 08:52:09.895009855 O: fi 154s 08:52:09.895924472 E: + grep -q ^ssh-dss 154s 08:52:09.896970843 E: + ssh-keygen -q -t dsa -N -f /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t8.out 154s 08:52:10.038777324 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 154s 08:52:10.039227673 E: + ssh -Q key 154s 08:52:10.039720997 O: ssh-keygen -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 154s 08:52:10.040192197 O: ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 154s 08:52:10.040620805 O: fi 154s 08:52:10.041103783 E: + grep -q ^ssh-dss 154s 08:52:10.042230383 E: + ssh-keygen -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t8.out 154s 08:52:10.045112588 E: + ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t8.out 154s 08:52:10.047981585 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 154s 08:52:10.048477766 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t9.out 154s 08:52:10.054223844 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 154s 08:52:10.054720428 O: ssh-keygen -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t9.out > /dev/null 154s 08:52:10.062091387 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 154s 08:52:10.062582617 O: ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t9.out > /dev/null 154s 08:52:10.069933360 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t10.out 154s 08:52:10.073503673 O: ssh-keygen -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t10.out > /dev/null 154s 08:52:10.076547676 O: ssh-keygen -Bf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t10.out > /dev/null 154s 08:52:10.079511173 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 154s 08:52:10.079991908 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 154s 08:52:10.080447035 O: awk '{print $2}' | diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t11.ok ; \ 154s 08:52:10.080872668 O: fi 154s 08:52:10.081406592 E: + /usr/bin/ssh -Q key 154s 08:52:10.081859766 E: + grep -q ^ssh-dss 154s 08:52:10.084949009 E: + ssh-keygen -E sha256 -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/rsa_openssh.pub 154s 08:52:10.085438283 E: + awk {print $2} 154s 08:52:10.085915410 E: + diff - /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t11.ok 154s 08:52:10.086858762 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t12.out 154s 08:52:10.090409379 O: ssh-keygen -lf /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 154s 08:52:10.094530486 E: run test connect.sh ... 156s 08:52:11.770140896 O: ok simple connect 156s 08:52:11.770245160 E: run test proxy-connect.sh ... 156s 08:52:11.907310744 O: plain username comp=no 157s 08:52:12.170400957 O: plain username comp=yes 157s 08:52:12.431335161 O: username with style 157s 08:52:12.691565615 O: ok proxy connect 157s 08:52:12.692092740 E: run test sshfp-connect.sh ... 157s 08:50:46.575526633 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 157s 08:50:46.576203218 E: run test connect-privsep.sh ... 161s 08:50:50.146086290 O: ok proxy connect with privsep 161s 08:50:50.146521647 E: run test connect-uri.sh ... 161s 08:50:50.411630511 O: uri connect: no trailing slash 161s 08:50:50.758373967 O: uri connect: trailing slash 162s 08:50:51.099040698 O: uri connect: with path name 162s 08:50:51.115720879 O: ok uri connect 162s 08:50:51.115886443 E: run test proto-version.sh ... 162s 08:50:51.306018122 O: ok sshd version with different protocol combinations 162s 08:50:51.306401128 E: run test proto-mismatch.sh ... 162s 08:50:51.496110133 O: ok protocol version mismatch 162s 08:50:51.496206490 E: run test exit-status.sh ... 162s 08:50:51.622313273 O: test remote exit status: status 0 168s 08:50:57.150180244 O: test remote exit status: status 1 173s 08:51:02.677564649 O: test remote exit status: status 4 179s 08:51:08.202834251 O: test remote exit status: status 5 184s 08:51:13.727725914 O: test remote exit status: status 44 190s 08:51:19.257922870 O: ok remote exit status 190s 08:51:19.259316856 E: run test exit-status-signal.sh ... 191s 08:51:20.395724702 O: ok exit status on signal 191s 08:51:20.395290254 E: run test envpass.sh ... 191s 08:51:20.530137323 O: test environment passing: pass env, don't accept 191s 08:51:20.791877513 O: test environment passing: setenv, don't accept 192s 08:51:21.052985878 O: test environment passing: don't pass env, accept 192s 08:51:21.312384772 O: test environment passing: pass single env, accept single env 192s 08:51:21.573423972 O: test environment passing: pass multiple env, accept multiple env 192s 08:51:21.833503327 O: test environment passing: setenv, accept 193s 08:51:22.097976334 O: test environment passing: setenv, first match wins 193s 08:51:22.359318480 O: test environment passing: server setenv wins 193s 08:51:22.620673797 O: test environment passing: server setenv wins 193s 08:51:22.886765543 O: ok environment passing 193s 08:51:22.887267511 E: run test transfer.sh ... 196s 08:51:25.134620044 O: ok transfer data 196s 08:51:25.134743247 E: run test banner.sh ... 196s 08:51:25.271171487 O: test banner: missing banner file 196s 08:51:25.538542123 O: test banner: size 0 196s 08:51:25.803025972 O: test banner: size 10 197s 08:51:26.071084144 O: test banner: size 100 197s 08:51:26.340321252 O: test banner: size 1000 197s 08:51:26.612602397 O: test banner: size 10000 197s 08:51:26.881131647 O: test banner: size 100000 198s 08:51:27.151153190 O: test banner: suppress banner (-q) 198s 08:51:27.414669428 O: ok banner 198s 08:51:27.415202301 E: run test rekey.sh ... 198s 08:51:27.561129935 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 199s 08:51:28.732830806 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 200s 08:51:29.913284052 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 202s 08:51:31.097842405 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 203s 08:51:32.273576418 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 204s 08:51:33.450631289 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 205s 08:51:34.627863677 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 206s 08:51:35.799481164 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 207s 08:51:36.970757376 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 209s 08:51:38.144094916 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 210s 08:51:39.325400986 O: client rekey KexAlgorithms=curve25519-sha256 211s 08:51:40.501739766 O: client rekey KexAlgorithms=curve25519-sha256@libssh.org 212s 08:51:41.680618857 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 213s 08:51:42.857076874 O: client rekey Ciphers=3des-cbc 215s 08:51:44.036445414 O: client rekey Ciphers=aes128-cbc 216s 08:51:45.210589938 O: client rekey Ciphers=aes192-cbc 217s 08:51:46.387560880 O: client rekey Ciphers=aes256-cbc 218s 08:51:47.561022609 O: client rekey Ciphers=aes128-ctr 219s 08:51:48.738283585 O: client rekey Ciphers=aes192-ctr 220s 08:51:49.929525603 O: client rekey Ciphers=aes256-ctr 222s 08:51:51.113619005 O: client rekey Ciphers=aes128-gcm@openssh.com 223s 08:51:52.309581055 O: client rekey Ciphers=aes256-gcm@openssh.com 224s 08:51:53.503142562 O: client rekey Ciphers=chacha20-poly1305@openssh.com 225s 08:51:54.686720424 O: client rekey MACs=hmac-sha1 226s 08:51:55.858561882 O: client rekey MACs=hmac-sha1-96 228s 08:51:57.033111749 O: client rekey MACs=hmac-sha2-256 229s 08:51:58.204438030 O: client rekey MACs=hmac-sha2-512 230s 08:51:59.399908246 O: client rekey MACs=hmac-md5 231s 08:52:00.587410959 O: client rekey MACs=hmac-md5-96 232s 08:52:01.765414769 O: client rekey MACs=umac-64@openssh.com 233s 08:52:02.939979917 O: client rekey MACs=umac-128@openssh.com 235s 08:52:04.132937228 O: client rekey MACs=hmac-sha1-etm@openssh.com 236s 08:52:05.335854286 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 237s 08:52:06.511899290 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 238s 08:52:07.687186561 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 239s 08:52:08.861557940 O: client rekey MACs=hmac-md5-etm@openssh.com 241s 08:52:10.036942949 O: client rekey MACs=hmac-md5-96-etm@openssh.com 242s 08:52:11.211615576 O: client rekey MACs=umac-64-etm@openssh.com 243s 08:52:12.383163205 O: client rekey MACs=umac-128-etm@openssh.com 244s 08:52:13.571619438 O: client rekey aes128-gcm@openssh.com diffie-hellman-group1-sha1 245s 08:52:14.844245618 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha1 247s 08:52:16.137388323 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha256 248s 08:52:17.407122590 O: client rekey aes128-gcm@openssh.com diffie-hellman-group16-sha512 249s 08:52:18.679710346 O: client rekey aes128-gcm@openssh.com diffie-hellman-group18-sha512 250s 08:52:19.960523870 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha1 252s 08:52:21.234355842 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha256 253s 08:52:22.504904267 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp256 254s 08:52:23.779389576 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp384 256s 08:52:25.051238345 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp521 257s 08:52:26.319181696 O: client rekey aes128-gcm@openssh.com curve25519-sha256 258s 08:52:27.590290234 O: client rekey aes128-gcm@openssh.com curve25519-sha256@libssh.org 259s 08:52:28.860142915 O: client rekey aes128-gcm@openssh.com sntrup761x25519-sha512@openssh.com 261s 08:52:30.134701941 O: client rekey aes256-gcm@openssh.com diffie-hellman-group1-sha1 262s 08:52:31.404297368 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha1 263s 08:52:32.673381843 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha256 264s 08:52:33.948071885 O: client rekey aes256-gcm@openssh.com diffie-hellman-group16-sha512 266s 08:52:35.219515890 O: client rekey aes256-gcm@openssh.com diffie-hellman-group18-sha512 267s 08:52:36.488117103 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha1 268s 08:52:37.761016565 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha256 270s 08:52:39.033318001 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp256 271s 08:52:40.307837495 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp384 272s 08:52:41.591124586 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp521 273s 08:52:42.865172828 O: client rekey aes256-gcm@openssh.com curve25519-sha256 275s 08:52:44.147245751 O: client rekey aes256-gcm@openssh.com curve25519-sha256@libssh.org 276s 08:52:45.418601943 O: client rekey aes256-gcm@openssh.com sntrup761x25519-sha512@openssh.com 277s 08:52:46.697282597 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group1-sha1 278s 08:52:47.868093994 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha1 280s 08:52:49.037676302 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha256 281s 08:52:50.207786533 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group16-sha512 282s 08:52:51.379479156 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group18-sha512 283s 08:52:52.559942281 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha1 284s 08:52:53.742610113 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha256 285s 08:52:54.916346753 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp256 287s 08:52:56.090052099 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp384 288s 08:52:57.261709967 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp521 289s 08:52:58.433377126 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256 290s 08:52:59.608027703 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256@libssh.org 291s 08:53:00.784319832 O: client rekey chacha20-poly1305@openssh.com sntrup761x25519-sha512@openssh.com 292s 08:53:01.956289514 O: client rekeylimit 16 294s 08:53:03.631732641 O: client rekeylimit 1k 296s 08:53:05.209062849 O: client rekeylimit 128k 297s 08:53:06.481321187 O: client rekeylimit 256k 298s 08:53:07.652418191 O: client rekeylimit default 5 313s 08:53:22.918040442 O: client rekeylimit default 10 334s 08:53:43.185943495 O: client rekeylimit default 5 no data 349s 08:53:58.450308280 O: client rekeylimit default 10 no data 369s 08:54:18.717270283 O: server rekeylimit 16 371s 08:54:20.421464903 O: server rekeylimit 1k 373s 08:54:22.005894533 O: server rekeylimit 128k 374s 08:54:23.289432977 O: server rekeylimit 256k 375s 08:54:24.469026978 O: server rekeylimit default 5 no data 390s 08:54:39.840651092 O: server rekeylimit default 10 no data 411s 08:55:00.112785739 O: rekeylimit parsing 422s 08:55:11.125880652 O: ok rekey 422s 08:55:11.126415255 E: run test dhgex.sh ... 422s 08:55:11.261044802 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 422s 08:55:11.445714732 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 422s 08:55:11.637971237 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 422s 08:55:11.832463670 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 423s 08:55:12.026963138 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 423s 08:55:12.210916714 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 423s 08:55:12.391321001 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 423s 08:55:12.577019218 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 423s 08:55:12.767724138 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 424s 08:55:13.020302570 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 424s 08:55:13.288133468 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 424s 08:55:13.552006804 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 424s 08:55:13.813351408 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 425s 08:55:14.077004924 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 425s 08:55:14.332809683 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 425s 08:55:14.590661993 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 425s 08:55:14.853360414 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 426s 08:55:15.114401159 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 426s 08:55:15.372708315 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 426s 08:55:15.721865441 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 427s 08:55:16.066531039 O: ok dhgex 427s 08:55:16.066645546 E: run test stderr-data.sh ... 427s 08:55:16.198160130 O: test stderr data transfer: () 433s 08:55:22.734311160 O: test stderr data transfer: (-n) 440s 08:55:29.267686350 O: ok stderr data transfer 440s 08:55:29.267704795 E: run test stderr-after-eof.sh ... 442s 08:55:31.675058278 O: ok stderr data after eof 442s 08:55:31.675648889 E: run test broken-pipe.sh ... 442s 08:55:31.825464389 O: ok broken pipe test 442s 08:55:31.825554664 E: run test try-ciphers.sh ... 443s 08:55:31.967156836 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 443s 08:55:32.235641959 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 443s 08:55:32.502627771 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 443s 08:55:32.767484271 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 444s 08:55:33.035730906 O: test try ciphers: cipher 3des-cbc mac hmac-md5 444s 08:55:33.304436196 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 444s 08:55:33.573069547 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 444s 08:55:33.841600178 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 445s 08:55:34.113140063 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 445s 08:55:34.384331240 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 445s 08:55:34.655832441 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 445s 08:55:34.926937986 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 446s 08:55:35.194938024 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 446s 08:55:35.470219264 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 446s 08:55:35.734989240 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 447s 08:55:36.003788068 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 447s 08:55:36.279214460 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 447s 08:55:36.545644414 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 447s 08:55:36.815423698 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 448s 08:55:37.085691254 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 448s 08:55:37.374652421 O: test try ciphers: cipher aes128-cbc mac hmac-md5 448s 08:55:37.643917611 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 448s 08:55:37.915113351 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 449s 08:55:38.186920754 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 449s 08:55:38.453851287 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 449s 08:55:38.730591048 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 450s 08:55:39.002508534 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 450s 08:55:39.271795274 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 450s 08:55:39.544018410 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 450s 08:55:39.812424445 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 451s 08:55:40.085539533 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 451s 08:55:40.360190993 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 451s 08:55:40.637914910 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 451s 08:55:40.919444153 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 452s 08:55:41.191771469 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 452s 08:55:41.466617718 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 452s 08:55:41.735286917 O: test try ciphers: cipher aes192-cbc mac hmac-md5 453s 08:55:42.007086778 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 453s 08:55:42.283469658 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 453s 08:55:42.550695378 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 453s 08:55:42.822188100 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 454s 08:55:43.090604576 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 454s 08:55:43.358281493 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 454s 08:55:43.623518181 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 454s 08:55:43.894760166 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 455s 08:55:44.167626170 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 455s 08:55:44.439305568 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 455s 08:55:44.715305067 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 456s 08:55:44.984718591 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 456s 08:55:45.256289747 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 456s 08:55:45.530358738 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 456s 08:55:45.803746195 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 457s 08:55:46.075566620 O: test try ciphers: cipher aes256-cbc mac hmac-md5 457s 08:55:46.340772689 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 457s 08:55:46.609215193 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 457s 08:55:46.880190707 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 458s 08:55:47.147133508 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 458s 08:55:47.414510982 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 458s 08:55:47.680602981 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 458s 08:55:47.954374921 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 459s 08:55:48.220046493 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 459s 08:55:48.489012851 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 459s 08:55:48.756432918 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 460s 08:55:49.021627413 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 460s 08:55:49.294723172 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 460s 08:55:49.559209356 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 460s 08:55:49.822813360 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 461s 08:55:50.087901078 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 461s 08:55:50.359314263 O: test try ciphers: cipher aes128-ctr mac hmac-md5 461s 08:55:50.633415214 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 461s 08:55:50.906231105 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 462s 08:55:51.175372747 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 462s 08:55:51.446098776 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 462s 08:55:51.712297916 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 463s 08:55:51.984218930 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 463s 08:55:52.259515852 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 463s 08:55:52.526644470 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 463s 08:55:52.794597968 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 464s 08:55:53.062036894 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 464s 08:55:53.333106127 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 464s 08:55:53.608489317 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 464s 08:55:53.880504931 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 465s 08:55:54.157325605 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 465s 08:55:54.432313297 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 465s 08:55:54.708704114 O: test try ciphers: cipher aes192-ctr mac hmac-md5 466s 08:55:54.978418993 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 466s 08:55:55.251643082 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 466s 08:55:55.522817903 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 466s 08:55:55.793950581 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 467s 08:55:56.069743789 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 467s 08:55:56.342651065 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 467s 08:55:56.617441883 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 467s 08:55:56.891647435 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 468s 08:55:57.160348472 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 468s 08:55:57.432065153 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 468s 08:55:57.701037491 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 469s 08:55:57.977814556 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 469s 08:55:58.249347744 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 469s 08:55:58.522617248 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 469s 08:55:58.801866559 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 470s 08:55:59.068687604 O: test try ciphers: cipher aes256-ctr mac hmac-md5 470s 08:55:59.338051257 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 470s 08:55:59.601696460 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 470s 08:55:59.873119974 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 471s 08:56:00.137688653 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 471s 08:56:00.403737139 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 471s 08:56:00.670174266 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 471s 08:56:00.937851758 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 472s 08:56:01.204821518 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 472s 08:56:01.475347150 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 472s 08:56:01.741908841 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 473s 08:56:02.016560120 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 473s 08:56:02.289885364 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 473s 08:56:02.561048364 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 473s 08:56:02.833154554 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 474s 08:56:03.095882488 O: ok try ciphers 474s 08:56:03.096358273 E: run test yes-head.sh ... 477s 08:56:06.228169638 O: ok yes pipe head 477s 08:56:06.228379535 E: run test login-timeout.sh ... 492s 08:56:21.846705068 O: ok connect after login grace timeout 492s 08:56:21.846859162 E: run test agent.sh ... 502s 08:56:31.313844632 O: ok simple agent test 502s 08:56:31.314021961 E: run test agent-getpeereid.sh ... 502s 08:56:31.462145286 O: ok disallow agent attach from other uid 502s 08:56:31.462284175 E: run test agent-timeout.sh ... 522s 08:56:51.690570985 O: ok agent timeout test 522s 08:56:51.690905373 E: run test agent-ptrace.sh ... 522s 08:56:51.832039314 O: skipped (gdb not found) 522s 08:56:51.832177113 E: run test agent-subprocess.sh ... 533s 08:57:01.978491530 O: ok agent subprocess 533s 08:57:01.978751720 E: run test keyscan.sh ... 535s 08:57:04.778470451 O: ok keyscan 535s 08:57:04.778633563 E: run test keygen-change.sh ... 540s 08:57:09.841500575 O: ok change passphrase for key 540s 08:57:09.841784275 E: run test keygen-comment.sh ... 544s 08:57:13.666080248 O: ok Comment extraction from private key 544s 08:57:13.666698824 E: run test keygen-convert.sh ... 548s 08:57:17.552509339 O: ok convert keys 548s 08:57:17.552524151 E: run test keygen-knownhosts.sh ... 548s 08:57:17.778012154 O: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts updated. 548s 08:57:17.778576885 O: Original contents retained as /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts.old 548s 08:57:17.790124877 O: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts updated. 548s 08:57:17.790713394 O: Original contents retained as /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts.old 548s 08:57:17.794521785 O: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts updated. 548s 08:57:17.795074447 O: Original contents retained as /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts.old 548s 08:57:17.802739454 O: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts updated. 548s 08:57:17.803286165 O: Original contents retained as /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hosts.old 548s 08:57:17.817034776 O: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hashed updated. 548s 08:57:17.817540185 O: Original contents retained as /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/kh.hashed.old 548s 08:57:17.820836382 O: ok ssh-keygen known_hosts 548s 08:57:17.820917228 E: run test keygen-moduli.sh ... 553s 08:57:22.910565169 O: ok keygen moduli 553s 08:57:22.910922239 E: run test keygen-sshfp.sh ... 554s 08:57:23.080927357 O: ok keygen-sshfp 554s 08:57:23.081057485 E: run test key-options.sh ... 554s 08:57:23.216109939 O: key option command="echo bar" 554s 08:57:23.492908812 O: key option no-pty,command="echo bar" 554s 08:57:23.753534073 O: key option pty default 555s 08:57:24.017428056 O: key option pty no-pty 555s 08:57:24.278029848 O: key option pty restrict 555s 08:57:24.537184685 O: key option pty restrict,pty 555s 08:57:24.796948781 O: key option environment 556s 08:57:25.449270732 O: key option from="127.0.0.1" 557s 08:57:26.409410634 O: key option from="127.0.0.0/8" 558s 08:57:27.128757382 O: key option expiry-time default 558s 08:57:27.389981581 O: key option expiry-time invalid 558s 08:57:27.731218910 O: key option expiry-time expired 559s 08:57:28.073374693 O: key option expiry-time valid 559s 08:57:28.354176742 O: ok key options 559s 08:57:28.354371052 E: run test scp.sh ... 559s 08:57:28.485707092 O: scp: scp mode: simple copy local file to local file 559s 08:57:28.489783658 O: scp: scp mode: simple copy local file to remote file 559s 08:57:28.493908577 O: scp: scp mode: simple copy remote file to local file 559s 08:57:28.498162068 O: scp: scp mode: copy local file to remote file in place 559s 08:57:28.502751535 O: scp: scp mode: copy remote file to local file in place 559s 08:57:28.507097101 O: scp: scp mode: copy local file to remote file clobber 559s 08:57:28.510891607 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 7 08:57 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/copy 559s 08:57:28.511353394 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 7 08:57 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/data 559s 08:57:28.511802457 O: scp: scp mode: copy remote file to local file clobber 559s 08:57:28.516798332 O: scp: scp mode: simple copy local file to remote dir 559s 08:57:28.521093452 O: scp: scp mode: simple copy local file to local dir 559s 08:57:28.525313521 O: scp: scp mode: simple copy remote file to local dir 559s 08:57:28.529568549 O: scp: scp mode: recursive local dir to remote dir 559s 08:57:28.537087456 O: scp: scp mode: recursive local dir to local dir 559s 08:57:28.544543037 O: scp: scp mode: recursive remote dir to local dir 559s 08:57:28.553083700 O: scp: scp mode: unmatched glob file local->remote 559s 08:57:28.557567911 O: scp: scp mode: unmatched glob file remote->local 559s 08:57:28.559739143 O: scp: scp mode: unmatched glob dir recursive local->remote 559s 08:57:28.565716645 O: scp: scp mode: unmatched glob dir recursive remote->local 559s 08:57:28.568559729 O: scp: scp mode: shell metacharacters 559s 08:57:28.572264249 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 559s 08:57:28.590032529 O: scp: scp mode: disallow bad server #0 559s 08:57:28.600539819 O: scp: scp mode: disallow bad server #1 559s 08:57:28.610804082 O: scp: scp mode: disallow bad server #2 559s 08:57:28.621242030 O: scp: scp mode: disallow bad server #3 559s 08:57:28.631941271 O: scp: scp mode: disallow bad server #4 559s 08:57:28.642530790 O: scp: scp mode: disallow bad server #5 559s 08:57:28.652725756 O: scp: scp mode: disallow bad server #6 559s 08:57:28.662914534 O: scp: scp mode: disallow bad server #7 559s 08:57:28.673893625 O: scp: scp mode: detect non-directory target 559s 08:57:28.675755047 E: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/copy2: Not a directory 559s 08:57:28.676692900 O: scp: sftp mode: simple copy local file to local file 559s 08:57:28.679951133 O: scp: sftp mode: simple copy local file to remote file 559s 08:57:28.685126942 O: scp: sftp mode: simple copy remote file to local file 559s 08:57:28.688812422 O: scp: sftp mode: copy local file to remote file in place 559s 08:57:28.693957194 O: scp: sftp mode: copy remote file to local file in place 559s 08:57:28.698412065 O: scp: sftp mode: copy local file to remote file clobber 559s 08:57:28.702711557 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 7 08:57 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/copy 559s 08:57:28.703581420 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 7 08:57 /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/data 559s 08:57:28.704153671 O: scp: sftp mode: copy remote file to local file clobber 559s 08:57:28.708300463 O: scp: sftp mode: simple copy local file to remote dir 559s 08:57:28.713235340 O: scp: sftp mode: simple copy local file to local dir 559s 08:57:28.717146979 O: scp: sftp mode: simple copy remote file to local dir 559s 08:57:28.721697943 O: scp: sftp mode: recursive local dir to remote dir 559s 08:57:28.730392306 O: scp: sftp mode: recursive local dir to local dir 559s 08:57:28.738281945 O: scp: sftp mode: recursive remote dir to local dir 559s 08:57:28.747896305 O: scp: sftp mode: unmatched glob file local->remote 559s 08:57:28.752728366 O: scp: sftp mode: unmatched glob file remote->local 559s 08:57:28.756353996 O: scp: sftp mode: unmatched glob dir recursive local->remote 559s 08:57:28.763190419 O: scp: sftp mode: unmatched glob dir recursive remote->local 559s 08:57:28.767780620 O: scp: sftp mode: shell metacharacters 559s 08:57:28.771465061 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 559s 08:57:28.788343011 O: scp: sftp mode: disallow bad server #0 559s 08:57:28.801884157 O: scp: sftp mode: disallow bad server #1 559s 08:57:28.814880337 O: scp: sftp mode: disallow bad server #2 559s 08:57:28.828199626 O: scp: sftp mode: disallow bad server #3 559s 08:57:28.841103942 O: scp: sftp mode: disallow bad server #4 559s 08:57:28.854162185 O: scp: sftp mode: disallow bad server #5 559s 08:57:28.867045510 O: scp: sftp mode: disallow bad server #6 559s 08:57:28.880159542 O: scp: sftp mode: disallow bad server #7 559s 08:57:28.893528969 O: scp: sftp mode: detect non-directory target 559s 08:57:28.895740326 E: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/copy2: Not a directory 559s 08:57:28.898576694 O: ok scp 559s 08:57:28.898693022 E: run test scp3.sh ... 560s 08:57:29.027385122 O: scp3: scp mode: simple copy remote file to remote file 560s 08:57:29.330563777 O: scp3: scp mode: simple copy remote file to remote dir 560s 08:57:29.626324321 O: scp3: scp mode: recursive remote dir to remote dir 560s 08:57:29.941500834 O: scp3: scp mode: detect non-directory target 561s 08:57:30.542434121 O: scp3: sftp mode: simple copy remote file to remote file 561s 08:57:30.547846666 O: scp3: sftp mode: simple copy remote file to remote dir 561s 08:57:30.553655071 O: scp3: sftp mode: recursive remote dir to remote dir 561s 08:57:30.564918284 O: scp3: sftp mode: detect non-directory target 561s 08:57:30.567539114 E: scp: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/copy2: destination is not a directory 561s 08:57:30.568122993 E: scp: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/copy2: destination is not a directory 561s 08:57:30.571201996 O: ok scp3 561s 08:57:30.571628630 E: run test scp-uri.sh ... 561s 08:57:30.705148377 O: scp-uri: scp mode: simple copy local file to remote file 561s 08:57:30.708140954 O: scp-uri: scp mode: simple copy remote file to local file 561s 08:57:30.711183375 O: scp-uri: scp mode: simple copy local file to remote dir 561s 08:57:30.714853223 O: scp-uri: scp mode: simple copy remote file to local dir 561s 08:57:30.718453435 O: scp-uri: scp mode: recursive local dir to remote dir 561s 08:57:30.722679658 O: scp-uri: scp mode: recursive remote dir to local dir 561s 08:57:30.727018672 O: scp-uri: sftp mode: simple copy local file to remote file 561s 08:57:30.730792861 O: scp-uri: sftp mode: simple copy remote file to local file 561s 08:57:30.734061672 O: scp-uri: sftp mode: simple copy local file to remote dir 561s 08:57:30.738384846 O: scp-uri: sftp mode: simple copy remote file to local dir 561s 08:57:30.742184525 O: scp-uri: sftp mode: recursive local dir to remote dir 561s 08:57:30.747210598 O: scp-uri: sftp mode: recursive remote dir to local dir 561s 08:57:30.753277618 O: ok scp-uri 561s 08:57:30.753729813 E: run test sftp.sh ... 561s 08:57:30.881438604 O: test basic sftp put/get: buffer_size 5 num_requests 1 563s 08:57:32.156600844 O: test basic sftp put/get: buffer_size 5 num_requests 2 563s 08:57:32.865921343 O: test basic sftp put/get: buffer_size 5 num_requests 10 564s 08:57:33.305070751 O: test basic sftp put/get: buffer_size 1000 num_requests 1 564s 08:57:33.315462940 O: test basic sftp put/get: buffer_size 1000 num_requests 2 564s 08:57:33.322514993 O: test basic sftp put/get: buffer_size 1000 num_requests 10 564s 08:57:33.328214956 O: test basic sftp put/get: buffer_size 32000 num_requests 1 564s 08:57:33.332190612 O: test basic sftp put/get: buffer_size 32000 num_requests 2 564s 08:57:33.335986559 O: test basic sftp put/get: buffer_size 32000 num_requests 10 564s 08:57:33.339790139 O: test basic sftp put/get: buffer_size 64000 num_requests 1 564s 08:57:33.343643857 O: test basic sftp put/get: buffer_size 64000 num_requests 2 564s 08:57:33.347628296 O: test basic sftp put/get: buffer_size 64000 num_requests 10 564s 08:57:33.352412337 O: ok basic sftp put/get 564s 08:57:33.352911052 E: run test sftp-chroot.sh ... 564s 08:57:33.627673689 O: test sftp in chroot: get 565s 08:57:33.988075313 O: test sftp in chroot: match 565s 08:57:34.497389926 O: ok sftp in chroot 565s 08:57:34.501472548 E: run test sftp-cmds.sh ... 565s 08:57:34.634164107 O: sftp commands: lls 565s 08:57:34.637767874 O: sftp commands: lls w/path 565s 08:57:34.641048368 O: sftp commands: ls 565s 08:57:34.645301870 O: sftp commands: shell 565s 08:57:34.647699421 O: sftp commands: pwd 565s 08:57:34.649252842 O: sftp commands: lpwd 565s 08:57:34.651005957 O: sftp commands: quit 565s 08:57:34.652519948 O: sftp commands: help 565s 08:57:34.654648439 O: sftp commands: get 565s 08:57:34.658026653 O: sftp commands: get quoted 565s 08:57:34.661992244 O: sftp commands: get filename with quotes 565s 08:57:34.666577952 O: sftp commands: get filename with spaces 565s 08:57:34.670532114 O: sftp commands: get filename with glob metacharacters 565s 08:57:34.673792766 O: sftp commands: get to directory 565s 08:57:34.677559768 O: sftp commands: glob get to directory 565s 08:57:34.716776892 O: sftp commands: get to local dir 565s 08:57:34.720105431 O: sftp commands: glob get to local dir 565s 08:57:34.742956401 O: sftp commands: put 565s 08:57:34.746956362 O: sftp commands: put filename with quotes 565s 08:57:34.750842554 O: sftp commands: put filename with spaces 565s 08:57:34.756118199 O: sftp commands: put to directory 565s 08:57:34.759941918 O: sftp commands: glob put to directory 565s 08:57:34.764650793 O: sftp commands: put to local dir 565s 08:57:34.768618208 O: sftp commands: glob put to local dir 565s 08:57:34.773083812 O: sftp commands: rename 565s 08:57:34.775250326 O: sftp commands: rename directory 565s 08:57:34.776987821 O: sftp commands: ln 565s 08:57:34.778961969 O: sftp commands: ln -s 565s 08:57:34.780998311 O: sftp commands: cp 565s 08:57:34.783882201 O: sftp commands: mkdir 565s 08:57:34.785576151 O: sftp commands: chdir 565s 08:57:34.787370788 O: sftp commands: rmdir 565s 08:57:34.788921044 O: sftp commands: lmkdir 565s 08:57:34.790697257 O: sftp commands: lchdir 565s 08:57:34.793467946 O: ok sftp commands 565s 08:57:34.793641808 E: run test sftp-badcmds.sh ... 565s 08:57:34.926706383 O: sftp invalid commands: get nonexistent 565s 08:57:34.928586177 O: sftp invalid commands: glob get to nonexistent directory 565s 08:57:34.936345827 O: sftp invalid commands: put nonexistent 565s 08:57:34.938529969 O: sftp invalid commands: glob put to nonexistent directory 565s 08:57:34.940990646 O: sftp invalid commands: rename nonexistent 565s 08:57:34.944299453 O: sftp invalid commands: rename target exists (directory) 565s 08:57:34.947769462 O: sftp invalid commands: glob put files to local file 565s 08:57:34.950867091 O: ok sftp invalid commands 565s 08:57:34.951379726 E: run test sftp-batch.sh ... 566s 08:57:35.085434342 O: sftp batchfile: good commands 566s 08:57:35.088729915 O: sftp batchfile: bad commands 566s 08:57:35.092712440 O: sftp batchfile: comments and blanks 566s 08:57:35.096450009 O: sftp batchfile: junk command 566s 08:57:35.098619591 O: ok sftp batchfile 566s 08:57:35.099113866 E: run test sftp-glob.sh ... 566s 08:57:35.232948370 O: sftp glob: file glob 566s 08:57:35.237174516 O: sftp glob: dir glob 566s 08:57:35.240222386 O: sftp glob: quoted glob 566s 08:57:35.243610456 O: sftp glob: escaped glob 566s 08:57:35.247389344 O: sftp glob: escaped quote 566s 08:57:35.250226684 O: sftp glob: quoted quote 566s 08:57:35.252902941 O: sftp glob: single-quoted quote 566s 08:57:35.256003503 O: sftp glob: escaped space 566s 08:57:35.259205155 O: sftp glob: quoted space 566s 08:57:35.262194678 O: sftp glob: escaped slash 566s 08:57:35.265838304 O: sftp glob: quoted slash 566s 08:57:35.268578872 O: sftp glob: escaped slash at EOL 566s 08:57:35.271282829 O: sftp glob: quoted slash at EOL 566s 08:57:35.274378921 O: sftp glob: escaped slash+quote 566s 08:57:35.277653435 O: sftp glob: quoted slash+quote 566s 08:57:35.280874007 O: ok sftp glob 566s 08:57:35.281066763 E: run test sftp-perm.sh ... 566s 08:57:35.413895280 O: sftp permissions: read-only upload 566s 08:57:35.421594789 O: sftp permissions: read-only setstat 566s 08:57:35.429584682 O: sftp permissions: read-only rm 566s 08:57:35.436914539 O: sftp permissions: read-only mkdir 566s 08:57:35.443528097 O: sftp permissions: read-only rmdir 566s 08:57:35.450943657 O: sftp permissions: read-only posix-rename 566s 08:57:35.458738877 O: sftp permissions: read-only oldrename 566s 08:57:35.465975596 O: sftp permissions: read-only symlink 566s 08:57:35.473149513 O: sftp permissions: read-only hardlink 566s 08:57:35.480226008 O: sftp permissions: explicit open 566s 08:57:35.495267690 O: sftp permissions: explicit read 566s 08:57:35.509659264 O: sftp permissions: explicit write 566s 08:57:35.525879905 O: sftp permissions: explicit lstat 566s 08:57:35.540462164 O: sftp permissions: explicit opendir 566s 08:57:35.557934601 O: sftp permissions: explicit readdir 566s 08:57:35.574346036 O: sftp permissions: explicit setstat 566s 08:57:35.589977361 O: sftp permissions: explicit remove 566s 08:57:35.604005894 O: sftp permissions: explicit mkdir 566s 08:57:35.616070256 O: sftp permissions: explicit rmdir 566s 08:57:35.630949112 O: sftp permissions: explicit rename 566s 08:57:35.645178830 O: sftp permissions: explicit symlink 566s 08:57:35.659514051 O: sftp permissions: explicit hardlink 566s 08:57:35.673377983 O: sftp permissions: explicit statvfs 566s 08:57:35.683756536 O: ok sftp permissions 566s 08:57:35.683907712 E: run test sftp-uri.sh ... 566s 08:57:35.953772727 O: sftp-uri: non-interactive fetch to local file 567s 08:57:36.311767175 O: sftp-uri: non-interactive fetch to local dir 567s 08:57:36.661388062 O: sftp-uri: put to remote directory (trailing slash) 568s 08:57:37.011490317 O: sftp-uri: put to remote directory (no slash) 568s 08:57:37.372170723 O: ok sftp-uri 568s 08:57:37.372707867 E: run test reconfigure.sh ... 582s 08:57:51.096244644 O: ok simple connect after reconfigure 582s 08:57:51.096385559 E: run test dynamic-forward.sh ... 582s 08:57:51.363065777 O: test -D forwarding 584s 08:57:53.016133600 O: test -R forwarding 587s 08:57:55.827907379 O: PermitRemoteOpen=any 588s 08:57:57.728370973 O: PermitRemoteOpen=none 589s 08:57:58.230498412 O: PermitRemoteOpen=explicit 591s 08:58:00.077914480 O: PermitRemoteOpen=disallowed 591s 08:58:00.588897982 O: ok dynamic forwarding 591s 08:58:00.589113826 E: run test forwarding.sh ... 599s 08:58:07.970582687 O: ok local and remote forwarding 599s 08:58:07.970655256 E: run test multiplex.sh ... 600s 08:58:09.255919207 O: test connection multiplexing: setenv 600s 08:58:09.263923152 O: test connection multiplexing: envpass 600s 08:58:09.271468773 O: test connection multiplexing: transfer 600s 08:58:09.315660394 O: test connection multiplexing: forward 602s 08:58:11.343847317 O: test connection multiplexing: status 0 () 607s 08:58:16.363678577 O: test connection multiplexing: status 0 (-Oproxy) 612s 08:58:21.382854193 O: test connection multiplexing: status 1 () 617s 08:58:26.402354655 O: test connection multiplexing: status 1 (-Oproxy) 622s 08:58:31.421669358 O: test connection multiplexing: status 4 () 627s 08:58:36.441753918 O: test connection multiplexing: status 4 (-Oproxy) 632s 08:58:41.461348578 O: test connection multiplexing: status 5 () 637s 08:58:46.480452570 O: test connection multiplexing: status 5 (-Oproxy) 642s 08:58:51.499742665 O: test connection multiplexing: status 44 () 647s 08:58:56.518810902 O: test connection multiplexing: status 44 (-Oproxy) 652s 08:59:01.537698450 O: test connection multiplexing: cmd check 652s 08:59:01.543218140 O: test connection multiplexing: cmd forward local (TCP) 653s 08:59:02.909409933 O: test connection multiplexing: cmd forward remote (TCP) 655s 08:59:04.288988747 O: test connection multiplexing: cmd forward local (UNIX) 656s 08:59:05.372387664 O: test connection multiplexing: cmd forward remote (UNIX) 657s 08:59:06.452604516 O: test connection multiplexing: cmd exit 657s 08:59:06.459323599 O: test connection multiplexing: cmd stop 668s 08:59:17.493665681 O: ok connection multiplexing 668s 08:59:17.493899276 E: run test reexec.sh ... 668s 08:59:17.629438274 O: test config passing 669s 08:59:18.132146726 O: test reexec fallback 669s 08:59:18.132784216 E: ln: failed to create hard link '/tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 669s 08:59:18.591187294 O: ok reexec tests 669s 08:59:18.591247230 E: run test brokenkeys.sh ... 670s 08:59:19.248446142 O: ok broken keys 670s 08:59:19.248610481 E: run test sshcfgparse.sh ... 670s 08:59:19.382972222 O: reparse minimal config 670s 08:59:19.393724669 O: ssh -W opts 670s 08:59:19.424897133 O: user first match 670s 08:59:19.446745310 O: pubkeyacceptedalgorithms 670s 08:59:19.494555580 O: agentforwarding 670s 08:59:19.519690004 O: command line override 670s 08:59:19.533103476 O: ok ssh config parse 670s 08:59:19.533601578 E: run test cfgparse.sh ... 670s 08:59:19.669059701 O: reparse minimal config 670s 08:59:19.809220040 O: reparse regress config 670s 08:59:19.948754818 O: listenaddress order 671s 08:59:20.094491508 O: ok sshd config parse 671s 08:59:20.095063970 E: run test cfgmatch.sh ... 679s 08:59:28.089199607 O: ok sshd_config match 679s 08:59:28.089697686 E: run test cfgmatchlisten.sh ... 691s 08:59:40.205031593 O: ok sshd_config matchlisten 691s 08:59:40.205068562 E: run test percent.sh ... 691s 08:59:40.336676875 O: percent expansions matchexec percent 694s 08:59:43.760579113 O: percent expansions localcommand percent 697s 08:59:46.898391139 O: percent expansions remotecommand percent 698s 08:59:46.974984292 O: percent expansions controlpath percent 698s 08:59:47.050331040 O: percent expansions identityagent percent 698s 08:59:47.126213353 O: percent expansions forwardagent percent 698s 08:59:47.201364314 O: percent expansions localforward percent 698s 08:59:47.277559316 O: percent expansions remoteforward percent 698s 08:59:47.353267279 O: percent expansions revokedhostkeys percent 698s 08:59:47.431610834 O: percent expansions userknownhostsfile percent 700s 08:59:49.774194704 O: percent expansions controlpath dollar 700s 08:59:49.780998256 O: percent expansions identityagent dollar 700s 08:59:49.787682542 O: percent expansions forwardagent dollar 700s 08:59:49.794577171 O: percent expansions localforward dollar 700s 08:59:49.801981637 O: percent expansions remoteforward dollar 700s 08:59:49.809035771 O: percent expansions userknownhostsfile dollar 701s 08:59:50.041717609 O: percent expansions controlpath tilde 701s 08:59:50.054742683 O: percent expansions identityagent tilde 701s 08:59:50.067799383 O: percent expansions forwardagent tilde 701s 08:59:50.080982417 O: ok percent expansions 701s 08:59:50.081128447 E: run test addrmatch.sh ... 701s 08:59:50.208877785 O: test first entry for user 192.168.0.1 somehost 701s 08:59:50.273471733 O: test negative match for user 192.168.30.1 somehost 701s 08:59:50.339727813 O: test no match for user 19.0.0.1 somehost 701s 08:59:50.405116026 O: test list middle for user 10.255.255.254 somehost 701s 08:59:50.471706749 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 701s 08:59:50.537671011 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 701s 08:59:50.602525152 O: test localaddress for user 19.0.0.1 somehost 701s 08:59:50.667494133 O: test localport for user 19.0.0.1 somehost 701s 08:59:50.734730362 O: test bare IP6 address for user ::1 somehost.example.com 701s 08:59:50.801258120 O: test deny IPv6 for user ::2 somehost.example.com 701s 08:59:50.865929413 O: test IP6 negated for user ::3 somehost 701s 08:59:50.931485717 O: test IP6 no match for user ::4 somehost 702s 08:59:50.996827343 O: test IP6 network for user 2000::1 somehost 702s 08:59:51.063938913 O: test IP6 network for user 2001::1 somehost 702s 08:59:51.129123022 O: test IP6 localaddress for user ::5 somehost 702s 08:59:51.194422132 O: test IP6 localport for user ::5 somehost 702s 08:59:51.259318556 O: test invalid Match address 10.0.1.0/8 702s 08:59:51.266317709 O: test invalid Match localaddress 10.0.1.0/8 702s 08:59:51.272660402 O: test invalid Match address 10.0.0.1/24 702s 08:59:51.278997247 O: test invalid Match localaddress 10.0.0.1/24 702s 08:59:51.285931884 O: test invalid Match address 2000:aa:bb:01::/56 702s 08:59:51.292675767 O: test invalid Match localaddress 2000:aa:bb:01::/56 702s 08:59:51.301371183 O: ok address match 702s 08:59:51.301592370 E: run test localcommand.sh ... 702s 08:59:51.429628939 O: test localcommand: proto localcommand 702s 08:59:51.695364381 E: run test forcecommand.sh ... 702s 08:59:51.695207218 O: ok localcommand 703s 08:59:52.891564886 E: Connection closed. 703s 08:59:52.892569408 E: Connection closed 704s 08:59:53.414835692 E: Connection closed. 704s 08:59:53.415876300 E: Connection closed 704s 08:59:53.680866397 O: ok forced command 704s 08:59:53.681425256 E: run test portnum.sh ... 704s 08:59:53.809123817 O: port number parsing: invalid port 0 704s 08:59:53.813665886 O: port number parsing: invalid port 65536 704s 08:59:53.818232417 O: port number parsing: invalid port 131073 704s 08:59:53.822889704 O: port number parsing: invalid port 2000blah 704s 08:59:53.827488040 O: port number parsing: invalid port blah2000 704s 08:59:53.832187033 O: port number parsing: valid port 1 705s 08:59:54.093106063 O: port number parsing: valid port 22 705s 08:59:54.353550644 O: port number parsing: valid port 2222 705s 08:59:54.614543414 O: port number parsing: valid port 22222 705s 08:59:54.883842793 O: port number parsing: valid port 65535 706s 08:59:55.147053100 O: ok port number parsing 706s 08:59:55.147965943 E: run test keytype.sh ... 706s 08:59:55.279280629 O: keygen ed25519, 512 bits 706s 08:59:55.285364908 O: keygen ed25519-sk, n/a bits 706s 08:59:55.293554047 O: keygen ecdsa, 256 bits 706s 08:59:55.298960691 O: keygen ecdsa, 384 bits 706s 08:59:55.304516883 O: keygen ecdsa, 521 bits 706s 08:59:55.310176302 O: keygen ecdsa-sk, n/a bits 706s 08:59:55.322598621 O: keygen dsa, 1024 bits 706s 08:59:55.419514085 O: keygen rsa, 2048 bits 706s 08:59:55.566375092 O: keygen rsa, 3072 bits 707s 08:59:56.479109149 O: userkey ed25519-512, hostkey ed25519-512 707s 08:59:56.623560804 O: userkey ed25519-512, hostkey ed25519-512 707s 08:59:56.766296563 O: userkey ed25519-512, hostkey ed25519-512 707s 08:59:56.912088955 O: userkey ed25519-sk, hostkey ed25519-sk 708s 08:59:57.059792816 O: userkey ed25519-sk, hostkey ed25519-sk 708s 08:59:57.206972538 O: userkey ed25519-sk, hostkey ed25519-sk 708s 08:59:57.358253104 O: userkey ecdsa-256, hostkey ecdsa-256 708s 08:59:57.528565297 O: userkey ecdsa-256, hostkey ecdsa-256 708s 08:59:57.692743180 O: userkey ecdsa-256, hostkey ecdsa-256 708s 08:59:57.860230158 O: userkey ecdsa-384, hostkey ecdsa-384 709s 08:59:58.072307496 O: userkey ecdsa-384, hostkey ecdsa-384 709s 08:59:58.286729474 O: userkey ecdsa-384, hostkey ecdsa-384 709s 08:59:58.500730861 O: userkey ecdsa-521, hostkey ecdsa-521 709s 08:59:58.774778384 O: userkey ecdsa-521, hostkey ecdsa-521 710s 08:59:59.055212418 O: userkey ecdsa-521, hostkey ecdsa-521 710s 08:59:59.341356881 O: userkey ecdsa-sk, hostkey ecdsa-sk 710s 08:59:59.518197593 O: userkey ecdsa-sk, hostkey ecdsa-sk 710s 08:59:59.694612210 O: userkey ecdsa-sk, hostkey ecdsa-sk 710s 08:59:59.871558420 O: userkey dsa-1024, hostkey dsa-1024 711s 09:00:00.049822531 O: userkey dsa-1024, hostkey dsa-1024 711s 09:00:00.189501662 O: userkey dsa-1024, hostkey dsa-1024 711s 09:00:00.330934355 O: userkey rsa-2048, hostkey rsa-2048 711s 09:00:00.471969640 O: userkey rsa-2048, hostkey rsa-2048 711s 09:00:00.613740170 O: userkey rsa-2048, hostkey rsa-2048 711s 09:00:00.757641015 O: userkey rsa-3072, hostkey rsa-3072 711s 09:00:00.904973478 O: userkey rsa-3072, hostkey rsa-3072 712s 09:00:01.055106041 O: userkey rsa-3072, hostkey rsa-3072 712s 09:00:01.203340366 O: ok login with different key types 712s 09:00:01.203959676 E: run test kextype.sh ... 712s 09:00:01.341167866 O: kex diffie-hellman-group1-sha1 713s 09:00:02.011982404 O: kex diffie-hellman-group14-sha1 713s 09:00:02.713544660 O: kex diffie-hellman-group14-sha256 714s 09:00:03.416819783 O: kex diffie-hellman-group16-sha512 715s 09:00:04.259801597 O: kex diffie-hellman-group18-sha512 716s 09:00:05.616519581 O: kex diffie-hellman-group-exchange-sha1 718s 09:00:06.992700722 O: kex diffie-hellman-group-exchange-sha256 719s 09:00:08.386852048 O: kex ecdh-sha2-nistp256 720s 09:00:09.047565320 O: kex ecdh-sha2-nistp384 720s 09:00:09.725653979 O: kex ecdh-sha2-nistp521 721s 09:00:10.435042504 O: kex curve25519-sha256 722s 09:00:11.136785197 O: kex curve25519-sha256@libssh.org 722s 09:00:11.836431159 O: kex sntrup761x25519-sha512@openssh.com 723s 09:00:12.891102154 O: ok login with different key exchange algorithms 723s 09:00:12.891754520 E: run test cert-hostkey.sh ... 724s 09:00:13.311195200 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/host_ca_key.pub 724s 09:00:13.311716909 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/host_ca_key2.pub 724s 09:00:13.312176047 O: certified host keys: sign host ed25519 cert 724s 09:00:13.317845088 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 724s 09:00:13.326664809 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 724s 09:00:13.328018000 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 724s 09:00:13.336331665 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 724s 09:00:13.344932405 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 724s 09:00:13.346484841 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 724s 09:00:13.353523145 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 724s 09:00:13.365835664 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 724s 09:00:13.367328885 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 724s 09:00:13.376402801 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 724s 09:00:13.392994908 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 724s 09:00:13.394293057 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 724s 09:00:13.407008529 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 724s 09:00:13.430799505 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 724s 09:00:13.432177275 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 724s 09:00:13.445551276 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 724s 09:00:13.457783321 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 724s 09:00:13.459099350 O: certified host keys: sign host dsa cert 724s 09:00:13.535052406 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_dsa.pub 724s 09:00:13.543926147 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_dsa-cert.pub 724s 09:00:13.545235058 O: certified host keys: sign host rsa cert 725s 09:00:14.008008052 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 725s 09:00:14.017069790 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 725s 09:00:14.018409889 O: certified host keys: sign host rsa-sha2-256 cert 725s 09:00:14.773746274 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 725s 09:00:14.787970164 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 725s 09:00:14.789487875 O: certified host keys: sign host rsa-sha2-512 cert 726s 09:00:15.077500224 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 726s 09:00:15.091605176 O: Revoking from /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 726s 09:00:15.093160713 O: certified host keys: host ed25519 cert connect 726s 09:00:15.094310716 O: certified host keys: ed25519 basic connect expect success yes 726s 09:00:15.311833805 O: certified host keys: ed25519 empty KRL expect success yes 726s 09:00:15.524905928 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 726s 09:00:15.681530696 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 726s 09:00:15.850262676 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 727s 09:00:16.021259851 O: certified host keys: ed25519 empty plaintext revocation expect success yes 727s 09:00:16.258803762 O: certified host keys: ed25519 plain key plaintext revocation expect success no 727s 09:00:16.414681119 O: certified host keys: ed25519 cert plaintext revocation expect success no 727s 09:00:16.586251240 O: certified host keys: ed25519 CA plaintext revocation expect success no 727s 09:00:16.756289574 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 727s 09:00:16.758556707 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 728s 09:00:16.987733500 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 728s 09:00:17.201295179 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 728s 09:00:17.360968980 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 728s 09:00:17.534530086 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 728s 09:00:17.711335612 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 728s 09:00:17.941682105 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 729s 09:00:18.100109646 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 729s 09:00:18.277530115 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 729s 09:00:18.452921501 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 729s 09:00:18.454815018 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 729s 09:00:18.704523610 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 729s 09:00:18.939852673 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 730s 09:00:19.106914677 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 730s 09:00:19.287414634 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 730s 09:00:19.472319766 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 730s 09:00:19.719493230 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 730s 09:00:19.887849478 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 731s 09:00:20.074615802 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 731s 09:00:20.254904682 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 731s 09:00:20.256816459 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 731s 09:00:20.520089284 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 731s 09:00:20.768726426 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 731s 09:00:20.951069054 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 732s 09:00:21.146715031 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 732s 09:00:21.341571376 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 732s 09:00:21.601043960 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 732s 09:00:21.786426533 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 733s 09:00:21.998042260 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 733s 09:00:22.190976112 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 733s 09:00:22.193092107 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 733s 09:00:22.499370681 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 733s 09:00:22.787929088 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 734s 09:00:22.992322913 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 734s 09:00:23.210693739 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 734s 09:00:23.431025260 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 734s 09:00:23.731619507 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 735s 09:00:23.950817205 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 735s 09:00:24.191656204 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 735s 09:00:24.409449917 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 735s 09:00:24.411543064 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 735s 09:00:24.651932211 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 735s 09:00:24.878427353 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 736s 09:00:25.049130412 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 736s 09:00:25.235901222 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 736s 09:00:25.420998839 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 736s 09:00:25.663830760 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 736s 09:00:25.849233599 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 737s 09:00:26.061528333 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 737s 09:00:26.245649887 O: certified host keys: host dsa cert connect 737s 09:00:26.247460190 O: certified host keys: dsa basic connect expect success yes 737s 09:00:26.475320923 O: certified host keys: dsa empty KRL expect success yes 737s 09:00:26.687279126 O: certified host keys: dsa KRL w/ plain key revoked expect success no 737s 09:00:26.839941957 O: certified host keys: dsa KRL w/ cert revoked expect success no 738s 09:00:27.007380651 O: certified host keys: dsa KRL w/ CA revoked expect success no 738s 09:00:27.174791894 O: certified host keys: dsa empty plaintext revocation expect success yes 738s 09:00:27.400461414 O: certified host keys: dsa plain key plaintext revocation expect success no 738s 09:00:27.568257843 O: certified host keys: dsa cert plaintext revocation expect success no 738s 09:00:27.765359324 O: certified host keys: dsa CA plaintext revocation expect success no 738s 09:00:27.934010407 O: certified host keys: host rsa cert connect 738s 09:00:27.935984275 O: certified host keys: rsa basic connect expect success yes 739s 09:00:28.173690236 O: certified host keys: rsa empty KRL expect success yes 739s 09:00:28.389432685 O: certified host keys: rsa KRL w/ plain key revoked expect success no 739s 09:00:28.552531146 O: certified host keys: rsa KRL w/ cert revoked expect success no 739s 09:00:28.728605207 O: certified host keys: rsa KRL w/ CA revoked expect success no 739s 09:00:28.910076881 O: certified host keys: rsa empty plaintext revocation expect success yes 740s 09:00:29.140163182 O: certified host keys: rsa plain key plaintext revocation expect success no 740s 09:00:29.317463800 O: certified host keys: rsa cert plaintext revocation expect success no 740s 09:00:29.526363132 O: certified host keys: rsa CA plaintext revocation expect success no 740s 09:00:29.702618837 O: certified host keys: host rsa-sha2-256 cert connect 740s 09:00:29.704755842 O: certified host keys: rsa-sha2-256 basic connect expect success yes 740s 09:00:29.929198415 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 741s 09:00:30.140249544 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 741s 09:00:30.299166258 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 741s 09:00:30.475622981 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 741s 09:00:30.635323070 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 741s 09:00:30.864519480 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 742s 09:00:31.038349017 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 742s 09:00:31.243737590 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 742s 09:00:31.415357296 O: certified host keys: host rsa-sha2-512 cert connect 742s 09:00:31.416732776 O: certified host keys: rsa-sha2-512 basic connect expect success yes 742s 09:00:31.645381717 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 742s 09:00:31.859608241 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 743s 09:00:32.018472671 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 743s 09:00:32.190167537 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 743s 09:00:32.349729882 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 743s 09:00:32.578584759 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 743s 09:00:32.751773750 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 743s 09:00:32.941612219 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 744s 09:00:33.120265679 O: certified host keys: host ed25519 revoked cert 744s 09:00:33.302064033 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 744s 09:00:33.501534479 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 744s 09:00:33.711780198 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 744s 09:00:33.934755285 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 745s 09:00:34.183529423 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 745s 09:00:34.398549118 O: certified host keys: host dsa revoked cert 745s 09:00:34.600939993 O: certified host keys: host rsa revoked cert 745s 09:00:34.807644077 O: certified host keys: host rsa-sha2-256 revoked cert 746s 09:00:35.010048671 O: certified host keys: host rsa-sha2-512 revoked cert 746s 09:00:35.217492722 O: certified host keys: host ed25519 revoked cert 746s 09:00:35.387073637 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 746s 09:00:35.561892938 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 746s 09:00:35.741454207 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 746s 09:00:35.935559832 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 747s 09:00:36.154051115 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 747s 09:00:36.344939117 O: certified host keys: host dsa revoked cert 747s 09:00:36.513371799 O: certified host keys: host rsa revoked cert 747s 09:00:36.690712367 O: certified host keys: host rsa-sha2-256 revoked cert 747s 09:00:36.864494423 O: certified host keys: host rsa-sha2-512 revoked cert 762s 09:00:51.119568907 O: certified host keys: host ed25519 cert downgrade to raw key 762s 09:00:51.558333367 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 763s 09:00:51.988836883 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 763s 09:00:52.450918445 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 763s 09:00:52.953325309 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 764s 09:00:53.544801321 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 765s 09:00:54.035246667 O: certified host keys: host dsa cert downgrade to raw key 765s 09:00:54.560313638 O: certified host keys: host rsa cert downgrade to raw key 766s 09:00:55.142428887 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 767s 09:00:56.013756383 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 767s 09:00:56.734583679 O: certified host keys: host ed25519 connect wrong cert 767s 09:00:56.899069726 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 768s 09:00:57.096012804 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 768s 09:00:57.287214600 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 768s 09:00:57.503733720 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 768s 09:00:57.770887861 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 769s 09:00:57.982087210 O: certified host keys: host dsa connect wrong cert 769s 09:00:58.240714619 O: certified host keys: host rsa connect wrong cert 769s 09:00:58.924379038 O: certified host keys: host rsa-sha2-256 connect wrong cert 770s 09:00:59.529483795 O: certified host keys: host rsa-sha2-512 connect wrong cert 771s 09:01:00.404928434 O: ok certified host keys 771s 09:01:00.406276448 E: run test cert-userkey.sh ... 772s 09:01:00.981867227 O: certified user keys: sign user ed25519 cert 772s 09:01:00.996729055 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 772s 09:01:01.013447331 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 772s 09:01:01.028665702 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 772s 09:01:01.045933822 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 772s 09:01:01.066735560 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 772s 09:01:01.087980031 O: certified user keys: sign user dsa cert 772s 09:01:01.188017684 O: certified user keys: sign user rsa cert 773s 09:01:01.999317339 O: certified user keys: sign user rsa-sha2-256 cert 773s 09:01:02.421688760 O: certified user keys: sign user rsa-sha2-512 cert 774s 09:01:03.199150993 O: certified user keys: ed25519 missing authorized_principals 774s 09:01:03.473758175 O: certified user keys: ed25519 empty authorized_principals 774s 09:01:03.759792873 O: certified user keys: ed25519 wrong authorized_principals 775s 09:01:04.046110075 O: certified user keys: ed25519 correct authorized_principals 775s 09:01:04.345870798 O: certified user keys: ed25519 authorized_principals bad key opt 775s 09:01:04.623407243 O: certified user keys: ed25519 authorized_principals command=false 775s 09:01:04.935305357 O: certified user keys: ed25519 authorized_principals command=true 776s 09:01:05.217420583 O: certified user keys: ed25519 wrong principals key option 776s 09:01:05.489260778 O: certified user keys: ed25519 correct principals key option 776s 09:01:05.779340190 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 777s 09:01:06.065414807 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 777s 09:01:06.356479000 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 777s 09:01:06.646188379 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 777s 09:01:06.915066608 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 778s 09:01:07.196275261 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 778s 09:01:07.487639850 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 778s 09:01:07.757846355 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 779s 09:01:08.043091713 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 779s 09:01:08.330722128 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 779s 09:01:08.595386740 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 779s 09:01:08.882757071 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 780s 09:01:09.166205230 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 780s 09:01:09.438962752 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 780s 09:01:09.703391335 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 781s 09:01:09.994674382 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 781s 09:01:10.268183849 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 781s 09:01:10.551244296 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 781s 09:01:10.853547449 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 782s 09:01:11.142334666 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 782s 09:01:11.452252621 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 782s 09:01:11.762382149 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 783s 09:01:12.084645507 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 783s 09:01:12.373008766 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 783s 09:01:12.691234350 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 784s 09:01:12.988908787 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 784s 09:01:13.284027294 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 784s 09:01:13.608580003 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 784s 09:01:13.906317232 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 785s 09:01:14.256523608 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 785s 09:01:14.602112645 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 786s 09:01:14.966442331 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 786s 09:01:15.264373630 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 786s 09:01:15.621156803 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 786s 09:01:15.952749504 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 787s 09:01:16.262953875 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 787s 09:01:16.641897666 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 787s 09:01:16.919580829 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 788s 09:01:17.211681391 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 788s 09:01:17.489058158 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 788s 09:01:17.787005701 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 789s 09:01:18.064250993 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 789s 09:01:18.358419961 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 789s 09:01:18.638689789 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 789s 09:01:18.918573711 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 790s 09:01:19.219482449 O: certified user keys: dsa missing authorized_principals 790s 09:01:19.489614948 O: certified user keys: dsa empty authorized_principals 790s 09:01:19.772938143 O: certified user keys: dsa wrong authorized_principals 791s 09:01:20.059032270 O: certified user keys: dsa correct authorized_principals 791s 09:01:20.341376267 O: certified user keys: dsa authorized_principals bad key opt 791s 09:01:20.611150968 O: certified user keys: dsa authorized_principals command=false 791s 09:01:20.895103680 O: certified user keys: dsa authorized_principals command=true 792s 09:01:21.163942680 O: certified user keys: dsa wrong principals key option 792s 09:01:21.446866356 O: certified user keys: dsa correct principals key option 792s 09:01:21.741580462 O: certified user keys: rsa missing authorized_principals 793s 09:01:22.009462779 O: certified user keys: rsa empty authorized_principals 793s 09:01:22.291316562 O: certified user keys: rsa wrong authorized_principals 793s 09:01:22.572441718 O: certified user keys: rsa correct authorized_principals 793s 09:01:22.859100524 O: certified user keys: rsa authorized_principals bad key opt 794s 09:01:23.127367681 O: certified user keys: rsa authorized_principals command=false 794s 09:01:23.413533700 O: certified user keys: rsa authorized_principals command=true 794s 09:01:23.686134536 O: certified user keys: rsa wrong principals key option 795s 09:01:23.958865700 O: certified user keys: rsa correct principals key option 795s 09:01:24.248924552 O: certified user keys: rsa-sha2-256 missing authorized_principals 795s 09:01:24.520445857 O: certified user keys: rsa-sha2-256 empty authorized_principals 795s 09:01:24.804444619 O: certified user keys: rsa-sha2-256 wrong authorized_principals 796s 09:01:25.090668844 O: certified user keys: rsa-sha2-256 correct authorized_principals 796s 09:01:25.377913364 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 796s 09:01:25.647314022 O: certified user keys: rsa-sha2-256 authorized_principals command=false 796s 09:01:25.936452682 O: certified user keys: rsa-sha2-256 authorized_principals command=true 797s 09:01:26.213039656 O: certified user keys: rsa-sha2-256 wrong principals key option 797s 09:01:26.476879596 O: certified user keys: rsa-sha2-256 correct principals key option 797s 09:01:26.778704749 O: certified user keys: rsa-sha2-512 missing authorized_principals 798s 09:01:27.048305858 O: certified user keys: rsa-sha2-512 empty authorized_principals 798s 09:01:27.326958749 O: certified user keys: rsa-sha2-512 wrong authorized_principals 798s 09:01:27.605576085 O: certified user keys: rsa-sha2-512 correct authorized_principals 798s 09:01:27.890122561 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 799s 09:01:28.154369476 O: certified user keys: rsa-sha2-512 authorized_principals command=false 799s 09:01:28.438321123 O: certified user keys: rsa-sha2-512 authorized_principals command=true 799s 09:01:28.710639674 O: certified user keys: rsa-sha2-512 wrong principals key option 800s 09:01:28.980779581 O: certified user keys: rsa-sha2-512 correct principals key option 800s 09:01:29.273399901 O: certified user keys: ed25519 authorized_keys connect 800s 09:01:29.549824586 O: certified user keys: ed25519 authorized_keys revoked key 800s 09:01:29.819218450 O: certified user keys: ed25519 authorized_keys revoked via KRL 801s 09:01:30.098087044 O: certified user keys: ed25519 authorized_keys empty KRL 801s 09:01:30.365682577 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 801s 09:01:30.629826610 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 801s 09:01:30.896698977 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 802s 09:01:31.181407459 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 802s 09:01:31.466258860 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 802s 09:01:31.744771281 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 803s 09:01:32.025539716 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 803s 09:01:32.313805184 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 803s 09:01:32.596033362 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 803s 09:01:32.895916052 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 804s 09:01:33.210246264 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 804s 09:01:33.518903274 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 804s 09:01:33.822791188 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 805s 09:01:34.164705586 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 805s 09:01:34.527433267 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 805s 09:01:34.874136306 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 806s 09:01:35.245665995 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 806s 09:01:35.532612674 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 806s 09:01:35.818871438 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 807s 09:01:36.123107506 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 807s 09:01:36.424334528 O: certified user keys: dsa authorized_keys connect 807s 09:01:36.701545645 O: certified user keys: dsa authorized_keys revoked key 808s 09:01:36.976799925 O: certified user keys: dsa authorized_keys revoked via KRL 808s 09:01:37.265971702 O: certified user keys: dsa authorized_keys empty KRL 808s 09:01:37.550809931 O: certified user keys: rsa authorized_keys connect 808s 09:01:37.822042526 O: certified user keys: rsa authorized_keys revoked key 809s 09:01:38.090724517 O: certified user keys: rsa authorized_keys revoked via KRL 809s 09:01:38.373916110 O: certified user keys: rsa authorized_keys empty KRL 809s 09:01:38.668634955 O: certified user keys: rsa-sha2-256 authorized_keys connect 809s 09:01:38.938007847 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 810s 09:01:39.215200009 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 810s 09:01:39.506897066 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 810s 09:01:39.800402942 O: certified user keys: rsa-sha2-512 authorized_keys connect 811s 09:01:40.073132257 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 811s 09:01:40.348124470 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 811s 09:01:40.636220099 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 811s 09:01:40.926812833 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 812s 09:01:41.201980281 O: certified user keys: authorized_keys CA does not authenticate 812s 09:01:41.203681456 O: certified user keys: ensure CA key does not authenticate user 812s 09:01:41.485598387 O: certified user keys: ed25519 TrustedUserCAKeys connect 812s 09:01:41.777329216 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 813s 09:01:42.047511241 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 813s 09:01:42.334648598 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 813s 09:01:42.626982809 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 813s 09:01:42.892594945 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 814s 09:01:43.160526030 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 814s 09:01:43.441842695 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 814s 09:01:43.709918123 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 815s 09:01:43.984009651 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 815s 09:01:44.264870760 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 815s 09:01:44.563469674 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 815s 09:01:44.840487683 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 816s 09:01:45.134356492 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 816s 09:01:45.439711179 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 816s 09:01:45.746255657 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 817s 09:01:46.071099129 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 817s 09:01:46.415481833 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 817s 09:01:46.776594615 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 818s 09:01:47.119449590 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 818s 09:01:47.483628308 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 818s 09:01:47.761010908 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 819s 09:01:48.038961311 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 819s 09:01:48.336610718 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 819s 09:01:48.632689948 O: certified user keys: dsa TrustedUserCAKeys connect 819s 09:01:48.902949954 O: certified user keys: dsa TrustedUserCAKeys revoked key 820s 09:01:49.177796612 O: certified user keys: dsa TrustedUserCAKeys revoked via KRL 820s 09:01:49.466542958 O: certified user keys: dsa TrustedUserCAKeys empty KRL 820s 09:01:49.761048590 O: certified user keys: rsa TrustedUserCAKeys connect 821s 09:01:50.031857136 O: certified user keys: rsa TrustedUserCAKeys revoked key 821s 09:01:50.301076587 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 821s 09:01:50.584016191 O: certified user keys: rsa TrustedUserCAKeys empty KRL 821s 09:01:50.869738843 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 822s 09:01:51.141908879 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 822s 09:01:51.416517435 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 822s 09:01:51.706932363 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 823s 09:01:52.006734558 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 823s 09:01:52.272304245 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 823s 09:01:52.541316772 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 823s 09:01:52.828457607 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 824s 09:01:53.100313126 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 824s 09:01:53.372156351 O: certified user keys: TrustedUserCAKeys CA does not authenticate 824s 09:01:53.373463854 O: certified user keys: ensure CA key does not authenticate user 824s 09:01:53.641150550 O: certified user keys: correct principal auth authorized_keys expect success rsa 824s 09:01:53.920588202 O: certified user keys: correct principal auth authorized_keys expect success ed25519 825s 09:01:54.199326362 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 825s 09:01:54.475943756 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 825s 09:01:54.747610189 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 826s 09:01:55.025192342 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 826s 09:01:55.297690947 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 826s 09:01:55.574244890 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 826s 09:01:55.850233010 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 827s 09:01:56.135348877 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 827s 09:01:56.420008814 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 827s 09:01:56.705310558 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 828s 09:01:56.994322596 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 828s 09:01:57.285475651 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 828s 09:01:57.579547593 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 828s 09:01:57.863715981 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 829s 09:01:58.146126196 O: certified user keys: cert expired auth authorized_keys expect failure rsa 829s 09:01:58.443399770 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 829s 09:01:58.740378380 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 830s 09:01:59.012691046 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 830s 09:01:59.289019091 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 830s 09:01:59.569861390 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 830s 09:01:59.849116846 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 831s 09:02:00.125224905 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 831s 09:02:00.398201101 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 831s 09:02:00.685220149 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 832s 09:02:00.970611301 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 832s 09:02:01.254613358 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 832s 09:02:01.537298995 O: certified user keys: force-command auth authorized_keys expect failure rsa 832s 09:02:01.823124374 O: certified user keys: force-command auth authorized_keys expect failure ed25519 833s 09:02:02.107123659 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 833s 09:02:02.385119867 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 833s 09:02:02.659334479 O: certified user keys: empty principals auth authorized_keys expect success rsa 833s 09:02:02.939277681 O: certified user keys: empty principals auth authorized_keys expect success ed25519 834s 09:02:03.219966948 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 834s 09:02:03.489409800 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 834s 09:02:03.774378762 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 835s 09:02:04.054290445 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 835s 09:02:04.331062672 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 835s 09:02:04.605201144 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 835s 09:02:04.890454601 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 836s 09:02:05.187388744 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 836s 09:02:05.464956889 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 836s 09:02:05.745215048 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 837s 09:02:06.029370791 O: certified user keys: force-command match true auth authorized_keys expect success rsa 837s 09:02:06.311621615 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 837s 09:02:06.591566193 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 837s 09:02:06.873164317 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 838s 09:02:07.153177560 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 838s 09:02:07.455197339 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 838s 09:02:07.735957463 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 839s 09:02:08.025213074 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 839s 09:02:08.313051645 O: certified user keys: user ed25519 connect wrong cert 839s 09:02:08.602789006 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 839s 09:02:08.878065203 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 840s 09:02:09.161634801 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 840s 09:02:09.464633579 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 840s 09:02:09.764326052 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 841s 09:02:10.038156885 O: certified user keys: user dsa connect wrong cert 841s 09:02:10.316087361 O: certified user keys: user rsa connect wrong cert 841s 09:02:10.618315772 O: certified user keys: user rsa-sha2-256 connect wrong cert 841s 09:02:10.917113361 O: certified user keys: user rsa-sha2-512 connect wrong cert 842s 09:02:11.203641059 O: ok certified user keys 842s 09:02:11.204328002 E: run test host-expand.sh ... 842s 09:02:11.598133692 O: ok expand %h and %n 842s 09:02:11.598284180 E: run test keys-command.sh ... 842s 09:02:11.743468094 O: SKIPPED: /var/run/keycommand_openssh-tests.44016 not executable (/var/run mounted noexec?) 842s 09:02:11.747446424 E: run test forward-control.sh ... 844s 09:02:13.631329098 O: check_lfwd done (expecting Y): default configuration 846s 09:02:15.267054972 O: check_rfwd done (expecting Y): default configuration 846s 09:02:15.880609570 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 848s 09:02:17.506727526 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 848s 09:02:17.793561233 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 850s 09:02:19.427321534 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 852s 09:02:21.061708214 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 853s 09:02:22.686219833 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 855s 09:02:23.980375142 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 856s 09:02:25.605358558 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 858s 09:02:27.221719136 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 859s 09:02:28.867138709 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 861s 09:02:30.159504847 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 861s 09:02:30.439823961 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 862s 09:02:31.731962042 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 863s 09:02:32.004436213 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 863s 09:02:32.619599713 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 865s 09:02:34.246455293 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 866s 09:02:35.862485632 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 867s 09:02:36.132711955 O: check_rfwd done (expecting N): AllowTcpForwarding=local 868s 09:02:37.423796989 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 868s 09:02:37.699535442 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 870s 09:02:39.331971401 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 870s 09:02:39.599649983 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 870s 09:02:39.891025564 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 871s 09:02:40.161031999 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 872s 09:02:41.781445275 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 873s 09:02:42.051066368 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 874s 09:02:43.339421730 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 874s 09:02:43.610546866 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 875s 09:02:44.898488803 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 876s 09:02:45.168720999 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 877s 09:02:46.780816226 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 878s 09:02:47.068607801 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 879s 09:02:48.369064199 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 881s 09:02:50.015044520 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 881s 09:02:50.302610382 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 882s 09:02:51.936154706 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 884s 09:02:53.229365623 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 884s 09:02:53.874324481 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 886s 09:02:55.157833233 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 887s 09:02:56.785978121 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 889s 09:02:58.071610939 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 890s 09:02:59.705413340 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 891s 09:02:59.990100602 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 891s 09:03:00.253529249 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 892s 09:03:01.536239009 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 892s 09:03:01.801276607 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 894s 09:03:03.086030377 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 895s 09:03:04.717110309 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 897s 09:03:06.009864996 O: check_lfwd done (expecting N): AllowTcpForwarding=no 897s 09:03:06.284500645 O: check_rfwd done (expecting N): AllowTcpForwarding=no 897s 09:03:06.569073335 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 897s 09:03:06.839292670 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 899s 09:03:08.131889744 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 899s 09:03:08.407700691 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 900s 09:03:09.700034191 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 901s 09:03:09.987046641 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 902s 09:03:11.276748533 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 902s 09:03:11.546795874 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 903s 09:03:12.835777099 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 904s 09:03:13.106215071 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 904s 09:03:13.392771643 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 904s 09:03:13.662606705 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 904s 09:03:13.953002165 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 905s 09:03:14.221473276 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 906s 09:03:15.831266598 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 907s 09:03:16.097138213 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 907s 09:03:16.708446812 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 909s 09:03:18.334284072 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 911s 09:03:19.951145520 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 911s 09:03:20.220561391 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 912s 09:03:21.831092787 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 913s 09:03:22.104525121 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 914s 09:03:23.395436277 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 914s 09:03:23.665253605 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 915s 09:03:24.951334136 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 917s 09:03:26.585516647 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 918s 09:03:27.875196672 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 919s 09:03:28.143949999 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 919s 09:03:28.429194440 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 919s 09:03:28.696042486 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 919s 09:03:28.704902264 O: ok sshd control of local and remote forwarding 919s 09:03:28.705182229 E: run test integrity.sh ... 919s 09:03:28.848818007 O: test integrity: hmac-sha1 @2900 920s 09:03:29.111529164 O: test integrity: hmac-sha1 @2901 920s 09:03:29.376913592 O: test integrity: hmac-sha1 @2902 920s 09:03:29.641784135 O: test integrity: hmac-sha1 @2903 920s 09:03:29.905583569 O: test integrity: hmac-sha1 @2904 921s 09:03:30.169598658 O: test integrity: hmac-sha1 @2905 921s 09:03:30.433293697 O: test integrity: hmac-sha1 @2906 921s 09:03:30.695906417 O: test integrity: hmac-sha1 @2907 922s 09:03:30.960853411 O: test integrity: hmac-sha1 @2908 922s 09:03:31.225175197 O: test integrity: hmac-sha1 @2909 922s 09:03:31.482392543 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 922s 09:03:31.490449327 O: test integrity: hmac-sha1-96 @2900 922s 09:03:31.754196759 O: test integrity: hmac-sha1-96 @2901 923s 09:03:32.018297518 O: test integrity: hmac-sha1-96 @2902 923s 09:03:32.280672059 O: test integrity: hmac-sha1-96 @2903 923s 09:03:32.542802987 O: test integrity: hmac-sha1-96 @2904 923s 09:03:32.806002663 O: test integrity: hmac-sha1-96 @2905 924s 09:03:33.069630316 O: test integrity: hmac-sha1-96 @2906 924s 09:03:33.334173977 O: test integrity: hmac-sha1-96 @2907 924s 09:03:33.597697150 O: test integrity: hmac-sha1-96 @2908 924s 09:03:33.864431075 O: test integrity: hmac-sha1-96 @2909 925s 09:03:34.123827360 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 925s 09:03:34.132054502 O: test integrity: hmac-sha2-256 @2900 925s 09:03:34.394630907 O: test integrity: hmac-sha2-256 @2901 925s 09:03:34.656891695 O: test integrity: hmac-sha2-256 @2902 925s 09:03:34.920309323 O: test integrity: hmac-sha2-256 @2903 926s 09:03:35.184877701 O: test integrity: hmac-sha2-256 @2904 926s 09:03:35.447877811 O: test integrity: hmac-sha2-256 @2905 926s 09:03:35.711120813 O: test integrity: hmac-sha2-256 @2906 927s 09:03:35.973448896 O: test integrity: hmac-sha2-256 @2907 927s 09:03:36.236811417 O: test integrity: hmac-sha2-256 @2908 927s 09:03:36.500886414 O: test integrity: hmac-sha2-256 @2909 927s 09:03:36.759502483 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 927s 09:03:36.768193133 O: test integrity: hmac-sha2-512 @2900 928s 09:03:37.027540847 O: test integrity: hmac-sha2-512 @2901 928s 09:03:37.291267687 O: test integrity: hmac-sha2-512 @2902 928s 09:03:37.553350979 O: test integrity: hmac-sha2-512 @2903 928s 09:03:37.815568701 O: test integrity: hmac-sha2-512 @2904 929s 09:03:38.077796058 O: test integrity: hmac-sha2-512 @2905 929s 09:03:38.338076134 O: test integrity: hmac-sha2-512 @2906 929s 09:03:38.598841046 O: test integrity: hmac-sha2-512 @2907 929s 09:03:38.861642476 O: test integrity: hmac-sha2-512 @2908 930s 09:03:39.121901773 O: test integrity: hmac-sha2-512 @2909 930s 09:03:39.376693476 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 930s 09:03:39.385617239 O: test integrity: hmac-md5 @2900 930s 09:03:39.652943188 O: test integrity: hmac-md5 @2901 930s 09:03:39.922053945 O: test integrity: hmac-md5 @2902 931s 09:03:40.192370100 O: test integrity: hmac-md5 @2903 931s 09:03:40.461038940 O: test integrity: hmac-md5 @2904 931s 09:03:40.729108113 O: test integrity: hmac-md5 @2905 932s 09:03:40.997831943 O: test integrity: hmac-md5 @2906 932s 09:03:41.267494041 O: test integrity: hmac-md5 @2907 932s 09:03:41.537808934 O: test integrity: hmac-md5 @2908 932s 09:03:41.806699425 O: test integrity: hmac-md5 @2909 933s 09:03:42.070320310 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 933s 09:03:42.077991644 O: test integrity: hmac-md5-96 @2900 933s 09:03:42.345442906 O: test integrity: hmac-md5-96 @2901 933s 09:03:42.612645230 O: test integrity: hmac-md5-96 @2902 933s 09:03:42.879771264 O: test integrity: hmac-md5-96 @2903 934s 09:03:43.148189987 O: test integrity: hmac-md5-96 @2904 934s 09:03:43.417170970 O: test integrity: hmac-md5-96 @2905 934s 09:03:43.688785135 O: test integrity: hmac-md5-96 @2906 935s 09:03:43.966901949 O: test integrity: hmac-md5-96 @2907 935s 09:03:44.236007986 O: test integrity: hmac-md5-96 @2908 935s 09:03:44.505783662 O: test integrity: hmac-md5-96 @2909 935s 09:03:44.773087823 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 935s 09:03:44.781135938 O: test integrity: umac-64@openssh.com @2900 936s 09:03:45.047149723 O: test integrity: umac-64@openssh.com @2901 936s 09:03:45.311784019 O: test integrity: umac-64@openssh.com @2902 936s 09:03:45.578287276 O: test integrity: umac-64@openssh.com @2903 936s 09:03:45.846354256 O: test integrity: umac-64@openssh.com @2904 937s 09:03:46.113770984 O: test integrity: umac-64@openssh.com @2905 937s 09:03:46.379313985 O: test integrity: umac-64@openssh.com @2906 937s 09:03:46.642717224 O: test integrity: umac-64@openssh.com @2907 937s 09:03:46.908044788 O: test integrity: umac-64@openssh.com @2908 938s 09:03:47.171858425 O: test integrity: umac-64@openssh.com @2909 938s 09:03:47.432125395 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 938s 09:03:47.439943990 O: test integrity: umac-128@openssh.com @2900 938s 09:03:47.707484890 O: test integrity: umac-128@openssh.com @2901 939s 09:03:47.979738898 O: test integrity: umac-128@openssh.com @2902 939s 09:03:48.246419790 O: test integrity: umac-128@openssh.com @2903 939s 09:03:48.514366671 O: test integrity: umac-128@openssh.com @2904 939s 09:03:48.781328747 O: test integrity: umac-128@openssh.com @2905 940s 09:03:49.050540962 O: test integrity: umac-128@openssh.com @2906 940s 09:03:49.319753057 O: test integrity: umac-128@openssh.com @2907 940s 09:03:49.592274756 O: test integrity: umac-128@openssh.com @2908 940s 09:03:49.863014982 O: test integrity: umac-128@openssh.com @2909 941s 09:03:50.127083890 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 941s 09:03:50.134447090 O: test integrity: hmac-sha1-etm@openssh.com @2900 941s 09:03:50.399444580 O: test integrity: hmac-sha1-etm@openssh.com @2901 941s 09:03:50.662272421 O: test integrity: hmac-sha1-etm@openssh.com @2902 941s 09:03:50.928944284 O: test integrity: hmac-sha1-etm@openssh.com @2903 942s 09:03:51.197847375 O: test integrity: hmac-sha1-etm@openssh.com @2904 942s 09:03:51.475363879 O: test integrity: hmac-sha1-etm@openssh.com @2905 942s 09:03:51.743232755 O: test integrity: hmac-sha1-etm@openssh.com @2906 943s 09:03:52.010209294 O: test integrity: hmac-sha1-etm@openssh.com @2907 943s 09:03:52.275129998 O: test integrity: hmac-sha1-etm@openssh.com @2908 943s 09:03:52.540623973 O: test integrity: hmac-sha1-etm@openssh.com @2909 943s 09:03:52.801209160 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 943s 09:03:52.809991274 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 944s 09:03:53.098373490 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 944s 09:03:53.370048360 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 944s 09:03:53.642485156 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 944s 09:03:53.914506623 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 945s 09:03:54.186033835 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 945s 09:03:54.459112580 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 945s 09:03:54.734026194 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 946s 09:03:55.006390994 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 946s 09:03:55.280384447 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 946s 09:03:55.546729979 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 946s 09:03:55.554950602 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 946s 09:03:55.822685591 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 947s 09:03:56.091232347 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 947s 09:03:56.360648761 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 947s 09:03:56.629140344 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 947s 09:03:56.898227203 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 948s 09:03:57.171362240 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 948s 09:03:57.442028836 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 948s 09:03:57.711729881 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 949s 09:03:57.981331244 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 949s 09:03:58.244067797 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 949s 09:03:58.252449229 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 949s 09:03:58.499396077 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 949s 09:03:58.745927390 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 950s 09:03:58.990683909 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 950s 09:03:59.235957993 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 950s 09:03:59.483845500 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 950s 09:03:59.731329203 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 951s 09:03:59.977478527 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 951s 09:04:00.220892582 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 951s 09:04:00.465717409 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 951s 09:04:00.703894713 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 951s 09:04:00.711936629 O: test integrity: hmac-md5-etm@openssh.com @2900 952s 09:04:00.975493529 O: test integrity: hmac-md5-etm@openssh.com @2901 952s 09:04:01.241563569 O: test integrity: hmac-md5-etm@openssh.com @2902 952s 09:04:01.504085921 O: test integrity: hmac-md5-etm@openssh.com @2903 952s 09:04:01.767253740 O: test integrity: hmac-md5-etm@openssh.com @2904 953s 09:04:02.029618501 O: test integrity: hmac-md5-etm@openssh.com @2905 953s 09:04:02.290611894 O: test integrity: hmac-md5-etm@openssh.com @2906 953s 09:04:02.549728436 O: test integrity: hmac-md5-etm@openssh.com @2907 953s 09:04:02.811125873 O: test integrity: hmac-md5-etm@openssh.com @2908 954s 09:04:03.070663382 O: test integrity: hmac-md5-etm@openssh.com @2909 954s 09:04:03.325010782 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 954s 09:04:03.332600911 O: test integrity: hmac-md5-96-etm@openssh.com @2900 954s 09:04:03.593142094 O: test integrity: hmac-md5-96-etm@openssh.com @2901 954s 09:04:03.853651167 O: test integrity: hmac-md5-96-etm@openssh.com @2902 955s 09:04:04.116385173 O: test integrity: hmac-md5-96-etm@openssh.com @2903 955s 09:04:04.378249083 O: test integrity: hmac-md5-96-etm@openssh.com @2904 955s 09:04:04.641396450 O: test integrity: hmac-md5-96-etm@openssh.com @2905 955s 09:04:04.905018298 O: test integrity: hmac-md5-96-etm@openssh.com @2906 956s 09:04:05.167358420 O: test integrity: hmac-md5-96-etm@openssh.com @2907 956s 09:04:05.428495249 O: test integrity: hmac-md5-96-etm@openssh.com @2908 956s 09:04:05.691329042 O: test integrity: hmac-md5-96-etm@openssh.com @2909 956s 09:04:05.950209590 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 957s 09:04:05.958429918 O: test integrity: umac-64-etm@openssh.com @2900 957s 09:04:06.227300046 O: test integrity: umac-64-etm@openssh.com @2901 957s 09:04:06.494476798 O: test integrity: umac-64-etm@openssh.com @2902 957s 09:04:06.763946818 O: test integrity: umac-64-etm@openssh.com @2903 958s 09:04:07.027242400 O: test integrity: umac-64-etm@openssh.com @2904 958s 09:04:07.292356435 O: test integrity: umac-64-etm@openssh.com @2905 958s 09:04:07.555638385 O: test integrity: umac-64-etm@openssh.com @2906 958s 09:04:07.819571944 O: test integrity: umac-64-etm@openssh.com @2907 959s 09:04:08.082759164 O: test integrity: umac-64-etm@openssh.com @2908 959s 09:04:08.347196133 O: test integrity: umac-64-etm@openssh.com @2909 959s 09:04:08.606090902 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 959s 09:04:08.614277036 O: test integrity: umac-128-etm@openssh.com @2900 959s 09:04:08.879364917 O: test integrity: umac-128-etm@openssh.com @2901 960s 09:04:09.143309868 O: test integrity: umac-128-etm@openssh.com @2902 960s 09:04:09.406133908 O: test integrity: umac-128-etm@openssh.com @2903 960s 09:04:09.669934754 O: test integrity: umac-128-etm@openssh.com @2904 960s 09:04:09.933677103 O: test integrity: umac-128-etm@openssh.com @2905 961s 09:04:10.196506111 O: test integrity: umac-128-etm@openssh.com @2906 961s 09:04:10.459773856 O: test integrity: umac-128-etm@openssh.com @2907 961s 09:04:10.723852199 O: test integrity: umac-128-etm@openssh.com @2908 962s 09:04:10.986239200 O: test integrity: umac-128-etm@openssh.com @2909 962s 09:04:11.243120488 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 962s 09:04:11.250840082 O: test integrity: aes128-gcm@openssh.com @2900 962s 09:04:11.491670731 O: test integrity: aes128-gcm@openssh.com @2901 962s 09:04:11.732414292 O: test integrity: aes128-gcm@openssh.com @2902 963s 09:04:11.973363406 O: test integrity: aes128-gcm@openssh.com @2903 963s 09:04:12.214321767 O: test integrity: aes128-gcm@openssh.com @2904 963s 09:04:12.455477921 O: test integrity: aes128-gcm@openssh.com @2905 963s 09:04:12.698158985 O: test integrity: aes128-gcm@openssh.com @2906 963s 09:04:12.939376996 O: test integrity: aes128-gcm@openssh.com @2907 964s 09:04:13.181333259 O: test integrity: aes128-gcm@openssh.com @2908 964s 09:04:13.425191646 O: test integrity: aes128-gcm@openssh.com @2909 964s 09:04:13.661742190 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 964s 09:04:13.669504887 O: test integrity: aes256-gcm@openssh.com @2900 964s 09:04:13.913062961 O: test integrity: aes256-gcm@openssh.com @2901 965s 09:04:14.159581319 O: test integrity: aes256-gcm@openssh.com @2902 965s 09:04:14.402939225 O: test integrity: aes256-gcm@openssh.com @2903 965s 09:04:14.645343411 O: test integrity: aes256-gcm@openssh.com @2904 965s 09:04:14.887065155 O: test integrity: aes256-gcm@openssh.com @2905 966s 09:04:15.128596079 O: test integrity: aes256-gcm@openssh.com @2906 966s 09:04:15.371268765 O: test integrity: aes256-gcm@openssh.com @2907 966s 09:04:15.614590866 O: test integrity: aes256-gcm@openssh.com @2908 966s 09:04:15.858635401 O: test integrity: aes256-gcm@openssh.com @2909 967s 09:04:16.097225985 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 967s 09:04:16.105220103 O: test integrity: chacha20-poly1305@openssh.com @2900 967s 09:04:16.347926094 O: test integrity: chacha20-poly1305@openssh.com @2901 967s 09:04:16.590320553 O: test integrity: chacha20-poly1305@openssh.com @2902 967s 09:04:16.833157824 O: test integrity: chacha20-poly1305@openssh.com @2903 968s 09:04:17.078160460 O: test integrity: chacha20-poly1305@openssh.com @2904 968s 09:04:17.322171702 O: test integrity: chacha20-poly1305@openssh.com @2905 968s 09:04:17.563927446 O: test integrity: chacha20-poly1305@openssh.com @2906 968s 09:04:17.806350456 O: test integrity: chacha20-poly1305@openssh.com @2907 969s 09:04:18.048396763 O: test integrity: chacha20-poly1305@openssh.com @2908 969s 09:04:18.291164147 O: test integrity: chacha20-poly1305@openssh.com @2909 969s 09:04:18.527800517 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 969s 09:04:18.528674018 O: ok integrity 969s 09:04:18.529092714 E: run test krl.sh ... 969s 09:04:18.667178302 O: key revocation lists: generating test keys 970s 09:04:19.705085179 O: key revocation lists: generating KRLs 970s 09:04:19.832516945 O: key revocation lists: checking revocations for revoked keys 971s 09:04:20.292101054 O: key revocation lists: checking revocations for unrevoked keys 971s 09:04:20.669441724 O: key revocation lists: checking revocations for revoked certs 972s 09:04:21.412434391 O: key revocation lists: checking revocations for unrevoked certs 973s 09:04:22.071112411 O: key revocation lists: testing KRL update 974s 09:04:23.138071153 O: key revocation lists: checking revocations for revoked keys 974s 09:04:23.600100187 O: key revocation lists: checking revocations for unrevoked keys 975s 09:04:23.977022213 O: key revocation lists: checking revocations for revoked certs 975s 09:04:24.712368776 O: key revocation lists: checking revocations for unrevoked certs 976s 09:04:25.366891926 O: ok key revocation lists 976s 09:04:25.367357442 E: run test multipubkey.sh ... 978s 09:04:27.779036735 E: run test limit-keytype.sh ... 978s 09:04:27.778887970 O: ok multiple pubkey 980s 09:04:29.559324436 O: allow rsa,ed25519 981s 09:04:30.343536053 O: allow ed25519 982s 09:04:31.075148755 O: allow cert only 982s 09:04:31.832328689 O: match w/ no match 983s 09:04:32.605886902 O: match w/ matching 984s 09:04:33.388146351 O: ok restrict pubkey type 984s 09:04:33.388751153 E: run test hostkey-agent.sh ... 985s 09:04:34.626612362 O: key type ssh-ed25519 985s 09:04:34.780094220 O: key type sk-ssh-ed25519@openssh.com 985s 09:04:34.927886972 O: key type ecdsa-sha2-nistp256 986s 09:04:35.086096204 O: key type ecdsa-sha2-nistp384 986s 09:04:35.254381217 O: key type ecdsa-sha2-nistp521 986s 09:04:35.458313726 O: key type sk-ecdsa-sha2-nistp256@openssh.com 986s 09:04:35.619365607 O: key type ssh-dss 986s 09:04:35.763663333 O: key type ssh-rsa 986s 09:04:35.922431237 O: cert type ssh-ed25519-cert-v01@openssh.com 987s 09:04:36.113610324 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 987s 09:04:36.305814071 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 987s 09:04:36.505825312 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 987s 09:04:36.725574676 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 987s 09:04:36.954886320 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 988s 09:04:37.159344756 O: cert type ssh-dss-cert-v01@openssh.com 988s 09:04:37.346802248 O: cert type ssh-rsa-cert-v01@openssh.com 988s 09:04:37.542777478 O: cert type rsa-sha2-256-cert-v01@openssh.com 988s 09:04:37.738125469 O: cert type rsa-sha2-512-cert-v01@openssh.com 988s 09:04:37.937975037 O: ok hostkey agent 988s 09:04:37.938204626 E: run test hostkey-rotate.sh ... 989s 09:04:38.721886557 O: learn hostkey with StrictHostKeyChecking=no 989s 09:04:38.928486160 O: learn additional hostkeys 990s 09:04:39.207640117 O: learn additional hostkeys, type=ssh-ed25519 990s 09:04:39.456165843 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 990s 09:04:39.706418550 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 990s 09:04:39.954943418 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 991s 09:04:40.214555325 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 991s 09:04:40.496267955 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 991s 09:04:40.751601885 O: learn additional hostkeys, type=ssh-dss 992s 09:04:41.000472895 O: learn additional hostkeys, type=ssh-rsa 992s 09:04:41.254641693 O: learn changed non-primary hostkey type=ssh-rsa 992s 09:04:41.657519483 O: learn new primary hostkey 992s 09:04:41.929797736 O: rotate primary hostkey 993s 09:04:42.212731685 O: check rotate primary hostkey 993s 09:04:42.491254565 O: ok hostkey rotate 993s 09:04:42.491413986 E: run test principals-command.sh ... 993s 09:04:42.884026731 O: SKIPPED: /var/run/principals_command_openssh-tests.60358 not executable (/var/run mounted noexec?) 993s 09:04:42.888184805 E: run test cert-file.sh ... 994s 09:04:43.068497118 O: identity cert with no plain public file 994s 09:04:43.344632424 O: CertificateFile with no plain public file 994s 09:04:43.613551520 O: plain keys 994s 09:04:43.902381854 O: untrusted cert 995s 09:04:44.181909458 O: good cert, bad key 995s 09:04:44.513789842 O: single trusted 995s 09:04:44.789452380 O: multiple trusted 997s 09:04:45.964809458 O: ok ssh with certificates 997s 09:04:45.964978510 E: run test cfginclude.sh ... 997s 09:04:46.102669379 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.108699485 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.114438179 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.119650353 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.125349486 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.131241316 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.136531415 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.142622877 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.148004981 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.160259775 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.165363716 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.170721833 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.178064004 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.184126092 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.189374869 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.195302621 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.201123753 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.206934215 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.212719274 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.218559877 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.224274598 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.236371235 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.241766242 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.246972354 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 997s 09:04:46.252668480 O: ok config include 997s 09:04:46.253161002 E: run test servcfginclude.sh ... 997s 09:04:46.526080753 O: ok server config include 997s 09:04:46.526627310 E: run test allow-deny-users.sh ... 999s 09:04:48.856810292 O: ok AllowUsers/DenyUsers 999s 09:04:48.856931438 E: run test authinfo.sh ... 1000s 09:04:48.990812373 O: ExposeAuthInfo=no 1000s 09:04:49.252106978 O: ExposeAuthInfo=yes 1000s 09:04:49.511181861 O: ok authinfo 1000s 09:04:49.511816284 E: run test sshsig.sh ... 1000s 09:04:49.654672396 O: sshsig: make certificates 1000s 09:04:49.697182531 O: sshsig: check signature for ssh-ed25519 1000s 09:04:49.908963150 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 1001s 09:04:50.144213988 O: sshsig: check signature for ecdsa-sha2-nistp256 1001s 09:04:50.462923012 O: sshsig: check signature for ecdsa-sha2-nistp384 1002s 09:04:50.987157052 O: sshsig: check signature for ecdsa-sha2-nistp521 1002s 09:04:51.843617709 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 1003s 09:04:52.236839773 O: sshsig: check signature for ssh-dss 1003s 09:04:52.451381100 O: sshsig: check signature for ssh-rsa 1003s 09:04:52.656800356 O: sshsig: check signature for ssh-ed25519-cert.pub 1004s 09:04:53.173473331 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 1004s 09:04:53.705091829 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 1005s 09:04:54.332092275 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 1006s 09:04:55.203704742 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 1007s 09:04:56.477303961 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1008s 09:04:57.188627787 O: sshsig: check signature for ssh-dss-cert.pub 1008s 09:04:57.652917038 O: sshsig: check signature for ssh-rsa-cert.pub 1009s 09:04:58.134182600 O: sshsig: match principals 1009s 09:04:58.151396423 O: sshsig: nomatch principals 1009s 09:04:58.162483156 O: ok sshsig 1009s 09:04:58.162710725 E: run test knownhosts.sh ... 1010s 09:04:59.777262745 O: ok known hosts 1010s 09:04:59.777773729 E: run test knownhosts-command.sh ... 1010s 09:04:59.905091333 O: simple connection 1011s 09:05:00.140598487 O: no keys 1011s 09:05:00.293422104 O: bad exit status 1011s 09:05:00.430314489 O: keytype ssh-ed25519 1011s 09:05:00.680291308 O: keytype sk-ssh-ed25519@openssh.com 1011s 09:05:00.891190671 O: keytype ecdsa-sha2-nistp256 1012s 09:05:01.096777627 O: keytype ecdsa-sha2-nistp384 1012s 09:05:01.326366175 O: keytype ecdsa-sha2-nistp521 1012s 09:05:01.581476463 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1012s 09:05:01.799791024 O: keytype ssh-rsa 1013s 09:05:02.011630730 O: ok known hosts command 1013s 09:05:02.011812741 E: run test agent-restrict.sh ... 1013s 09:05:02.141337446 O: generate keys 1013s 09:05:02.196898318 O: prepare client config 1013s 09:05:02.203222734 O: prepare known_hosts 1013s 09:05:02.205559194 O: prepare server configs 1013s 09:05:02.213448941 O: authentication w/o agent 1014s 09:05:03.704655401 O: start agent 1018s 09:05:07.709327211 O: authentication with agent (no restrict) 1020s 09:05:09.230231086 O: unrestricted keylist 1021s 09:05:10.016137200 O: authentication with agent (basic restrict) 1021s 09:05:10.791717509 O: authentication with agent incorrect key (basic restrict) 1022s 09:05:11.453431510 O: keylist (basic restrict) 1023s 09:05:12.247314360 O: username 1024s 09:05:13.018121467 O: username wildcard 1024s 09:05:13.790398046 O: username incorrect 1024s 09:05:13.836912934 O: agent restriction honours certificate principal 1024s 09:05:13.858067554 O: multihop without agent 1025s 09:05:14.728812874 O: multihop agent unrestricted 1026s 09:05:15.605377527 O: multihop restricted 1027s 09:05:16.488480809 O: multihop username 1028s 09:05:17.340762530 O: multihop wildcard username 1029s 09:05:18.199436232 O: multihop wrong username 1029s 09:05:18.924797867 O: multihop cycle no agent 1031s 09:05:20.160752851 O: multihop cycle agent unrestricted 1032s 09:05:21.407218125 O: multihop cycle restricted deny 1033s 09:05:21.977956243 O: multihop cycle restricted allow 1034s 09:05:23.245307094 O: ok agent restrictions 1034s 09:05:23.245413347 E: run test hostbased.sh ... 1034s 09:05:23.381250007 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1034s 09:05:23.381800572 E: run test channel-timeout.sh ... 1034s 09:05:23.513408578 O: no timeout 1039s 09:05:28.777283047 O: command timeout 1040s 09:05:29.116711055 O: command long timeout 1040s 09:05:29.375267312 O: command wildcard timeout 1041s 09:05:30.118594009 O: command irrelevant timeout 1046s 09:05:35.385375527 O: multiplexed command timeout 1057s 09:05:46.135391661 O: irrelevant multiplexed command timeout 1072s 09:06:01.416335553 O: global command timeout 1094s 09:06:23.142055459 O: sftp no timeout 1099s 09:06:28.425501510 O: sftp timeout 1100s 09:06:29.118547708 O: sftp irrelevant timeout 1100s 09:06:29.118772102 E: Connection closed 1105s 09:06:34.396821266 O: ok channel timeout 1105s 09:06:34.397665444 E: run test connection-timeout.sh ... 1105s 09:06:34.528483721 O: no timeout 1110s 09:06:39.795958988 O: timeout 1119s 09:06:48.058545122 O: session inhibits timeout 1127s 09:06:56.322084251 O: timeout after session 1135s 09:07:04.326310627 O: timeout with listeners 1143s 09:07:12.586890287 E: run test match-subsystem.sh ... 1143s 09:07:12.587479443 O: ok unused connection timeout 1146s 09:07:15.299702781 E: run test agent-pkcs11-restrict.sh ... 1146s 09:07:15.299368372 O: ok sshd_config match subsystem 1146s 09:07:15.427456479 O: SKIPPED: No PKCS#11 library found 1146s 09:07:15.427904944 E: run test agent-pkcs11-cert.sh ... 1146s 09:07:15.553271605 O: SKIPPED: No PKCS#11 library found 1146s 09:07:15.553735961 O: set -e ; if test -z "" ; then \ 1146s 09:07:15.554171388 O: V="" ; \ 1146s 09:07:15.554608236 O: test "x" = "x" || \ 1146s 09:07:15.555055714 O: V=/tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1146s 09:07:15.555506779 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1146s 09:07:15.555953272 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1146s 09:07:15.556399002 O: -d /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1146s 09:07:15.556844558 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1146s 09:07:15.557288942 O: -d /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1146s 09:07:15.557715689 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1146s 09:07:15.558171272 O: -d /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1146s 09:07:15.558610639 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1146s 09:07:15.559051327 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1146s 09:07:15.559496302 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1146s 09:07:15.559938360 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1146s 09:07:15.560373218 O: -d /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1146s 09:07:15.560808744 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1146s 09:07:15.561245288 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1146s 09:07:15.561671757 O: if test "x" = "xyes" ; then \ 1146s 09:07:15.562108301 O: $V /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1146s 09:07:15.562530209 O: fi \ 1146s 09:07:15.562952892 O: fi 1162s 09:07:31.301510096 O: test_sshbuf: ...................................................................................................... 103 tests ok 1270s 09:09:19.070654746 O: test_sshkey: ........................................................................................................ 104 tests ok 1270s 09:09:19.088624380 O: test_sshsig: ........ 8 tests ok 1270s 09:09:19.329582244 O: test_authopt: .................................................................................................................................................. 146 tests ok 1284s 09:09:33.164404401 O: test_bitmap: .. 2 tests ok 1284s 09:09:33.167162369 O: test_conversion: . 1 tests ok 1297s 09:09:46.420811590 O: test_kex: ............................................................................................................................................................................................................................................................................................................................................................................................................. 398 tests ok 1297s 09:09:46.876992377 O: test_hostkeys: .................. 18 tests ok 1297s 09:09:46.880254155 O: test_match: ...... 6 tests ok 1297s 09:09:46.882910139 O: test_misc: ........................................... 43 tests ok 1297s 09:09:46.883665922 E: run test putty-transfer.sh ... 1299s 09:09:48.359100392 O: plink version 0.81 minor 81 1299s 09:09:48.365278764 O: putty transfer data: compression 0 1301s 09:09:50.151717237 O: putty transfer data: compression 1 1302s 09:09:51.922656568 O: ok putty transfer data 1302s 09:09:51.923219992 E: run test putty-ciphers.sh ... 1303s 09:09:52.948189595 O: plink version 0.81 minor 81 1304s 09:09:53.184601176 O: putty ciphers: cipher default mac default 1304s 09:09:53.419191666 O: putty ciphers: cipher default mac hmac-sha1 1304s 09:09:53.645999654 O: putty ciphers: cipher default mac hmac-sha1-96 1304s 09:09:53.874523958 O: putty ciphers: cipher default mac hmac-sha2-256 1305s 09:09:54.096182666 O: putty ciphers: cipher default mac hmac-sha2-512 1305s 09:09:54.320150420 O: putty ciphers: cipher default mac hmac-md5 1305s 09:09:54.547592188 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1305s 09:09:54.769609593 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1306s 09:09:54.996139404 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1306s 09:09:55.221359653 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1306s 09:09:55.448448510 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1306s 09:09:55.670483433 O: putty ciphers: cipher 3des-cbc mac default 1306s 09:09:55.894627741 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1307s 09:09:56.124237124 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1307s 09:09:56.349685257 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1307s 09:09:56.574998804 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1307s 09:09:56.838010681 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1308s 09:09:57.055983830 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1308s 09:09:57.274091655 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1308s 09:09:57.497580049 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1308s 09:09:57.723504847 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1308s 09:09:57.946314173 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1309s 09:09:58.166052637 O: putty ciphers: cipher aes128-cbc mac default 1309s 09:09:58.398555086 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1309s 09:09:58.626622579 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1309s 09:09:58.850689990 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1310s 09:09:59.074139355 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1310s 09:09:59.294584409 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1310s 09:09:59.511878218 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1310s 09:09:59.733861134 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1310s 09:09:59.953997046 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1311s 09:10:00.182190017 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1311s 09:10:00.405701629 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1311s 09:10:00.626736469 O: putty ciphers: cipher aes192-cbc mac default 1311s 09:10:00.848659881 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1312s 09:10:01.072736897 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1312s 09:10:01.293354588 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1312s 09:10:01.514149268 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1312s 09:10:01.740514167 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1313s 09:10:01.960768157 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1313s 09:10:02.179984620 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1313s 09:10:02.400940191 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1313s 09:10:02.620743992 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1313s 09:10:02.846579268 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1314s 09:10:03.069549855 O: putty ciphers: cipher aes256-cbc mac default 1314s 09:10:03.288049477 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1314s 09:10:03.510002941 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1314s 09:10:03.742376862 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1315s 09:10:03.965171259 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1315s 09:10:04.189353819 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1315s 09:10:04.406724243 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1315s 09:10:04.634175409 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1315s 09:10:04.853375458 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1316s 09:10:05.072875085 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1316s 09:10:05.294147679 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1316s 09:10:05.511775308 O: putty ciphers: cipher aes128-ctr mac default 1316s 09:10:05.736596505 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1317s 09:10:05.965020777 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1317s 09:10:06.188306663 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1317s 09:10:06.407291025 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1317s 09:10:06.640042678 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1317s 09:10:06.863873420 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1318s 09:10:07.085289840 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1318s 09:10:07.306302514 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1318s 09:10:07.530324834 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1318s 09:10:07.748001939 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1319s 09:10:07.969906180 O: putty ciphers: cipher aes192-ctr mac default 1319s 09:10:08.190365968 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1319s 09:10:08.412483485 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1319s 09:10:08.638012437 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1319s 09:10:08.861949613 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1320s 09:10:09.082111961 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1320s 09:10:09.302901556 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1320s 09:10:09.526180971 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1320s 09:10:09.748309041 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1321s 09:10:09.974678666 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1321s 09:10:10.195886836 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1321s 09:10:10.421523721 O: putty ciphers: cipher aes256-ctr mac default 1321s 09:10:10.641210109 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1321s 09:10:10.864060724 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1322s 09:10:11.087361663 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1322s 09:10:11.309288475 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1322s 09:10:11.528333976 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1322s 09:10:11.744868396 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1323s 09:10:11.967880290 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1323s 09:10:12.193782519 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1323s 09:10:12.418806372 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1323s 09:10:12.652933348 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1323s 09:10:12.872528859 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1324s 09:10:13.098438477 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1324s 09:10:13.324319655 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1324s 09:10:13.552814779 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1324s 09:10:13.773548457 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1325s 09:10:13.995520729 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1325s 09:10:14.213387387 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1325s 09:10:14.430537097 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1325s 09:10:14.652387017 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1325s 09:10:14.872446669 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1326s 09:10:15.095740364 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1326s 09:10:15.322222360 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1326s 09:10:15.545376669 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1326s 09:10:15.771922214 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1327s 09:10:16.000995827 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1327s 09:10:16.220679913 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1327s 09:10:16.450681331 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1327s 09:10:16.683845473 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1327s 09:10:16.902215855 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1328s 09:10:17.128291521 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1328s 09:10:17.350026385 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1328s 09:10:17.569951336 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1328s 09:10:17.793202018 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1329s 09:10:18.019063394 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1329s 09:10:18.242722144 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1329s 09:10:18.467047505 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1329s 09:10:18.690781018 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1329s 09:10:18.914333567 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1330s 09:10:19.131624171 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1330s 09:10:19.353868553 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1330s 09:10:19.577147058 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1330s 09:10:19.796836971 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1331s 09:10:20.020818902 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1331s 09:10:20.240301964 O: ok putty ciphers 1331s 09:10:20.240500673 E: run test putty-kex.sh ... 1335s 09:10:24.091118656 O: plink version 0.81 minor 81 1335s 09:10:24.200974190 O: putty KEX: kex diffie-hellman-group1-sha1 1335s 09:10:24.392124395 O: putty KEX: kex diffie-hellman-group14-sha1 1335s 09:10:24.719655489 O: putty KEX: kex diffie-hellman-group14-sha256 1336s 09:10:25.055174358 O: putty KEX: kex diffie-hellman-group16-sha512 1337s 09:10:26.329649182 O: putty KEX: kex diffie-hellman-group18-sha512 1345s 09:10:34.735531617 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1346s 09:10:35.092560285 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1347s 09:10:36.386912180 O: putty KEX: kex ecdh-sha2-nistp256 1347s 09:10:36.559885169 O: putty KEX: kex ecdh-sha2-nistp384 1347s 09:10:36.764054143 O: putty KEX: kex ecdh-sha2-nistp521 1348s 09:10:37.046069577 O: putty KEX: kex curve25519-sha256 1348s 09:10:37.229507844 O: putty KEX: kex curve25519-sha256@libssh.org 1348s 09:10:37.398581308 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1348s 09:10:37.608737442 O: ok putty KEX 1348s 09:10:37.608830893 E: run test conch-ciphers.sh ... 1348s 09:10:37.871548753 O: conch ciphers: cipher aes256-ctr 1349s 09:10:38.383158077 O: conch ciphers: cipher aes256-cbc 1349s 09:10:38.893397643 O: conch ciphers: cipher aes192-ctr 1350s 09:10:39.394103826 O: conch ciphers: cipher aes192-cbc 1350s 09:10:39.892924583 O: conch ciphers: cipher aes128-ctr 1351s 09:10:40.403700924 O: conch ciphers: cipher aes128-cbc 1351s 09:10:40.905323566 O: conch ciphers: cipher cast128-cbc 1352s 09:10:41.402591809 O: conch ciphers: cipher blowfish 1352s 09:10:41.902621745 O: conch ciphers: cipher 3des-cbc 1353s 09:10:42.411436712 O: ok conch ciphers 1353s 09:10:42.411566177 E: run test dropbear-ciphers.sh ... 1354s 09:10:43.497024098 E: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1354s 09:10:43.568211848 O: ok dropbear ciphers 1354s 09:10:43.568644188 E: run test dropbear-kex.sh ... 1354s 09:10:43.645304871 E: /tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1354s 09:10:43.713527134 O: dropbear kex: kex curve25519-sha256 1354s 09:10:43.860973054 O: dropbear kex: kex curve25519-sha256@libssh.org 1355s 09:10:44.004822697 O: dropbear kex: kex diffie-hellman-group14-sha256 1355s 09:10:44.178894747 O: dropbear kex: kex diffie-hellman-group14-sha1 1355s 09:10:44.350088032 O: ok dropbear kex 1355s 09:10:44.350586393 O: make: Leaving directory '/tmp/autopkgtest.PyKwRX/autopkgtest_tmp/user/regress' 1355s 09:10:44.351247267 I: Finished with exitcode 0 1355s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1355s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 1355s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1356s info: Looking for files to backup/remove ... 1356s info: Removing files ... 1356s info: Removing crontab ... 1356s info: Removing user `openssh-tests' ... 1356s autopkgtest [09:10:45]: test regress: -----------------------] 1357s autopkgtest [09:10:46]: test regress: - - - - - - - - - - results - - - - - - - - - - 1357s regress PASS 1357s autopkgtest [09:10:46]: test ssh-gssapi: preparing testbed 1426s autopkgtest [09:11:55]: testbed dpkg architecture: s390x 1426s autopkgtest [09:11:55]: testbed apt version: 2.9.8 1426s autopkgtest [09:11:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1427s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1427s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1428s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [171 kB] 1428s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [18.8 kB] 1428s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1550 kB] 1428s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [218 kB] 1428s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [1191 kB] 1428s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [19.0 kB] 1428s Fetched 3249 kB in 1s (3370 kB/s) 1428s Reading package lists... 1430s Reading package lists... 1430s Building dependency tree... 1430s Reading state information... 1430s Calculating upgrade... 1430s The following packages will be upgraded: 1430s libpam-modules libpam-modules-bin libpam-runtime libpam0g 1430s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1430s Need to get 460 kB of archives. 1430s After this operation, 0 B of additional disk space will be used. 1430s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam0g s390x 1.5.3-7ubuntu3 [69.9 kB] 1431s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules-bin s390x 1.5.3-7ubuntu3 [56.2 kB] 1431s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules s390x 1.5.3-7ubuntu3 [294 kB] 1431s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 1431s Preconfiguring packages ... 1431s Fetched 460 kB in 0s (994 kB/s) 1431s (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 ... 55483 files and directories currently installed.) 1431s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_s390x.deb ... 1431s Unpacking libpam0g:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1431s Setting up libpam0g:s390x (1.5.3-7ubuntu3) ... 1431s (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 ... 55483 files and directories currently installed.) 1431s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_s390x.deb ... 1431s Unpacking libpam-modules-bin (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1431s Setting up libpam-modules-bin (1.5.3-7ubuntu3) ... 1431s pam_namespace.service is a disabled or a static unit not running, not starting it. 1431s (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 ... 55483 files and directories currently installed.) 1431s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_s390x.deb ... 1432s Unpacking libpam-modules:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1432s Setting up libpam-modules:s390x (1.5.3-7ubuntu3) ... 1432s (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 ... 55483 files and directories currently installed.) 1432s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 1432s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1432s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 1432s Processing triggers for man-db (2.12.1-3) ... 1432s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1432s Reading package lists... 1433s Building dependency tree... 1433s Reading state information... 1433s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1433s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1433s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1433s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1433s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1434s Reading package lists... 1434s Reading package lists... 1434s Building dependency tree... 1434s Reading state information... 1434s Calculating upgrade... 1434s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1434s Reading package lists... 1434s Building dependency tree... 1434s Reading state information... 1434s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1435s autopkgtest [09:12:04]: rebooting testbed after setup commands that affected boot 1456s Reading package lists... 1456s Building dependency tree... 1456s Reading state information... 1456s Starting pkgProblemResolver with broken count: 0 1456s Starting 2 pkgProblemResolver with broken count: 0 1456s Done 1456s The following additional packages will be installed: 1456s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1456s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1456s libverto-libevent1t64 libverto1t64 1456s Suggested packages: 1456s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 1456s The following NEW packages will be installed: 1456s autopkgtest-satdep krb5-admin-server krb5-config krb5-kdc krb5-user 1456s libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 1456s libkdb5-10t64 libverto-libevent1t64 libverto1t64 1456s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 1456s Need to get 783 kB/784 kB of archives. 1456s After this operation, 2606 kB of additional disk space will be used. 1456s Get:1 /tmp/autopkgtest.PyKwRX/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 1456s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-config all 2.7 [22.0 kB] 1456s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-3 [59.1 kB] 1456s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-3 [40.3 kB] 1456s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-3 [41.8 kB] 1456s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-3 [55.4 kB] 1456s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-user s390x 1.21.3-3 [110 kB] 1456s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 1456s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 1456s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 1456s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-kdc s390x 1.21.3-3 [197 kB] 1457s Get:12 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-admin-server s390x 1.21.3-3 [95.7 kB] 1457s Preconfiguring packages ... 1457s Fetched 783 kB in 1s (1452 kB/s) 1457s Selecting previously unselected package krb5-config. 1457s (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 ... 55483 files and directories currently installed.) 1457s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1457s Unpacking krb5-config (2.7) ... 1457s Selecting previously unselected package libgssrpc4t64:s390x. 1457s Preparing to unpack .../01-libgssrpc4t64_1.21.3-3_s390x.deb ... 1457s Unpacking libgssrpc4t64:s390x (1.21.3-3) ... 1457s Selecting previously unselected package libkadm5clnt-mit12:s390x. 1457s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_s390x.deb ... 1457s Unpacking libkadm5clnt-mit12:s390x (1.21.3-3) ... 1457s Selecting previously unselected package libkdb5-10t64:s390x. 1457s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_s390x.deb ... 1457s Unpacking libkdb5-10t64:s390x (1.21.3-3) ... 1457s Selecting previously unselected package libkadm5srv-mit12:s390x. 1457s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_s390x.deb ... 1457s Unpacking libkadm5srv-mit12:s390x (1.21.3-3) ... 1457s Selecting previously unselected package krb5-user. 1457s Preparing to unpack .../05-krb5-user_1.21.3-3_s390x.deb ... 1457s Unpacking krb5-user (1.21.3-3) ... 1457s Selecting previously unselected package libevent-2.1-7t64:s390x. 1457s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 1457s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1457s Selecting previously unselected package libverto1t64:s390x. 1457s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1457s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1457s Selecting previously unselected package libverto-libevent1t64:s390x. 1457s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1457s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1457s Selecting previously unselected package krb5-kdc. 1457s Preparing to unpack .../09-krb5-kdc_1.21.3-3_s390x.deb ... 1457s Unpacking krb5-kdc (1.21.3-3) ... 1457s Selecting previously unselected package krb5-admin-server. 1457s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_s390x.deb ... 1457s Unpacking krb5-admin-server (1.21.3-3) ... 1457s Selecting previously unselected package autopkgtest-satdep. 1457s Preparing to unpack .../11-2-autopkgtest-satdep.deb ... 1457s Unpacking autopkgtest-satdep (0) ... 1457s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1457s Setting up libgssrpc4t64:s390x (1.21.3-3) ... 1457s Setting up krb5-config (2.7) ... 1457s Setting up libkadm5clnt-mit12:s390x (1.21.3-3) ... 1457s Setting up libkdb5-10t64:s390x (1.21.3-3) ... 1457s Setting up libkadm5srv-mit12:s390x (1.21.3-3) ... 1457s Setting up krb5-user (1.21.3-3) ... 1457s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1457s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1457s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1457s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1457s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1457s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1457s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1457s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1457s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1457s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1457s Setting up krb5-kdc (1.21.3-3) ... 1458s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1458s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1458s Setting up krb5-admin-server (1.21.3-3) ... 1458s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1458s Setting up autopkgtest-satdep (0) ... 1458s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1458s Processing triggers for man-db (2.12.1-3) ... 1461s (Reading database ... 55597 files and directories currently installed.) 1461s Removing autopkgtest-satdep (0) ... 1463s autopkgtest [09:12:32]: test ssh-gssapi: [----------------------- 1463s ## Setting up test environment 1463s ## Creating Kerberos realm EXAMPLE.FAKE 1463s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1463s master key name 'K/M@EXAMPLE.FAKE' 1463s ## Creating principals 1463s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1463s Principal "testuser1647@EXAMPLE.FAKE" created. 1463s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1463s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1463s ## Extracting service principal host/sshd-gssapi.example.fake 1463s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1463s 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. 1463s 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. 1463s ## Adjusting /etc/krb5.conf 1463s ## TESTS 1463s 1463s ## TEST test_gssapi_login 1463s ## Configuring sshd for gssapi-with-mic authentication 1463s ## Restarting ssh 1463s ## Obtaining TGT 1463s Password for testuser1647@EXAMPLE.FAKE: 1463s Ticket cache: FILE:/tmp/krb5cc_0 1463s Default principal: testuser1647@EXAMPLE.FAKE 1463s 1463s Valid starting Expires Service principal 1463s 11/07/24 09:13:58 11/07/24 19:13:58 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1463s renew until 11/08/24 09:13:58 1463s 1463s ## ssh'ing into localhost using gssapi-with-mic auth 1463s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1463s Thu Nov 7 09:13:59 UTC 2024 1463s 1463s ## checking that we got a service ticket for ssh (host/) 1463s 11/07/24 09:13:58 11/07/24 19:13:58 host/sshd-gssapi.example.fake@ 1463s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1463s 1463s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1463s Nov 07 09:13:59 sshd-gssapi.example.fake sshd[1710]: Accepted gssapi-with-mic for testuser1647 from 127.0.0.1 port 47332 ssh2: testuser1647@EXAMPLE.FAKE 1463s ## PASS test_gssapi_login 1463s 1463s ## TEST test_gssapi_keyex_login 1463s ## Configuring sshd for gssapi-keyex authentication 1463s ## Restarting ssh 1464s ## Obtaining TGT 1464s Password for testuser1647@EXAMPLE.FAKE: 1464s Ticket cache: FILE:/tmp/krb5cc_0 1464s Default principal: testuser1647@EXAMPLE.FAKE 1464s 1464s Valid starting Expires Service principal 1464s 11/07/24 09:13:59 11/07/24 19:13:59 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1464s renew until 11/08/24 09:13:59 1464s 1464s ## ssh'ing into localhost using gssapi-keyex auth 1464s Thu Nov 7 09:13:59 UTC 2024 1464s 1464s ## checking that we got a service ticket for ssh (host/) 1464s 11/07/24 09:13:59 11/07/24 19:13:59 host/sshd-gssapi.example.fake@ 1464s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1464s 1464s ## Checking ssh logs to confirm gssapi-keyex auth was used 1464s Nov 07 09:13:59 sshd-gssapi.example.fake sshd[1761]: Accepted gssapi-keyex for testuser1647 from 127.0.0.1 port 47346 ssh2: testuser1647@EXAMPLE.FAKE 1464s ## PASS test_gssapi_keyex_login 1464s 1464s ## ALL TESTS PASSED 1464s ## Cleaning up 1464s autopkgtest [09:12:33]: test ssh-gssapi: -----------------------] 1464s ssh-gssapi PASS 1464s autopkgtest [09:12:33]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1465s autopkgtest [09:12:34]: test systemd-socket-activation: preparing testbed 1527s autopkgtest [09:13:36]: testbed dpkg architecture: s390x 1527s autopkgtest [09:13:36]: testbed apt version: 2.9.8 1527s autopkgtest [09:13:36]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1528s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1529s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1550 kB] 1529s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1529s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [18.8 kB] 1529s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [171 kB] 1529s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [218 kB] 1529s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [1191 kB] 1529s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [19.0 kB] 1530s Fetched 3249 kB in 2s (1748 kB/s) 1530s Reading package lists... 1531s Reading package lists... 1531s Building dependency tree... 1531s Reading state information... 1532s Calculating upgrade... 1532s The following packages will be upgraded: 1532s libpam-modules libpam-modules-bin libpam-runtime libpam0g 1532s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1532s Need to get 460 kB of archives. 1532s After this operation, 0 B of additional disk space will be used. 1532s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam0g s390x 1.5.3-7ubuntu3 [69.9 kB] 1532s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules-bin s390x 1.5.3-7ubuntu3 [56.2 kB] 1532s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-modules s390x 1.5.3-7ubuntu3 [294 kB] 1532s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 1532s Preconfiguring packages ... 1532s Fetched 460 kB in 0s (994 kB/s) 1533s (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 ... 55483 files and directories currently installed.) 1533s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_s390x.deb ... 1533s Unpacking libpam0g:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1533s Setting up libpam0g:s390x (1.5.3-7ubuntu3) ... 1533s (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 ... 55483 files and directories currently installed.) 1533s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_s390x.deb ... 1533s Unpacking libpam-modules-bin (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1533s Setting up libpam-modules-bin (1.5.3-7ubuntu3) ... 1533s pam_namespace.service is a disabled or a static unit not running, not starting it. 1533s (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 ... 55483 files and directories currently installed.) 1533s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_s390x.deb ... 1533s Unpacking libpam-modules:s390x (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1533s Setting up libpam-modules:s390x (1.5.3-7ubuntu3) ... 1533s (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 ... 55483 files and directories currently installed.) 1533s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 1533s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1533s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 1533s Processing triggers for man-db (2.12.1-3) ... 1534s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1534s Reading package lists... 1534s Building dependency tree... 1534s Reading state information... 1534s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1534s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1534s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1535s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1535s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1535s Reading package lists... 1535s Reading package lists... 1535s Building dependency tree... 1535s Reading state information... 1536s Calculating upgrade... 1536s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1536s Reading package lists... 1536s Building dependency tree... 1536s Reading state information... 1536s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1536s autopkgtest [09:13:45]: rebooting testbed after setup commands that affected boot 1540s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 1558s Reading package lists... 1558s Building dependency tree... 1558s Reading state information... 1558s Starting pkgProblemResolver with broken count: 0 1558s Starting 2 pkgProblemResolver with broken count: 0 1558s Done 1558s The following NEW packages will be installed: 1558s autopkgtest-satdep 1558s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1558s Need to get 0 B/720 B of archives. 1558s After this operation, 0 B of additional disk space will be used. 1558s Get:1 /tmp/autopkgtest.PyKwRX/3-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [720 B] 1558s Selecting previously unselected package autopkgtest-satdep. 1558s (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 ... 55483 files and directories currently installed.) 1558s Preparing to unpack .../3-autopkgtest-satdep.deb ... 1558s Unpacking autopkgtest-satdep (0) ... 1558s Setting up autopkgtest-satdep (0) ... 1560s (Reading database ... 55483 files and directories currently installed.) 1560s Removing autopkgtest-satdep (0) ... 1562s autopkgtest [09:14:11]: test systemd-socket-activation: [----------------------- 1563s Stopping ssh.service... 1563s Checking that ssh.socket is active and listening... 1563s Checking that ssh.service is inactive/dead... 1563s Checking that a connection attempt activates ssh.service... 1563s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1563s Checking that sshd can be re-executed... 1563s Checking sshd can run in debug mode... 1564s debug1: SELinux support disabled 1564s debug1: PAM: reinitializing credentials 1564s debug1: permanently_set_uid: 0/0 1564s debug3: Copy environment: XDG_SESSION_ID=5 1564s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1564s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1564s debug3: Copy environment: XDG_SESSION_TYPE=tty 1564s debug3: Copy environment: XDG_SESSION_CLASS=user 1564s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1564s debug3: Copy environment: TERM=linux 1564s debug3: Copy environment: http_proxy=http://squid.internal:3128 1564s debug3: Copy environment: https_proxy=http://squid.internal:3128 1564s 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 1564s debug3: Copy environment: LANG=C.UTF-8 1564s Environment: 1564s LANG=C.UTF-8 1564s USER=root 1564s LOGNAME=root 1564s HOME=/root 1564s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1564s SHELL=/bin/bash 1564s XDG_SESSION_ID=5 1564s XDG_RUNTIME_DIR=/run/user/0 1564s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1564s XDG_SESSION_TYPE=tty 1564s XDG_SESSION_CLASS=user 1564s TERM=linux 1564s http_proxy=http://squid.internal:3128 1564s https_proxy=http://squid.internal:3128 1564s 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 1564s SSH_CLIENT=::1 35342 22 1564s SSH_CONNECTION=::1 35342 ::1 22 1564s Done. 1564s autopkgtest [09:14:13]: test systemd-socket-activation: -----------------------] 1564s systemd-socket-activation PASS 1564s autopkgtest [09:14:13]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1565s autopkgtest [09:14:14]: test sshd-socket-generator: preparing testbed 1566s Reading package lists... 1566s Building dependency tree... 1566s Reading state information... 1566s Starting pkgProblemResolver with broken count: 0 1566s Starting 2 pkgProblemResolver with broken count: 0 1566s Done 1566s The following NEW packages will be installed: 1566s autopkgtest-satdep 1566s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1566s Need to get 0 B/724 B of archives. 1566s After this operation, 0 B of additional disk space will be used. 1566s Get:1 /tmp/autopkgtest.PyKwRX/4-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 1567s Selecting previously unselected package autopkgtest-satdep. 1567s (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 ... 55483 files and directories currently installed.) 1567s Preparing to unpack .../4-autopkgtest-satdep.deb ... 1567s Unpacking autopkgtest-satdep (0) ... 1567s Setting up autopkgtest-satdep (0) ... 1568s (Reading database ... 55483 files and directories currently installed.) 1568s Removing autopkgtest-satdep (0) ... 1568s autopkgtest [09:14:17]: test sshd-socket-generator: [----------------------- 1569s test_default...PASS 1569s test_custom_port...PASS 1569s test_default_and_custom_port...PASS 1569s test_mutiple_custom_ports...PASS 1569s test_custom_listenaddress...PASS 1569s test_custom_listenaddress_and_port...PASS 1569s test_custom_ipv6_listenaddress...PASS 1569s test_custom_family_ipv4...PASS 1569s test_custom_family_ipv6...PASS 1569s test_custom_port_and_family_ipv4...PASS 1569s test_custom_port_and_family_ipv6...PASS 1569s autopkgtest [09:14:18]: test sshd-socket-generator: -----------------------] 1569s sshd-socket-generator PASS 1569s autopkgtest [09:14:18]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1570s autopkgtest [09:14:19]: @@@@@@@@@@@@@@@@@@@@ summary 1570s regress PASS 1570s ssh-gssapi PASS 1570s systemd-socket-activation PASS 1570s sshd-socket-generator PASS 1581s virt: nova [W] Using flock in prodstack6-s390x 1581s virt: flock: timeout while waiting to get lock 1581s virt: Creating nova instance adt-plucky-s390x-openssh-20241107-084809-juju-7f2275-prod-proposed-migration-environment-2-a1e7ba71-2c07-4004-bd78-db85934c6914 from image adt/ubuntu-plucky-s390x-server-20241106.img (UUID 38f5fd2f-4109-4daf-8153-f3d6e38e9923)... 1581s virt: nova [W] Using flock in prodstack6-s390x 1581s virt: Creating nova instance adt-plucky-s390x-openssh-20241107-084809-juju-7f2275-prod-proposed-migration-environment-2-a1e7ba71-2c07-4004-bd78-db85934c6914 from image adt/ubuntu-plucky-s390x-server-20241107.img (UUID 5f98410d-9736-44f5-8a8a-4b56456cb25f)... 1581s virt: nova [W] Using flock in prodstack6-s390x 1581s virt: Creating nova instance adt-plucky-s390x-openssh-20241107-084809-juju-7f2275-prod-proposed-migration-environment-2-a1e7ba71-2c07-4004-bd78-db85934c6914 from image adt/ubuntu-plucky-s390x-server-20241107.img (UUID 5f98410d-9736-44f5-8a8a-4b56456cb25f)...