0s autopkgtest [08:38:05]: starting date and time: 2024-11-07 08:38:05+0000 0s autopkgtest [08:38:05]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [08:38:05]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.928bt1cv/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-arm64-23.secgroup --name adt-plucky-arm64-openssh-20241107-083805-juju-7f2275-prod-proposed-migration-environment-15-32a689a2-acc9-4c3a-b736-e31a7a01d83d --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration -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/ 119s autopkgtest [08:40:04]: testbed dpkg architecture: arm64 120s autopkgtest [08:40:05]: testbed apt version: 2.9.8 120s autopkgtest [08:40:05]: @@@@@@@@@@@@@@@@@@@@ test bed setup 120s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 121s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1550 kB] 121s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [18.8 kB] 121s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 121s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [171 kB] 121s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [238 kB] 121s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB] 121s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [1235 kB] 121s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [30.1 kB] 122s Fetched 3374 kB in 1s (3164 kB/s) 122s Reading package lists... 125s Reading package lists... 125s Building dependency tree... 125s Reading state information... 126s Calculating upgrade... 127s The following packages will be upgraded: 127s flash-kernel libarchive13t64 libpam-modules libpam-modules-bin 127s libpam-runtime libpam0g python3-jaraco.functools python3-typeguard 127s ssh-import-id ufw 127s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s Need to get 1094 kB of archives. 127s After this operation, 3072 B of additional disk space will be used. 127s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam0g arm64 1.5.3-7ubuntu3 [68.5 kB] 128s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-modules-bin arm64 1.5.3-7ubuntu3 [50.4 kB] 128s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-modules arm64 1.5.3-7ubuntu3 [285 kB] 128s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 128s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 ufw all 0.36.2-8 [170 kB] 128s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 flash-kernel arm64 3.107ubuntu13 [49.9 kB] 128s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 libarchive13t64 arm64 3.7.4-1ubuntu1 [379 kB] 128s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-jaraco.functools all 4.1.0-1 [11.8 kB] 128s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-typeguard all 4.4.1-1 [29.0 kB] 128s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 ssh-import-id all 5.11-0ubuntu3 [10.1 kB] 128s Preconfiguring packages ... 129s Fetched 1094 kB in 1s (1973 kB/s) 129s (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 ... 79706 files and directories currently installed.) 129s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_arm64.deb ... 129s Unpacking libpam0g:arm64 (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 129s Setting up libpam0g:arm64 (1.5.3-7ubuntu3) ... 129s (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 ... 79706 files and directories currently installed.) 129s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_arm64.deb ... 129s Unpacking libpam-modules-bin (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 129s Setting up libpam-modules-bin (1.5.3-7ubuntu3) ... 130s pam_namespace.service is a disabled or a static unit not running, not starting it. 130s (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 ... 79706 files and directories currently installed.) 130s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_arm64.deb ... 130s Unpacking libpam-modules:arm64 (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 130s Setting up libpam-modules:arm64 (1.5.3-7ubuntu3) ... 130s (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 ... 79706 files and directories currently installed.) 130s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 130s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 130s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 131s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 79706 files and directories currently installed.) 131s Preparing to unpack .../0-ufw_0.36.2-8_all.deb ... 131s Unpacking ufw (0.36.2-8) over (0.36.2-6) ... 131s Preparing to unpack .../1-flash-kernel_3.107ubuntu13_arm64.deb ... 131s Unpacking flash-kernel (3.107ubuntu13) over (3.107ubuntu11) ... 131s Preparing to unpack .../2-libarchive13t64_3.7.4-1ubuntu1_arm64.deb ... 131s Unpacking libarchive13t64:arm64 (3.7.4-1ubuntu1) over (3.7.4-1) ... 131s Preparing to unpack .../3-python3-jaraco.functools_4.1.0-1_all.deb ... 131s Unpacking python3-jaraco.functools (4.1.0-1) over (4.0.2-1) ... 131s Preparing to unpack .../4-python3-typeguard_4.4.1-1_all.deb ... 131s Unpacking python3-typeguard (4.4.1-1) over (4.3.0-1) ... 131s Preparing to unpack .../5-ssh-import-id_5.11-0ubuntu3_all.deb ... 131s Unpacking ssh-import-id (5.11-0ubuntu3) over (5.11-0ubuntu2) ... 132s Setting up flash-kernel (3.107ubuntu13) ... 132s Installing new version of config file /etc/flash-kernel/bootscript/bootscr.zynqmp.kria ... 132s flash-kernel: deferring update (trigger activated) 132s Setting up python3-jaraco.functools (4.1.0-1) ... 132s Setting up ssh-import-id (5.11-0ubuntu3) ... 132s Setting up python3-typeguard (4.4.1-1) ... 133s Setting up ufw (0.36.2-8) ... 134s Setting up libarchive13t64:arm64 (3.7.4-1ubuntu1) ... 134s Processing triggers for libc-bin (2.40-1ubuntu3) ... 134s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 134s Processing triggers for man-db (2.12.1-3) ... 136s Processing triggers for initramfs-tools (0.142ubuntu34) ... 136s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 136s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 160s System running in EFI mode, skipping. 160s Processing triggers for flash-kernel (3.107ubuntu13) ... 160s System running in EFI mode, skipping. 161s Reading package lists... 161s Building dependency tree... 161s Reading state information... 162s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 162s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 162s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 163s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 163s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 164s Reading package lists... 164s Reading package lists... 164s Building dependency tree... 164s Reading state information... 165s Calculating upgrade... 166s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 168s Reading package lists... 168s autopkgtest [08:40:52]: rebooting testbed after setup commands that affected boot 168s Building dependency tree... 168s Reading state information... 168s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 171s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 193s autopkgtest [08:41:18]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 196s autopkgtest [08:41:21]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 201s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (dsc) [3327 B] 201s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (tar) [1849 kB] 201s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (asc) [833 B] 201s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (diff) [208 kB] 202s gpgv: Signature made Wed Oct 2 00:50:47 2024 UTC 202s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 202s gpgv: Can't check signature: No public key 202s dpkg-source: warning: cannot verify inline signature for ./openssh_9.7p1-7ubuntu4.dsc: no acceptable signature found 202s autopkgtest [08:41:27]: testing package openssh version 1:9.7p1-7ubuntu4 203s autopkgtest [08:41:28]: build not needed 203s autopkgtest [08:41:28]: test regress: preparing testbed 211s Reading package lists... 211s Building dependency tree... 211s Reading state information... 211s Starting pkgProblemResolver with broken count: 0 212s Starting 2 pkgProblemResolver with broken count: 0 212s Done 212s The following additional packages will be installed: 212s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 212s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 212s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 212s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 212s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 212s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 212s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 212s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 212s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 212s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 212s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 212s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 212s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 212s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 212s python3-incremental python3-pyasn1 python3-pyasn1-modules 212s python3-service-identity python3-twisted python3-zope.interface wdiff 212s Suggested packages: 212s adequate at autopkgtest bls-standalone bsd-mailx | mailx build-essential 212s check-all-the-things cvs-buildpackage debhelper diffoscope disorderfs 212s docker.io dose-extra duck elpa-devscripts faketime gnuplot how-can-i-help 212s libauthen-sasl-perl libdbd-pg-perl libfile-desktopentry-perl 212s libterm-size-perl libyaml-syck-perl mmdebstrap mutt piuparts 212s postgresql-client pristine-lfs python3-pycurl quilt ratt reprotest 212s svn-buildpackage w3m debian-keyring equivs libgitlab-api-v4-perl 212s libsoap-lite-perl pristine-tar dropbear-initramfs runit libdata-dump-perl 212s libio-compress-brotli-perl libcrypt-ssleay-perl libscalar-number-perl 212s libxstring-perl libsub-name-perl libbusiness-isbn-perl libregexp-ipv6-perl 212s libauthen-ntlm-perl putty-doc python3-pampy python3-tk python3-wxgtk4.0 212s wdiff-doc 212s Recommended packages: 212s dctrl-tools dput | dupload libdistro-info-perl libgit-wrapper-perl 212s libjson-perl liblist-compare-perl libstring-shellquote-perl licensecheck 212s lintian python3-debian python3-magic python3-unidiff python3-xdg unzip 212s libhtml-format-perl libnamespace-clean-perl libdata-dump-perl 212s libhtml-form-perl libhttp-daemon-perl libmailtools-perl python3-click 212s The following NEW packages will be installed: 212s autopkgtest-satdep devscripts dropbear dropbear-bin haveged 212s libb-hooks-op-check-perl libclass-method-modifiers-perl 212s libclass-xsaccessor-perl libclone-perl libdevel-callchecker-perl 212s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 212s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 212s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 212s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 212s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 212s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 212s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 212s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 212s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 212s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 212s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 212s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 212s python3-incremental python3-pyasn1 python3-pyasn1-modules 212s python3-service-identity python3-twisted python3-zope.interface wdiff 212s 0 upgraded, 62 newly installed, 0 to remove and 0 not upgraded. 212s Need to get 8378 kB/8379 kB of archives. 212s After this operation, 38.6 MB of additional disk space will be used. 212s Get:1 /tmp/autopkgtest.SWD2Q6/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [768 B] 212s Get:2 http://ftpmaster.internal/ubuntu plucky/universe arm64 libtommath1 arm64 1.3.0-1 [59.1 kB] 213s Get:3 http://ftpmaster.internal/ubuntu plucky/universe arm64 libtomcrypt1 arm64 1.18.2+dfsg-7build1 [407 kB] 213s Get:4 http://ftpmaster.internal/ubuntu plucky/universe arm64 dropbear-bin arm64 2024.85-3 [142 kB] 213s Get:5 http://ftpmaster.internal/ubuntu plucky/universe arm64 dropbear all 2024.85-3 [8668 B] 213s Get:6 http://ftpmaster.internal/ubuntu plucky/universe arm64 libhavege2 arm64 1.9.14-1ubuntu2 [25.1 kB] 213s Get:7 http://ftpmaster.internal/ubuntu plucky/universe arm64 haveged arm64 1.9.14-1ubuntu2 [33.0 kB] 213s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 libfile-dirlist-perl all 0.05-3 [7286 B] 213s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 libfile-which-perl all 1.27-2 [12.5 kB] 213s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 libfile-homedir-perl all 1.006-2 [37.0 kB] 213s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 libfile-touch-perl all 0.12-2 [7498 B] 213s Get:12 http://ftpmaster.internal/ubuntu plucky/main arm64 libio-pty-perl arm64 1:1.20-1build3 [31.3 kB] 213s Get:13 http://ftpmaster.internal/ubuntu plucky/main arm64 libipc-run-perl all 20231003.0-2 [91.5 kB] 213s Get:14 http://ftpmaster.internal/ubuntu plucky/main arm64 libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 213s Get:15 http://ftpmaster.internal/ubuntu plucky/main arm64 libclass-xsaccessor-perl arm64 1.19-4build5 [32.7 kB] 213s Get:16 http://ftpmaster.internal/ubuntu plucky/main arm64 libb-hooks-op-check-perl arm64 0.22-3build2 [9348 B] 213s Get:17 http://ftpmaster.internal/ubuntu plucky/main arm64 libdynaloader-functions-perl all 0.004-1 [11.4 kB] 213s Get:18 http://ftpmaster.internal/ubuntu plucky/main arm64 libdevel-callchecker-perl arm64 0.009-1build1 [14.0 kB] 213s Get:19 http://ftpmaster.internal/ubuntu plucky/main arm64 libparams-classify-perl arm64 0.015-2build6 [19.8 kB] 213s Get:20 http://ftpmaster.internal/ubuntu plucky/main arm64 libmodule-runtime-perl all 0.016-2 [16.4 kB] 213s Get:21 http://ftpmaster.internal/ubuntu plucky/main arm64 libimport-into-perl all 1.002005-2 [10.7 kB] 213s Get:22 http://ftpmaster.internal/ubuntu plucky/main arm64 librole-tiny-perl all 2.002004-1 [16.3 kB] 213s Get:23 http://ftpmaster.internal/ubuntu plucky/main arm64 libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 213s Get:24 http://ftpmaster.internal/ubuntu plucky/main arm64 libmoo-perl all 2.005005-1 [47.4 kB] 213s Get:25 http://ftpmaster.internal/ubuntu plucky/main arm64 libencode-locale-perl all 1.05-3 [11.6 kB] 213s Get:26 http://ftpmaster.internal/ubuntu plucky/main arm64 libtimedate-perl all 2.3300-2 [34.0 kB] 213s Get:27 http://ftpmaster.internal/ubuntu plucky/main arm64 libhttp-date-perl all 6.06-1 [10.2 kB] 213s Get:28 http://ftpmaster.internal/ubuntu plucky/main arm64 libfile-listing-perl all 6.16-1 [11.3 kB] 213s Get:29 http://ftpmaster.internal/ubuntu plucky/main arm64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 213s Get:30 http://ftpmaster.internal/ubuntu plucky/main arm64 liburi-perl all 5.28-1 [88.1 kB] 213s Get:31 http://ftpmaster.internal/ubuntu plucky/main arm64 libhtml-parser-perl arm64 3.83-1build1 [85.3 kB] 213s Get:32 http://ftpmaster.internal/ubuntu plucky/main arm64 libhtml-tree-perl all 5.07-3 [200 kB] 213s Get:33 http://ftpmaster.internal/ubuntu plucky/main arm64 libclone-perl arm64 0.47-1 [10.4 kB] 213s Get:34 http://ftpmaster.internal/ubuntu plucky/main arm64 libio-html-perl all 1.004-3 [15.9 kB] 213s Get:35 http://ftpmaster.internal/ubuntu plucky/main arm64 liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 213s Get:36 http://ftpmaster.internal/ubuntu plucky/main arm64 libhttp-message-perl all 6.46-1ubuntu1 [75.9 kB] 213s Get:37 http://ftpmaster.internal/ubuntu plucky/main arm64 libhttp-cookies-perl all 6.11-1 [18.2 kB] 213s Get:38 http://ftpmaster.internal/ubuntu plucky/main arm64 libhttp-negotiate-perl all 6.01-2 [12.4 kB] 213s Get:39 http://ftpmaster.internal/ubuntu plucky/main arm64 perl-openssl-defaults arm64 7build3 [6628 B] 213s Get:40 http://ftpmaster.internal/ubuntu plucky/main arm64 libnet-ssleay-perl arm64 1.94-2 [307 kB] 213s Get:41 http://ftpmaster.internal/ubuntu plucky/main arm64 libio-socket-ssl-perl all 2.088-1 [200 kB] 213s Get:42 http://ftpmaster.internal/ubuntu plucky/main arm64 libnet-http-perl all 6.23-1 [22.3 kB] 213s Get:43 http://ftpmaster.internal/ubuntu plucky/main arm64 liblwp-protocol-https-perl all 6.14-1 [9040 B] 213s Get:44 http://ftpmaster.internal/ubuntu plucky/main arm64 libtry-tiny-perl all 0.31-2 [20.8 kB] 213s Get:45 http://ftpmaster.internal/ubuntu plucky/main arm64 libwww-robotrules-perl all 6.02-1 [12.6 kB] 213s Get:46 http://ftpmaster.internal/ubuntu plucky/main arm64 libwww-perl all 6.77-1 [138 kB] 213s Get:47 http://ftpmaster.internal/ubuntu plucky/main arm64 patchutils arm64 0.4.2-1build3 [75.3 kB] 213s Get:48 http://ftpmaster.internal/ubuntu plucky/main arm64 wdiff arm64 1.2.2-6build1 [28.6 kB] 213s Get:49 http://ftpmaster.internal/ubuntu plucky/main arm64 devscripts all 2.24.1 [1071 kB] 213s Get:50 http://ftpmaster.internal/ubuntu plucky/universe arm64 putty-tools arm64 0.81-2 [701 kB] 213s Get:51 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-bcrypt arm64 4.2.0-1 [210 kB] 213s Get:52 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-hamcrest all 2.1.0-1 [28.1 kB] 213s Get:53 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-pyasn1 all 0.5.1-1 [57.4 kB] 213s Get:54 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-pyasn1-modules all 0.3.0-1 [80.2 kB] 213s Get:55 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-service-identity all 24.1.0-1 [11.2 kB] 213s Get:56 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-automat all 24.8.1-1 [35.5 kB] 213s Get:57 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-constantly all 23.10.4-2 [13.9 kB] 214s Get:58 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-hyperlink all 21.0.0-6 [68.0 kB] 214s Get:59 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-incremental all 24.7.2-2 [18.5 kB] 214s Get:60 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-zope.interface arm64 6.4-1 [138 kB] 214s Get:61 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-twisted all 24.7.0-2 [2062 kB] 214s Get:62 http://ftpmaster.internal/ubuntu plucky/universe arm64 openssh-tests arm64 1:9.7p1-7ubuntu4 [1378 kB] 214s Fetched 8378 kB in 1s (6211 kB/s) 214s Selecting previously unselected package libtommath1:arm64. 214s (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 ... 79706 files and directories currently installed.) 214s Preparing to unpack .../00-libtommath1_1.3.0-1_arm64.deb ... 214s Unpacking libtommath1:arm64 (1.3.0-1) ... 214s Selecting previously unselected package libtomcrypt1:arm64. 214s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_arm64.deb ... 214s Unpacking libtomcrypt1:arm64 (1.18.2+dfsg-7build1) ... 215s Selecting previously unselected package dropbear-bin. 215s Preparing to unpack .../02-dropbear-bin_2024.85-3_arm64.deb ... 215s Unpacking dropbear-bin (2024.85-3) ... 215s Selecting previously unselected package dropbear. 215s Preparing to unpack .../03-dropbear_2024.85-3_all.deb ... 215s Unpacking dropbear (2024.85-3) ... 215s Selecting previously unselected package libhavege2:arm64. 215s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_arm64.deb ... 215s Unpacking libhavege2:arm64 (1.9.14-1ubuntu2) ... 215s Selecting previously unselected package haveged. 215s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_arm64.deb ... 215s Unpacking haveged (1.9.14-1ubuntu2) ... 215s Selecting previously unselected package libfile-dirlist-perl. 215s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 215s Unpacking libfile-dirlist-perl (0.05-3) ... 215s Selecting previously unselected package libfile-which-perl. 215s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 215s Unpacking libfile-which-perl (1.27-2) ... 215s Selecting previously unselected package libfile-homedir-perl. 215s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 215s Unpacking libfile-homedir-perl (1.006-2) ... 215s Selecting previously unselected package libfile-touch-perl. 215s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 215s Unpacking libfile-touch-perl (0.12-2) ... 215s Selecting previously unselected package libio-pty-perl. 215s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_arm64.deb ... 215s Unpacking libio-pty-perl (1:1.20-1build3) ... 215s Selecting previously unselected package libipc-run-perl. 215s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 215s Unpacking libipc-run-perl (20231003.0-2) ... 215s Selecting previously unselected package libclass-method-modifiers-perl. 215s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 215s Unpacking libclass-method-modifiers-perl (2.15-1) ... 215s Selecting previously unselected package libclass-xsaccessor-perl. 215s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_arm64.deb ... 215s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 215s Selecting previously unselected package libb-hooks-op-check-perl:arm64. 215s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_arm64.deb ... 215s Unpacking libb-hooks-op-check-perl:arm64 (0.22-3build2) ... 215s Selecting previously unselected package libdynaloader-functions-perl. 215s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 215s Unpacking libdynaloader-functions-perl (0.004-1) ... 215s Selecting previously unselected package libdevel-callchecker-perl:arm64. 215s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_arm64.deb ... 215s Unpacking libdevel-callchecker-perl:arm64 (0.009-1build1) ... 215s Selecting previously unselected package libparams-classify-perl:arm64. 215s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_arm64.deb ... 215s Unpacking libparams-classify-perl:arm64 (0.015-2build6) ... 215s Selecting previously unselected package libmodule-runtime-perl. 215s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 215s Unpacking libmodule-runtime-perl (0.016-2) ... 215s Selecting previously unselected package libimport-into-perl. 215s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 215s Unpacking libimport-into-perl (1.002005-2) ... 215s Selecting previously unselected package librole-tiny-perl. 215s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 215s Unpacking librole-tiny-perl (2.002004-1) ... 215s Selecting previously unselected package libsub-quote-perl. 215s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 215s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 215s Selecting previously unselected package libmoo-perl. 215s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 215s Unpacking libmoo-perl (2.005005-1) ... 215s Selecting previously unselected package libencode-locale-perl. 215s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 215s Unpacking libencode-locale-perl (1.05-3) ... 215s Selecting previously unselected package libtimedate-perl. 215s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 215s Unpacking libtimedate-perl (2.3300-2) ... 215s Selecting previously unselected package libhttp-date-perl. 215s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 215s Unpacking libhttp-date-perl (6.06-1) ... 215s Selecting previously unselected package libfile-listing-perl. 215s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 215s Unpacking libfile-listing-perl (6.16-1) ... 215s Selecting previously unselected package libhtml-tagset-perl. 215s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 215s Unpacking libhtml-tagset-perl (3.24-1) ... 215s Selecting previously unselected package liburi-perl. 216s Preparing to unpack .../28-liburi-perl_5.28-1_all.deb ... 216s Unpacking liburi-perl (5.28-1) ... 216s Selecting previously unselected package libhtml-parser-perl:arm64. 216s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_arm64.deb ... 216s Unpacking libhtml-parser-perl:arm64 (3.83-1build1) ... 216s Selecting previously unselected package libhtml-tree-perl. 216s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 216s Unpacking libhtml-tree-perl (5.07-3) ... 216s Selecting previously unselected package libclone-perl:arm64. 216s Preparing to unpack .../31-libclone-perl_0.47-1_arm64.deb ... 216s Unpacking libclone-perl:arm64 (0.47-1) ... 216s Selecting previously unselected package libio-html-perl. 216s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 216s Unpacking libio-html-perl (1.004-3) ... 216s Selecting previously unselected package liblwp-mediatypes-perl. 216s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 216s Unpacking liblwp-mediatypes-perl (6.04-2) ... 216s Selecting previously unselected package libhttp-message-perl. 216s Preparing to unpack .../34-libhttp-message-perl_6.46-1ubuntu1_all.deb ... 216s Unpacking libhttp-message-perl (6.46-1ubuntu1) ... 216s Selecting previously unselected package libhttp-cookies-perl. 216s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 216s Unpacking libhttp-cookies-perl (6.11-1) ... 216s Selecting previously unselected package libhttp-negotiate-perl. 216s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 216s Unpacking libhttp-negotiate-perl (6.01-2) ... 216s Selecting previously unselected package perl-openssl-defaults:arm64. 216s Preparing to unpack .../37-perl-openssl-defaults_7build3_arm64.deb ... 216s Unpacking perl-openssl-defaults:arm64 (7build3) ... 216s Selecting previously unselected package libnet-ssleay-perl:arm64. 216s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_arm64.deb ... 216s Unpacking libnet-ssleay-perl:arm64 (1.94-2) ... 216s Selecting previously unselected package libio-socket-ssl-perl. 216s Preparing to unpack .../39-libio-socket-ssl-perl_2.088-1_all.deb ... 216s Unpacking libio-socket-ssl-perl (2.088-1) ... 216s Selecting previously unselected package libnet-http-perl. 216s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 216s Unpacking libnet-http-perl (6.23-1) ... 216s Selecting previously unselected package liblwp-protocol-https-perl. 216s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 216s Unpacking liblwp-protocol-https-perl (6.14-1) ... 216s Selecting previously unselected package libtry-tiny-perl. 216s Preparing to unpack .../42-libtry-tiny-perl_0.31-2_all.deb ... 216s Unpacking libtry-tiny-perl (0.31-2) ... 216s Selecting previously unselected package libwww-robotrules-perl. 216s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 216s Unpacking libwww-robotrules-perl (6.02-1) ... 216s Selecting previously unselected package libwww-perl. 216s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 216s Unpacking libwww-perl (6.77-1) ... 216s Selecting previously unselected package patchutils. 216s Preparing to unpack .../45-patchutils_0.4.2-1build3_arm64.deb ... 216s Unpacking patchutils (0.4.2-1build3) ... 216s Selecting previously unselected package wdiff. 216s Preparing to unpack .../46-wdiff_1.2.2-6build1_arm64.deb ... 216s Unpacking wdiff (1.2.2-6build1) ... 216s Selecting previously unselected package devscripts. 216s Preparing to unpack .../47-devscripts_2.24.1_all.deb ... 216s Unpacking devscripts (2.24.1) ... 216s Selecting previously unselected package putty-tools. 216s Preparing to unpack .../48-putty-tools_0.81-2_arm64.deb ... 216s Unpacking putty-tools (0.81-2) ... 216s Selecting previously unselected package python3-bcrypt. 216s Preparing to unpack .../49-python3-bcrypt_4.2.0-1_arm64.deb ... 216s Unpacking python3-bcrypt (4.2.0-1) ... 216s Selecting previously unselected package python3-hamcrest. 216s Preparing to unpack .../50-python3-hamcrest_2.1.0-1_all.deb ... 216s Unpacking python3-hamcrest (2.1.0-1) ... 216s Selecting previously unselected package python3-pyasn1. 216s Preparing to unpack .../51-python3-pyasn1_0.5.1-1_all.deb ... 216s Unpacking python3-pyasn1 (0.5.1-1) ... 217s Selecting previously unselected package python3-pyasn1-modules. 217s Preparing to unpack .../52-python3-pyasn1-modules_0.3.0-1_all.deb ... 217s Unpacking python3-pyasn1-modules (0.3.0-1) ... 217s Selecting previously unselected package python3-service-identity. 217s Preparing to unpack .../53-python3-service-identity_24.1.0-1_all.deb ... 217s Unpacking python3-service-identity (24.1.0-1) ... 217s Selecting previously unselected package python3-automat. 217s Preparing to unpack .../54-python3-automat_24.8.1-1_all.deb ... 217s Unpacking python3-automat (24.8.1-1) ... 217s Selecting previously unselected package python3-constantly. 217s Preparing to unpack .../55-python3-constantly_23.10.4-2_all.deb ... 217s Unpacking python3-constantly (23.10.4-2) ... 217s Selecting previously unselected package python3-hyperlink. 217s Preparing to unpack .../56-python3-hyperlink_21.0.0-6_all.deb ... 217s Unpacking python3-hyperlink (21.0.0-6) ... 217s Selecting previously unselected package python3-incremental. 217s Preparing to unpack .../57-python3-incremental_24.7.2-2_all.deb ... 217s Unpacking python3-incremental (24.7.2-2) ... 217s Selecting previously unselected package python3-zope.interface. 217s Preparing to unpack .../58-python3-zope.interface_6.4-1_arm64.deb ... 217s Unpacking python3-zope.interface (6.4-1) ... 217s Selecting previously unselected package python3-twisted. 217s Preparing to unpack .../59-python3-twisted_24.7.0-2_all.deb ... 217s Unpacking python3-twisted (24.7.0-2) ... 217s Selecting previously unselected package openssh-tests. 217s Preparing to unpack .../60-openssh-tests_1%3a9.7p1-7ubuntu4_arm64.deb ... 217s Unpacking openssh-tests (1:9.7p1-7ubuntu4) ... 217s Selecting previously unselected package autopkgtest-satdep. 217s Preparing to unpack .../61-1-autopkgtest-satdep.deb ... 217s Unpacking autopkgtest-satdep (0) ... 217s Setting up wdiff (1.2.2-6build1) ... 217s Setting up libfile-which-perl (1.27-2) ... 217s Setting up libdynaloader-functions-perl (0.004-1) ... 217s Setting up libclass-method-modifiers-perl (2.15-1) ... 217s Setting up libio-pty-perl (1:1.20-1build3) ... 217s Setting up python3-zope.interface (6.4-1) ... 217s Setting up libclone-perl:arm64 (0.47-1) ... 217s Setting up libtommath1:arm64 (1.3.0-1) ... 217s Setting up libhtml-tagset-perl (3.24-1) ... 217s Setting up python3-bcrypt (4.2.0-1) ... 218s Setting up python3-automat (24.8.1-1) ... 218s Setting up liblwp-mediatypes-perl (6.04-2) ... 218s Setting up libtry-tiny-perl (0.31-2) ... 218s Setting up perl-openssl-defaults:arm64 (7build3) ... 218s Setting up libencode-locale-perl (1.05-3) ... 218s Setting up python3-hamcrest (2.1.0-1) ... 218s Setting up putty-tools (0.81-2) ... 218s Setting up libhavege2:arm64 (1.9.14-1ubuntu2) ... 218s Setting up patchutils (0.4.2-1build3) ... 218s Setting up python3-incremental (24.7.2-2) ... 218s Setting up python3-hyperlink (21.0.0-6) ... 218s Setting up libio-html-perl (1.004-3) ... 218s Setting up libb-hooks-op-check-perl:arm64 (0.22-3build2) ... 218s Setting up libipc-run-perl (20231003.0-2) ... 218s Setting up libtimedate-perl (2.3300-2) ... 218s Setting up librole-tiny-perl (2.002004-1) ... 218s Setting up python3-pyasn1 (0.5.1-1) ... 218s Setting up python3-constantly (23.10.4-2) ... 219s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 219s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 219s Setting up libfile-dirlist-perl (0.05-3) ... 219s Setting up libfile-homedir-perl (1.006-2) ... 219s Setting up liburi-perl (5.28-1) ... 219s Setting up libfile-touch-perl (0.12-2) ... 219s Setting up libnet-ssleay-perl:arm64 (1.94-2) ... 219s Setting up libtomcrypt1:arm64 (1.18.2+dfsg-7build1) ... 219s Setting up libhttp-date-perl (6.06-1) ... 219s Setting up haveged (1.9.14-1ubuntu2) ... 219s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 219s Setting up dropbear-bin (2024.85-3) ... 219s Setting up libfile-listing-perl (6.16-1) ... 219s Setting up libnet-http-perl (6.23-1) ... 219s Setting up libdevel-callchecker-perl:arm64 (0.009-1build1) ... 219s Setting up dropbear (2024.85-3) ... 219s Converting existing OpenSSH RSA host key to Dropbear format. 219s Key is a ssh-rsa key 219s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 219s 3072 SHA256:ETW+G1MA/gD6Ii5vm8GLLfUF/rfOEkp670LxB+ofG+o /etc/dropbear/dropbear_rsa_host_key (RSA) 219s +---[RSA 3072]----+ 219s | . oo+ | 219s | . o o o | 219s | . + . . | 219s | o.. + o | 219s | ...=..S = | 219s | o..=.+ . + | 219s |..+* +oo . | 219s |.=o+*.o=. | 219s |.o*+E==++. | 219s +----[SHA256]-----+ 219s Converting existing OpenSSH ECDSA host key to Dropbear format. 219s Key is a ecdsa-sha2-nistp256 key 219s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 219s 256 SHA256:gYiAPefpxoZYjQFYqoVmHShG5cl9mY+ArIQ0SELUd/4 /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 219s +---[ECDSA 256]---+ 219s |%@=o | 219s |O*Xo*o oo | 219s |*+o#o+++. | 219s |=.+ + o.o. | 219s |.+ + .S. | 219s |. . = E | 219s | o | 219s | | 219s | | 219s +----[SHA256]-----+ 219s Converting existing OpenSSH ED25519 host key to Dropbear format. 219s Key is a ssh-ed25519 key 219s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 219s 256 SHA256:V0Vjs5G/X2PUqAwXsUFndeoBg93GmmBFqMR1ObfS/gw /etc/dropbear/dropbear_ed25519_host_key (ED25519) 219s +--[ED25519 256]--+ 219s | . ..BX*Xoo| 219s | o =.=X**.| 219s | . o .oO++.| 219s | . ..=.+oo| 219s | S .+ +o .| 219s | . o E+.| 219s | .++| 219s | +| 219s | | 219s +----[SHA256]-----+ 220s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 220s Setting up python3-pyasn1-modules (0.3.0-1) ... 220s Setting up python3-service-identity (24.1.0-1) ... 221s Setting up libwww-robotrules-perl (6.02-1) ... 221s Setting up libhtml-parser-perl:arm64 (3.83-1build1) ... 221s Setting up libio-socket-ssl-perl (2.088-1) ... 221s Setting up libhttp-message-perl (6.46-1ubuntu1) ... 221s Setting up libhttp-negotiate-perl (6.01-2) ... 221s Setting up libhttp-cookies-perl (6.11-1) ... 221s Setting up libhtml-tree-perl (5.07-3) ... 221s Setting up libparams-classify-perl:arm64 (0.015-2build6) ... 221s Setting up libmodule-runtime-perl (0.016-2) ... 221s Setting up python3-twisted (24.7.0-2) ... 224s Setting up libimport-into-perl (1.002005-2) ... 224s Setting up libmoo-perl (2.005005-1) ... 224s Setting up openssh-tests (1:9.7p1-7ubuntu4) ... 224s Setting up liblwp-protocol-https-perl (6.14-1) ... 224s Setting up libwww-perl (6.77-1) ... 224s Setting up devscripts (2.24.1) ... 224s Setting up autopkgtest-satdep (0) ... 224s Processing triggers for libc-bin (2.40-1ubuntu3) ... 224s Processing triggers for man-db (2.12.1-3) ... 226s Processing triggers for install-info (7.1.1-1) ... 229s (Reading database ... 82876 files and directories currently installed.) 229s Removing autopkgtest-satdep (0) ... 230s autopkgtest [08:41:55]: test regress: [----------------------- 230s info: Adding user `openssh-tests' ... 230s info: Selecting UID/GID from range 1000 to 59999 ... 230s info: Adding new group `openssh-tests' (1001) ... 230s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 230s info: Creating home directory `/home/openssh-tests' ... 230s info: Copying files from `/etc/skel' ... 230s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 230s info: Adding user `openssh-tests' to group `users' ... 230s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 230s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 231s I: annotate-output 2.24.1 231s I: prefix='%H:%M:%S.%N ' 231s 08:41:56.613514510 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user 231s 08:41:56.641204750 O: make: Entering directory '/tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress' 231s 08:41:56.643219390 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/valgrind-out 231s 08:41:56.645217430 E: + /usr/bin/ssh -Q key 231s 08:41:56.645869230 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 231s 08:41:56.646651670 E: + grep -q ^ssh-rsa 231s 08:41:56.647116990 O: ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 231s 08:41:56.648461790 O: tr '\n' '\r' /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 231s 08:41:56.649841230 O: ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 231s 08:41:56.651160830 O: awk '{print $0 "\r"}' /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 231s 08:41:56.652658430 O: ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 231s 08:41:56.653882790 O: fi 231s 08:41:56.660040430 E: + ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2.prv 231s 08:41:56.661995270 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv 231s 08:41:56.664754390 E: + tr \n \r 231s 08:41:56.667012550 E: + + ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 231s 08:41:56.668231190 E: diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv 231s 08:41:56.671677310 E: + awk {print $0 "\r"} /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2.prv 231s 08:41:56.674104950 E: + ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 231s 08:41:56.675362110 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv 231s 08:41:56.680650470 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 231s 08:41:56.682885310 E: + /usr/bin/ssh -Q key 231s 08:41:56.684353230 O: cat /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t2.out ; \ 231s 08:41:56.685455310 E: + grep -q ^ssh-rsa 231s 08:41:56.686613110 O: chmod 600 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t2.out ; \ 231s 08:41:56.687726150 O: ssh-keygen -yf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 231s 08:41:56.688963110 O: fi 231s 08:41:56.688935390 E: + cat /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.prv 231s 08:41:56.690243750 E: + chmod 600 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t2.out 231s 08:41:56.691358070 E: + ssh-keygen -yf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t2.out 231s 08:41:56.692659070 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub 231s 08:41:56.698240590 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 231s 08:41:56.699498230 E: + /usr/bin/ssh -Q key 231s 08:41:56.700390550 O: ssh-keygen -ef /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t3.out ; \ 231s 08:41:56.701577630 E: + grep -q ^ssh-rsa 231s 08:41:56.702827590 O: ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 231s 08:41:56.703968270 O: fi 231s 08:41:56.705455550 E: + ssh-keygen -ef /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub 231s 08:41:56.711009390 E: + ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t3.out 231s 08:41:56.712298270 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub 231s 08:41:56.714675830 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 231s 08:41:56.715970870 E: + /usr/bin/ssh -Q key 231s 08:41:56.717002870 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 231s 08:41:56.718249070 E: + grep -q ^ssh-rsa 231s 08:41:56.719471790 O: awk '{print $2}' | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t4.ok ; \ 231s 08:41:56.720709430 O: fi 231s 08:41:56.724414390 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub 231s 08:41:56.725907430 E: + awk {print $2} 231s 08:41:56.727115070 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t4.ok 231s 08:41:56.729018670 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 231s 08:41:56.730141830 E: + /usr/bin/ssh -Q key 231s 08:41:56.731239750 O: ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 231s 08:41:56.733315710 O: awk '{print $2}' | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t5.ok ; \ 231s 08:41:56.733710150 E: + grep -q ^ssh-rsa 231s 08:41:56.734890830 O: fi 231s 08:41:56.736382150 E: + ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub 231s 08:41:56.739638910 E: + awk {print $2} 231s 08:41:56.740978750 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t5.ok 231s 08:41:56.744185030 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 231s 08:41:56.745291350 E: + /usr/bin/ssh -Q key 231s 08:41:56.746435430 O: ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out1 ; \ 231s 08:41:56.748713430 E: + grep -q ^ssh-dss 231s 08:41:56.749124150 O: ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out2 ; \ 231s 08:41:56.750382390 O: chmod 600 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out1 ; \ 231s 08:41:56.752408350 O: ssh-keygen -yf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out2 ; \ 231s 08:41:56.752898870 E: + ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/dsa_ssh2.prv 231s 08:41:56.754073110 O: fi 231s 08:41:56.757179670 E: + ssh-keygen -if /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/dsa_ssh2.pub 231s 08:41:56.762366150 E: + chmod 600 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out1 231s 08:41:56.765295670 E: + ssh-keygen -yf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out1 231s 08:41:56.766781870 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t6.out2 231s 08:41:56.771149790 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 231s 08:41:56.772368470 E: + /usr/bin/ssh -Q key 231s 08:41:56.773402150 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t7.out ; \ 231s 08:41:56.774920070 O: fi 231s 08:41:56.776039350 E: + grep -q ^ssh-dss 231s 08:41:56.777867510 E: + ssh-keygen -q -t rsa -N -f /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t7.out 232s 08:41:57.808978310 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 232s 08:41:57.810427270 O: ssh-keygen -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 232s 08:41:57.811246390 E: + /usr/bin/ssh -Q key 232s 08:41:57.813413750 E: + grep -q ^ssh-dss 232s 08:41:57.813859550 O: ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 232s 08:41:57.815932830 O: fi 232s 08:41:57.816597070 E: + ssh-keygen -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t7.out 232s 08:41:57.820295870 E: + ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t7.out 232s 08:41:57.824844990 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 232s 08:41:57.827128590 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t8.out ; \ 232s 08:41:57.828297790 O: fi 232s 08:41:57.828297830 E: + grep -q ^ssh-dss 232s 08:41:57.829668430 E: + ssh -Q key 232s 08:41:57.833160870 E: + ssh-keygen -q -t dsa -N -f /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t8.out 232s 08:41:57.931700830 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 232s 08:41:57.933501630 O: ssh-keygen -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 232s 08:41:57.937085910 O: ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 232s 08:41:57.937202390 E: + ssh -Q key 232s 08:41:57.939219070 O: fi 232s 08:41:57.943286790 E: + grep -q ^ssh-dss 232s 08:41:57.945514710 E: + ssh-keygen -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t8.out 232s 08:41:57.950725870 E: + ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t8.out 232s 08:41:57.956397830 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 232s 08:41:57.958661470 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t9.out 232s 08:41:57.971990990 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 232s 08:41:57.974216630 O: ssh-keygen -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t9.out > /dev/null 233s 08:41:57.986963430 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 233s 08:41:57.989146390 O: ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t9.out > /dev/null 233s 08:41:58.001279710 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t10.out 233s 08:41:58.010766270 O: ssh-keygen -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t10.out > /dev/null 233s 08:41:58.019766790 O: ssh-keygen -Bf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t10.out > /dev/null 233s 08:41:58.026695230 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 233s 08:41:58.028749510 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 233s 08:41:58.031955790 E: + /usr/bin/ssh -Q key 233s 08:41:58.032450110 O: awk '{print $2}' | diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t11.ok ; \ 233s 08:41:58.034915350 E: + grep -q ^ssh-dss 233s 08:41:58.036124590 O: fi 233s 08:41:58.043757630 E: + ssh-keygen -E sha256 -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/rsa_openssh.pub 233s 08:41:58.049224910 E: + awk {print $2} 233s 08:41:58.049129230 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t12.out 233s 08:41:58.052024950 E: + diff - /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t11.ok 233s 08:41:58.055700630 O: ssh-keygen -lf /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 233s 08:41:58.064740030 E: run test connect.sh ... 235s 08:41:59.735991590 O: ok simple connect 235s 08:41:59.736985430 E: run test proxy-connect.sh ... 235s 08:41:59.937266550 O: plain username comp=no 235s 08:42:00.141011790 O: plain username comp=yes 235s 08:42:00.347618990 O: username with style 235s 08:42:00.550434590 O: ok proxy connect 235s 08:42:00.551864070 E: run test sshfp-connect.sh ... 235s 08:42:00.761425510 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 235s 08:42:00.762365790 E: run test connect-privsep.sh ... 238s 08:42:03.603792510 O: ok proxy connect with privsep 238s 08:42:03.605038710 E: run test connect-uri.sh ... 238s 08:42:03.899335150 O: uri connect: no trailing slash 239s 08:42:04.163756150 O: uri connect: trailing slash 239s 08:42:04.429911430 O: uri connect: with path name 239s 08:42:04.459256390 O: ok uri connect 239s 08:42:04.460271070 E: run test proto-version.sh ... 239s 08:42:04.690849190 O: ok sshd version with different protocol combinations 239s 08:42:04.691958590 E: run test proto-mismatch.sh ... 239s 08:42:04.963782950 O: ok protocol version mismatch 240s 08:42:04.965662190 E: run test exit-status.sh ... 240s 08:42:05.191263350 O: test remote exit status: status 0 245s 08:42:10.607215830 O: test remote exit status: status 1 252s 08:42:16.031214563 O: test remote exit status: status 4 256s 08:42:21.457302060 O: test remote exit status: status 5 261s 08:42:26.879144441 O: test remote exit status: status 44 267s 08:42:32.287277022 O: ok remote exit status 267s 08:42:32.289714479 E: run test exit-status-signal.sh ... 268s 08:42:33.501755026 O: ok exit status on signal 268s 08:42:33.503361760 E: run test envpass.sh ... 268s 08:42:33.705910139 O: test environment passing: pass env, don't accept 268s 08:42:33.913820925 O: test environment passing: setenv, don't accept 269s 08:42:34.118061319 O: test environment passing: don't pass env, accept 269s 08:42:34.320713294 O: test environment passing: pass single env, accept single env 269s 08:42:34.522448556 O: test environment passing: pass multiple env, accept multiple env 269s 08:42:34.724085491 O: test environment passing: setenv, accept 269s 08:42:34.931150289 O: test environment passing: setenv, first match wins 270s 08:42:35.135976497 O: test environment passing: server setenv wins 270s 08:42:35.337724017 O: test environment passing: server setenv wins 270s 08:42:35.542599965 O: ok environment passing 270s 08:42:35.543473069 E: run test transfer.sh ... 272s 08:42:37.455873021 O: ok transfer data 272s 08:42:37.456339131 E: run test banner.sh ... 272s 08:42:37.656873065 O: test banner: missing banner file 272s 08:42:37.863670523 O: test banner: size 0 273s 08:42:38.071056098 O: test banner: size 10 273s 08:42:38.290394960 O: test banner: size 100 273s 08:42:38.521979241 O: test banner: size 1000 273s 08:42:38.768450258 O: test banner: size 10000 274s 08:42:39.018504824 O: test banner: size 100000 274s 08:42:39.248492902 O: test banner: suppress banner (-q) 274s 08:42:39.456768472 O: ok banner 274s 08:42:39.458092187 E: run test rekey.sh ... 274s 08:42:39.710685982 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 275s 08:42:40.604444640 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 276s 08:42:41.497287180 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 277s 08:42:42.397656829 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 278s 08:42:43.302589730 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 279s 08:42:44.211215823 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 280s 08:42:45.120236001 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 281s 08:42:46.015137583 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 281s 08:42:46.899110302 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 282s 08:42:47.787174159 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 283s 08:42:48.668413510 O: client rekey KexAlgorithms=curve25519-sha256 284s 08:42:49.554712156 O: client rekey KexAlgorithms=curve25519-sha256@libssh.org 285s 08:42:50.443638044 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 286s 08:42:51.346448372 O: client rekey Ciphers=3des-cbc 287s 08:42:52.285939903 O: client rekey Ciphers=aes128-cbc 290s 08:42:53.249373495 O: client rekey Ciphers=aes192-cbc 290s 08:42:54.148587754 O: client rekey Ciphers=aes256-cbc 290s 08:42:55.039170900 O: client rekey Ciphers=aes128-ctr 290s 08:42:55.954205234 O: client rekey Ciphers=aes192-ctr 291s 08:42:56.853286936 O: client rekey Ciphers=aes256-ctr 292s 08:42:57.747782252 O: client rekey Ciphers=aes128-gcm@openssh.com 293s 08:42:58.633641101 O: client rekey Ciphers=aes256-gcm@openssh.com 294s 08:42:59.512267621 O: client rekey Ciphers=chacha20-poly1305@openssh.com 295s 08:43:00.389401320 O: client rekey MACs=hmac-sha1 296s 08:43:01.264708957 O: client rekey MACs=hmac-sha1-96 297s 08:43:02.148476386 O: client rekey MACs=hmac-sha2-256 298s 08:43:03.025251718 O: client rekey MACs=hmac-sha2-512 298s 08:43:03.903459064 O: client rekey MACs=hmac-md5 299s 08:43:04.780042166 O: client rekey MACs=hmac-md5-96 300s 08:43:05.656308410 O: client rekey MACs=umac-64@openssh.com 301s 08:43:06.533730840 O: client rekey MACs=umac-128@openssh.com 302s 08:43:07.415091065 O: client rekey MACs=hmac-sha1-etm@openssh.com 303s 08:43:08.296384029 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 304s 08:43:09.173628404 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 305s 08:43:10.048419793 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 305s 08:43:10.927436751 O: client rekey MACs=hmac-md5-etm@openssh.com 306s 08:43:11.804334030 O: client rekey MACs=hmac-md5-96-etm@openssh.com 307s 08:43:12.681731413 O: client rekey MACs=umac-64-etm@openssh.com 308s 08:43:13.560658403 O: client rekey MACs=umac-128-etm@openssh.com 309s 08:43:14.462989624 O: client rekey aes128-gcm@openssh.com diffie-hellman-group1-sha1 310s 08:43:15.342006528 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha1 311s 08:43:16.217376259 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha256 312s 08:43:17.095119274 O: client rekey aes128-gcm@openssh.com diffie-hellman-group16-sha512 312s 08:43:17.969982858 O: client rekey aes128-gcm@openssh.com diffie-hellman-group18-sha512 313s 08:43:18.841823491 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha1 314s 08:43:19.710641358 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha256 315s 08:43:20.580794538 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp256 316s 08:43:21.451754844 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp384 317s 08:43:22.323080747 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp521 318s 08:43:23.193217469 O: client rekey aes128-gcm@openssh.com curve25519-sha256 319s 08:43:24.065149510 O: client rekey aes128-gcm@openssh.com curve25519-sha256@libssh.org 319s 08:43:24.941473211 O: client rekey aes128-gcm@openssh.com sntrup761x25519-sha512@openssh.com 320s 08:43:25.827202009 O: client rekey aes256-gcm@openssh.com diffie-hellman-group1-sha1 321s 08:43:26.703763028 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha1 322s 08:43:27.583083549 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha256 323s 08:43:28.461672559 O: client rekey aes256-gcm@openssh.com diffie-hellman-group16-sha512 324s 08:43:29.338694242 O: client rekey aes256-gcm@openssh.com diffie-hellman-group18-sha512 325s 08:43:30.224197861 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha1 326s 08:43:31.104216017 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha256 327s 08:43:31.983351545 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp256 327s 08:43:32.859747980 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp384 328s 08:43:33.734212997 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp521 329s 08:43:34.602084654 O: client rekey aes256-gcm@openssh.com curve25519-sha256 330s 08:43:35.476017948 O: client rekey aes256-gcm@openssh.com curve25519-sha256@libssh.org 331s 08:43:36.353375536 O: client rekey aes256-gcm@openssh.com sntrup761x25519-sha512@openssh.com 332s 08:43:37.240867138 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group1-sha1 333s 08:43:38.116558458 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha1 334s 08:43:39.001718794 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha256 334s 08:43:39.881031418 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group16-sha512 335s 08:43:40.756578614 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group18-sha512 336s 08:43:41.633395189 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha1 337s 08:43:42.506044532 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha256 338s 08:43:43.381061908 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp256 339s 08:43:44.256022047 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp384 340s 08:43:45.133098414 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp521 341s 08:43:46.008649628 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256 341s 08:43:46.881204172 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256@libssh.org 342s 08:43:47.757504265 O: client rekey chacha20-poly1305@openssh.com sntrup761x25519-sha512@openssh.com 343s 08:43:48.632614001 O: client rekeylimit 16 344s 08:43:49.840369893 O: client rekeylimit 1k 346s 08:43:50.982204487 O: client rekeylimit 128k 346s 08:43:51.856452185 O: client rekeylimit 256k 347s 08:43:52.730941129 O: client rekeylimit default 5 362s 08:44:07.943231415 O: client rekeylimit default 10 383s 08:44:28.153396304 O: client rekeylimit default 5 no data 398s 08:44:43.362496154 O: client rekeylimit default 10 no data 418s 08:45:03.570365826 O: server rekeylimit 16 419s 08:45:04.779846432 O: server rekeylimit 1k 420s 08:45:05.927991015 O: server rekeylimit 128k 421s 08:45:06.806271588 O: server rekeylimit 256k 422s 08:45:07.683116265 O: server rekeylimit default 5 no data 437s 08:45:22.955621505 O: server rekeylimit default 10 no data 458s 08:45:43.233645367 O: rekeylimit parsing 466s 08:45:51.160483971 O: ok rekey 466s 08:45:51.161412819 E: run test dhgex.sh ... 466s 08:45:51.363340476 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 466s 08:45:51.533977728 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 466s 08:45:51.724004297 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 466s 08:45:51.894350001 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 467s 08:45:52.059247902 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 467s 08:45:52.222368598 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 467s 08:45:52.382617545 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 467s 08:45:52.552424865 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 467s 08:45:52.724145522 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 468s 08:45:52.987691585 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 468s 08:45:53.254807969 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 468s 08:45:53.527825594 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 468s 08:45:53.796417533 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 469s 08:45:54.072429568 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 469s 08:45:54.342168982 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 469s 08:45:54.614807457 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 469s 08:45:54.888185440 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 470s 08:45:55.160937497 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 470s 08:45:55.433949377 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 470s 08:45:55.823218036 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 471s 08:45:56.212874272 O: ok dhgex 471s 08:45:56.213241624 E: run test stderr-data.sh ... 471s 08:45:56.404272786 O: test stderr data transfer: () 477s 08:46:02.819226487 O: test stderr data transfer: (-n) 484s 08:46:09.237508757 O: ok stderr data transfer 484s 08:46:09.238238888 E: run test stderr-after-eof.sh ... 486s 08:46:11.645858863 O: ok stderr data after eof 486s 08:46:11.646058357 E: run test broken-pipe.sh ... 486s 08:46:11.879341718 O: ok broken pipe test 486s 08:46:11.879503810 E: run test try-ciphers.sh ... 487s 08:46:12.087643703 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 487s 08:46:12.300026592 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 487s 08:46:12.515307318 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 487s 08:46:12.725500739 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 487s 08:46:12.937760140 O: test try ciphers: cipher 3des-cbc mac hmac-md5 488s 08:46:13.146636276 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 488s 08:46:13.357144252 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 488s 08:46:13.572049082 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 488s 08:46:13.790507989 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 489s 08:46:13.999799444 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 489s 08:46:14.211402191 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 489s 08:46:14.421708612 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 489s 08:46:14.629236730 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 489s 08:46:14.836389583 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 490s 08:46:15.045138896 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 490s 08:46:15.250147555 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 490s 08:46:15.464477337 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 490s 08:46:15.672111006 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 490s 08:46:15.884233325 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 491s 08:46:16.092891767 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 491s 08:46:16.307171406 O: test try ciphers: cipher aes128-cbc mac hmac-md5 491s 08:46:16.517053246 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 491s 08:46:16.732645689 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 491s 08:46:16.943319578 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 492s 08:46:17.155332798 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 492s 08:46:17.367443166 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 492s 08:46:17.589481755 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 492s 08:46:17.805869911 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 493s 08:46:18.015656639 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 493s 08:46:18.227702992 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 493s 08:46:18.442055887 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 493s 08:46:18.656474507 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 493s 08:46:18.875619977 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 494s 08:46:19.101451363 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 494s 08:46:19.319255978 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 494s 08:46:19.525657862 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 494s 08:46:19.733910898 O: test try ciphers: cipher aes192-cbc mac hmac-md5 494s 08:46:19.945891440 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 495s 08:46:20.158176170 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 495s 08:46:20.368859510 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 495s 08:46:20.574471388 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 495s 08:46:20.783971098 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 496s 08:46:20.991375683 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 496s 08:46:21.196370459 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 496s 08:46:21.411652151 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 496s 08:46:21.624300368 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 496s 08:46:21.836093734 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 497s 08:46:22.051010038 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 497s 08:46:22.268930801 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 497s 08:46:22.478131381 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 497s 08:46:22.702689728 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 497s 08:46:22.908324861 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 498s 08:46:23.117364324 O: test try ciphers: cipher aes256-cbc mac hmac-md5 498s 08:46:23.330841355 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 498s 08:46:23.535732738 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 498s 08:46:23.743711136 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 498s 08:46:23.952263126 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 499s 08:46:24.160274542 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 499s 08:46:24.374215526 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 499s 08:46:24.582338545 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 499s 08:46:24.792121296 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 500s 08:46:25.000749622 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 500s 08:46:25.206343556 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 500s 08:46:25.415423523 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 500s 08:46:25.628220414 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 501s 08:46:25.843757056 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 501s 08:46:26.057666998 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 501s 08:46:26.267670632 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 501s 08:46:26.473514319 O: test try ciphers: cipher aes128-ctr mac hmac-md5 501s 08:46:26.686871015 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 501s 08:46:26.896223213 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 502s 08:46:27.105680726 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 502s 08:46:27.320941059 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 502s 08:46:27.536274956 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 502s 08:46:27.745868267 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 502s 08:46:27.957828224 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 503s 08:46:28.171704378 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 503s 08:46:28.387414073 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 503s 08:46:28.596919761 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 503s 08:46:28.803833554 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 504s 08:46:29.020734094 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 504s 08:46:29.232406719 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 504s 08:46:29.444956550 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 504s 08:46:29.657854519 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 504s 08:46:29.866409904 O: test try ciphers: cipher aes192-ctr mac hmac-md5 505s 08:46:30.073720765 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 505s 08:46:30.287468842 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 505s 08:46:30.501086673 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 505s 08:46:30.708593153 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 505s 08:46:30.920747309 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 506s 08:46:31.132840276 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 506s 08:46:31.352150420 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 506s 08:46:31.565338576 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 506s 08:46:31.773614687 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 507s 08:46:31.982390662 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 507s 08:46:32.197123101 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 507s 08:46:32.419177525 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 507s 08:46:32.630426936 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 507s 08:46:32.837322013 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 508s 08:46:33.043802956 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 508s 08:46:33.248412360 O: test try ciphers: cipher aes256-ctr mac hmac-md5 508s 08:46:33.452409894 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 508s 08:46:33.657277191 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 508s 08:46:33.862233811 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 509s 08:46:34.064378567 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 509s 08:46:34.272996285 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 509s 08:46:34.478925274 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 509s 08:46:34.688821412 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 509s 08:46:34.898662828 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 510s 08:46:35.105939362 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 510s 08:46:35.314014375 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 510s 08:46:35.521908541 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 510s 08:46:35.741317366 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 510s 08:46:35.959992398 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 511s 08:46:36.178994632 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 511s 08:46:36.388704246 O: ok try ciphers 511s 08:46:36.389047342 E: run test yes-head.sh ... 514s 08:46:39.578908627 O: ok yes pipe head 514s 08:46:39.579208360 E: run test login-timeout.sh ... 530s 08:46:55.148468179 E: run test agent.sh ... 530s 08:46:55.148295053 O: ok connect after login grace timeout 538s 08:47:02.994910530 O: ok simple agent test 538s 08:47:02.995263260 E: run test agent-getpeereid.sh ... 538s 08:47:03.214973197 O: ok disallow agent attach from other uid 538s 08:47:03.215954546 E: run test agent-timeout.sh ... 558s 08:47:23.488426817 O: ok agent timeout test 558s 08:47:23.489590002 E: run test agent-ptrace.sh ... 558s 08:47:23.674659160 O: skipped (gdb not found) 558s 08:47:23.675644582 E: run test agent-subprocess.sh ... 568s 08:47:33.879279235 O: ok agent subprocess 568s 08:47:33.880404055 E: run test keyscan.sh ... 571s 08:47:35.875479048 O: ok keyscan 571s 08:47:35.875569250 E: run test keygen-change.sh ... 576s 08:47:41.410827912 O: ok change passphrase for key 576s 08:47:41.413023707 E: run test keygen-comment.sh ... 583s 08:47:47.286944486 O: ok Comment extraction from private key 583s 08:47:47.287284291 E: run test keygen-convert.sh ... 587s 08:47:52.260335186 O: ok convert keys 587s 08:47:52.260790032 E: run test keygen-knownhosts.sh ... 587s 08:47:52.596045514 O: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts updated. 587s 08:47:52.597148809 O: Original contents retained as /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts.old 587s 08:47:52.616908553 O: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts updated. 587s 08:47:52.618081969 O: Original contents retained as /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts.old 587s 08:47:52.625434147 O: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts updated. 587s 08:47:52.626732124 O: Original contents retained as /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts.old 587s 08:47:52.640590550 O: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts updated. 587s 08:47:52.641793206 O: Original contents retained as /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hosts.old 587s 08:47:52.666709659 O: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hashed updated. 587s 08:47:52.667911635 O: Original contents retained as /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/kh.hashed.old 587s 08:47:52.672796180 O: ok ssh-keygen known_hosts 587s 08:47:52.673627991 E: run test keygen-moduli.sh ... 595s 08:48:00.202845564 O: ok keygen moduli 595s 08:48:00.204008898 E: run test keygen-sshfp.sh ... 595s 08:48:00.442379043 O: ok keygen-sshfp 595s 08:48:00.443447896 E: run test key-options.sh ... 595s 08:48:00.629478070 O: key option command="echo bar" 595s 08:48:00.831155986 O: key option no-pty,command="echo bar" 596s 08:48:01.032269211 O: key option pty default 596s 08:48:01.238726623 O: key option pty no-pty 596s 08:48:01.438731721 O: key option pty restrict 596s 08:48:01.635760185 O: key option pty restrict,pty 596s 08:48:01.836433931 O: key option environment 597s 08:48:02.329062088 O: key option from="127.0.0.1" 598s 08:48:03.036451993 O: key option from="127.0.0.0/8" 598s 08:48:03.544858760 O: key option expiry-time default 598s 08:48:03.747212327 O: key option expiry-time invalid 600s 08:48:03.992735054 O: key option expiry-time expired 600s 08:48:04.223386494 O: key option expiry-time valid 600s 08:48:04.441122832 O: ok key options 600s 08:48:04.442326205 E: run test scp.sh ... 600s 08:48:04.641686942 O: scp: scp mode: simple copy local file to local file 600s 08:48:04.655355252 O: scp: scp mode: simple copy local file to remote file 600s 08:48:04.666343292 O: scp: scp mode: simple copy remote file to local file 600s 08:48:04.676980568 O: scp: scp mode: copy local file to remote file in place 600s 08:48:04.688471373 O: scp: scp mode: copy remote file to local file in place 600s 08:48:04.700873869 O: scp: scp mode: copy local file to remote file clobber 600s 08:48:04.711611826 O: -rw-rw-r-- 1 openssh-tests openssh-tests 330088 Nov 7 08:48 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/copy 600s 08:48:04.713179843 O: -rw-rw-r-- 1 openssh-tests openssh-tests 330088 Nov 7 08:48 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/data 600s 08:48:04.715057944 O: scp: scp mode: copy remote file to local file clobber 600s 08:48:04.725011572 O: scp: scp mode: simple copy local file to remote dir 600s 08:48:04.737195225 O: scp: scp mode: simple copy local file to local dir 600s 08:48:04.749180396 O: scp: scp mode: simple copy remote file to local dir 600s 08:48:04.761321049 O: scp: scp mode: recursive local dir to remote dir 600s 08:48:04.781279787 O: scp: scp mode: recursive local dir to local dir 600s 08:48:04.799555386 O: scp: scp mode: recursive remote dir to local dir 600s 08:48:04.819972129 O: scp: scp mode: unmatched glob file local->remote 600s 08:48:04.829329832 O: scp: scp mode: unmatched glob file remote->local 600s 08:48:04.834695730 O: scp: scp mode: unmatched glob dir recursive local->remote 600s 08:48:04.856654490 O: scp: scp mode: unmatched glob dir recursive remote->local 600s 08:48:04.863779248 O: scp: scp mode: shell metacharacters 600s 08:48:04.872394062 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 600s 08:48:04.914967967 O: scp: scp mode: disallow bad server #0 600s 08:48:04.939239712 O: scp: scp mode: disallow bad server #1 600s 08:48:04.963244894 O: scp: scp mode: disallow bad server #2 600s 08:48:04.987687481 O: scp: scp mode: disallow bad server #3 600s 08:48:05.013467920 O: scp: scp mode: disallow bad server #4 600s 08:48:05.040752333 O: scp: scp mode: disallow bad server #5 600s 08:48:05.070807296 O: scp: scp mode: disallow bad server #6 600s 08:48:05.100265492 O: scp: scp mode: disallow bad server #7 600s 08:48:05.128327353 O: scp: scp mode: detect non-directory target 600s 08:48:05.133822812 E: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/copy2: Not a directory 600s 08:48:05.136107877 O: scp: sftp mode: simple copy local file to local file 600s 08:48:05.146682110 O: scp: sftp mode: simple copy local file to remote file 600s 08:48:05.160405378 O: scp: sftp mode: simple copy remote file to local file 600s 08:48:05.171294295 O: scp: sftp mode: copy local file to remote file in place 600s 08:48:05.183390064 O: scp: sftp mode: copy remote file to local file in place 600s 08:48:05.196111601 O: scp: sftp mode: copy local file to remote file clobber 600s 08:48:05.206775595 O: -rw-rw-r-- 1 openssh-tests openssh-tests 330088 Nov 7 08:48 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/copy 600s 08:48:05.207926008 O: -rw-rw-r-- 1 openssh-tests openssh-tests 330088 Nov 7 08:48 /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/data 600s 08:48:05.209618186 O: scp: sftp mode: copy remote file to local file clobber 600s 08:48:05.222277402 O: scp: sftp mode: simple copy local file to remote dir 600s 08:48:05.235815627 O: scp: sftp mode: simple copy local file to local dir 600s 08:48:05.247476112 O: scp: sftp mode: simple copy remote file to local dir 600s 08:48:05.262195270 O: scp: sftp mode: recursive local dir to remote dir 600s 08:48:05.286932096 O: scp: sftp mode: recursive local dir to local dir 600s 08:48:05.308656249 O: scp: sftp mode: recursive remote dir to local dir 600s 08:48:05.333844720 O: scp: sftp mode: unmatched glob file local->remote 600s 08:48:05.344648116 O: scp: sftp mode: unmatched glob file remote->local 600s 08:48:05.352680402 O: scp: sftp mode: unmatched glob dir recursive local->remote 600s 08:48:05.368775055 O: scp: sftp mode: unmatched glob dir recursive remote->local 600s 08:48:05.379044765 O: scp: sftp mode: shell metacharacters 600s 08:48:05.388128383 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 600s 08:48:05.430097833 O: scp: sftp mode: disallow bad server #0 600s 08:48:05.462169217 O: scp: sftp mode: disallow bad server #1 600s 08:48:05.493405713 O: scp: sftp mode: disallow bad server #2 600s 08:48:05.525098333 O: scp: sftp mode: disallow bad server #3 600s 08:48:05.555721342 O: scp: sftp mode: disallow bad server #4 600s 08:48:05.586453472 O: scp: sftp mode: disallow bad server #5 600s 08:48:05.617012360 O: scp: sftp mode: disallow bad server #6 600s 08:48:05.647336406 O: scp: sftp mode: disallow bad server #7 600s 08:48:05.678552541 O: scp: sftp mode: detect non-directory target 600s 08:48:05.683282351 E: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/copy2: Not a directory 600s 08:48:05.689955983 O: ok scp 600s 08:48:05.691166356 E: run test scp3.sh ... 600s 08:48:05.883969866 O: scp3: scp mode: simple copy remote file to remote file 601s 08:48:06.117054187 O: scp3: scp mode: simple copy remote file to remote dir 601s 08:48:06.348767113 O: scp3: scp mode: recursive remote dir to remote dir 601s 08:48:06.603441481 O: scp3: scp mode: detect non-directory target 602s 08:48:07.053943907 O: scp3: sftp mode: simple copy remote file to remote file 602s 08:48:07.066012152 O: scp3: sftp mode: simple copy remote file to remote dir 602s 08:48:07.079223729 O: scp3: sftp mode: recursive remote dir to remote dir 602s 08:48:07.105117318 O: scp3: sftp mode: detect non-directory target 602s 08:48:07.112209432 E: scp: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/copy2: destination is not a directory 602s 08:48:07.114582056 E: scp: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/copy2: destination is not a directory 602s 08:48:07.121655290 O: ok scp3 602s 08:48:07.121916692 E: run test scp-uri.sh ... 602s 08:48:07.318491332 O: scp-uri: scp mode: simple copy local file to remote file 602s 08:48:07.326206132 O: scp-uri: scp mode: simple copy remote file to local file 602s 08:48:07.333828371 O: scp-uri: scp mode: simple copy local file to remote dir 602s 08:48:07.343418511 O: scp-uri: scp mode: simple copy remote file to local dir 602s 08:48:07.353081211 O: scp-uri: scp mode: recursive local dir to remote dir 602s 08:48:07.364219287 O: scp-uri: scp mode: recursive remote dir to local dir 602s 08:48:07.375968929 O: scp-uri: sftp mode: simple copy local file to remote file 602s 08:48:07.385757750 O: scp-uri: sftp mode: simple copy remote file to local file 602s 08:48:07.395276729 O: scp-uri: sftp mode: simple copy local file to remote dir 602s 08:48:07.406207282 O: scp-uri: sftp mode: simple copy remote file to local dir 602s 08:48:07.416887473 O: scp-uri: sftp mode: recursive local dir to remote dir 602s 08:48:07.429199521 O: scp-uri: sftp mode: recursive remote dir to local dir 602s 08:48:07.445143807 O: ok scp-uri 602s 08:48:07.446064176 E: run test sftp.sh ... 602s 08:48:07.638095169 O: test basic sftp put/get: buffer_size 5 num_requests 1 605s 08:48:10.309084402 O: test basic sftp put/get: buffer_size 5 num_requests 2 606s 08:48:11.606294649 O: test basic sftp put/get: buffer_size 5 num_requests 10 607s 08:48:12.279055043 O: test basic sftp put/get: buffer_size 1000 num_requests 1 607s 08:48:12.299951922 O: test basic sftp put/get: buffer_size 1000 num_requests 2 607s 08:48:12.314958585 O: test basic sftp put/get: buffer_size 1000 num_requests 10 607s 08:48:12.327155302 O: test basic sftp put/get: buffer_size 32000 num_requests 1 607s 08:48:12.336867994 O: test basic sftp put/get: buffer_size 32000 num_requests 2 607s 08:48:12.345623197 O: test basic sftp put/get: buffer_size 32000 num_requests 10 607s 08:48:12.354623923 O: test basic sftp put/get: buffer_size 64000 num_requests 1 607s 08:48:12.363984172 O: test basic sftp put/get: buffer_size 64000 num_requests 2 607s 08:48:12.373438342 O: test basic sftp put/get: buffer_size 64000 num_requests 10 607s 08:48:12.385186454 O: ok basic sftp put/get 607s 08:48:12.386367666 E: run test sftp-chroot.sh ... 607s 08:48:12.685199072 O: test sftp in chroot: get 608s 08:48:12.975432197 O: test sftp in chroot: match 608s 08:48:13.371623391 O: ok sftp in chroot 608s 08:48:13.381471244 E: run test sftp-cmds.sh ... 608s 08:48:13.583679737 O: sftp commands: lls 608s 08:48:13.589615193 O: sftp commands: lls w/path 608s 08:48:13.597739949 O: sftp commands: ls 608s 08:48:13.605590902 O: sftp commands: shell 608s 08:48:13.611493758 O: sftp commands: pwd 608s 08:48:13.615640516 O: sftp commands: lpwd 608s 08:48:13.619458032 O: sftp commands: quit 608s 08:48:13.623882154 O: sftp commands: help 608s 08:48:13.628914361 O: sftp commands: get 608s 08:48:13.636252470 O: sftp commands: get quoted 608s 08:48:13.645977561 O: sftp commands: get filename with quotes 608s 08:48:13.656231817 O: sftp commands: get filename with spaces 608s 08:48:13.666697235 O: sftp commands: get filename with glob metacharacters 608s 08:48:13.674645909 O: sftp commands: get to directory 608s 08:48:13.682603944 O: sftp commands: glob get to directory 608s 08:48:13.797276017 O: sftp commands: get to local dir 608s 08:48:13.805157411 O: sftp commands: glob get to local dir 608s 08:48:13.861415138 O: sftp commands: put 608s 08:48:13.869861857 O: sftp commands: put filename with quotes 608s 08:48:13.878177975 O: sftp commands: put filename with spaces 608s 08:48:13.890203768 O: sftp commands: put to directory 608s 08:48:13.899406134 O: sftp commands: glob put to directory 608s 08:48:13.911943291 O: sftp commands: put to local dir 608s 08:48:13.921195018 O: sftp commands: glob put to local dir 608s 08:48:13.931077150 O: sftp commands: rename 608s 08:48:13.936725163 O: sftp commands: rename directory 608s 08:48:13.940586639 O: sftp commands: ln 608s 08:48:13.946037130 O: sftp commands: ln -s 608s 08:48:13.951760624 O: sftp commands: cp 608s 08:48:13.959125293 O: sftp commands: mkdir 608s 08:48:13.963869257 O: sftp commands: chdir 608s 08:48:13.968384380 O: sftp commands: rmdir 609s 08:48:13.972782701 O: sftp commands: lmkdir 609s 08:48:13.977384104 O: sftp commands: lchdir 609s 08:48:13.985298218 O: ok sftp commands 609s 08:48:13.986835912 E: run test sftp-badcmds.sh ... 609s 08:48:14.185156620 O: sftp invalid commands: get nonexistent 609s 08:48:14.190734951 O: sftp invalid commands: glob get to nonexistent directory 609s 08:48:14.205185164 O: sftp invalid commands: put nonexistent 609s 08:48:14.209957608 O: sftp invalid commands: glob put to nonexistent directory 609s 08:48:14.215989584 O: sftp invalid commands: rename nonexistent 609s 08:48:14.223258411 O: sftp invalid commands: rename target exists (directory) 609s 08:48:14.231088883 O: sftp invalid commands: glob put files to local file 609s 08:48:14.237895706 O: ok sftp invalid commands 609s 08:48:14.238832034 E: run test sftp-batch.sh ... 609s 08:48:14.441990224 O: sftp batchfile: good commands 609s 08:48:14.449386172 O: sftp batchfile: bad commands 609s 08:48:14.458263934 O: sftp batchfile: comments and blanks 609s 08:48:14.465451520 O: sftp batchfile: junk command 609s 08:48:14.470688768 O: ok sftp batchfile 609s 08:48:14.470628968 E: run test sftp-glob.sh ... 609s 08:48:14.676264261 O: sftp glob: file glob 609s 08:48:14.685839789 O: sftp glob: dir glob 609s 08:48:14.692993255 O: sftp glob: quoted glob 609s 08:48:14.701732255 O: sftp glob: escaped glob 609s 08:48:14.711836068 O: sftp glob: escaped quote 609s 08:48:14.718996614 O: sftp glob: quoted quote 609s 08:48:14.725976398 O: sftp glob: single-quoted quote 609s 08:48:14.733816910 O: sftp glob: escaped space 609s 08:48:14.740453931 O: sftp glob: quoted space 609s 08:48:14.747243914 O: sftp glob: escaped slash 609s 08:48:14.753902695 O: sftp glob: quoted slash 609s 08:48:14.760689278 O: sftp glob: escaped slash at EOL 609s 08:48:14.769162796 O: sftp glob: quoted slash at EOL 609s 08:48:14.778359480 O: sftp glob: escaped slash+quote 609s 08:48:14.783896291 O: sftp glob: quoted slash+quote 609s 08:48:14.793897303 O: ok sftp glob 609s 08:48:14.795046794 E: run test sftp-perm.sh ... 610s 08:48:14.999698318 O: sftp permissions: read-only upload 610s 08:48:15.020856389 O: sftp permissions: read-only setstat 610s 08:48:15.040084523 O: sftp permissions: read-only rm 610s 08:48:15.057367880 O: sftp permissions: read-only mkdir 610s 08:48:15.073697107 O: sftp permissions: read-only rmdir 610s 08:48:15.092033633 O: sftp permissions: read-only posix-rename 610s 08:48:15.110126797 O: sftp permissions: read-only oldrename 610s 08:48:15.128055799 O: sftp permissions: read-only symlink 610s 08:48:15.145851080 O: sftp permissions: read-only hardlink 610s 08:48:15.163469959 O: sftp permissions: explicit open 610s 08:48:15.199192243 O: sftp permissions: explicit read 610s 08:48:15.235315130 O: sftp permissions: explicit write 610s 08:48:15.272707108 O: sftp permissions: explicit lstat 610s 08:48:15.306444933 O: sftp permissions: explicit opendir 610s 08:48:15.340476361 O: sftp permissions: explicit readdir 610s 08:48:15.376096243 O: sftp permissions: explicit setstat 610s 08:48:15.415763282 O: sftp permissions: explicit remove 610s 08:48:15.451274443 O: sftp permissions: explicit mkdir 610s 08:48:15.482118682 O: sftp permissions: explicit rmdir 610s 08:48:15.517052239 O: sftp permissions: explicit rename 610s 08:48:15.551190467 O: sftp permissions: explicit symlink 610s 08:48:15.584344327 O: sftp permissions: explicit hardlink 610s 08:48:15.617317106 O: sftp permissions: explicit statvfs 610s 08:48:15.642286932 O: ok sftp permissions 610s 08:48:15.643435102 E: run test sftp-uri.sh ... 610s 08:48:15.928958085 O: sftp-uri: non-interactive fetch to local file 611s 08:48:16.198705695 O: sftp-uri: non-interactive fetch to local dir 611s 08:48:16.470776514 O: sftp-uri: put to remote directory (trailing slash) 611s 08:48:16.741465242 O: sftp-uri: put to remote directory (no slash) 612s 08:48:17.036001655 O: ok sftp-uri 612s 08:48:17.037172106 E: run test reconfigure.sh ... 625s 08:48:30.449020173 O: ok simple connect after reconfigure 625s 08:48:30.449181935 E: run test dynamic-forward.sh ... 625s 08:48:30.745257756 O: test -D forwarding 627s 08:48:31.981557846 O: test -R forwarding 628s 08:48:33.270922723 O: PermitRemoteOpen=any 629s 08:48:34.557358994 O: PermitRemoteOpen=none 629s 08:48:34.961271814 O: PermitRemoteOpen=explicit 631s 08:48:36.402281275 O: PermitRemoteOpen=disallowed 631s 08:48:36.911471102 O: ok dynamic forwarding 631s 08:48:36.912858711 E: run test forwarding.sh ... 637s 08:48:42.921690987 O: ok local and remote forwarding 637s 08:48:42.922379351 E: run test multiplex.sh ... 639s 08:48:44.240044766 O: test connection multiplexing: setenv 639s 08:48:44.256076693 O: test connection multiplexing: envpass 639s 08:48:44.271495657 O: test connection multiplexing: transfer 639s 08:48:44.355095109 O: test connection multiplexing: forward 641s 08:48:46.403753802 O: test connection multiplexing: status 0 () 646s 08:48:51.438225911 O: test connection multiplexing: status 0 (-Oproxy) 651s 08:48:56.472047730 O: test connection multiplexing: status 1 () 656s 08:49:01.505326775 O: test connection multiplexing: status 1 (-Oproxy) 661s 08:49:06.539607115 O: test connection multiplexing: status 4 () 666s 08:49:11.572371439 O: test connection multiplexing: status 4 (-Oproxy) 671s 08:49:16.606257900 O: test connection multiplexing: status 5 () 676s 08:49:21.640120045 O: test connection multiplexing: status 5 (-Oproxy) 681s 08:49:26.672344787 O: test connection multiplexing: status 44 () 686s 08:49:31.707592092 O: test connection multiplexing: status 44 (-Oproxy) 691s 08:49:36.741487281 O: test connection multiplexing: cmd check 691s 08:49:36.752259301 O: test connection multiplexing: cmd forward local (TCP) 693s 08:49:38.066449566 O: test connection multiplexing: cmd forward remote (TCP) 694s 08:49:39.358628950 O: test connection multiplexing: cmd forward local (UNIX) 695s 08:49:40.425230882 O: test connection multiplexing: cmd forward remote (UNIX) 696s 08:49:41.492616063 O: test connection multiplexing: cmd exit 696s 08:49:41.505229049 O: test connection multiplexing: cmd stop 707s 08:49:52.569787415 O: ok connection multiplexing 707s 08:49:52.571181223 E: run test reexec.sh ... 707s 08:49:52.781181851 O: test config passing 708s 08:49:53.168627922 E: ln: failed to create hard link '/tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 708s 08:49:53.167851801 O: test reexec fallback 708s 08:49:53.533424867 O: ok reexec tests 708s 08:49:53.534490493 E: run test brokenkeys.sh ... 709s 08:49:54.105745435 O: ok broken keys 709s 08:49:54.107089847 E: run test sshcfgparse.sh ... 709s 08:49:54.297919755 O: reparse minimal config 709s 08:49:54.318201405 O: ssh -W opts 709s 08:49:54.377315562 O: user first match 709s 08:49:54.420444931 O: pubkeyacceptedalgorithms 709s 08:49:54.519397106 O: agentforwarding 709s 08:49:54.573151655 O: command line override 709s 08:49:54.601410300 O: ok ssh config parse 709s 08:49:54.601935193 E: run test cfgparse.sh ... 709s 08:49:54.814274208 O: reparse minimal config 709s 08:49:54.911113690 O: reparse regress config 710s 08:49:55.009743963 O: listenaddress order 710s 08:49:55.115107648 O: ok sshd config parse 710s 08:49:55.116393223 E: run test cfgmatch.sh ... 717s 08:50:02.892453790 O: ok sshd_config match 717s 08:50:02.893740728 E: run test cfgmatchlisten.sh ... 728s 08:50:13.641725794 O: ok sshd_config matchlisten 728s 08:50:13.642322447 E: run test percent.sh ... 728s 08:50:13.832223476 O: percent expansions matchexec percent 731s 08:50:16.531279386 O: percent expansions localcommand percent 734s 08:50:18.973055044 O: percent expansions remotecommand percent 734s 08:50:19.132044924 O: percent expansions controlpath percent 734s 08:50:19.294228834 O: percent expansions identityagent percent 734s 08:50:19.457177712 O: percent expansions forwardagent percent 734s 08:50:19.624322769 O: percent expansions localforward percent 734s 08:50:19.790199961 O: percent expansions remoteforward percent 734s 08:50:19.955569094 O: percent expansions revokedhostkeys percent 735s 08:50:20.113790447 O: percent expansions userknownhostsfile percent 737s 08:50:22.076736808 O: percent expansions controlpath dollar 737s 08:50:22.090502148 O: percent expansions identityagent dollar 737s 08:50:22.104462440 O: percent expansions forwardagent dollar 737s 08:50:22.118662721 O: percent expansions localforward dollar 737s 08:50:22.132417142 O: percent expansions remoteforward dollar 737s 08:50:22.150053439 O: percent expansions userknownhostsfile dollar 737s 08:50:22.342878794 O: percent expansions controlpath tilde 737s 08:50:22.370316038 O: percent expansions identityagent tilde 737s 08:50:22.397884717 O: percent expansions forwardagent tilde 737s 08:50:22.425447915 O: ok percent expansions 737s 08:50:22.426634785 E: run test addrmatch.sh ... 737s 08:50:22.624109745 O: test first entry for user 192.168.0.1 somehost 737s 08:50:22.665255171 O: test negative match for user 192.168.30.1 somehost 737s 08:50:22.705878780 O: test no match for user 19.0.0.1 somehost 737s 08:50:22.747420229 O: test list middle for user 10.255.255.254 somehost 737s 08:50:22.788340905 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 737s 08:50:22.829602647 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 737s 08:50:22.870240414 O: test localaddress for user 19.0.0.1 somehost 737s 08:50:22.911440398 O: test localport for user 19.0.0.1 somehost 737s 08:50:22.952310996 O: test bare IP6 address for user ::1 somehost.example.com 738s 08:50:22.992971203 O: test deny IPv6 for user ::2 somehost.example.com 738s 08:50:23.034336989 O: test IP6 negated for user ::3 somehost 738s 08:50:23.075412190 O: test IP6 no match for user ::4 somehost 738s 08:50:23.116729421 O: test IP6 network for user 2000::1 somehost 738s 08:50:23.158063090 O: test IP6 network for user 2001::1 somehost 738s 08:50:23.199016176 O: test IP6 localaddress for user ::5 somehost 738s 08:50:23.240264010 O: test IP6 localport for user ::5 somehost 738s 08:50:23.281432806 O: test invalid Match address 10.0.1.0/8 738s 08:50:23.295292706 O: test invalid Match localaddress 10.0.1.0/8 738s 08:50:23.309065050 O: test invalid Match address 10.0.0.1/24 738s 08:50:23.322913670 O: test invalid Match localaddress 10.0.0.1/24 738s 08:50:23.336373587 O: test invalid Match address 2000:aa:bb:01::/56 738s 08:50:23.349840983 O: test invalid Match localaddress 2000:aa:bb:01::/56 738s 08:50:23.365953628 O: ok address match 738s 08:50:23.366228497 E: run test localcommand.sh ... 738s 08:50:23.553412862 O: test localcommand: proto localcommand 738s 08:50:23.755027342 O: ok localcommand 738s 08:50:23.754156979 E: run test forcecommand.sh ... 739s 08:50:24.781552027 E: Connection closed. 739s 08:50:24.784321272 E: Connection closed 740s 08:50:25.177360015 E: Connection closed. 740s 08:50:25.179330134 E: Connection closed 740s 08:50:25.383654092 O: ok forced command 740s 08:50:25.383888442 E: run test portnum.sh ... 740s 08:50:25.574275897 O: port number parsing: invalid port 0 740s 08:50:25.583553793 O: port number parsing: invalid port 65536 740s 08:50:25.593000083 O: port number parsing: invalid port 131073 740s 08:50:25.602834437 O: port number parsing: invalid port 2000blah 740s 08:50:25.612020777 O: port number parsing: invalid port blah2000 740s 08:50:25.621187879 O: port number parsing: valid port 1 740s 08:50:25.827654149 O: port number parsing: valid port 22 741s 08:50:26.031506415 O: port number parsing: valid port 2222 741s 08:50:26.233265214 O: port number parsing: valid port 22222 741s 08:50:26.435862738 O: port number parsing: valid port 65535 741s 08:50:26.636561700 O: ok port number parsing 741s 08:50:26.638267190 E: run test keytype.sh ... 741s 08:50:26.836132149 O: keygen ed25519, 512 bits 741s 08:50:26.848314849 O: keygen ed25519-sk, n/a bits 741s 08:50:26.864665138 O: keygen ecdsa, 256 bits 741s 08:50:26.876676965 O: keygen ecdsa, 384 bits 741s 08:50:26.890693909 O: keygen ecdsa, 521 bits 741s 08:50:26.907514979 O: keygen ecdsa-sk, n/a bits 741s 08:50:26.925000141 O: keygen dsa, 1024 bits 742s 08:50:27.198033067 O: keygen rsa, 2048 bits 742s 08:50:27.313718110 O: keygen rsa, 3072 bits 743s 08:50:28.219421315 O: userkey ed25519-512, hostkey ed25519-512 743s 08:50:28.354727953 O: userkey ed25519-512, hostkey ed25519-512 743s 08:50:28.491003872 O: userkey ed25519-512, hostkey ed25519-512 743s 08:50:28.633515218 O: userkey ed25519-sk, hostkey ed25519-sk 743s 08:50:28.780443626 O: userkey ed25519-sk, hostkey ed25519-sk 743s 08:50:28.928510907 O: userkey ed25519-sk, hostkey ed25519-sk 744s 08:50:29.082648764 O: userkey ecdsa-256, hostkey ecdsa-256 744s 08:50:29.221714727 O: userkey ecdsa-256, hostkey ecdsa-256 744s 08:50:29.357989161 O: userkey ecdsa-256, hostkey ecdsa-256 744s 08:50:29.502740215 O: userkey ecdsa-384, hostkey ecdsa-384 744s 08:50:29.679380825 O: userkey ecdsa-384, hostkey ecdsa-384 744s 08:50:29.858194668 O: userkey ecdsa-384, hostkey ecdsa-384 745s 08:50:30.044291309 O: userkey ecdsa-521, hostkey ecdsa-521 745s 08:50:30.311259073 O: userkey ecdsa-521, hostkey ecdsa-521 745s 08:50:30.576141640 O: userkey ecdsa-521, hostkey ecdsa-521 745s 08:50:30.848686701 O: userkey ecdsa-sk, hostkey ecdsa-sk 746s 08:50:30.993365995 O: userkey ecdsa-sk, hostkey ecdsa-sk 746s 08:50:31.137858332 O: userkey ecdsa-sk, hostkey ecdsa-sk 746s 08:50:31.286746857 O: userkey dsa-1024, hostkey dsa-1024 746s 08:50:31.427382069 O: userkey dsa-1024, hostkey dsa-1024 746s 08:50:31.572132398 O: userkey dsa-1024, hostkey dsa-1024 746s 08:50:31.719368908 O: userkey rsa-2048, hostkey rsa-2048 746s 08:50:31.869300151 O: userkey rsa-2048, hostkey rsa-2048 747s 08:50:32.012892689 O: userkey rsa-2048, hostkey rsa-2048 747s 08:50:32.162037482 O: userkey rsa-3072, hostkey rsa-3072 747s 08:50:32.317226796 O: userkey rsa-3072, hostkey rsa-3072 747s 08:50:32.472259276 O: userkey rsa-3072, hostkey rsa-3072 747s 08:50:32.629774378 O: ok login with different key types 747s 08:50:32.630299717 E: run test kextype.sh ... 747s 08:50:32.837251828 O: kex diffie-hellman-group1-sha1 748s 08:50:33.388361771 O: kex diffie-hellman-group14-sha1 749s 08:50:34.005616964 O: kex diffie-hellman-group14-sha256 749s 08:50:34.623786235 O: kex diffie-hellman-group16-sha512 750s 08:50:35.421347022 O: kex diffie-hellman-group18-sha512 751s 08:50:36.921350335 O: kex diffie-hellman-group-exchange-sha1 753s 08:50:38.444192419 O: kex diffie-hellman-group-exchange-sha256 755s 08:50:39.977820014 O: kex ecdh-sha2-nistp256 755s 08:50:40.634878413 O: kex ecdh-sha2-nistp384 756s 08:50:41.209826266 O: kex ecdh-sha2-nistp521 756s 08:50:41.871023395 O: kex curve25519-sha256 757s 08:50:42.456119585 O: kex curve25519-sha256@libssh.org 758s 08:50:43.011843937 O: kex sntrup761x25519-sha512@openssh.com 758s 08:50:43.809669912 O: ok login with different key exchange algorithms 758s 08:50:43.810239131 E: run test cert-hostkey.sh ... 759s 08:50:44.838500026 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/host_ca_key.pub 759s 08:50:44.839750181 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/host_ca_key2.pub 759s 08:50:44.840946737 O: certified host keys: sign host ed25519 cert 759s 08:50:44.849844132 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 759s 08:50:44.861876892 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 759s 08:50:44.865264408 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 759s 08:50:44.878386169 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 759s 08:50:44.890782956 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 759s 08:50:44.894057836 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 759s 08:50:44.902832195 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 759s 08:50:44.915357458 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 759s 08:50:44.918722815 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 759s 08:50:44.931057204 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 759s 08:50:44.946968422 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 759s 08:50:44.950190424 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 760s 08:50:44.970039339 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 760s 08:50:44.992059574 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 760s 08:50:44.995278017 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 760s 08:50:45.009355904 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 760s 08:50:45.021960286 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 760s 08:50:45.025371602 O: certified host keys: sign host dsa cert 760s 08:50:45.156785790 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_dsa.pub 760s 08:50:45.168861631 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_dsa-cert.pub 760s 08:50:45.172313706 O: certified host keys: sign host rsa cert 760s 08:50:45.766734839 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 760s 08:50:45.778400376 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 760s 08:50:45.781898689 O: certified host keys: sign host rsa-sha2-256 cert 761s 08:50:46.861108694 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 761s 08:50:46.881924463 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 761s 08:50:46.884872717 O: certified host keys: sign host rsa-sha2-512 cert 763s 08:50:48.085402261 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 763s 08:50:48.107252802 O: Revoking from /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 763s 08:50:48.110888913 O: certified host keys: host ed25519 cert connect 763s 08:50:48.113386424 O: certified host keys: ed25519 basic connect expect success yes 763s 08:50:48.288663778 O: certified host keys: ed25519 empty KRL expect success yes 763s 08:50:48.468679684 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 763s 08:50:48.589565537 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 763s 08:50:48.710252197 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 763s 08:50:48.830635547 O: certified host keys: ed25519 empty plaintext revocation expect success yes 764s 08:50:49.002831652 O: certified host keys: ed25519 plain key plaintext revocation expect success no 764s 08:50:49.126222803 O: certified host keys: ed25519 cert plaintext revocation expect success no 764s 08:50:49.262478379 O: certified host keys: ed25519 CA plaintext revocation expect success no 764s 08:50:49.411511022 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 764s 08:50:49.420800933 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 764s 08:50:49.610253025 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 764s 08:50:49.790393807 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 764s 08:50:49.914510533 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 765s 08:50:50.055995696 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 765s 08:50:50.183595806 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 765s 08:50:50.366015868 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 765s 08:50:50.490723040 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 765s 08:50:50.635118920 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 765s 08:50:50.762120371 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 765s 08:50:50.764873315 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 765s 08:50:50.940487816 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 766s 08:50:51.118050994 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 766s 08:50:51.240490954 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 766s 08:50:51.378392412 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 766s 08:50:51.535813148 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 766s 08:50:51.733296964 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 766s 08:50:51.854358531 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 767s 08:50:51.982444533 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 767s 08:50:52.117644072 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 767s 08:50:52.121145310 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 767s 08:50:52.330234446 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 767s 08:50:52.532910444 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 767s 08:50:52.665388681 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 767s 08:50:52.801644867 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 767s 08:50:52.947385364 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 768s 08:50:53.170191140 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 768s 08:50:53.308152457 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 768s 08:50:53.451293875 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 768s 08:50:53.596299788 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 768s 08:50:53.599624713 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 768s 08:50:53.829292184 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 769s 08:50:54.057782547 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 769s 08:50:54.215459778 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 769s 08:50:54.372281237 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 769s 08:50:54.531623170 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 769s 08:50:54.772113479 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 769s 08:50:54.941895774 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 770s 08:50:55.117652689 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 770s 08:50:55.291179452 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 770s 08:50:55.294187790 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 770s 08:50:55.480118170 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 770s 08:50:55.666070550 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 770s 08:50:55.795671371 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 770s 08:50:55.924129952 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 771s 08:50:56.053792502 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 771s 08:50:56.242891695 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 771s 08:50:56.380312399 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 771s 08:50:56.537627108 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 771s 08:50:56.665277143 O: certified host keys: host dsa cert connect 771s 08:50:56.667755859 O: certified host keys: dsa basic connect expect success yes 771s 08:50:56.840629722 O: certified host keys: dsa empty KRL expect success yes 772s 08:50:57.013792257 O: certified host keys: dsa KRL w/ plain key revoked expect success no 772s 08:50:57.134493553 O: certified host keys: dsa KRL w/ cert revoked expect success no 772s 08:50:57.255493079 O: certified host keys: dsa KRL w/ CA revoked expect success no 772s 08:50:57.388591157 O: certified host keys: dsa empty plaintext revocation expect success yes 772s 08:50:57.585050782 O: certified host keys: dsa plain key plaintext revocation expect success no 772s 08:50:57.711685478 O: certified host keys: dsa cert plaintext revocation expect success no 772s 08:50:57.854406672 O: certified host keys: dsa CA plaintext revocation expect success no 773s 08:50:57.985477539 O: certified host keys: host rsa cert connect 773s 08:50:57.991643731 O: certified host keys: rsa basic connect expect success yes 773s 08:50:58.209602596 O: certified host keys: rsa empty KRL expect success yes 773s 08:50:58.396471023 O: certified host keys: rsa KRL w/ plain key revoked expect success no 773s 08:50:58.533479358 O: certified host keys: rsa KRL w/ cert revoked expect success no 773s 08:50:58.671474260 O: certified host keys: rsa KRL w/ CA revoked expect success no 773s 08:50:58.815392525 O: certified host keys: rsa empty plaintext revocation expect success yes 774s 08:50:59.021129164 O: certified host keys: rsa plain key plaintext revocation expect success no 774s 08:50:59.160278737 O: certified host keys: rsa cert plaintext revocation expect success no 774s 08:50:59.315122268 O: certified host keys: rsa CA plaintext revocation expect success no 774s 08:50:59.461462721 O: certified host keys: host rsa-sha2-256 cert connect 774s 08:50:59.470446502 O: certified host keys: rsa-sha2-256 basic connect expect success yes 774s 08:50:59.677742209 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 774s 08:50:59.858796868 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 775s 08:50:59.991588612 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 775s 08:51:00.122369208 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 775s 08:51:00.255814678 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 775s 08:51:00.457794602 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 775s 08:51:00.593482118 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 775s 08:51:00.747474149 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 775s 08:51:00.880815262 O: certified host keys: host rsa-sha2-512 cert connect 775s 08:51:00.883290260 O: certified host keys: rsa-sha2-512 basic connect expect success yes 776s 08:51:01.071008111 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 776s 08:51:01.256387462 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 776s 08:51:01.388593560 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 776s 08:51:01.517420689 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 776s 08:51:01.648166514 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 776s 08:51:01.831193983 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 777s 08:51:01.971429297 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 777s 08:51:02.141047874 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 777s 08:51:02.283756096 O: certified host keys: host ed25519 revoked cert 777s 08:51:02.418083031 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 777s 08:51:02.579369007 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 777s 08:51:02.715188133 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 777s 08:51:02.880728450 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 778s 08:51:03.053041556 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 778s 08:51:03.195947520 O: certified host keys: host dsa revoked cert 778s 08:51:03.343292140 O: certified host keys: host rsa revoked cert 778s 08:51:03.511757035 O: certified host keys: host rsa-sha2-256 revoked cert 778s 08:51:03.674813345 O: certified host keys: host rsa-sha2-512 revoked cert 778s 08:51:03.838169086 O: certified host keys: host ed25519 revoked cert 779s 08:51:03.974974368 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 779s 08:51:04.129514780 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 779s 08:51:04.273512738 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 779s 08:51:04.432230220 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 779s 08:51:04.608681092 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 779s 08:51:04.751498607 O: certified host keys: host dsa revoked cert 779s 08:51:04.896910399 O: certified host keys: host rsa revoked cert 780s 08:51:05.072266319 O: certified host keys: host rsa-sha2-256 revoked cert 780s 08:51:05.236445818 O: certified host keys: host rsa-sha2-512 revoked cert 791s 08:51:16.864941046 O: certified host keys: host ed25519 cert downgrade to raw key 792s 08:51:17.242424859 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 792s 08:51:17.627742343 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 793s 08:51:18.004321648 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 793s 08:51:18.405209980 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 793s 08:51:18.914156154 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 794s 08:51:19.308278860 O: certified host keys: host dsa cert downgrade to raw key 795s 08:51:20.064854688 O: certified host keys: host rsa cert downgrade to raw key 795s 08:51:20.603094585 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 796s 08:51:21.370861792 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 797s 08:51:22.611380429 O: certified host keys: host ed25519 connect wrong cert 797s 08:51:22.748369830 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 797s 08:51:22.908285248 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 798s 08:51:23.050169155 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 798s 08:51:23.210242637 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 798s 08:51:23.422149628 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 798s 08:51:23.583500353 O: certified host keys: host dsa connect wrong cert 799s 08:51:23.991445192 O: certified host keys: host rsa connect wrong cert 799s 08:51:24.479641568 O: certified host keys: host rsa-sha2-256 connect wrong cert 801s 08:51:26.862199783 O: certified host keys: host rsa-sha2-512 connect wrong cert 803s 08:51:28.007374793 O: ok certified host keys 803s 08:51:28.007409232 E: run test cert-userkey.sh ... 803s 08:51:28.540672919 O: certified user keys: sign user ed25519 cert 803s 08:51:28.561944206 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 803s 08:51:28.586689796 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 803s 08:51:28.607308381 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 803s 08:51:28.631355830 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 803s 08:51:28.661703424 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 803s 08:51:28.687516544 O: certified user keys: sign user dsa cert 804s 08:51:29.010737011 O: certified user keys: sign user rsa cert 804s 08:51:29.295326400 O: certified user keys: sign user rsa-sha2-256 cert 805s 08:51:30.222127900 O: certified user keys: sign user rsa-sha2-512 cert 805s 08:51:30.579514609 O: certified user keys: ed25519 missing authorized_principals 805s 08:51:30.775188136 O: certified user keys: ed25519 empty authorized_principals 806s 08:51:30.969592618 O: certified user keys: ed25519 wrong authorized_principals 806s 08:51:31.165269091 O: certified user keys: ed25519 correct authorized_principals 806s 08:51:31.383205439 O: certified user keys: ed25519 authorized_principals bad key opt 806s 08:51:31.576170511 O: certified user keys: ed25519 authorized_principals command=false 806s 08:51:31.786238075 O: certified user keys: ed25519 authorized_principals command=true 807s 08:51:31.996798026 O: certified user keys: ed25519 wrong principals key option 807s 08:51:32.186847928 O: certified user keys: ed25519 correct principals key option 807s 08:51:32.397139879 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 807s 08:51:32.586474921 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 807s 08:51:32.774665115 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 808s 08:51:32.973494498 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 808s 08:51:33.174044543 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 808s 08:51:33.363826723 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 808s 08:51:33.564949956 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 808s 08:51:33.768466404 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 808s 08:51:33.963398445 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 809s 08:51:34.186181161 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 809s 08:51:34.372850575 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 809s 08:51:34.559355074 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 809s 08:51:34.746512315 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 809s 08:51:34.944770177 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 810s 08:51:35.130478877 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 810s 08:51:35.328760249 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 810s 08:51:35.531915451 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 810s 08:51:35.725473350 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 810s 08:51:35.934933583 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 811s 08:51:36.133221296 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 811s 08:51:36.329225360 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 811s 08:51:36.527221851 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 811s 08:51:36.764411139 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 811s 08:51:36.960260646 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 812s 08:51:37.180008986 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 812s 08:51:37.399827650 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 812s 08:51:37.610314001 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 812s 08:51:37.846777464 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 813s 08:51:38.057230225 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 813s 08:51:38.293293895 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 813s 08:51:38.503477926 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 813s 08:51:38.753385872 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 813s 08:51:38.961360841 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 814s 08:51:39.212725941 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 814s 08:51:39.464002609 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 814s 08:51:39.684348366 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 814s 08:51:39.946761383 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 815s 08:51:40.132147874 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 815s 08:51:40.315642103 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 815s 08:51:40.500777209 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 815s 08:51:40.701852460 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 815s 08:51:40.887191041 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 816s 08:51:41.089970941 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 816s 08:51:41.296086652 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 816s 08:51:41.494337887 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 816s 08:51:41.706712276 O: certified user keys: dsa missing authorized_principals 816s 08:51:41.903940217 O: certified user keys: dsa empty authorized_principals 817s 08:51:42.098576359 O: certified user keys: dsa wrong authorized_principals 817s 08:51:42.294751796 O: certified user keys: dsa correct authorized_principals 817s 08:51:42.501924191 O: certified user keys: dsa authorized_principals bad key opt 817s 08:51:42.696264195 O: certified user keys: dsa authorized_principals command=false 817s 08:51:42.915582958 O: certified user keys: dsa authorized_principals command=true 818s 08:51:43.123692786 O: certified user keys: dsa wrong principals key option 818s 08:51:43.309838268 O: certified user keys: dsa correct principals key option 818s 08:51:43.516046598 O: certified user keys: rsa missing authorized_principals 818s 08:51:43.702120202 O: certified user keys: rsa empty authorized_principals 818s 08:51:43.887774817 O: certified user keys: rsa wrong authorized_principals 819s 08:51:44.073728395 O: certified user keys: rsa correct authorized_principals 819s 08:51:44.281549593 O: certified user keys: rsa authorized_principals bad key opt 819s 08:51:44.467491468 O: certified user keys: rsa authorized_principals command=false 819s 08:51:44.673338277 O: certified user keys: rsa authorized_principals command=true 819s 08:51:44.882723036 O: certified user keys: rsa wrong principals key option 820s 08:51:45.075838379 O: certified user keys: rsa correct principals key option 820s 08:51:45.290409757 O: certified user keys: rsa-sha2-256 missing authorized_principals 820s 08:51:45.473734885 O: certified user keys: rsa-sha2-256 empty authorized_principals 820s 08:51:45.681863506 O: certified user keys: rsa-sha2-256 wrong authorized_principals 820s 08:51:45.863175965 O: certified user keys: rsa-sha2-256 correct authorized_principals 821s 08:51:46.080761718 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 821s 08:51:46.262094042 O: certified user keys: rsa-sha2-256 authorized_principals command=false 821s 08:51:46.467432403 O: certified user keys: rsa-sha2-256 authorized_principals command=true 821s 08:51:46.675933605 O: certified user keys: rsa-sha2-256 wrong principals key option 821s 08:51:46.873383684 O: certified user keys: rsa-sha2-256 correct principals key option 822s 08:51:47.089437748 O: certified user keys: rsa-sha2-512 missing authorized_principals 822s 08:51:47.280571814 O: certified user keys: rsa-sha2-512 empty authorized_principals 822s 08:51:47.470638945 O: certified user keys: rsa-sha2-512 wrong authorized_principals 822s 08:51:47.661602255 O: certified user keys: rsa-sha2-512 correct authorized_principals 822s 08:51:47.887110341 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 823s 08:51:48.077509395 O: certified user keys: rsa-sha2-512 authorized_principals command=false 823s 08:51:48.301203639 O: certified user keys: rsa-sha2-512 authorized_principals command=true 823s 08:51:48.513391448 O: certified user keys: rsa-sha2-512 wrong principals key option 823s 08:51:48.705495636 O: certified user keys: rsa-sha2-512 correct principals key option 823s 08:51:48.920958884 O: certified user keys: ed25519 authorized_keys connect 824s 08:51:49.131633109 O: certified user keys: ed25519 authorized_keys revoked key 824s 08:51:49.313873207 O: certified user keys: ed25519 authorized_keys revoked via KRL 824s 08:51:49.499910092 O: certified user keys: ed25519 authorized_keys empty KRL 824s 08:51:49.704240485 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 824s 08:51:49.912859892 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 825s 08:51:50.116925028 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 825s 08:51:50.320822660 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 825s 08:51:50.534803164 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 825s 08:51:50.736722885 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 825s 08:51:50.934724982 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 826s 08:51:51.135783862 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 826s 08:51:51.344221411 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 826s 08:51:51.566169111 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 826s 08:51:51.788229328 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 827s 08:51:51.995098915 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 827s 08:51:52.216097867 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 827s 08:51:52.468275383 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 827s 08:51:52.715771932 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 827s 08:51:52.933042496 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 828s 08:51:53.185582347 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 828s 08:51:53.392112320 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 828s 08:51:53.587333045 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 828s 08:51:53.784913434 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 829s 08:51:53.995442189 O: certified user keys: dsa authorized_keys connect 829s 08:51:54.201738154 O: certified user keys: dsa authorized_keys revoked key 829s 08:51:54.394483846 O: certified user keys: dsa authorized_keys revoked via KRL 829s 08:51:54.585202065 O: certified user keys: dsa authorized_keys empty KRL 829s 08:51:54.792139616 O: certified user keys: rsa authorized_keys connect 830s 08:51:55.002738238 O: certified user keys: rsa authorized_keys revoked key 830s 08:51:55.202026399 O: certified user keys: rsa authorized_keys revoked via KRL 830s 08:51:55.404228650 O: certified user keys: rsa authorized_keys empty KRL 830s 08:51:55.621931331 O: certified user keys: rsa-sha2-256 authorized_keys connect 830s 08:51:55.838304724 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 831s 08:51:56.035575818 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 831s 08:51:56.232825453 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 831s 08:51:56.447756109 O: certified user keys: rsa-sha2-512 authorized_keys connect 831s 08:51:56.660770050 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 831s 08:51:56.854740484 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 832s 08:51:57.048172976 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 832s 08:51:57.259732140 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 832s 08:51:57.446732083 O: certified user keys: authorized_keys CA does not authenticate 832s 08:51:57.448244288 O: certified user keys: ensure CA key does not authenticate user 832s 08:51:57.633035604 O: certified user keys: ed25519 TrustedUserCAKeys connect 832s 08:51:57.836359482 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 833s 08:51:58.019225526 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 833s 08:51:58.221698610 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 833s 08:51:58.424399170 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 833s 08:51:58.635030543 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 833s 08:51:58.837299592 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 834s 08:51:59.048647395 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 834s 08:51:59.316014230 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 834s 08:51:59.569319314 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 834s 08:51:59.797124713 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 835s 08:52:00.036742281 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 835s 08:52:00.297425946 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 835s 08:52:00.572389680 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 835s 08:52:00.798512187 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 836s 08:52:01.040188179 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 836s 08:52:01.308603018 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 836s 08:52:01.613210902 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 836s 08:52:01.865325678 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 837s 08:52:02.125461245 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 837s 08:52:02.469921732 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 837s 08:52:02.732677415 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 837s 08:52:02.949379717 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 838s 08:52:03.164747670 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 838s 08:52:03.385706061 O: certified user keys: dsa TrustedUserCAKeys connect 838s 08:52:03.595220355 O: certified user keys: dsa TrustedUserCAKeys revoked key 838s 08:52:03.791516430 O: certified user keys: dsa TrustedUserCAKeys revoked via KRL 839s 08:52:03.992887589 O: certified user keys: dsa TrustedUserCAKeys empty KRL 839s 08:52:04.203077212 O: certified user keys: rsa TrustedUserCAKeys connect 839s 08:52:04.409637758 O: certified user keys: rsa TrustedUserCAKeys revoked key 839s 08:52:04.597689405 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 839s 08:52:04.788023760 O: certified user keys: rsa TrustedUserCAKeys empty KRL 840s 08:52:04.998367141 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 840s 08:52:05.210980536 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 840s 08:52:05.398450699 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 840s 08:52:05.586376212 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 840s 08:52:05.797284646 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 841s 08:52:06.007337499 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 841s 08:52:06.203279375 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 841s 08:52:06.403623072 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 841s 08:52:06.618219649 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 841s 08:52:06.804010353 O: certified user keys: TrustedUserCAKeys CA does not authenticate 841s 08:52:06.806957927 O: certified user keys: ensure CA key does not authenticate user 842s 08:52:06.994379074 O: certified user keys: correct principal auth authorized_keys expect success rsa 842s 08:52:07.231236259 O: certified user keys: correct principal auth authorized_keys expect success ed25519 842s 08:52:07.452490193 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 842s 08:52:07.677181010 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 842s 08:52:07.895002221 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 843s 08:52:08.097031276 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 843s 08:52:08.297647056 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 843s 08:52:08.493889333 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 843s 08:52:08.691697255 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 843s 08:52:08.901207156 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 844s 08:52:09.110784470 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 844s 08:52:09.313191114 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 844s 08:52:09.515566279 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 844s 08:52:09.724506818 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 844s 08:52:09.934460895 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 845s 08:52:10.138240326 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 845s 08:52:10.344383591 O: certified user keys: cert expired auth authorized_keys expect failure rsa 845s 08:52:10.552311778 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 845s 08:52:10.763216819 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 846s 08:52:10.968241110 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 846s 08:52:11.171108148 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 846s 08:52:11.395314203 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 846s 08:52:11.618170248 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 846s 08:52:11.842668857 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 847s 08:52:12.062077024 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 847s 08:52:12.272393127 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 847s 08:52:12.478790596 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 847s 08:52:12.683310986 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 847s 08:52:12.900493260 O: certified user keys: force-command auth authorized_keys expect failure rsa 850s 08:52:13.125377381 O: certified user keys: force-command auth authorized_keys expect failure ed25519 850s 08:52:13.349072420 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 850s 08:52:13.590348278 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 850s 08:52:13.807893889 O: certified user keys: empty principals auth authorized_keys expect success rsa 850s 08:52:14.034682145 O: certified user keys: empty principals auth authorized_keys expect success ed25519 850s 08:52:14.255150999 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 850s 08:52:14.457816076 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 850s 08:52:14.660986702 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 850s 08:52:14.885099197 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 850s 08:52:15.103676063 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 850s 08:52:15.306185487 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 850s 08:52:15.509493373 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 850s 08:52:15.736419794 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 850s 08:52:15.959812690 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 851s 08:52:16.170383721 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 851s 08:52:16.378096176 O: certified user keys: force-command match true auth authorized_keys expect success rsa 851s 08:52:16.604625391 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 851s 08:52:16.827132852 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 852s 08:52:17.053048766 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 852s 08:52:17.276473472 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 852s 08:52:17.487424082 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 852s 08:52:17.701817620 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 852s 08:52:17.913063344 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 854s 08:52:18.129870245 O: certified user keys: user ed25519 connect wrong cert 854s 08:52:18.330130984 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 854s 08:52:18.511464163 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 854s 08:52:18.702162744 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 854s 08:52:18.900884315 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 854s 08:52:19.085276239 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 854s 08:52:19.282574222 O: certified user keys: user dsa connect wrong cert 854s 08:52:19.475794171 O: certified user keys: user rsa connect wrong cert 854s 08:52:19.704635334 O: certified user keys: user rsa-sha2-256 connect wrong cert 854s 08:52:19.912073665 O: certified user keys: user rsa-sha2-512 connect wrong cert 855s 08:52:20.106317604 O: ok certified user keys 855s 08:52:20.107085188 E: run test host-expand.sh ... 855s 08:52:20.483213184 O: ok expand %h and %n 855s 08:52:20.485297421 E: run test keys-command.sh ... 855s 08:52:20.695733032 O: SKIPPED: /var/run/keycommand_openssh-tests.43806 not executable (/var/run mounted noexec?) 855s 08:52:20.705241234 E: run test forward-control.sh ... 857s 08:52:22.449394745 O: check_lfwd done (expecting Y): default configuration 858s 08:52:23.928067683 O: check_rfwd done (expecting Y): default configuration 859s 08:52:24.396028198 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 860s 08:52:25.876909641 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 861s 08:52:26.102062957 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 862s 08:52:27.589383076 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 864s 08:52:29.051633180 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 865s 08:52:30.529539216 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 866s 08:52:31.756018922 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 868s 08:52:33.237193526 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 869s 08:52:34.716770343 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 870s 08:52:35.193601071 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 870s 08:52:35.417048597 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 870s 08:52:35.627362257 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 870s 08:52:35.846995377 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 871s 08:52:36.055104365 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 872s 08:52:37.525922804 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 873s 08:52:38.004654416 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 874s 08:52:39.468792894 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 874s 08:52:39.675460104 O: check_rfwd done (expecting N): AllowTcpForwarding=local 876s 08:52:40.899714263 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 876s 08:52:41.107303765 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 876s 08:52:41.568954436 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 876s 08:52:41.771822236 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 878s 08:52:42.995413046 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 878s 08:52:43.200245126 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 878s 08:52:43.671331668 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 878s 08:52:43.878150192 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 879s 08:52:44.098898106 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 879s 08:52:44.304863784 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 879s 08:52:44.529594996 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 879s 08:52:44.735465076 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 881s 08:52:46.210656892 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 881s 08:52:46.416404292 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 881s 08:52:46.646763241 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 883s 08:52:48.137832820 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 883s 08:52:48.359604526 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 884s 08:52:49.847033634 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 886s 08:52:51.070589487 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 886s 08:52:51.548912394 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 886s 08:52:51.770945953 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 887s 08:52:52.258013886 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 888s 08:52:53.487007708 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 890s 08:52:54.971631955 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 891s 08:52:56.194378167 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 891s 08:52:56.400656134 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 892s 08:52:57.626503231 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 892s 08:52:57.834199831 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 894s 08:52:59.053429582 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 896s 08:53:00.534089553 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 896s 08:53:01.757658935 O: check_lfwd done (expecting N): AllowTcpForwarding=no 897s 08:53:01.963689070 O: check_rfwd done (expecting N): AllowTcpForwarding=no 898s 08:53:03.189084486 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 898s 08:53:03.400952155 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 899s 08:53:04.620154011 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 899s 08:53:04.825704363 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 901s 08:53:06.044733153 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 901s 08:53:06.247929136 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 901s 08:53:06.463821387 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 901s 08:53:06.664966363 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 901s 08:53:06.881249448 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 902s 08:53:07.083910125 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 903s 08:53:08.299932064 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 903s 08:53:08.503004359 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 903s 08:53:08.719604112 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 903s 08:53:08.921877180 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 905s 08:53:10.384462497 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 905s 08:53:10.590868488 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 907s 08:53:12.056730361 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 908s 08:53:13.536946133 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 910s 08:53:15.000222841 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 910s 08:53:15.206259433 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 911s 08:53:16.678969857 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 911s 08:53:16.887226468 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 913s 08:53:18.108343109 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 913s 08:53:18.312117620 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 914s 08:53:19.538192427 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 916s 08:53:21.022788940 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 916s 08:53:21.241697218 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 916s 08:53:21.445117336 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 917s 08:53:22.664585067 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 917s 08:53:22.868499351 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 917s 08:53:22.888212446 O: ok sshd control of local and remote forwarding 917s 08:53:22.888485802 E: run test integrity.sh ... 918s 08:53:23.096122035 O: test integrity: hmac-sha1 @2900 918s 08:53:23.309582226 O: test integrity: hmac-sha1 @2901 918s 08:53:23.521418402 O: test integrity: hmac-sha1 @2902 918s 08:53:23.732575749 O: test integrity: hmac-sha1 @2903 918s 08:53:23.945710345 O: test integrity: hmac-sha1 @2904 919s 08:53:24.155988315 O: test integrity: hmac-sha1 @2905 919s 08:53:24.367750546 O: test integrity: hmac-sha1 @2906 919s 08:53:24.578957186 O: test integrity: hmac-sha1 @2907 919s 08:53:24.791673523 O: test integrity: hmac-sha1 @2908 920s 08:53:25.005316005 O: test integrity: hmac-sha1 @2909 920s 08:53:25.203909972 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 920s 08:53:25.219225498 O: test integrity: hmac-sha1-96 @2900 920s 08:53:25.430345313 O: test integrity: hmac-sha1-96 @2901 920s 08:53:25.641724484 O: test integrity: hmac-sha1-96 @2902 920s 08:53:25.852902018 O: test integrity: hmac-sha1-96 @2903 921s 08:53:26.062470981 O: test integrity: hmac-sha1-96 @2904 921s 08:53:26.272080553 O: test integrity: hmac-sha1-96 @2905 921s 08:53:26.483854172 O: test integrity: hmac-sha1-96 @2906 921s 08:53:26.694245332 O: test integrity: hmac-sha1-96 @2907 921s 08:53:26.906061150 O: test integrity: hmac-sha1-96 @2908 922s 08:53:27.118123092 O: test integrity: hmac-sha1-96 @2909 922s 08:53:27.321058699 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 922s 08:53:27.337201055 O: test integrity: hmac-sha2-256 @2900 922s 08:53:27.546055492 O: test integrity: hmac-sha2-256 @2901 922s 08:53:27.757218054 O: test integrity: hmac-sha2-256 @2902 923s 08:53:27.969092765 O: test integrity: hmac-sha2-256 @2903 923s 08:53:28.179665707 O: test integrity: hmac-sha2-256 @2904 923s 08:53:28.390101734 O: test integrity: hmac-sha2-256 @2905 923s 08:53:28.600231405 O: test integrity: hmac-sha2-256 @2906 923s 08:53:28.813042916 O: test integrity: hmac-sha2-256 @2907 924s 08:53:29.025157919 O: test integrity: hmac-sha2-256 @2908 924s 08:53:29.235128566 O: test integrity: hmac-sha2-256 @2909 924s 08:53:29.434490332 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 924s 08:53:29.450223296 O: test integrity: hmac-sha2-512 @2900 924s 08:53:29.643606872 O: test integrity: hmac-sha2-512 @2901 924s 08:53:29.836496455 O: test integrity: hmac-sha2-512 @2902 925s 08:53:30.032186919 O: test integrity: hmac-sha2-512 @2903 925s 08:53:30.224451884 O: test integrity: hmac-sha2-512 @2904 925s 08:53:30.417861632 O: test integrity: hmac-sha2-512 @2905 925s 08:53:30.609060213 O: test integrity: hmac-sha2-512 @2906 925s 08:53:30.802309204 O: test integrity: hmac-sha2-512 @2907 926s 08:53:30.995105601 O: test integrity: hmac-sha2-512 @2908 926s 08:53:31.187248419 O: test integrity: hmac-sha2-512 @2909 926s 08:53:31.365763441 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 926s 08:53:31.383273501 O: test integrity: hmac-md5 @2900 926s 08:53:31.597009158 O: test integrity: hmac-md5 @2901 926s 08:53:31.807889698 O: test integrity: hmac-md5 @2902 927s 08:53:32.020277377 O: test integrity: hmac-md5 @2903 927s 08:53:32.232341233 O: test integrity: hmac-md5 @2904 927s 08:53:32.444340770 O: test integrity: hmac-md5 @2905 927s 08:53:32.656919778 O: test integrity: hmac-md5 @2906 927s 08:53:32.869887260 O: test integrity: hmac-md5 @2907 928s 08:53:33.082761509 O: test integrity: hmac-md5 @2908 928s 08:53:33.294645380 O: test integrity: hmac-md5 @2909 928s 08:53:33.496898834 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 928s 08:53:33.512483124 O: test integrity: hmac-md5-96 @2900 928s 08:53:33.722594382 O: test integrity: hmac-md5-96 @2901 928s 08:53:33.933434589 O: test integrity: hmac-md5-96 @2902 929s 08:53:34.142966064 O: test integrity: hmac-md5-96 @2903 929s 08:53:34.353028695 O: test integrity: hmac-md5-96 @2904 929s 08:53:34.562384057 O: test integrity: hmac-md5-96 @2905 929s 08:53:34.772003095 O: test integrity: hmac-md5-96 @2906 930s 08:53:34.981497974 O: test integrity: hmac-md5-96 @2907 930s 08:53:35.189676125 O: test integrity: hmac-md5-96 @2908 930s 08:53:35.399337695 O: test integrity: hmac-md5-96 @2909 930s 08:53:35.599384766 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 930s 08:53:35.615040257 O: test integrity: umac-64@openssh.com @2900 930s 08:53:35.820647806 O: test integrity: umac-64@openssh.com @2901 931s 08:53:36.027982012 O: test integrity: umac-64@openssh.com @2902 931s 08:53:36.231456245 O: test integrity: umac-64@openssh.com @2903 931s 08:53:36.436179379 O: test integrity: umac-64@openssh.com @2904 931s 08:53:36.639911689 O: test integrity: umac-64@openssh.com @2905 931s 08:53:36.844093551 O: test integrity: umac-64@openssh.com @2906 932s 08:53:37.059492374 O: test integrity: umac-64@openssh.com @2907 932s 08:53:37.266627086 O: test integrity: umac-64@openssh.com @2908 932s 08:53:37.475856248 O: test integrity: umac-64@openssh.com @2909 932s 08:53:37.674208287 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 932s 08:53:37.689293988 O: test integrity: umac-128@openssh.com @2900 932s 08:53:37.902166617 O: test integrity: umac-128@openssh.com @2901 933s 08:53:38.113731711 O: test integrity: umac-128@openssh.com @2902 933s 08:53:38.327014907 O: test integrity: umac-128@openssh.com @2903 933s 08:53:38.547697156 O: test integrity: umac-128@openssh.com @2904 933s 08:53:38.767560456 O: test integrity: umac-128@openssh.com @2905 934s 08:53:38.979829986 O: test integrity: umac-128@openssh.com @2906 934s 08:53:39.194566892 O: test integrity: umac-128@openssh.com @2907 934s 08:53:39.408474371 O: test integrity: umac-128@openssh.com @2908 934s 08:53:39.623093440 O: test integrity: umac-128@openssh.com @2909 934s 08:53:39.823724750 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 934s 08:53:39.839866558 O: test integrity: hmac-sha1-etm@openssh.com @2900 935s 08:53:40.054672707 O: test integrity: hmac-sha1-etm@openssh.com @2901 935s 08:53:40.264595776 O: test integrity: hmac-sha1-etm@openssh.com @2902 935s 08:53:40.478641146 O: test integrity: hmac-sha1-etm@openssh.com @2903 935s 08:53:40.691761049 O: test integrity: hmac-sha1-etm@openssh.com @2904 935s 08:53:40.911683934 O: test integrity: hmac-sha1-etm@openssh.com @2905 936s 08:53:41.122457038 O: test integrity: hmac-sha1-etm@openssh.com @2906 936s 08:53:41.334092815 O: test integrity: hmac-sha1-etm@openssh.com @2907 936s 08:53:41.546116466 O: test integrity: hmac-sha1-etm@openssh.com @2908 936s 08:53:41.760546242 O: test integrity: hmac-sha1-etm@openssh.com @2909 937s 08:53:41.964358851 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 937s 08:53:41.980452181 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 937s 08:53:42.192098608 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 937s 08:53:42.399924852 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 937s 08:53:42.606409194 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 937s 08:53:42.814545992 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 938s 08:53:43.021852204 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 938s 08:53:43.232807775 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 938s 08:53:43.439345728 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 938s 08:53:43.645941920 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 938s 08:53:43.852715230 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 939s 08:53:44.050166715 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 939s 08:53:44.065812254 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 939s 08:53:44.277301229 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 939s 08:53:44.485898245 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 939s 08:53:44.696086959 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 939s 08:53:44.905165328 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 940s 08:53:45.114863695 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 940s 08:53:45.324555507 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 940s 08:53:45.534273839 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 940s 08:53:45.746020343 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 940s 08:53:45.957024016 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 941s 08:53:46.156631059 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 941s 08:53:46.171572410 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 941s 08:53:46.348064699 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 941s 08:53:46.523807198 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 941s 08:53:46.701221314 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 941s 08:53:46.877665364 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 942s 08:53:47.053111110 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 942s 08:53:47.228262028 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 942s 08:53:47.402877873 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 942s 08:53:47.578361346 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 942s 08:53:47.753871378 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 942s 08:53:47.916862465 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 942s 08:53:47.932502047 O: test integrity: hmac-md5-etm@openssh.com @2900 943s 08:53:48.144114544 O: test integrity: hmac-md5-etm@openssh.com @2901 943s 08:53:48.353965469 O: test integrity: hmac-md5-etm@openssh.com @2902 943s 08:53:48.566684474 O: test integrity: hmac-md5-etm@openssh.com @2903 943s 08:53:48.776956953 O: test integrity: hmac-md5-etm@openssh.com @2904 944s 08:53:48.988651013 O: test integrity: hmac-md5-etm@openssh.com @2905 944s 08:53:49.198739745 O: test integrity: hmac-md5-etm@openssh.com @2906 944s 08:53:49.409427870 O: test integrity: hmac-md5-etm@openssh.com @2907 944s 08:53:49.620754947 O: test integrity: hmac-md5-etm@openssh.com @2908 944s 08:53:49.831077277 O: test integrity: hmac-md5-etm@openssh.com @2909 945s 08:53:50.043442461 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 945s 08:53:50.058844849 O: test integrity: hmac-md5-96-etm@openssh.com @2900 945s 08:53:50.267873448 O: test integrity: hmac-md5-96-etm@openssh.com @2901 945s 08:53:50.477854234 O: test integrity: hmac-md5-96-etm@openssh.com @2902 945s 08:53:50.686081764 O: test integrity: hmac-md5-96-etm@openssh.com @2903 945s 08:53:50.895391520 O: test integrity: hmac-md5-96-etm@openssh.com @2904 946s 08:53:51.110419282 O: test integrity: hmac-md5-96-etm@openssh.com @2905 946s 08:53:51.318994779 O: test integrity: hmac-md5-96-etm@openssh.com @2906 946s 08:53:51.528995656 O: test integrity: hmac-md5-96-etm@openssh.com @2907 946s 08:53:51.738487581 O: test integrity: hmac-md5-96-etm@openssh.com @2908 946s 08:53:51.949119249 O: test integrity: hmac-md5-96-etm@openssh.com @2909 947s 08:53:52.149789983 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 947s 08:53:52.165113733 O: test integrity: umac-64-etm@openssh.com @2900 947s 08:53:52.372117662 O: test integrity: umac-64-etm@openssh.com @2901 947s 08:53:52.577838649 O: test integrity: umac-64-etm@openssh.com @2902 947s 08:53:52.782707088 O: test integrity: umac-64-etm@openssh.com @2903 948s 08:53:52.991134638 O: test integrity: umac-64-etm@openssh.com @2904 948s 08:53:53.198575572 O: test integrity: umac-64-etm@openssh.com @2905 948s 08:53:53.405683791 O: test integrity: umac-64-etm@openssh.com @2906 948s 08:53:53.613679718 O: test integrity: umac-64-etm@openssh.com @2907 948s 08:53:53.819175959 O: test integrity: umac-64-etm@openssh.com @2908 949s 08:53:54.025875945 O: test integrity: umac-64-etm@openssh.com @2909 949s 08:53:54.220876459 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 949s 08:53:54.236123692 O: test integrity: umac-128-etm@openssh.com @2900 949s 08:53:54.448226134 O: test integrity: umac-128-etm@openssh.com @2901 949s 08:53:54.660757531 O: test integrity: umac-128-etm@openssh.com @2902 949s 08:53:54.872426819 O: test integrity: umac-128-etm@openssh.com @2903 950s 08:53:55.084740742 O: test integrity: umac-128-etm@openssh.com @2904 950s 08:53:55.298314456 O: test integrity: umac-128-etm@openssh.com @2905 950s 08:53:55.510128073 O: test integrity: umac-128-etm@openssh.com @2906 950s 08:53:55.723002275 O: test integrity: umac-128-etm@openssh.com @2907 950s 08:53:55.936189434 O: test integrity: umac-128-etm@openssh.com @2908 951s 08:53:56.148246215 O: test integrity: umac-128-etm@openssh.com @2909 951s 08:53:56.350113177 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 951s 08:53:56.365826726 O: test integrity: aes128-gcm@openssh.com @2900 951s 08:53:56.540646772 O: test integrity: aes128-gcm@openssh.com @2901 951s 08:53:56.716751121 O: test integrity: aes128-gcm@openssh.com @2902 951s 08:53:56.894846963 O: test integrity: aes128-gcm@openssh.com @2903 952s 08:53:57.071706265 O: test integrity: aes128-gcm@openssh.com @2904 952s 08:53:57.248378656 O: test integrity: aes128-gcm@openssh.com @2905 952s 08:53:57.424054979 O: test integrity: aes128-gcm@openssh.com @2906 952s 08:53:57.600618891 O: test integrity: aes128-gcm@openssh.com @2907 952s 08:53:57.778656383 O: test integrity: aes128-gcm@openssh.com @2908 952s 08:53:57.955312134 O: test integrity: aes128-gcm@openssh.com @2909 953s 08:53:58.121695268 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 953s 08:53:58.139585269 O: test integrity: aes256-gcm@openssh.com @2900 953s 08:53:58.320318414 O: test integrity: aes256-gcm@openssh.com @2901 953s 08:53:58.497310929 O: test integrity: aes256-gcm@openssh.com @2902 953s 08:53:58.674124127 O: test integrity: aes256-gcm@openssh.com @2903 953s 08:53:58.851208281 O: test integrity: aes256-gcm@openssh.com @2904 954s 08:53:59.028468794 O: test integrity: aes256-gcm@openssh.com @2905 954s 08:53:59.205608956 O: test integrity: aes256-gcm@openssh.com @2906 954s 08:53:59.384051140 O: test integrity: aes256-gcm@openssh.com @2907 954s 08:53:59.560728749 O: test integrity: aes256-gcm@openssh.com @2908 954s 08:53:59.743616754 O: test integrity: aes256-gcm@openssh.com @2909 954s 08:53:59.910312575 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 954s 08:53:59.926175604 O: test integrity: chacha20-poly1305@openssh.com @2900 955s 08:54:00.103836764 O: test integrity: chacha20-poly1305@openssh.com @2901 955s 08:54:00.285608914 O: test integrity: chacha20-poly1305@openssh.com @2902 955s 08:54:00.462965322 O: test integrity: chacha20-poly1305@openssh.com @2903 955s 08:54:00.638722671 O: test integrity: chacha20-poly1305@openssh.com @2904 955s 08:54:00.815233890 O: test integrity: chacha20-poly1305@openssh.com @2905 956s 08:54:00.992207064 O: test integrity: chacha20-poly1305@openssh.com @2906 956s 08:54:01.166987914 O: test integrity: chacha20-poly1305@openssh.com @2907 956s 08:54:01.342069561 O: test integrity: chacha20-poly1305@openssh.com @2908 956s 08:54:01.517591162 O: test integrity: chacha20-poly1305@openssh.com @2909 956s 08:54:01.683395492 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 956s 08:54:01.685559583 O: ok integrity 956s 08:54:01.686021897 E: run test krl.sh ... 956s 08:54:01.887077321 O: key revocation lists: generating test keys 958s 08:54:03.351918333 O: key revocation lists: generating KRLs 958s 08:54:03.452214897 O: key revocation lists: checking revocations for revoked keys 958s 08:54:03.956150489 O: key revocation lists: checking revocations for unrevoked keys 959s 08:54:04.485423373 O: key revocation lists: checking revocations for revoked certs 960s 08:54:05.263832576 O: key revocation lists: checking revocations for unrevoked certs 961s 08:54:06.041387898 O: key revocation lists: testing KRL update 961s 08:54:06.864150508 O: key revocation lists: checking revocations for revoked keys 962s 08:54:07.409280938 O: key revocation lists: checking revocations for unrevoked keys 962s 08:54:07.946418438 O: key revocation lists: checking revocations for revoked certs 963s 08:54:08.718911571 O: key revocation lists: checking revocations for unrevoked certs 964s 08:54:09.483675989 O: ok key revocation lists 964s 08:54:09.484759495 E: run test multipubkey.sh ... 966s 08:54:11.370087274 O: ok multiple pubkey 966s 08:54:11.371242419 E: run test limit-keytype.sh ... 969s 08:54:14.081885650 O: allow rsa,ed25519 969s 08:54:14.655051829 O: allow ed25519 970s 08:54:15.165769002 O: allow cert only 970s 08:54:15.701605196 O: match w/ no match 971s 08:54:16.236701449 O: match w/ matching 971s 08:54:16.827876814 O: ok restrict pubkey type 971s 08:54:16.830309864 E: run test hostkey-agent.sh ... 972s 08:54:17.869353384 O: key type ssh-ed25519 973s 08:54:18.005956602 O: key type sk-ssh-ed25519@openssh.com 973s 08:54:18.144955915 O: key type ecdsa-sha2-nistp256 973s 08:54:18.286501717 O: key type ecdsa-sha2-nistp384 973s 08:54:18.435926141 O: key type ecdsa-sha2-nistp521 973s 08:54:18.623228854 O: key type sk-ecdsa-sha2-nistp256@openssh.com 973s 08:54:18.764263062 O: key type ssh-dss 973s 08:54:18.900226974 O: key type ssh-rsa 974s 08:54:19.057929897 O: cert type ssh-ed25519-cert-v01@openssh.com 974s 08:54:19.225324585 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 974s 08:54:19.396846862 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 974s 08:54:19.564820103 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 974s 08:54:19.750268487 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 974s 08:54:19.947993640 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 975s 08:54:20.121663535 O: cert type ssh-dss-cert-v01@openssh.com 975s 08:54:20.289462545 O: cert type ssh-rsa-cert-v01@openssh.com 975s 08:54:20.467330271 O: cert type rsa-sha2-256-cert-v01@openssh.com 975s 08:54:20.651242403 O: cert type rsa-sha2-512-cert-v01@openssh.com 975s 08:54:20.834334344 O: ok hostkey agent 975s 08:54:20.834960457 E: run test hostkey-rotate.sh ... 977s 08:54:22.054153025 O: learn hostkey with StrictHostKeyChecking=no 977s 08:54:22.230142909 O: learn additional hostkeys 977s 08:54:22.487882831 O: learn additional hostkeys, type=ssh-ed25519 977s 08:54:22.681281902 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 977s 08:54:22.882186641 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 978s 08:54:23.079050632 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 978s 08:54:23.280711410 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 978s 08:54:23.504701476 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 978s 08:54:23.704386838 O: learn additional hostkeys, type=ssh-dss 978s 08:54:23.901395313 O: learn additional hostkeys, type=ssh-rsa 979s 08:54:24.105603664 O: learn changed non-primary hostkey type=ssh-rsa 979s 08:54:24.822154626 O: learn new primary hostkey 980s 08:54:25.034588403 O: rotate primary hostkey 980s 08:54:25.249950991 O: check rotate primary hostkey 980s 08:54:25.457832310 O: ok hostkey rotate 980s 08:54:25.458019388 E: run test principals-command.sh ... 982s 08:54:27.789635701 O: SKIPPED: /var/run/principals_command_openssh-tests.60133 not executable (/var/run mounted noexec?) 982s 08:54:27.798122479 E: run test cert-file.sh ... 983s 08:54:28.034272916 O: identity cert with no plain public file 983s 08:54:28.238701422 O: CertificateFile with no plain public file 983s 08:54:28.434455192 O: plain keys 983s 08:54:28.641564626 O: untrusted cert 983s 08:54:28.848303465 O: good cert, bad key 984s 08:54:29.073958279 O: single trusted 984s 08:54:29.269201023 O: multiple trusted 985s 08:54:30.126591290 O: ok ssh with certificates 985s 08:54:30.127298922 E: run test cfginclude.sh ... 985s 08:54:30.314651808 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.324069695 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.332800431 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.342722673 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.351887644 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.360697499 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.369761191 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.379684632 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.388588006 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.408661167 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.418361491 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.426603553 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.440497387 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.450629586 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.459647359 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.469634120 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.478713931 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.487721424 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.497722625 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.506987394 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.515666491 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.535441735 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.544662385 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.553623358 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 985s 08:54:30.565360858 O: ok config include 985s 08:54:30.566887240 E: run test servcfginclude.sh ... 986s 08:54:31.036254725 O: ok server config include 986s 08:54:31.037330032 E: run test allow-deny-users.sh ... 987s 08:54:32.849128412 O: ok AllowUsers/DenyUsers 987s 08:54:32.850187160 E: run test authinfo.sh ... 988s 08:54:33.037916697 O: ExposeAuthInfo=no 988s 08:54:33.241070019 O: ExposeAuthInfo=yes 988s 08:54:33.441480972 O: ok authinfo 988s 08:54:33.444318699 E: run test sshsig.sh ... 988s 08:54:33.648704606 O: sshsig: make certificates 988s 08:54:33.694135029 O: sshsig: check signature for ssh-ed25519 989s 08:54:34.017511692 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 989s 08:54:34.377361977 O: sshsig: check signature for ecdsa-sha2-nistp256 989s 08:54:34.690438213 O: sshsig: check signature for ecdsa-sha2-nistp384 990s 08:54:35.194079653 O: sshsig: check signature for ecdsa-sha2-nistp521 991s 08:54:36.055072995 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 991s 08:54:36.420094727 O: sshsig: check signature for ssh-dss 991s 08:54:36.722366633 O: sshsig: check signature for ssh-rsa 992s 08:54:37.060505641 O: sshsig: check signature for ssh-ed25519-cert.pub 992s 08:54:37.694024858 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 993s 08:54:38.345844874 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 993s 08:54:38.953552294 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 994s 08:54:39.806419455 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 996s 08:54:41.110735475 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 996s 08:54:41.795142919 O: sshsig: check signature for ssh-dss-cert.pub 997s 08:54:42.393646926 O: sshsig: check signature for ssh-rsa-cert.pub 998s 08:54:43.055024941 O: sshsig: match principals 998s 08:54:43.077931079 O: sshsig: nomatch principals 998s 08:54:43.095773795 O: ok sshsig 998s 08:54:43.096024232 E: run test knownhosts.sh ... 999s 08:54:44.554309415 O: ok known hosts 999s 08:54:44.556025276 E: run test knownhosts-command.sh ... 999s 08:54:44.745746233 O: simple connection 999s 08:54:44.938755633 O: no keys 1000s 08:54:45.058823866 O: bad exit status 1000s 08:54:45.134063211 O: keytype ssh-ed25519 1000s 08:54:45.313893967 O: keytype sk-ssh-ed25519@openssh.com 1000s 08:54:45.494059959 O: keytype ecdsa-sha2-nistp256 1000s 08:54:45.670478754 O: keytype ecdsa-sha2-nistp384 1000s 08:54:45.856825717 O: keytype ecdsa-sha2-nistp521 1001s 08:54:46.062999736 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1001s 08:54:46.243069296 O: keytype ssh-rsa 1001s 08:54:46.427194409 O: ok known hosts command 1001s 08:54:46.429134387 E: run test agent-restrict.sh ... 1001s 08:54:46.605676587 O: generate keys 1001s 08:54:46.675370918 O: prepare client config 1001s 08:54:46.689492358 O: prepare known_hosts 1001s 08:54:46.694839937 O: prepare server configs 1001s 08:54:46.713620724 O: authentication w/o agent 1003s 08:54:48.122354167 O: start agent 1007s 08:54:52.128477870 O: authentication with agent (no restrict) 1008s 08:54:53.573958007 O: unrestricted keylist 1009s 08:54:54.330690624 O: authentication with agent (basic restrict) 1010s 08:54:55.079876861 O: authentication with agent incorrect key (basic restrict) 1010s 08:54:55.654517284 O: keylist (basic restrict) 1011s 08:54:56.458215117 O: username 1013s 08:54:57.199597772 O: username wildcard 1013s 08:54:57.948423883 O: username incorrect 1013s 08:54:58.034353862 O: agent restriction honours certificate principal 1013s 08:54:58.075893848 O: multihop without agent 1013s 08:54:58.897445748 O: multihop agent unrestricted 1014s 08:54:59.727777415 O: multihop restricted 1015s 08:55:00.580998854 O: multihop username 1016s 08:55:01.405177990 O: multihop wildcard username 1017s 08:55:02.235219043 O: multihop wrong username 1018s 08:55:02.966196024 O: multihop cycle no agent 1019s 08:55:04.133806726 O: multihop cycle agent unrestricted 1020s 08:55:05.334714513 O: multihop cycle restricted deny 1021s 08:55:05.956366412 O: multihop cycle restricted allow 1022s 08:55:07.194867584 O: ok agent restrictions 1022s 08:55:07.195733735 E: run test hostbased.sh ... 1022s 08:55:07.389999985 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1022s 08:55:07.391372050 E: run test channel-timeout.sh ... 1022s 08:55:07.585388063 O: no timeout 1027s 08:55:12.790111772 O: command timeout 1028s 08:55:13.273739617 O: command long timeout 1028s 08:55:13.481090283 O: command wildcard timeout 1029s 08:55:14.274290216 O: command irrelevant timeout 1034s 08:55:19.485568001 O: multiplexed command timeout 1045s 08:55:30.296842140 O: irrelevant multiplexed command timeout 1060s 08:55:45.530517159 O: global command timeout 1082s 08:56:07.306233518 O: sftp no timeout 1087s 08:56:12.519589292 O: sftp timeout 1088s 08:56:13.274217393 O: sftp irrelevant timeout 1088s 08:56:13.274770108 E: Connection closed 1093s 08:56:18.481208581 O: ok channel timeout 1093s 08:56:18.482078413 E: run test connection-timeout.sh ... 1093s 08:56:18.667146339 O: no timeout 1098s 08:56:23.879752949 O: timeout 1107s 08:56:32.086762737 O: session inhibits timeout 1115s 08:56:40.335952507 O: timeout after session 1123s 08:56:48.346011942 O: timeout with listeners 1131s 08:56:56.600941721 O: ok unused connection timeout 1131s 08:56:56.601735274 E: run test match-subsystem.sh ... 1134s 08:56:59.406942156 O: ok sshd_config match subsystem 1134s 08:56:59.408410304 E: run test agent-pkcs11-restrict.sh ... 1134s 08:56:59.863150461 O: SKIPPED: No PKCS#11 library found 1134s 08:56:59.866219795 E: run test agent-pkcs11-cert.sh ... 1135s 08:57:00.327997976 O: SKIPPED: No PKCS#11 library found 1135s 08:57:00.331493027 O: set -e ; if test -z "" ; then \ 1135s 08:57:00.334498722 O: V="" ; \ 1135s 08:57:00.338181290 O: test "x" = "x" || \ 1135s 08:57:00.341417663 O: V=/tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1135s 08:57:00.344705035 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1135s 08:57:00.347843649 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1135s 08:57:00.351005022 O: -d /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1135s 08:57:00.354246835 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1135s 08:57:00.357759685 O: -d /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1135s 08:57:00.360901619 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1135s 08:57:00.364018752 O: -d /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1135s 08:57:00.367081047 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1135s 08:57:00.370153541 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1135s 08:57:00.373189915 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1135s 08:57:00.376172850 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1135s 08:57:00.379169305 O: -d /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1135s 08:57:00.382256919 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1135s 08:57:00.385278493 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1135s 08:57:00.388341787 O: if test "x" = "xyes" ; then \ 1135s 08:57:00.391346042 O: $V /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1135s 08:57:00.394472015 O: fi \ 1135s 08:57:00.398058665 O: fi 1154s 08:57:19.143106261 O: test_sshbuf: ...................................................................................................... 103 tests ok 1221s 08:58:26.649301012 O: test_sshkey: ........................................................................................................ 104 tests ok 1221s 08:58:26.659327647 O: test_sshsig: ........ 8 tests ok 1221s 08:58:26.840319281 O: test_authopt: .................................................................................................................................................. 146 tests ok 1235s 08:58:40.771420670 O: test_bitmap: .. 2 tests ok 1235s 08:58:40.775612858 O: test_conversion: . 1 tests ok 1252s 08:58:57.532025966 O: test_kex: ............................................................................................................................................................................................................................................................................................................................................................................................................. 398 tests ok 1252s 08:58:57.699328647 O: test_hostkeys: .................. 18 tests ok 1252s 08:58:57.703603069 O: test_match: ...... 6 tests ok 1252s 08:58:57.707291277 O: test_misc: ........................................... 43 tests ok 1252s 08:58:57.708931876 E: run test putty-transfer.sh ... 1254s 08:58:58.956338603 O: plink version 0.81 minor 81 1254s 08:58:58.967473588 O: putty transfer data: compression 0 1255s 08:59:00.159576989 O: putty transfer data: compression 1 1256s 08:59:01.356756603 O: ok putty transfer data 1256s 08:59:01.357888990 E: run test putty-ciphers.sh ... 1257s 08:59:02.090836880 O: plink version 0.81 minor 81 1257s 08:59:02.376292677 O: putty ciphers: cipher default mac default 1257s 08:59:02.526603353 O: putty ciphers: cipher default mac hmac-sha1 1257s 08:59:02.677446562 O: putty ciphers: cipher default mac hmac-sha1-96 1257s 08:59:02.826102439 O: putty ciphers: cipher default mac hmac-sha2-256 1258s 08:59:02.977844268 O: putty ciphers: cipher default mac hmac-sha2-512 1258s 08:59:03.130442024 O: putty ciphers: cipher default mac hmac-md5 1258s 08:59:03.284821220 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1258s 08:59:03.436680596 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1258s 08:59:03.591600444 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1258s 08:59:03.744246519 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1258s 08:59:03.895715086 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1259s 08:59:04.057622771 O: putty ciphers: cipher 3des-cbc mac default 1259s 08:59:04.209036922 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1259s 08:59:04.359564172 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1259s 08:59:04.509956139 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1259s 08:59:04.659497806 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1259s 08:59:04.812557835 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1260s 08:59:04.966861772 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1260s 08:59:05.118701521 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1260s 08:59:05.271443287 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1260s 08:59:05.424572461 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1260s 08:59:05.577798198 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1260s 08:59:05.730640647 O: putty ciphers: cipher aes128-cbc mac default 1260s 08:59:05.882094383 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1261s 08:59:06.033714359 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1261s 08:59:06.182869547 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1261s 08:59:06.333704134 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1261s 08:59:06.484825167 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1261s 08:59:06.639565763 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1261s 08:59:06.799690163 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1262s 08:59:06.954396879 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1262s 08:59:07.109269427 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1262s 08:59:07.261884679 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1262s 08:59:07.413303424 O: putty ciphers: cipher aes192-cbc mac default 1262s 08:59:07.564598606 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1262s 08:59:07.714732641 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1262s 08:59:07.865211604 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1263s 08:59:08.014616741 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1263s 08:59:08.164775922 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1263s 08:59:08.318295419 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1263s 08:59:08.469678228 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1265s 08:59:08.622795596 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1265s 08:59:08.776839185 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1265s 08:59:08.927243291 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1265s 08:59:09.080459373 O: putty ciphers: cipher aes256-cbc mac default 1265s 08:59:09.232577904 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1265s 08:59:09.385931422 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1265s 08:59:09.535494054 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1265s 08:59:09.686480478 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1265s 08:59:09.837882952 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1265s 08:59:09.990585815 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1265s 08:59:10.141566625 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1265s 08:59:10.296717209 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1265s 08:59:10.447459372 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1265s 08:59:10.599915455 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1265s 08:59:10.752656904 O: putty ciphers: cipher aes128-ctr mac default 1265s 08:59:10.903837837 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1266s 08:59:11.055973987 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1266s 08:59:11.205300944 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1266s 08:59:11.354424216 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1266s 08:59:11.505476892 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1266s 08:59:11.659721640 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1266s 08:59:11.811900261 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1267s 08:59:11.968190214 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1267s 08:59:12.123765539 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1267s 08:59:12.275080886 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1267s 08:59:12.428443198 O: putty ciphers: cipher aes192-ctr mac default 1267s 08:59:12.578263351 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1267s 08:59:12.728298709 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1267s 08:59:12.877625812 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1268s 08:59:13.028306821 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1268s 08:59:13.180834300 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1268s 08:59:13.336607771 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1268s 08:59:13.489360135 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1268s 08:59:13.644705516 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1268s 08:59:13.799425004 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1268s 08:59:13.952744340 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1269s 08:59:14.106452474 O: putty ciphers: cipher aes256-ctr mac default 1269s 08:59:14.259387027 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1269s 08:59:14.412931513 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1269s 08:59:14.568331321 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1269s 08:59:14.717464029 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1269s 08:59:14.868390097 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1270s 08:59:15.022495434 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1270s 08:59:15.177316613 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1270s 08:59:15.332492520 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1270s 08:59:15.485308215 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1270s 08:59:15.637170849 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1270s 08:59:15.790598358 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1270s 08:59:15.938970155 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1271s 08:59:16.089160183 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1271s 08:59:16.239492089 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1271s 08:59:16.390505489 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1271s 08:59:16.541441368 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1271s 08:59:16.695475275 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1271s 08:59:16.847015407 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1272s 08:59:17.008561759 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1272s 08:59:17.162150641 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1272s 08:59:17.313953804 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1272s 08:59:17.469877977 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1272s 08:59:17.619267768 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1272s 08:59:17.773058934 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1272s 08:59:17.924069600 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1273s 08:59:18.072930652 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1273s 08:59:18.224806602 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1273s 08:59:18.378639514 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1273s 08:59:18.530733029 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1273s 08:59:18.683958649 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1273s 08:59:18.845222803 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1274s 08:59:18.998598586 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1274s 08:59:19.148956209 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1274s 08:59:19.299814362 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1274s 08:59:19.448474907 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1274s 08:59:19.602443768 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1274s 08:59:19.751720327 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1274s 08:59:19.902147191 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1275s 08:59:20.064124859 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1275s 08:59:20.216601273 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1275s 08:59:20.369653179 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1275s 08:59:20.522215435 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1275s 08:59:20.676435846 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1275s 08:59:20.829460792 O: ok putty ciphers 1275s 08:59:20.830637617 E: run test putty-kex.sh ... 1276s 08:59:21.321829877 O: plink version 0.81 minor 81 1276s 08:59:21.459549079 O: putty KEX: kex diffie-hellman-group1-sha1 1276s 08:59:21.577778141 O: putty KEX: kex diffie-hellman-group14-sha1 1276s 08:59:21.734176905 O: putty KEX: kex diffie-hellman-group14-sha256 1276s 08:59:21.889764611 O: putty KEX: kex diffie-hellman-group16-sha512 1277s 08:59:22.270031563 O: putty KEX: kex diffie-hellman-group18-sha512 1279s 08:59:24.148785185 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1279s 08:59:24.307280228 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1279s 08:59:24.707846808 O: putty KEX: kex ecdh-sha2-nistp256 1279s 08:59:24.820127351 O: putty KEX: kex ecdh-sha2-nistp384 1279s 08:59:24.946414991 O: putty KEX: kex ecdh-sha2-nistp521 1280s 08:59:25.100981661 O: putty KEX: kex curve25519-sha256 1280s 08:59:25.214042370 O: putty KEX: kex curve25519-sha256@libssh.org 1280s 08:59:25.326672989 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1280s 08:59:25.476104906 O: ok putty KEX 1280s 08:59:25.476995565 E: run test conch-ciphers.sh ... 1280s 08:59:25.753811253 O: conch ciphers: cipher aes256-ctr 1281s 08:59:26.530180365 O: conch ciphers: cipher aes256-cbc 1282s 08:59:27.180318024 O: conch ciphers: cipher aes192-ctr 1282s 08:59:27.817840373 O: conch ciphers: cipher aes192-cbc 1283s 08:59:28.464680353 O: conch ciphers: cipher aes128-ctr 1284s 08:59:29.240267139 O: conch ciphers: cipher aes128-cbc 1284s 08:59:29.876303818 O: conch ciphers: cipher cast128-cbc 1285s 08:59:30.512245449 O: conch ciphers: cipher blowfish 1286s 08:59:31.146949153 O: conch ciphers: cipher 3des-cbc 1286s 08:59:31.805744383 O: ok conch ciphers 1286s 08:59:31.806275154 E: run test dropbear-ciphers.sh ... 1287s 08:59:32.414258421 E: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1287s 08:59:32.468976782 O: ok dropbear ciphers 1287s 08:59:32.470100245 E: run test dropbear-kex.sh ... 1287s 08:59:32.626292963 E: /tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1287s 08:59:32.672469029 O: dropbear kex: kex curve25519-sha256 1287s 08:59:32.824205056 O: dropbear kex: kex curve25519-sha256@libssh.org 1288s 08:59:32.972379010 O: dropbear kex: kex diffie-hellman-group14-sha256 1288s 08:59:33.149390221 O: dropbear kex: kex diffie-hellman-group14-sha1 1288s 08:59:33.329103444 O: ok dropbear kex 1288s 08:59:33.330974482 O: make: Leaving directory '/tmp/autopkgtest.SWD2Q6/autopkgtest_tmp/user/regress' 1288s 08:59:33.332797480 I: Finished with exitcode 0 1288s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1288s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 1288s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1289s info: Looking for files to backup/remove ... 1289s info: Removing files ... 1289s info: Removing crontab ... 1289s info: Removing user `openssh-tests' ... 1289s autopkgtest [08:59:34]: test regress: -----------------------] 1290s autopkgtest [08:59:35]: test regress: - - - - - - - - - - results - - - - - - - - - - 1290s regress PASS 1290s autopkgtest [08:59:35]: test ssh-gssapi: preparing testbed 1368s autopkgtest [09:00:53]: testbed dpkg architecture: arm64 1368s autopkgtest [09:00:53]: testbed apt version: 2.9.8 1368s autopkgtest [09:00:53]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1369s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1370s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1370s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1550 kB] 1370s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [171 kB] 1370s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [18.8 kB] 1370s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [238 kB] 1370s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB] 1370s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [1235 kB] 1370s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [30.1 kB] 1370s Fetched 3374 kB in 1s (3067 kB/s) 1370s Reading package lists... 1373s Reading package lists... 1374s Building dependency tree... 1374s Reading state information... 1375s Calculating upgrade... 1376s The following packages will be upgraded: 1376s flash-kernel libarchive13t64 libpam-modules libpam-modules-bin 1376s libpam-runtime libpam0g python3-jaraco.functools python3-typeguard 1376s ssh-import-id ufw 1376s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1376s Need to get 1094 kB of archives. 1376s After this operation, 3072 B of additional disk space will be used. 1376s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam0g arm64 1.5.3-7ubuntu3 [68.5 kB] 1376s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-modules-bin arm64 1.5.3-7ubuntu3 [50.4 kB] 1376s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-modules arm64 1.5.3-7ubuntu3 [285 kB] 1376s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 1376s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 ufw all 0.36.2-8 [170 kB] 1376s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 flash-kernel arm64 3.107ubuntu13 [49.9 kB] 1376s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 libarchive13t64 arm64 3.7.4-1ubuntu1 [379 kB] 1376s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-jaraco.functools all 4.1.0-1 [11.8 kB] 1376s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-typeguard all 4.4.1-1 [29.0 kB] 1376s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 ssh-import-id all 5.11-0ubuntu3 [10.1 kB] 1377s Preconfiguring packages ... 1377s Fetched 1094 kB in 1s (2007 kB/s) 1377s (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 ... 79706 files and directories currently installed.) 1377s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_arm64.deb ... 1377s Unpacking libpam0g:arm64 (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1377s Setting up libpam0g:arm64 (1.5.3-7ubuntu3) ... 1378s (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 ... 79706 files and directories currently installed.) 1378s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_arm64.deb ... 1378s Unpacking libpam-modules-bin (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1378s Setting up libpam-modules-bin (1.5.3-7ubuntu3) ... 1378s pam_namespace.service is a disabled or a static unit not running, not starting it. 1378s (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 ... 79706 files and directories currently installed.) 1378s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_arm64.deb ... 1378s Unpacking libpam-modules:arm64 (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1378s Setting up libpam-modules:arm64 (1.5.3-7ubuntu3) ... 1378s (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 ... 79706 files and directories currently installed.) 1378s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 1378s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1378s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 1379s (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 ... 79706 files and directories currently installed.) 1379s Preparing to unpack .../0-ufw_0.36.2-8_all.deb ... 1379s Unpacking ufw (0.36.2-8) over (0.36.2-6) ... 1379s Preparing to unpack .../1-flash-kernel_3.107ubuntu13_arm64.deb ... 1379s Unpacking flash-kernel (3.107ubuntu13) over (3.107ubuntu11) ... 1379s Preparing to unpack .../2-libarchive13t64_3.7.4-1ubuntu1_arm64.deb ... 1379s Unpacking libarchive13t64:arm64 (3.7.4-1ubuntu1) over (3.7.4-1) ... 1379s Preparing to unpack .../3-python3-jaraco.functools_4.1.0-1_all.deb ... 1379s Unpacking python3-jaraco.functools (4.1.0-1) over (4.0.2-1) ... 1379s Preparing to unpack .../4-python3-typeguard_4.4.1-1_all.deb ... 1379s Unpacking python3-typeguard (4.4.1-1) over (4.3.0-1) ... 1379s Preparing to unpack .../5-ssh-import-id_5.11-0ubuntu3_all.deb ... 1379s Unpacking ssh-import-id (5.11-0ubuntu3) over (5.11-0ubuntu2) ... 1380s Setting up flash-kernel (3.107ubuntu13) ... 1380s Installing new version of config file /etc/flash-kernel/bootscript/bootscr.zynqmp.kria ... 1380s flash-kernel: deferring update (trigger activated) 1380s Setting up python3-jaraco.functools (4.1.0-1) ... 1380s Setting up ssh-import-id (5.11-0ubuntu3) ... 1380s Setting up python3-typeguard (4.4.1-1) ... 1381s Setting up ufw (0.36.2-8) ... 1382s Setting up libarchive13t64:arm64 (3.7.4-1ubuntu1) ... 1382s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1382s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 1382s Processing triggers for man-db (2.12.1-3) ... 1384s Processing triggers for initramfs-tools (0.142ubuntu34) ... 1384s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 1384s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1406s System running in EFI mode, skipping. 1406s Processing triggers for flash-kernel (3.107ubuntu13) ... 1406s System running in EFI mode, skipping. 1407s Reading package lists... 1407s Building dependency tree... 1407s Reading state information... 1408s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1408s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1408s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1408s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1408s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1409s Reading package lists... 1410s Reading package lists... 1410s Building dependency tree... 1410s Reading state information... 1410s Calculating upgrade... 1411s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1411s Reading package lists... 1411s Building dependency tree... 1411s Reading state information... 1412s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1412s autopkgtest [09:01:37]: rebooting testbed after setup commands that affected boot 1416s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 1442s Reading package lists... 1442s Building dependency tree... 1442s Reading state information... 1443s Starting pkgProblemResolver with broken count: 0 1443s Starting 2 pkgProblemResolver with broken count: 0 1443s Done 1443s The following additional packages will be installed: 1443s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1443s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1443s libverto-libevent1t64 libverto1t64 1443s Suggested packages: 1443s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 1443s The following NEW packages will be installed: 1443s autopkgtest-satdep krb5-admin-server krb5-config krb5-kdc krb5-user 1443s libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 1443s libkdb5-10t64 libverto-libevent1t64 libverto1t64 1444s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 1444s Need to get 762 kB/763 kB of archives. 1444s After this operation, 3615 kB of additional disk space will be used. 1444s Get:1 /tmp/autopkgtest.SWD2Q6/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [724 B] 1444s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 krb5-config all 2.7 [22.0 kB] 1444s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 libgssrpc4t64 arm64 1.21.3-3 [58.1 kB] 1444s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 libkadm5clnt-mit12 arm64 1.21.3-3 [39.7 kB] 1444s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 libkdb5-10t64 arm64 1.21.3-3 [40.6 kB] 1444s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 libkadm5srv-mit12 arm64 1.21.3-3 [53.1 kB] 1444s Get:7 http://ftpmaster.internal/ubuntu plucky/universe arm64 krb5-user arm64 1.21.3-3 [108 kB] 1444s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 libevent-2.1-7t64 arm64 2.1.12-stable-10 [140 kB] 1444s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 libverto1t64 arm64 0.3.1-1.2ubuntu3 [10.4 kB] 1444s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 libverto-libevent1t64 arm64 0.3.1-1.2ubuntu3 [6328 B] 1444s Get:11 http://ftpmaster.internal/ubuntu plucky/universe arm64 krb5-kdc arm64 1.21.3-3 [189 kB] 1444s Get:12 http://ftpmaster.internal/ubuntu plucky/universe arm64 krb5-admin-server arm64 1.21.3-3 [94.9 kB] 1444s Preconfiguring packages ... 1445s Fetched 762 kB in 1s (1338 kB/s) 1445s Selecting previously unselected package krb5-config. 1445s (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 ... 79706 files and directories currently installed.) 1445s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1445s Unpacking krb5-config (2.7) ... 1445s Selecting previously unselected package libgssrpc4t64:arm64. 1445s Preparing to unpack .../01-libgssrpc4t64_1.21.3-3_arm64.deb ... 1445s Unpacking libgssrpc4t64:arm64 (1.21.3-3) ... 1445s Selecting previously unselected package libkadm5clnt-mit12:arm64. 1445s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_arm64.deb ... 1445s Unpacking libkadm5clnt-mit12:arm64 (1.21.3-3) ... 1445s Selecting previously unselected package libkdb5-10t64:arm64. 1445s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_arm64.deb ... 1445s Unpacking libkdb5-10t64:arm64 (1.21.3-3) ... 1445s Selecting previously unselected package libkadm5srv-mit12:arm64. 1445s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_arm64.deb ... 1445s Unpacking libkadm5srv-mit12:arm64 (1.21.3-3) ... 1445s Selecting previously unselected package krb5-user. 1445s Preparing to unpack .../05-krb5-user_1.21.3-3_arm64.deb ... 1445s Unpacking krb5-user (1.21.3-3) ... 1445s Selecting previously unselected package libevent-2.1-7t64:arm64. 1445s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_arm64.deb ... 1445s Unpacking libevent-2.1-7t64:arm64 (2.1.12-stable-10) ... 1445s Selecting previously unselected package libverto1t64:arm64. 1445s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_arm64.deb ... 1445s Unpacking libverto1t64:arm64 (0.3.1-1.2ubuntu3) ... 1445s Selecting previously unselected package libverto-libevent1t64:arm64. 1445s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_arm64.deb ... 1445s Unpacking libverto-libevent1t64:arm64 (0.3.1-1.2ubuntu3) ... 1445s Selecting previously unselected package krb5-kdc. 1445s Preparing to unpack .../09-krb5-kdc_1.21.3-3_arm64.deb ... 1445s Unpacking krb5-kdc (1.21.3-3) ... 1445s Selecting previously unselected package krb5-admin-server. 1445s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_arm64.deb ... 1445s Unpacking krb5-admin-server (1.21.3-3) ... 1445s Selecting previously unselected package autopkgtest-satdep. 1445s Preparing to unpack .../11-2-autopkgtest-satdep.deb ... 1445s Unpacking autopkgtest-satdep (0) ... 1445s Setting up libevent-2.1-7t64:arm64 (2.1.12-stable-10) ... 1445s Setting up libgssrpc4t64:arm64 (1.21.3-3) ... 1445s Setting up krb5-config (2.7) ... 1446s Setting up libkadm5clnt-mit12:arm64 (1.21.3-3) ... 1446s Setting up libkdb5-10t64:arm64 (1.21.3-3) ... 1446s Setting up libkadm5srv-mit12:arm64 (1.21.3-3) ... 1446s Setting up krb5-user (1.21.3-3) ... 1446s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1446s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1446s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1446s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1446s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1446s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1446s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1446s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1446s Setting up libverto1t64:arm64 (0.3.1-1.2ubuntu3) ... 1446s Setting up libverto-libevent1t64:arm64 (0.3.1-1.2ubuntu3) ... 1446s Setting up krb5-kdc (1.21.3-3) ... 1446s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1446s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1446s Setting up krb5-admin-server (1.21.3-3) ... 1447s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1447s Setting up autopkgtest-satdep (0) ... 1447s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1447s Processing triggers for man-db (2.12.1-3) ... 1451s (Reading database ... 79820 files and directories currently installed.) 1451s Removing autopkgtest-satdep (0) ... 1457s autopkgtest [09:02:22]: test ssh-gssapi: [----------------------- 1457s ## Setting up test environment 1457s ## Creating Kerberos realm EXAMPLE.FAKE 1457s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1457s master key name 'K/M@EXAMPLE.FAKE' 1457s ## Creating principals 1457s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1457s Principal "testuser1616@EXAMPLE.FAKE" created. 1457s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1457s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1457s ## Extracting service principal host/sshd-gssapi.example.fake 1457s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1457s 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. 1457s 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. 1457s ## Adjusting /etc/krb5.conf 1457s ## TESTS 1457s 1457s ## TEST test_gssapi_login 1457s ## Configuring sshd for gssapi-with-mic authentication 1457s ## Restarting ssh 1458s ## Obtaining TGT 1458s Password for testuser1616@EXAMPLE.FAKE: 1458s Ticket cache: FILE:/tmp/krb5cc_0 1458s Default principal: testuser1616@EXAMPLE.FAKE 1458s 1458s Valid starting Expires Service principal 1458s 11/07/24 09:02:24 11/07/24 19:02:24 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1458s renew until 11/08/24 09:02:24 1458s 1458s ## ssh'ing into localhost using gssapi-with-mic auth 1458s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1458s Thu Nov 7 09:02:24 UTC 2024 1458s 1458s ## checking that we got a service ticket for ssh (host/) 1458s 11/07/24 09:02:24 11/07/24 19:02:24 host/sshd-gssapi.example.fake@ 1458s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1458s 1458s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1458s Nov 07 09:02:24 sshd-gssapi.example.fake sshd[1679]: Accepted gssapi-with-mic for testuser1616 from 127.0.0.1 port 60512 ssh2: testuser1616@EXAMPLE.FAKE 1458s ## PASS test_gssapi_login 1458s 1458s ## TEST test_gssapi_keyex_login 1458s ## Configuring sshd for gssapi-keyex authentication 1458s ## Restarting ssh 1458s ## Obtaining TGT 1458s Password for testuser1616@EXAMPLE.FAKE: 1458s Ticket cache: FILE:/tmp/krb5cc_0 1458s Default principal: testuser1616@EXAMPLE.FAKE 1458s 1458s Valid starting Expires Service principal 1458s 11/07/24 09:02:24 11/07/24 19:02:24 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1458s renew until 11/08/24 09:02:24 1458s 1458s ## ssh'ing into localhost using gssapi-keyex auth 1458s Thu Nov 7 09:02:24 UTC 2024 1458s 1458s ## checking that we got a service ticket for ssh (host/) 1458s 11/07/24 09:02:24 11/07/24 19:02:24 host/sshd-gssapi.example.fake@ 1458s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1458s 1458s ## Checking ssh logs to confirm gssapi-keyex auth was used 1458s Nov 07 09:02:24 sshd-gssapi.example.fake sshd[1729]: Accepted gssapi-keyex for testuser1616 from 127.0.0.1 port 60524 ssh2: testuser1616@EXAMPLE.FAKE 1458s ## PASS test_gssapi_keyex_login 1458s 1458s ## ALL TESTS PASSED 1458s ## Cleaning up 1459s autopkgtest [09:02:24]: test ssh-gssapi: -----------------------] 1459s ssh-gssapi PASS 1459s autopkgtest [09:02:24]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1459s autopkgtest [09:02:24]: test systemd-socket-activation: preparing testbed 1526s autopkgtest [09:03:30]: testbed dpkg architecture: arm64 1526s autopkgtest [09:03:31]: testbed apt version: 2.9.8 1526s autopkgtest [09:03:31]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1527s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1527s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1527s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [171 kB] 1527s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [18.8 kB] 1527s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1550 kB] 1527s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [238 kB] 1527s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB] 1527s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [1235 kB] 1527s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [30.1 kB] 1527s Fetched 3374 kB in 1s (3257 kB/s) 1528s Reading package lists... 1530s Reading package lists... 1530s Building dependency tree... 1530s Reading state information... 1530s Calculating upgrade... 1531s The following packages will be upgraded: 1531s flash-kernel libarchive13t64 libpam-modules libpam-modules-bin 1531s libpam-runtime libpam0g python3-jaraco.functools python3-typeguard 1531s ssh-import-id ufw 1531s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1531s Need to get 1094 kB of archives. 1531s After this operation, 3072 B of additional disk space will be used. 1531s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam0g arm64 1.5.3-7ubuntu3 [68.5 kB] 1531s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-modules-bin arm64 1.5.3-7ubuntu3 [50.4 kB] 1531s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-modules arm64 1.5.3-7ubuntu3 [285 kB] 1532s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpam-runtime all 1.5.3-7ubuntu3 [40.8 kB] 1532s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 ufw all 0.36.2-8 [170 kB] 1532s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 flash-kernel arm64 3.107ubuntu13 [49.9 kB] 1532s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 libarchive13t64 arm64 3.7.4-1ubuntu1 [379 kB] 1532s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-jaraco.functools all 4.1.0-1 [11.8 kB] 1532s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-typeguard all 4.4.1-1 [29.0 kB] 1532s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 ssh-import-id all 5.11-0ubuntu3 [10.1 kB] 1532s Preconfiguring packages ... 1533s Fetched 1094 kB in 1s (847 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 ... 79706 files and directories currently installed.) 1533s Preparing to unpack .../libpam0g_1.5.3-7ubuntu3_arm64.deb ... 1533s Unpacking libpam0g:arm64 (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1533s Setting up libpam0g:arm64 (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 ... 79706 files and directories currently installed.) 1533s Preparing to unpack .../libpam-modules-bin_1.5.3-7ubuntu3_arm64.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) ... 1534s pam_namespace.service is a disabled or a static unit not running, not starting it. 1534s (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 ... 79706 files and directories currently installed.) 1534s Preparing to unpack .../libpam-modules_1.5.3-7ubuntu3_arm64.deb ... 1534s Unpacking libpam-modules:arm64 (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1534s Setting up libpam-modules:arm64 (1.5.3-7ubuntu3) ... 1534s (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 ... 79706 files and directories currently installed.) 1534s Preparing to unpack .../libpam-runtime_1.5.3-7ubuntu3_all.deb ... 1534s Unpacking libpam-runtime (1.5.3-7ubuntu3) over (1.5.3-7ubuntu2) ... 1534s Setting up libpam-runtime (1.5.3-7ubuntu3) ... 1534s (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 ... 79706 files and directories currently installed.) 1534s Preparing to unpack .../0-ufw_0.36.2-8_all.deb ... 1534s Unpacking ufw (0.36.2-8) over (0.36.2-6) ... 1534s Preparing to unpack .../1-flash-kernel_3.107ubuntu13_arm64.deb ... 1534s Unpacking flash-kernel (3.107ubuntu13) over (3.107ubuntu11) ... 1535s Preparing to unpack .../2-libarchive13t64_3.7.4-1ubuntu1_arm64.deb ... 1535s Unpacking libarchive13t64:arm64 (3.7.4-1ubuntu1) over (3.7.4-1) ... 1535s Preparing to unpack .../3-python3-jaraco.functools_4.1.0-1_all.deb ... 1535s Unpacking python3-jaraco.functools (4.1.0-1) over (4.0.2-1) ... 1535s Preparing to unpack .../4-python3-typeguard_4.4.1-1_all.deb ... 1535s Unpacking python3-typeguard (4.4.1-1) over (4.3.0-1) ... 1535s Preparing to unpack .../5-ssh-import-id_5.11-0ubuntu3_all.deb ... 1535s Unpacking ssh-import-id (5.11-0ubuntu3) over (5.11-0ubuntu2) ... 1535s Setting up flash-kernel (3.107ubuntu13) ... 1535s Installing new version of config file /etc/flash-kernel/bootscript/bootscr.zynqmp.kria ... 1535s flash-kernel: deferring update (trigger activated) 1535s Setting up python3-jaraco.functools (4.1.0-1) ... 1535s Setting up ssh-import-id (5.11-0ubuntu3) ... 1536s Setting up python3-typeguard (4.4.1-1) ... 1536s Setting up ufw (0.36.2-8) ... 1537s Setting up libarchive13t64:arm64 (3.7.4-1ubuntu1) ... 1537s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1537s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 1537s Processing triggers for man-db (2.12.1-3) ... 1538s Processing triggers for initramfs-tools (0.142ubuntu34) ... 1538s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 1538s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1554s System running in EFI mode, skipping. 1554s Processing triggers for flash-kernel (3.107ubuntu13) ... 1554s System running in EFI mode, skipping. 1555s Reading package lists... 1555s Building dependency tree... 1555s Reading state information... 1555s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1556s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1556s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1556s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1556s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1557s Reading package lists... 1557s Reading package lists... 1557s Building dependency tree... 1557s Reading state information... 1558s Calculating upgrade... 1558s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1558s Reading package lists... 1558s Building dependency tree... 1558s Reading state information... 1559s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1559s autopkgtest [09:04:04]: rebooting testbed after setup commands that affected boot 1595s Reading package lists... 1595s Building dependency tree... 1595s Reading state information... 1596s Starting pkgProblemResolver with broken count: 0 1596s Starting 2 pkgProblemResolver with broken count: 0 1596s Done 1596s The following NEW packages will be installed: 1596s autopkgtest-satdep 1596s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1596s Need to get 0 B/720 B of archives. 1596s After this operation, 0 B of additional disk space will be used. 1596s Get:1 /tmp/autopkgtest.SWD2Q6/3-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [720 B] 1597s Selecting previously unselected package autopkgtest-satdep. 1597s (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 ... 79706 files and directories currently installed.) 1597s Preparing to unpack .../3-autopkgtest-satdep.deb ... 1597s Unpacking autopkgtest-satdep (0) ... 1597s Setting up autopkgtest-satdep (0) ... 1600s (Reading database ... 79706 files and directories currently installed.) 1600s Removing autopkgtest-satdep (0) ... 1602s autopkgtest [09:04:47]: test systemd-socket-activation: [----------------------- 1603s Stopping ssh.service... 1603s Checking that ssh.socket is active and listening... 1603s Checking that ssh.service is inactive/dead... 1603s Checking that a connection attempt activates ssh.service... 1603s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1604s Checking that sshd can be re-executed... 1604s Checking sshd can run in debug mode... 1604s debug1: SELinux support disabled 1604s debug1: PAM: reinitializing credentials 1604s debug1: permanently_set_uid: 0/0 1604s debug3: Copy environment: XDG_SESSION_ID=5 1604s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1604s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1604s debug3: Copy environment: XDG_SESSION_TYPE=tty 1604s debug3: Copy environment: XDG_SESSION_CLASS=user 1604s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1604s debug3: Copy environment: TERM=linux 1604s debug3: Copy environment: http_proxy=http://squid.internal:3128 1604s debug3: Copy environment: https_proxy=http://squid.internal:3128 1604s 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 1604s debug3: Copy environment: LANG=C.UTF-8 1604s Environment: 1604s LANG=C.UTF-8 1604s USER=root 1604s LOGNAME=root 1604s HOME=/root 1604s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1604s SHELL=/bin/bash 1604s XDG_SESSION_ID=5 1604s XDG_RUNTIME_DIR=/run/user/0 1604s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1604s XDG_SESSION_TYPE=tty 1604s XDG_SESSION_CLASS=user 1604s TERM=linux 1604s http_proxy=http://squid.internal:3128 1604s https_proxy=http://squid.internal:3128 1604s 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 1604s SSH_CLIENT=::1 38520 22 1604s SSH_CONNECTION=::1 38520 ::1 22 1604s Done. 1604s autopkgtest [09:04:49]: test systemd-socket-activation: -----------------------] 1605s systemd-socket-activation PASS 1605s autopkgtest [09:04:50]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1605s autopkgtest [09:04:50]: test sshd-socket-generator: preparing testbed 1612s Reading package lists... 1612s Building dependency tree... 1612s Reading state information... 1613s Starting pkgProblemResolver with broken count: 0 1613s Starting 2 pkgProblemResolver with broken count: 0 1613s Done 1613s The following NEW packages will be installed: 1613s autopkgtest-satdep 1614s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1614s Need to get 0 B/720 B of archives. 1614s After this operation, 0 B of additional disk space will be used. 1614s Get:1 /tmp/autopkgtest.SWD2Q6/4-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [720 B] 1614s Selecting previously unselected package autopkgtest-satdep. 1614s (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 ... 79706 files and directories currently installed.) 1614s Preparing to unpack .../4-autopkgtest-satdep.deb ... 1614s Unpacking autopkgtest-satdep (0) ... 1614s Setting up autopkgtest-satdep (0) ... 1616s (Reading database ... 79706 files and directories currently installed.) 1616s Removing autopkgtest-satdep (0) ... 1617s autopkgtest [09:05:02]: test sshd-socket-generator: [----------------------- 1617s test_default...PASS 1617s test_custom_port...PASS 1617s test_default_and_custom_port...PASS 1617s test_mutiple_custom_ports...PASS 1617s test_custom_listenaddress...PASS 1617s test_custom_listenaddress_and_port...PASS 1617s test_custom_ipv6_listenaddress...PASS 1617s test_custom_family_ipv4...PASS 1617s test_custom_family_ipv6...PASS 1617s test_custom_port_and_family_ipv4...PASS 1617s test_custom_port_and_family_ipv6...PASS 1618s autopkgtest [09:05:03]: test sshd-socket-generator: -----------------------] 1618s sshd-socket-generator PASS 1618s autopkgtest [09:05:03]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1618s autopkgtest [09:05:03]: @@@@@@@@@@@@@@@@@@@@ summary 1618s regress PASS 1618s ssh-gssapi PASS 1618s systemd-socket-activation PASS 1618s sshd-socket-generator PASS 1630s nova [W] Skipping flock in bos03-arm64 1630s Creating nova instance adt-plucky-arm64-openssh-20241107-083805-juju-7f2275-prod-proposed-migration-environment-15-32a689a2-acc9-4c3a-b736-e31a7a01d83d from image adt/ubuntu-plucky-arm64-server-20241106.img (UUID bb4f5218-c1f4-4167-8452-b16bb0a4cb65)... 1630s nova [W] Skipping flock in bos03-arm64 1630s Creating nova instance adt-plucky-arm64-openssh-20241107-083805-juju-7f2275-prod-proposed-migration-environment-15-32a689a2-acc9-4c3a-b736-e31a7a01d83d from image adt/ubuntu-plucky-arm64-server-20241106.img (UUID bb4f5218-c1f4-4167-8452-b16bb0a4cb65)... 1630s nova [W] Skipping flock in bos03-arm64 1630s Creating nova instance adt-plucky-arm64-openssh-20241107-083805-juju-7f2275-prod-proposed-migration-environment-15-32a689a2-acc9-4c3a-b736-e31a7a01d83d from image adt/ubuntu-plucky-arm64-server-20241106.img (UUID bb4f5218-c1f4-4167-8452-b16bb0a4cb65)...