0s autopkgtest [06:24:53]: starting date and time: 2025-02-22 06:24:53+0000 0s autopkgtest [06:24:53]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [06:24:53]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.l5ko7bf2/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:devscripts --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=devscripts/2.25.3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-1.secgroup --name adt-plucky-ppc64el-openssh-20250222-062453-juju-7f2275-prod-proposed-migration-environment-15-c39324e7-8f46-47bd-b384-99d85058aa0b --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 105s autopkgtest [06:26:38]: testbed dpkg architecture: ppc64el 105s autopkgtest [06:26:38]: testbed apt version: 2.9.30ubuntu1 105s autopkgtest [06:26:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 106s autopkgtest [06:26:39]: testbed release detected to be: None 106s autopkgtest [06:26:39]: updating testbed package index (apt update) 107s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 107s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 107s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 107s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 107s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 107s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 107s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 107s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 107s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [127 kB] 107s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [760 B] 107s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [432 kB] 107s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [3292 B] 108s Fetched 1276 kB in 1s (1352 kB/s) 108s Reading package lists... 109s + lsb_release --codename --short 109s + RELEASE=plucky 109s + cat 109s + [ plucky != trusty ] 109s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 109s Reading package lists... 109s Building dependency tree... 109s Reading state information... 110s Calculating upgrade... 110s Calculating upgrade... 110s The following packages will be upgraded: 110s curl libcurl3t64-gnutls libcurl4t64 110s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Need to get 1221 kB of archives. 110s After this operation, 3072 B disk space will be freed. 110s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el curl ppc64el 8.12.1-2ubuntu1 [258 kB] 110s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl4t64 ppc64el 8.12.1-2ubuntu1 [482 kB] 110s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl3t64-gnutls ppc64el 8.12.1-2ubuntu1 [481 kB] 111s Fetched 1221 kB in 1s (2016 kB/s) 111s (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 ... 106371 files and directories currently installed.) 111s Preparing to unpack .../curl_8.12.1-2ubuntu1_ppc64el.deb ... 111s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 111s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_ppc64el.deb ... 111s Unpacking libcurl4t64:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 111s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_ppc64el.deb ... 111s Unpacking libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 111s Setting up libcurl4t64:ppc64el (8.12.1-2ubuntu1) ... 111s Setting up libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) ... 111s Setting up curl (8.12.1-2ubuntu1) ... 111s Processing triggers for man-db (2.13.0-1) ... 112s Processing triggers for libc-bin (2.40-4ubuntu1) ... 112s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 112s + /usr/lib/apt/apt-helper analyze-pattern ?true 112s + uname -r 112s + sed s/\./\\./g 112s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 112s + apt list ?obsolete 112s + grep -v ^linux-.*6\.12\.0-15-generic.* 112s + tail -n+2 112s + cut -d/ -f1 112s + true 112s + obsolete_pkgs= 112s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 112s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Solving dependencies... 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 113s + grep -q trusty /etc/lsb-release 113s + [ ! -d /usr/share/doc/unattended-upgrades ] 113s + [ ! -d /usr/share/doc/lxd ] 113s + [ ! -d /usr/share/doc/lxd-client ] 113s + [ ! -d /usr/share/doc/snapd ] 113s + type iptables 113s + cat 113s + chmod 755 /etc/rc.local 113s + . /etc/rc.local 113s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 113s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 113s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 113s + uname -m 113s + [ ppc64le = ppc64le ] 113s + systemctl is-active keyboard-setup.service 113s + [ active = failed ] 113s + [ -d /run/systemd/system ] 113s + systemd-detect-virt --quiet --vm 113s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 113s + cat 113s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 113s + echo COMPRESS=lz4 113s autopkgtest [06:26:46]: upgrading testbed (apt dist-upgrade and autopurge) 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 113s Starting 2 pkgProblemResolver with broken count: 0 113s Done 114s Entering ResolveByKeep 114s 114s Calculating upgrade... 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 115s Starting pkgProblemResolver with broken count: 0 115s Starting 2 pkgProblemResolver with broken count: 0 115s Done 115s Solving dependencies... 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s autopkgtest [06:26:50]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb 4 16:32:08 UTC 2025 118s autopkgtest [06:26:51]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 123s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (dsc) [3480 B] 123s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (tar) [1965 kB] 123s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (asc) [833 B] 123s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (diff) [210 kB] 123s gpgv: Signature made Thu Nov 21 14:24:07 2024 UTC 123s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 123s gpgv: Can't check signature: No public key 123s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu2.dsc: no acceptable signature found 123s autopkgtest [06:26:56]: testing package openssh version 1:9.9p1-3ubuntu2 125s autopkgtest [06:26:58]: build not needed 128s autopkgtest [06:27:01]: test regress: preparing testbed 128s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s Starting pkgProblemResolver with broken count: 0 129s Starting 2 pkgProblemResolver with broken count: 0 129s Done 129s The following NEW packages will be installed: 129s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 129s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 129s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 129s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 129s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 129s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 129s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 129s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 129s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 129s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 129s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 129s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 129s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 129s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 129s python3-packaging python3-pyasn1 python3-pyasn1-modules 129s python3-service-identity python3-twisted python3-zope.interface wdiff 129s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 129s Need to get 8772 kB of archives. 129s After this operation, 42.0 MB of additional disk space will be used. 129s Get:1 http://ftpmaster.internal/ubuntu plucky/universe ppc64el libtommath1 ppc64el 1.3.0-1 [77.7 kB] 130s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el libtomcrypt1 ppc64el 1.18.2+dfsg-7build1 [503 kB] 130s Get:3 http://ftpmaster.internal/ubuntu plucky/universe ppc64el dropbear-bin ppc64el 2024.86-2 [183 kB] 130s Get:4 http://ftpmaster.internal/ubuntu plucky/universe ppc64el dropbear all 2024.86-2 [8664 B] 130s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-dirlist-perl all 0.05-3 [7286 B] 130s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-which-perl all 1.27-2 [12.5 kB] 130s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-homedir-perl all 1.006-2 [37.0 kB] 130s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-touch-perl all 0.12-2 [7498 B] 130s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el libio-pty-perl ppc64el 1:1.20-1build3 [32.0 kB] 130s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el libipc-run-perl all 20231003.0-2 [91.5 kB] 130s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 130s Get:12 http://ftpmaster.internal/ubuntu plucky/main ppc64el libclass-xsaccessor-perl ppc64el 1.19-4build6 [35.2 kB] 130s Get:13 http://ftpmaster.internal/ubuntu plucky/main ppc64el libb-hooks-op-check-perl ppc64el 0.22-3build2 [9884 B] 130s Get:14 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdynaloader-functions-perl all 0.004-1 [11.4 kB] 130s Get:15 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdevel-callchecker-perl ppc64el 0.009-1build1 [14.1 kB] 130s Get:16 http://ftpmaster.internal/ubuntu plucky/main ppc64el libparams-classify-perl ppc64el 0.015-2build6 [22.2 kB] 130s Get:17 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmodule-runtime-perl all 0.016-2 [16.4 kB] 130s Get:18 http://ftpmaster.internal/ubuntu plucky/main ppc64el libimport-into-perl all 1.002005-2 [10.7 kB] 130s Get:19 http://ftpmaster.internal/ubuntu plucky/main ppc64el librole-tiny-perl all 2.002004-1 [16.3 kB] 130s Get:20 http://ftpmaster.internal/ubuntu plucky/main ppc64el libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 130s Get:21 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmoo-perl all 2.005005-1 [47.4 kB] 130s Get:22 http://ftpmaster.internal/ubuntu plucky/main ppc64el libencode-locale-perl all 1.05-3 [11.6 kB] 130s Get:23 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtimedate-perl all 2.3300-2 [34.0 kB] 130s Get:24 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-date-perl all 6.06-1 [10.2 kB] 130s Get:25 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-listing-perl all 6.16-1 [11.3 kB] 130s Get:26 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-tagset-perl all 3.24-1 [14.1 kB] 130s Get:27 http://ftpmaster.internal/ubuntu plucky/main ppc64el liburi-perl all 5.30-1 [94.4 kB] 130s Get:28 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-parser-perl ppc64el 3.83-1build1 [91.8 kB] 130s Get:29 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-tree-perl all 5.07-3 [200 kB] 130s Get:30 http://ftpmaster.internal/ubuntu plucky/main ppc64el libclone-perl ppc64el 0.47-1 [11.1 kB] 130s Get:31 http://ftpmaster.internal/ubuntu plucky/main ppc64el libio-html-perl all 1.004-3 [15.9 kB] 130s Get:32 http://ftpmaster.internal/ubuntu plucky/main ppc64el liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 130s Get:33 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 130s Get:34 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-cookies-perl all 6.11-1 [18.2 kB] 130s Get:35 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-negotiate-perl all 6.01-2 [12.4 kB] 130s Get:36 http://ftpmaster.internal/ubuntu plucky/main ppc64el perl-openssl-defaults ppc64el 7build3 [6628 B] 130s Get:37 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnet-ssleay-perl ppc64el 1.94-3 [323 kB] 130s Get:38 http://ftpmaster.internal/ubuntu plucky/main ppc64el libio-socket-ssl-perl all 2.089-1 [200 kB] 130s Get:39 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnet-http-perl all 6.23-1 [22.3 kB] 130s Get:40 http://ftpmaster.internal/ubuntu plucky/main ppc64el liblwp-protocol-https-perl all 6.14-1 [9040 B] 130s Get:41 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtry-tiny-perl all 0.32-1 [21.2 kB] 130s Get:42 http://ftpmaster.internal/ubuntu plucky/main ppc64el libwww-robotrules-perl all 6.02-1 [12.6 kB] 130s Get:43 http://ftpmaster.internal/ubuntu plucky/main ppc64el libwww-perl all 6.77-1 [138 kB] 130s Get:44 http://ftpmaster.internal/ubuntu plucky/main ppc64el patchutils ppc64el 0.4.2-1build3 [86.6 kB] 130s Get:45 http://ftpmaster.internal/ubuntu plucky/main ppc64el wdiff ppc64el 1.2.2-7 [29.5 kB] 130s Get:46 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el devscripts all 2.25.3 [1058 kB] 130s Get:47 http://ftpmaster.internal/ubuntu plucky/universe ppc64el libhavege2 ppc64el 1.9.19-9ubuntu1 [31.9 kB] 130s Get:48 http://ftpmaster.internal/ubuntu plucky/universe ppc64el putty-tools ppc64el 0.83-2 [884 kB] 130s Get:49 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-hamcrest all 2.1.0-1 [28.1 kB] 130s Get:50 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-pyasn1 all 0.6.0-1 [57.8 kB] 130s Get:51 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-pyasn1-modules all 0.4.1-1 [80.2 kB] 130s Get:52 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-service-identity all 24.1.0-1 [11.2 kB] 131s Get:53 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-automat all 24.8.1-1 [35.5 kB] 131s Get:54 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-constantly all 23.10.4-2 [13.9 kB] 131s Get:55 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-hyperlink all 21.0.0-6 [68.0 kB] 131s Get:56 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-packaging all 24.2-1 [51.5 kB] 131s Get:57 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-incremental all 24.7.2-3 [18.6 kB] 131s Get:58 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-zope.interface ppc64el 7.2-1 [142 kB] 131s Get:59 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-twisted all 24.11.0-1 [2066 kB] 131s Get:60 http://ftpmaster.internal/ubuntu plucky/universe ppc64el openssh-tests ppc64el 1:9.9p1-3ubuntu2 [1540 kB] 131s Get:61 http://ftpmaster.internal/ubuntu plucky/universe ppc64el haveged ppc64el 1.9.19-9ubuntu1 [32.9 kB] 131s Fetched 8772 kB in 1s (6589 kB/s) 131s Selecting previously unselected package libtommath1:ppc64el. 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 ... 106371 files and directories currently installed.) 131s Preparing to unpack .../00-libtommath1_1.3.0-1_ppc64el.deb ... 131s Unpacking libtommath1:ppc64el (1.3.0-1) ... 131s Selecting previously unselected package libtomcrypt1:ppc64el. 131s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_ppc64el.deb ... 131s Unpacking libtomcrypt1:ppc64el (1.18.2+dfsg-7build1) ... 131s Selecting previously unselected package dropbear-bin. 131s Preparing to unpack .../02-dropbear-bin_2024.86-2_ppc64el.deb ... 131s Unpacking dropbear-bin (2024.86-2) ... 131s Selecting previously unselected package dropbear. 131s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 131s Unpacking dropbear (2024.86-2) ... 131s Selecting previously unselected package libfile-dirlist-perl. 131s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 131s Unpacking libfile-dirlist-perl (0.05-3) ... 131s Selecting previously unselected package libfile-which-perl. 131s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 131s Unpacking libfile-which-perl (1.27-2) ... 131s Selecting previously unselected package libfile-homedir-perl. 131s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 131s Unpacking libfile-homedir-perl (1.006-2) ... 131s Selecting previously unselected package libfile-touch-perl. 131s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 131s Unpacking libfile-touch-perl (0.12-2) ... 131s Selecting previously unselected package libio-pty-perl. 131s Preparing to unpack .../08-libio-pty-perl_1%3a1.20-1build3_ppc64el.deb ... 131s Unpacking libio-pty-perl (1:1.20-1build3) ... 131s Selecting previously unselected package libipc-run-perl. 131s Preparing to unpack .../09-libipc-run-perl_20231003.0-2_all.deb ... 131s Unpacking libipc-run-perl (20231003.0-2) ... 131s Selecting previously unselected package libclass-method-modifiers-perl. 131s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 131s Unpacking libclass-method-modifiers-perl (2.15-1) ... 131s Selecting previously unselected package libclass-xsaccessor-perl. 131s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_ppc64el.deb ... 131s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 131s Selecting previously unselected package libb-hooks-op-check-perl:ppc64el. 131s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_ppc64el.deb ... 131s Unpacking libb-hooks-op-check-perl:ppc64el (0.22-3build2) ... 131s Selecting previously unselected package libdynaloader-functions-perl. 131s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-1_all.deb ... 131s Unpacking libdynaloader-functions-perl (0.004-1) ... 131s Selecting previously unselected package libdevel-callchecker-perl:ppc64el. 131s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-1build1_ppc64el.deb ... 131s Unpacking libdevel-callchecker-perl:ppc64el (0.009-1build1) ... 131s Selecting previously unselected package libparams-classify-perl:ppc64el. 131s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_ppc64el.deb ... 131s Unpacking libparams-classify-perl:ppc64el (0.015-2build6) ... 132s Selecting previously unselected package libmodule-runtime-perl. 132s Preparing to unpack .../16-libmodule-runtime-perl_0.016-2_all.deb ... 132s Unpacking libmodule-runtime-perl (0.016-2) ... 132s Selecting previously unselected package libimport-into-perl. 132s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 132s Unpacking libimport-into-perl (1.002005-2) ... 132s Selecting previously unselected package librole-tiny-perl. 132s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 132s Unpacking librole-tiny-perl (2.002004-1) ... 132s Selecting previously unselected package libsub-quote-perl. 132s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 132s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 132s Selecting previously unselected package libmoo-perl. 132s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 132s Unpacking libmoo-perl (2.005005-1) ... 132s Selecting previously unselected package libencode-locale-perl. 132s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 132s Unpacking libencode-locale-perl (1.05-3) ... 132s Selecting previously unselected package libtimedate-perl. 132s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 132s Unpacking libtimedate-perl (2.3300-2) ... 132s Selecting previously unselected package libhttp-date-perl. 132s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 132s Unpacking libhttp-date-perl (6.06-1) ... 132s Selecting previously unselected package libfile-listing-perl. 132s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 132s Unpacking libfile-listing-perl (6.16-1) ... 132s Selecting previously unselected package libhtml-tagset-perl. 132s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 132s Unpacking libhtml-tagset-perl (3.24-1) ... 132s Selecting previously unselected package liburi-perl. 132s Preparing to unpack .../26-liburi-perl_5.30-1_all.deb ... 132s Unpacking liburi-perl (5.30-1) ... 132s Selecting previously unselected package libhtml-parser-perl:ppc64el. 132s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_ppc64el.deb ... 132s Unpacking libhtml-parser-perl:ppc64el (3.83-1build1) ... 132s Selecting previously unselected package libhtml-tree-perl. 132s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 132s Unpacking libhtml-tree-perl (5.07-3) ... 132s Selecting previously unselected package libclone-perl:ppc64el. 132s Preparing to unpack .../29-libclone-perl_0.47-1_ppc64el.deb ... 132s Unpacking libclone-perl:ppc64el (0.47-1) ... 132s Selecting previously unselected package libio-html-perl. 132s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 132s Unpacking libio-html-perl (1.004-3) ... 132s Selecting previously unselected package liblwp-mediatypes-perl. 132s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 132s Unpacking liblwp-mediatypes-perl (6.04-2) ... 132s Selecting previously unselected package libhttp-message-perl. 132s Preparing to unpack .../32-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 132s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 132s Selecting previously unselected package libhttp-cookies-perl. 132s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 132s Unpacking libhttp-cookies-perl (6.11-1) ... 132s Selecting previously unselected package libhttp-negotiate-perl. 132s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 132s Unpacking libhttp-negotiate-perl (6.01-2) ... 132s Selecting previously unselected package perl-openssl-defaults:ppc64el. 132s Preparing to unpack .../35-perl-openssl-defaults_7build3_ppc64el.deb ... 132s Unpacking perl-openssl-defaults:ppc64el (7build3) ... 132s Selecting previously unselected package libnet-ssleay-perl:ppc64el. 132s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_ppc64el.deb ... 132s Unpacking libnet-ssleay-perl:ppc64el (1.94-3) ... 132s Selecting previously unselected package libio-socket-ssl-perl. 132s Preparing to unpack .../37-libio-socket-ssl-perl_2.089-1_all.deb ... 132s Unpacking libio-socket-ssl-perl (2.089-1) ... 132s Selecting previously unselected package libnet-http-perl. 132s Preparing to unpack .../38-libnet-http-perl_6.23-1_all.deb ... 132s Unpacking libnet-http-perl (6.23-1) ... 132s Selecting previously unselected package liblwp-protocol-https-perl. 132s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 132s Unpacking liblwp-protocol-https-perl (6.14-1) ... 132s Selecting previously unselected package libtry-tiny-perl. 132s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 132s Unpacking libtry-tiny-perl (0.32-1) ... 132s Selecting previously unselected package libwww-robotrules-perl. 132s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1_all.deb ... 132s Unpacking libwww-robotrules-perl (6.02-1) ... 132s Selecting previously unselected package libwww-perl. 132s Preparing to unpack .../42-libwww-perl_6.77-1_all.deb ... 132s Unpacking libwww-perl (6.77-1) ... 132s Selecting previously unselected package patchutils. 132s Preparing to unpack .../43-patchutils_0.4.2-1build3_ppc64el.deb ... 132s Unpacking patchutils (0.4.2-1build3) ... 132s Selecting previously unselected package wdiff. 132s Preparing to unpack .../44-wdiff_1.2.2-7_ppc64el.deb ... 132s Unpacking wdiff (1.2.2-7) ... 132s Selecting previously unselected package devscripts. 132s Preparing to unpack .../45-devscripts_2.25.3_all.deb ... 132s Unpacking devscripts (2.25.3) ... 132s Selecting previously unselected package libhavege2:ppc64el. 132s Preparing to unpack .../46-libhavege2_1.9.19-9ubuntu1_ppc64el.deb ... 132s Unpacking libhavege2:ppc64el (1.9.19-9ubuntu1) ... 132s Selecting previously unselected package putty-tools. 132s Preparing to unpack .../47-putty-tools_0.83-2_ppc64el.deb ... 132s Unpacking putty-tools (0.83-2) ... 132s Selecting previously unselected package python3-hamcrest. 132s Preparing to unpack .../48-python3-hamcrest_2.1.0-1_all.deb ... 132s Unpacking python3-hamcrest (2.1.0-1) ... 132s Selecting previously unselected package python3-pyasn1. 132s Preparing to unpack .../49-python3-pyasn1_0.6.0-1_all.deb ... 132s Unpacking python3-pyasn1 (0.6.0-1) ... 132s Selecting previously unselected package python3-pyasn1-modules. 132s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-1_all.deb ... 132s Unpacking python3-pyasn1-modules (0.4.1-1) ... 132s Selecting previously unselected package python3-service-identity. 132s Preparing to unpack .../51-python3-service-identity_24.1.0-1_all.deb ... 132s Unpacking python3-service-identity (24.1.0-1) ... 132s Selecting previously unselected package python3-automat. 132s Preparing to unpack .../52-python3-automat_24.8.1-1_all.deb ... 132s Unpacking python3-automat (24.8.1-1) ... 132s Selecting previously unselected package python3-constantly. 132s Preparing to unpack .../53-python3-constantly_23.10.4-2_all.deb ... 132s Unpacking python3-constantly (23.10.4-2) ... 132s Selecting previously unselected package python3-hyperlink. 132s Preparing to unpack .../54-python3-hyperlink_21.0.0-6_all.deb ... 132s Unpacking python3-hyperlink (21.0.0-6) ... 132s Selecting previously unselected package python3-packaging. 132s Preparing to unpack .../55-python3-packaging_24.2-1_all.deb ... 132s Unpacking python3-packaging (24.2-1) ... 132s Selecting previously unselected package python3-incremental. 132s Preparing to unpack .../56-python3-incremental_24.7.2-3_all.deb ... 132s Unpacking python3-incremental (24.7.2-3) ... 132s Selecting previously unselected package python3-zope.interface. 132s Preparing to unpack .../57-python3-zope.interface_7.2-1_ppc64el.deb ... 132s Unpacking python3-zope.interface (7.2-1) ... 132s Selecting previously unselected package python3-twisted. 132s Preparing to unpack .../58-python3-twisted_24.11.0-1_all.deb ... 132s Unpacking python3-twisted (24.11.0-1) ... 133s Selecting previously unselected package openssh-tests. 133s Preparing to unpack .../59-openssh-tests_1%3a9.9p1-3ubuntu2_ppc64el.deb ... 133s Unpacking openssh-tests (1:9.9p1-3ubuntu2) ... 133s Selecting previously unselected package haveged. 133s Preparing to unpack .../60-haveged_1.9.19-9ubuntu1_ppc64el.deb ... 133s Unpacking haveged (1.9.19-9ubuntu1) ... 133s Setting up wdiff (1.2.2-7) ... 133s Setting up libfile-which-perl (1.27-2) ... 133s Setting up libdynaloader-functions-perl (0.004-1) ... 133s Setting up libclass-method-modifiers-perl (2.15-1) ... 133s Setting up libio-pty-perl (1:1.20-1build3) ... 133s Setting up python3-zope.interface (7.2-1) ... 134s Setting up libclone-perl:ppc64el (0.47-1) ... 134s Setting up libtommath1:ppc64el (1.3.0-1) ... 134s Setting up libhtml-tagset-perl (3.24-1) ... 134s Setting up python3-automat (24.8.1-1) ... 134s Setting up liblwp-mediatypes-perl (6.04-2) ... 134s Setting up libtry-tiny-perl (0.32-1) ... 134s Setting up perl-openssl-defaults:ppc64el (7build3) ... 134s Setting up libencode-locale-perl (1.05-3) ... 134s Setting up python3-hamcrest (2.1.0-1) ... 134s Setting up putty-tools (0.83-2) ... 134s Setting up libhavege2:ppc64el (1.9.19-9ubuntu1) ... 134s Setting up patchutils (0.4.2-1build3) ... 134s Setting up python3-packaging (24.2-1) ... 134s Setting up python3-incremental (24.7.2-3) ... 134s Setting up python3-hyperlink (21.0.0-6) ... 134s Setting up libio-html-perl (1.004-3) ... 134s Setting up libb-hooks-op-check-perl:ppc64el (0.22-3build2) ... 134s Setting up libipc-run-perl (20231003.0-2) ... 134s Setting up libtimedate-perl (2.3300-2) ... 134s Setting up librole-tiny-perl (2.002004-1) ... 134s Setting up python3-pyasn1 (0.6.0-1) ... 134s Setting up python3-constantly (23.10.4-2) ... 134s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 134s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 134s Setting up libfile-dirlist-perl (0.05-3) ... 134s Setting up libfile-homedir-perl (1.006-2) ... 134s Setting up liburi-perl (5.30-1) ... 134s Setting up libfile-touch-perl (0.12-2) ... 134s Setting up libnet-ssleay-perl:ppc64el (1.94-3) ... 134s Setting up libtomcrypt1:ppc64el (1.18.2+dfsg-7build1) ... 134s Setting up libhttp-date-perl (6.06-1) ... 134s Setting up haveged (1.9.19-9ubuntu1) ... 134s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 135s Setting up dropbear-bin (2024.86-2) ... 135s Setting up libfile-listing-perl (6.16-1) ... 135s Setting up libnet-http-perl (6.23-1) ... 135s Setting up libdevel-callchecker-perl:ppc64el (0.009-1build1) ... 135s Setting up dropbear (2024.86-2) ... 135s Converting existing OpenSSH RSA host key to Dropbear format. 135s Key is a ssh-rsa key 135s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 135s 3072 SHA256:5ykBQ8SIa+/3GYWrcHu6MLlEIouevUU3BK4trGslpkc /etc/dropbear/dropbear_rsa_host_key (RSA) 135s +---[RSA 3072]----+ 135s | . =o | 135s | . o.o | 135s | . .o. | 135s | + o .o . | 135s |...=.o oS o | 135s |.=E++.. .* . | 135s |=oo.*.. + o | 135s |o.=.o*.o.+ | 135s |.= oo.==o | 135s +----[SHA256]-----+ 135s Converting existing OpenSSH ECDSA host key to Dropbear format. 135s Key is a ecdsa-sha2-nistp256 key 135s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 135s 256 SHA256:w2mq6gWQyDipIRavDMF33p5NFnR6ki1UjBEOew1Qj6w /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 135s +---[ECDSA 256]---+ 135s |o. o+B*o | 135s |+=o. . *oX. | 135s |@o..o .. X.= | 135s |*+. . oo++ | 135s |.o. .ES | 135s | . = o | 135s | . . | 135s | . . | 135s | .o.. | 135s +----[SHA256]-----+ 135s Converting existing OpenSSH ED25519 host key to Dropbear format. 135s Key is a ssh-ed25519 key 135s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 135s 256 SHA256:lCe1tjBwkT8ASj+E82WWZ0we5SryFhBRRfGVKBrVAho /etc/dropbear/dropbear_ed25519_host_key (ED25519) 135s +--[ED25519 256]--+ 135s | ..E+=@O+.... | 135s | .o+ *O+*=o.. | 135s | .o=+*B=oo | 135s | .+.*oo | 135s | . S o. | 135s | o o | 135s | o | 135s | . | 135s | | 135s +----[SHA256]-----+ 135s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 135s Setting up python3-pyasn1-modules (0.4.1-1) ... 136s Setting up python3-service-identity (24.1.0-1) ... 136s Setting up libwww-robotrules-perl (6.02-1) ... 136s Setting up libhtml-parser-perl:ppc64el (3.83-1build1) ... 136s Setting up libio-socket-ssl-perl (2.089-1) ... 136s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 136s Setting up libhttp-negotiate-perl (6.01-2) ... 136s Setting up libhttp-cookies-perl (6.11-1) ... 136s Setting up libhtml-tree-perl (5.07-3) ... 136s Setting up libparams-classify-perl:ppc64el (0.015-2build6) ... 136s Setting up libmodule-runtime-perl (0.016-2) ... 136s Setting up python3-twisted (24.11.0-1) ... 139s Setting up libimport-into-perl (1.002005-2) ... 139s Setting up libmoo-perl (2.005005-1) ... 139s Setting up openssh-tests (1:9.9p1-3ubuntu2) ... 139s Setting up liblwp-protocol-https-perl (6.14-1) ... 139s Setting up libwww-perl (6.77-1) ... 139s Setting up devscripts (2.25.3) ... 139s Processing triggers for install-info (7.1.1-1) ... 139s Processing triggers for libc-bin (2.40-4ubuntu1) ... 139s Processing triggers for man-db (2.13.0-1) ... 142s autopkgtest [06:27:15]: test regress: [----------------------- 142s info: Adding user `openssh-tests' ... 142s info: Selecting UID/GID from range 1000 to 59999 ... 142s info: Adding new group `openssh-tests' (1001) ... 142s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 142s info: Creating home directory `/home/openssh-tests' ... 142s info: Copying files from `/etc/skel' ... 142s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 142s info: Adding user `openssh-tests' to group `users' ... 142s I: annotate-output 2.25.3 142s I: prefix='%H:%M:%S.%N ' 142s 06:27:15.630202142 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user 142s 06:27:15.687233298 O: make: Entering directory '/tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress' 142s 06:27:15.688183819 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/valgrind-out 142s 06:27:15.689007715 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 142s 06:27:15.690095556 O: ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 142s 06:27:15.691422609 E: + /usr/bin/ssh -Q key 142s 06:27:15.693260297 E: + grep -q ^ssh-rsa 142s 06:27:15.692647463 O: tr '\n' '\r' /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 142s 06:27:15.694775598 O: ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 142s 06:27:15.696294530 O: awk '{print $0 "\r"}' /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 142s 06:27:15.697673678 O: ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 142s 06:27:15.700057974 E: + ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv 142s 06:27:15.701589323 E: + diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 142s 06:27:15.703346044 O: fi 142s 06:27:15.705070261 E: + tr \n \r 142s 06:27:15.706459761 E: + ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 142s 06:27:15.708559537 E: + diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 142s 06:27:15.711424136 E: + awk {print $0 "\r"} /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2.prv 142s 06:27:15.713181849 E: + ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 142s 06:27:15.714638561 E: + diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 142s 06:27:15.717178151 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 142s 06:27:15.718006914 O: cat /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t2.out ; \ 142s 06:27:15.719306044 E: + grep -q ^ssh-rsa 142s 06:27:15.720590856 E: + /usr/bin/ssh -Q key 142s 06:27:15.721782629 O: chmod 600 /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t2.out ; \ 142s 06:27:15.723348475 O: ssh-keygen -yf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 142s 06:27:15.724643756 E: + cat /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.prv 142s 06:27:15.726730190 O: fi 142s 06:27:15.726242573 E: + chmod 600 /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t2.out 142s 06:27:15.728669286 E: + ssh-keygen -yf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t2.out 142s 06:27:15.730155755 E: + diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 142s 06:27:15.731392376 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 142s 06:27:15.732778569 E: + /usr/bin/ssh -Q key 142s 06:27:15.734408005 O: ssh-keygen -ef /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t3.out ; \ 142s 06:27:15.735594898 E: + grep -q ^ssh-rsa 142s 06:27:15.736784481 O: ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 142s 06:27:15.737713549 E: + ssh-keygen -ef /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 142s 06:27:15.738202983 O: fi 142s 06:27:15.739858766 E: + ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t3.out+ diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 142s 06:27:15.741266284 E: 142s 06:27:15.744733098 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 142s 06:27:15.746181547 E: + /usr/bin/ssh -Q key 142s 06:27:15.747872757 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 142s 06:27:15.749009747 E: + grep -q ^ssh-rsa 142s 06:27:15.751411727 O: awk '{print $2}' | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t4.ok ; \ 142s 06:27:15.750327406 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 142s 06:27:15.752784527 O: fi 142s 06:27:15.754923013 E: + awk {print $2} 142s 06:27:15.756550433 E: + diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t4.ok 142s 06:27:15.758006230 E: + /usr/bin/ssh -Q key 142s 06:27:15.758799380 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 142s 06:27:15.760477996 O: ssh-keygen -Bf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 142s 06:27:15.763455906 E: + grep -q ^ssh-rsa 142s 06:27:15.762005333 O: awk '{print $2}' | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t5.ok ; \ 142s 06:27:15.765285080 E: + ssh-keygen -Bf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub 142s 06:27:15.766203682 O: fi 142s 06:27:15.766627979 E: + awk {print $2} 142s 06:27:15.767753552 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 142s 06:27:15.768963858 E: + diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t5.ok 142s 06:27:15.770376192 E: + /usr/bin/ssh -Q key 142s 06:27:15.771961110 O: ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t6.out1 ; \ 142s 06:27:15.772872624 O: ssh-keygen -if /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t6.out2 ; \ 142s 06:27:15.773611286 O: chmod 600 /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t6.out1 ; \ 142s 06:27:15.773759271 E: + grep -q ^ssh-dss 142s 06:27:15.774571444 O: ssh-keygen -yf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t6.out2 ; \ 142s 06:27:15.777704189 O: fi 142s 06:27:15.776977662 E: + /usr/bin/ssh -Q key 142s 06:27:15.780067987 E: + grep -q ^ssh-dss 142s 06:27:15.780402449 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 142s 06:27:15.781311446 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t7.out ; \ 142s 06:27:15.782619208 O: fi 142s 06:27:15.783168940 E: + /usr/bin/ssh -Q key 142s 06:27:15.783799589 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 142s 06:27:15.784696725 O: ssh-keygen -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 142s 06:27:15.785074052 E: + grep -q ^ssh-dss 142s 06:27:15.785673191 O: ssh-keygen -Bf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 142s 06:27:15.786455150 O: fi 142s 06:27:15.788588591 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 142s 06:27:15.789653187 E: + ssh -Q key 142s 06:27:15.790778058 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t8.out ; \ 142s 06:27:15.791679855 E: + grep -q ^ssh-dss 142s 06:27:15.792284498 O: fi 142s 06:27:15.796019265 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 142s 06:27:15.797123926 E: + ssh -Q key 142s 06:27:15.798313898 O: ssh-keygen -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 142s 06:27:15.799809133 O: ssh-keygen -Bf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 142s 06:27:15.800485781 O: fi 142s 06:27:15.800683284 E: + grep -q ^ssh-dss 142s 06:27:15.804659470 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 142s 06:27:15.805436329 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t9.out 142s 06:27:15.814753819 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 142s 06:27:15.815654806 O: ssh-keygen -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t9.out > /dev/null 142s 06:27:15.825183474 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 142s 06:27:15.826205437 O: ssh-keygen -Bf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t9.out > /dev/null 142s 06:27:15.834480752 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t10.out 142s 06:27:15.838675629 O: ssh-keygen -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t10.out > /dev/null 142s 06:27:15.843184018 O: ssh-keygen -Bf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t10.out > /dev/null 142s 06:27:15.847545319 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 142s 06:27:15.848369494 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 142s 06:27:15.849245172 O: awk '{print $2}' | diff - /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t11.ok ; \ 142s 06:27:15.849929967 E: + /usr/bin/ssh -Q key 142s 06:27:15.850695118 O: fi 142s 06:27:15.851655249 E: + grep -q ^ssh-dss 142s 06:27:15.853633750 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t12.out 142s 06:27:15.858803745 O: ssh-keygen -lf /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 142s 06:27:15.864366388 E: run test connect.sh ... 143s 06:27:16.600456787 O: ok simple connect 143s 06:27:16.601608608 E: run test proxy-connect.sh ... 143s 06:27:16.737167123 O: plain username comp=no 143s 06:27:16.858572399 O: plain username comp=yes 144s 06:27:16.965246555 O: username with style 144s 06:27:17.068028202 O: ok proxy connect 144s 06:27:17.069111796 E: run test sshfp-connect.sh ... 144s 06:27:17.199481519 E: run test connect-privsep.sh ... 144s 06:27:17.199160279 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 145s 06:27:18.741445645 O: ok proxy connect with privsep 145s 06:27:18.741306144 E: run test connect-uri.sh ... 145s 06:27:18.902492006 O: uri connect: no trailing slash 146s 06:27:19.097381419 O: uri connect: trailing slash 146s 06:27:19.293540284 O: uri connect: with path name 146s 06:27:19.318465840 E: run test proto-version.sh ... 146s 06:27:19.319904896 O: ok uri connect 146s 06:27:19.509348868 O: ok sshd version with different protocol combinations 146s 06:27:19.509628333 E: run test proto-mismatch.sh ... 146s 06:27:19.691451804 E: run test exit-status.sh ... 146s 06:27:19.692252038 O: ok protocol version mismatch 146s 06:27:19.857218648 O: test remote exit status: status 0 152s 06:27:25.137758850 O: test remote exit status: status 1 157s 06:27:30.385957629 O: test remote exit status: status 4 162s 06:27:35.653865983 O: test remote exit status: status 5 168s 06:27:40.989520307 O: test remote exit status: status 44 173s 06:27:46.285399359 O: ok remote exit status 173s 06:27:46.284535299 E: run test exit-status-signal.sh ... 174s 06:27:47.486048743 O: ok exit status on signal 174s 06:27:47.487544033 E: run test envpass.sh ... 174s 06:27:47.682135829 O: test environment passing: pass env, don't accept 174s 06:27:47.811396740 O: test environment passing: setenv, don't accept 174s 06:27:47.930416675 O: test environment passing: don't pass env, accept 175s 06:27:48.037220373 O: test environment passing: pass single env, accept single env 175s 06:27:48.148337307 O: test environment passing: pass multiple env, accept multiple env 175s 06:27:48.255606173 O: test environment passing: setenv, accept 175s 06:27:48.365326649 O: test environment passing: setenv, first match wins 175s 06:27:48.475832823 O: test environment passing: server setenv wins 175s 06:27:48.586598605 O: test environment passing: server setenv wins 175s 06:27:48.696878325 E: run test transfer.sh ... 175s 06:27:48.697595334 O: ok environment passing 176s 06:27:49.818436901 E: run test banner.sh ... 176s 06:27:49.818420569 O: ok transfer data 177s 06:27:49.979045340 O: test banner: missing banner file 177s 06:27:50.097897638 O: test banner: size 0 177s 06:27:50.209687809 O: test banner: size 10 177s 06:27:50.332075581 O: test banner: size 100 177s 06:27:50.456475049 O: test banner: size 1000 177s 06:27:50.577918788 O: test banner: size 10000 177s 06:27:50.698487830 O: test banner: size 100000 177s 06:27:50.844986634 O: test banner: suppress banner (-q) 178s 06:27:50.974485828 O: ok banner 178s 06:27:50.975414495 E: run test rekey.sh ... 178s 06:27:51.158324736 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 178s 06:27:51.301148311 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 178s 06:27:51.451242358 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 178s 06:27:51.592728257 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 178s 06:27:51.783987908 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 179s 06:27:52.186641937 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 179s 06:27:52.631727821 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 180s 06:27:53.130925305 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 180s 06:27:53.260419152 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 180s 06:27:53.404731328 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 180s 06:27:53.578720250 O: client rekey KexAlgorithms=curve25519-sha256 180s 06:27:53.753156506 O: client rekey KexAlgorithms=sntrup761x25519-sha512 180s 06:27:53.940336483 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 181s 06:27:54.114242156 O: client rekey KexAlgorithms=mlkem768x25519-sha256 181s 06:27:54.256479026 O: client rekey Ciphers=3des-cbc 181s 06:27:54.403050665 O: client rekey Ciphers=aes128-cbc 181s 06:27:54.555630835 O: client rekey Ciphers=aes192-cbc 181s 06:27:54.707676116 O: client rekey Ciphers=aes256-cbc 181s 06:27:54.852878644 O: client rekey Ciphers=aes128-ctr 182s 06:27:54.999324102 O: client rekey Ciphers=aes192-ctr 182s 06:27:55.143520826 O: client rekey Ciphers=aes256-ctr 182s 06:27:55.300442940 O: client rekey Ciphers=aes128-gcm@openssh.com 184s 06:27:57.351597776 O: client rekey Ciphers=aes256-gcm@openssh.com 186s 06:27:59.657604126 O: client rekey Ciphers=chacha20-poly1305@openssh.com 189s 06:28:02.240999262 O: client rekey MACs=hmac-sha1 189s 06:28:02.390737876 O: client rekey MACs=hmac-sha1-96 189s 06:28:02.537475762 O: client rekey MACs=hmac-sha2-256 189s 06:28:02.677460083 O: client rekey MACs=hmac-sha2-512 189s 06:28:02.820742182 O: client rekey MACs=hmac-md5 190s 06:28:02.960289254 O: client rekey MACs=hmac-md5-96 190s 06:28:03.101754791 O: client rekey MACs=umac-64@openssh.com 190s 06:28:03.248343586 O: client rekey MACs=umac-128@openssh.com 190s 06:28:03.399788696 O: client rekey MACs=hmac-sha1-etm@openssh.com 190s 06:28:03.542433311 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 190s 06:28:03.687577787 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 190s 06:28:03.831670684 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 191s 06:28:03.969435557 O: client rekey MACs=hmac-md5-etm@openssh.com 191s 06:28:04.125185998 O: client rekey MACs=hmac-md5-96-etm@openssh.com 191s 06:28:04.288490807 O: client rekey MACs=umac-64-etm@openssh.com 191s 06:28:04.456239530 O: client rekey MACs=umac-128-etm@openssh.com 191s 06:28:04.636907325 O: client rekeylimit 16 191s 06:28:04.825449521 O: client rekeylimit 1k 191s 06:28:04.966835445 O: client rekeylimit 128k 192s 06:28:05.130265810 O: client rekeylimit 256k 192s 06:28:05.361519281 O: client rekeylimit default 5 207s 06:28:20.478276795 O: client rekeylimit default 10 227s 06:28:40.606786426 O: client rekeylimit default 5 no data 242s 06:28:55.746540294 O: client rekeylimit default 10 no data 262s 06:29:15.869113738 O: server rekeylimit 16 263s 06:29:16.097570827 O: server rekeylimit 1k 263s 06:29:16.309651929 O: server rekeylimit 128k 263s 06:29:16.525256994 O: server rekeylimit 256k 263s 06:29:16.847587649 O: server rekeylimit default 5 no data 279s 06:29:32.024943157 O: server rekeylimit default 10 no data 299s 06:29:52.172593677 O: rekeylimit parsing: bytes 299s 06:29:52.260435949 O: rekeylimit parsing: time 299s 06:29:52.344791273 O: ok rekey 299s 06:29:52.346190591 E: run test dhgex.sh ... 299s 06:29:52.488155423 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 299s 06:29:52.606912907 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 299s 06:29:52.717110252 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 299s 06:29:52.830084285 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 299s 06:29:52.940994589 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 300s 06:29:53.048359954 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 300s 06:29:53.152746517 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 300s 06:29:53.270154466 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 300s 06:29:53.391493237 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 300s 06:29:53.530026582 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 300s 06:29:53.673672155 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 300s 06:29:53.815093188 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 300s 06:29:53.948465003 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 301s 06:29:54.093530193 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 301s 06:29:54.233306618 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 301s 06:29:54.373165972 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 301s 06:29:54.510587230 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 301s 06:29:54.657041928 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 301s 06:29:54.820313563 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 302s 06:29:55.005437172 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 302s 06:29:55.189763714 E: run test stderr-data.sh ... 302s 06:29:55.191023496 O: ok dhgex 302s 06:29:55.311325009 O: test stderr data transfer: () 308s 06:30:01.591445290 O: test stderr data transfer: (-n) 314s 06:30:07.853025207 O: ok stderr data transfer 314s 06:30:07.854057983 E: run test stderr-after-eof.sh ... 317s 06:30:10.178096955 O: ok stderr data after eof 317s 06:30:10.179187925 E: run test broken-pipe.sh ... 317s 06:30:10.377433213 E: run test try-ciphers.sh ... 317s 06:30:10.377458996 O: ok broken pipe test 317s 06:30:10.520604980 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 317s 06:30:10.657097164 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 317s 06:30:10.790559329 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 317s 06:30:10.913560808 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 318s 06:30:11.038548533 O: test try ciphers: cipher 3des-cbc mac hmac-md5 318s 06:30:11.176237607 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 318s 06:30:11.302921335 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 318s 06:30:11.426399142 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 318s 06:30:11.550147484 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 318s 06:30:11.677931908 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 318s 06:30:11.823033159 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 318s 06:30:11.961168320 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 319s 06:30:12.087566716 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 319s 06:30:12.209947253 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 319s 06:30:12.344132551 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 319s 06:30:12.475866127 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 319s 06:30:12.599155682 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 319s 06:30:12.720968351 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 319s 06:30:12.839948091 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 319s 06:30:12.958448000 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 320s 06:30:13.080226699 O: test try ciphers: cipher aes128-cbc mac hmac-md5 320s 06:30:13.192835142 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 320s 06:30:13.310493944 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 320s 06:30:13.426303138 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 320s 06:30:13.544886726 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 320s 06:30:13.666042581 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 320s 06:30:13.788228307 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 320s 06:30:13.906032769 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 321s 06:30:14.028429803 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 321s 06:30:14.151979850 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 321s 06:30:14.269717730 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 321s 06:30:14.399333855 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 321s 06:30:14.524199937 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 321s 06:30:14.644559722 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 321s 06:30:14.759443562 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 321s 06:30:14.870942997 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 322s 06:30:14.986800582 O: test try ciphers: cipher aes192-cbc mac hmac-md5 322s 06:30:15.132596041 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 322s 06:30:15.254162477 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 322s 06:30:15.388053122 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 322s 06:30:15.526606801 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 322s 06:30:15.652068693 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 322s 06:30:15.795580150 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 322s 06:30:15.942387600 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 323s 06:30:16.077387501 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 323s 06:30:16.212386680 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 323s 06:30:16.346694739 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 323s 06:30:16.458301385 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 323s 06:30:16.591849517 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 323s 06:30:16.721161725 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 323s 06:30:16.842824563 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 323s 06:30:16.953888401 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 324s 06:30:17.079340001 O: test try ciphers: cipher aes256-cbc mac hmac-md5 324s 06:30:17.199023865 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 324s 06:30:17.311025685 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 324s 06:30:17.423276085 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 324s 06:30:17.540774669 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 324s 06:30:17.671910258 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 324s 06:30:17.792066798 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 324s 06:30:17.908438710 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 325s 06:30:18.029277575 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 325s 06:30:18.154034476 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 325s 06:30:18.291278027 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 325s 06:30:18.447777316 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 325s 06:30:18.596520809 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 325s 06:30:18.738085488 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 325s 06:30:18.853739402 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 326s 06:30:18.972559390 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 326s 06:30:19.088981252 O: test try ciphers: cipher aes128-ctr mac hmac-md5 326s 06:30:19.208713842 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 326s 06:30:19.334286703 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 326s 06:30:19.455382680 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 326s 06:30:19.569542102 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 326s 06:30:19.700937120 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 326s 06:30:19.817746782 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 326s 06:30:19.930351440 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 327s 06:30:20.055700774 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 327s 06:30:20.179060342 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 327s 06:30:20.303874937 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 327s 06:30:20.423995134 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 327s 06:30:20.550712568 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 327s 06:30:20.668420116 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 327s 06:30:20.777631933 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 327s 06:30:20.893737847 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 328s 06:30:21.012252761 O: test try ciphers: cipher aes192-ctr mac hmac-md5 328s 06:30:21.127763362 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 328s 06:30:21.241867494 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 328s 06:30:21.360971894 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 328s 06:30:21.484810879 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 328s 06:30:21.608807261 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 328s 06:30:21.739135968 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 328s 06:30:21.859469717 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 329s 06:30:21.973650839 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 329s 06:30:22.094809274 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 329s 06:30:22.234598170 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 329s 06:30:22.370324467 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 329s 06:30:22.538201517 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 329s 06:30:22.679561430 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 329s 06:30:22.840503634 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 330s 06:30:22.971492725 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 330s 06:30:23.104188745 O: test try ciphers: cipher aes256-ctr mac hmac-md5 330s 06:30:23.238656251 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 330s 06:30:23.356085893 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 330s 06:30:23.476159868 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 330s 06:30:23.602562713 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 330s 06:30:23.721548794 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 330s 06:30:23.841275990 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 331s 06:30:23.974799416 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 331s 06:30:24.103783972 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 331s 06:30:24.227736694 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 331s 06:30:24.348257020 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 331s 06:30:24.473740485 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 331s 06:30:24.607359118 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 331s 06:30:24.732309100 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 331s 06:30:24.858918088 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 332s 06:30:24.981064983 E: run test yes-head.sh ... 332s 06:30:24.981533723 O: ok try ciphers 335s 06:30:28.099012527 O: ok yes pipe head 335s 06:30:28.099708487 E: run test login-timeout.sh ... 350s 06:30:43.489696058 E: run test agent.sh ... 350s 06:30:43.489290917 O: ok connect after login grace timeout 354s 06:30:47.799919882 O: ok simple agent test 354s 06:30:47.800671192 E: run test agent-getpeereid.sh ... 354s 06:30:47.951184125 O: ok disallow agent attach from other uid 354s 06:30:47.951716818 E: run test agent-timeout.sh ... 375s 06:31:08.137603713 O: ok agent timeout test 375s 06:31:08.138908954 E: run test agent-ptrace.sh ... 375s 06:31:08.282041073 E: run test agent-subprocess.sh ... 375s 06:31:08.281925588 O: skipped (gdb not found) 385s 06:31:18.433529120 O: ok agent subprocess 385s 06:31:18.434614632 E: run test keyscan.sh ... 386s 06:31:19.230017432 E: run test keygen-change.sh ... 386s 06:31:19.230417811 O: ok keyscan 391s 06:31:24.685227713 O: ok change passphrase for key 391s 06:31:24.686115621 E: run test keygen-comment.sh ... 394s 06:31:27.208648664 O: ok Comment extraction from private key 394s 06:31:27.210010181 E: run test keygen-convert.sh ... 398s 06:31:31.451548794 O: ok convert keys 398s 06:31:31.453075602 E: run test keygen-knownhosts.sh ... 398s 06:31:31.733546237 O: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts updated. 398s 06:31:31.734416175 O: Original contents retained as /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts.old 398s 06:31:31.752002274 O: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts updated. 398s 06:31:31.753484040 O: Original contents retained as /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts.old 398s 06:31:31.760557986 O: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts updated. 398s 06:31:31.761996556 O: Original contents retained as /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts.old 398s 06:31:31.777411228 O: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts updated. 398s 06:31:31.778404845 O: Original contents retained as /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hosts.old 398s 06:31:31.802547837 O: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hashed updated. 398s 06:31:31.803814651 O: Original contents retained as /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/kh.hashed.old 398s 06:31:31.807705895 O: ok ssh-keygen known_hosts 398s 06:31:31.807796315 E: run test keygen-moduli.sh ... 401s 06:31:34.143714412 E: run test keygen-sshfp.sh ... 401s 06:31:34.144641118 O: ok keygen moduli 401s 06:31:34.330575155 O: ok keygen-sshfp 401s 06:31:34.331553232 E: run test key-options.sh ... 401s 06:31:34.468083890 O: key option command="echo bar" 401s 06:31:34.604998286 O: key option no-pty,command="echo bar" 401s 06:31:34.727737902 O: key option pty default 401s 06:31:34.860268401 O: key option pty no-pty 402s 06:31:34.991324891 O: key option pty restrict 402s 06:31:35.128432665 O: key option pty restrict,pty 402s 06:31:35.271581899 O: key option environment 402s 06:31:35.546158609 O: key option from="127.0.0.1" 404s 06:31:35.978280072 O: key option from="127.0.0.0/8" 404s 06:31:36.305578744 O: key option expiry-time default 404s 06:31:36.418074773 O: key option expiry-time invalid 404s 06:31:36.545965225 O: key option expiry-time expired 404s 06:31:36.673207544 O: key option expiry-time valid 404s 06:31:36.800939253 O: ok key options 404s 06:31:36.801346431 E: run test scp.sh ... 404s 06:31:36.933013763 O: scp: scp mode: simple copy local file to local file 404s 06:31:36.942389359 O: scp: scp mode: simple copy local file to remote file 404s 06:31:36.952103564 O: scp: scp mode: simple copy remote file to local file 404s 06:31:36.961226587 O: scp: scp mode: copy local file to remote file in place 404s 06:31:36.974054376 O: scp: scp mode: copy remote file to local file in place 404s 06:31:36.989142327 O: scp: scp mode: copy local file to remote file clobber 404s 06:31:36.999495099 O: -rw-r--r-- 1 openssh-tests openssh-tests 1248304 Feb 22 06:31 /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/copy 404s 06:31:37.000388506 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1248304 Feb 22 06:31 /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/data 404s 06:31:37.004170852 O: scp: scp mode: copy remote file to local file clobber 404s 06:31:37.018033107 O: scp: scp mode: simple copy local file to remote dir 404s 06:31:37.030226078 O: scp: scp mode: simple copy local file to local dir 404s 06:31:37.042359015 O: scp: scp mode: simple copy remote file to local dir 404s 06:31:37.058898359 O: scp: scp mode: recursive local dir to remote dir 404s 06:31:37.081190495 O: scp: scp mode: recursive local dir to local dir 404s 06:31:37.103735931 O: scp: scp mode: recursive remote dir to local dir 404s 06:31:37.135120048 O: scp: scp mode: unmatched glob file local->remote 404s 06:31:37.146862507 O: scp: scp mode: unmatched glob file remote->local 404s 06:31:37.153982038 O: scp: scp mode: unmatched glob dir recursive local->remote 404s 06:31:37.170403617 O: scp: scp mode: unmatched glob dir recursive remote->local 404s 06:31:37.182190435 O: scp: scp mode: shell metacharacters 404s 06:31:37.193010386 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 404s 06:31:37.237936955 O: scp: scp mode: disallow bad server #0 404s 06:31:37.263273476 O: scp: scp mode: disallow bad server #1 404s 06:31:37.283102392 O: scp: scp mode: disallow bad server #2 404s 06:31:37.309082922 O: scp: scp mode: disallow bad server #3 404s 06:31:37.330374870 O: scp: scp mode: disallow bad server #4 404s 06:31:37.359383090 O: scp: scp mode: disallow bad server #5 404s 06:31:37.380248009 O: scp: scp mode: disallow bad server #6 404s 06:31:37.403466389 O: scp: scp mode: disallow bad server #7 404s 06:31:37.423858392 O: scp: scp mode: detect non-directory target 404s 06:31:37.427811301 E: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/copy2: Not a directory 404s 06:31:37.429701052 O: scp: sftp mode: simple copy local file to local file 404s 06:31:37.438451338 O: scp: sftp mode: simple copy local file to remote file 404s 06:31:37.451648905 O: scp: sftp mode: simple copy remote file to local file 404s 06:31:37.461501753 O: scp: sftp mode: copy local file to remote file in place 404s 06:31:37.472056822 O: scp: sftp mode: copy remote file to local file in place 404s 06:31:37.483495948 O: scp: sftp mode: copy local file to remote file clobber 404s 06:31:37.492639929 O: -rw-r--r-- 1 openssh-tests openssh-tests 1248304 Feb 22 06:31 /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/copy 404s 06:31:37.493691998 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1248304 Feb 22 06:31 /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/data 404s 06:31:37.496223746 O: scp: sftp mode: copy remote file to local file clobber 404s 06:31:37.507217467 O: scp: sftp mode: simple copy local file to remote dir 404s 06:31:37.519645307 O: scp: sftp mode: simple copy local file to local dir 404s 06:31:37.531992766 O: scp: sftp mode: simple copy remote file to local dir 404s 06:31:37.543605398 O: scp: sftp mode: recursive local dir to remote dir 404s 06:31:37.566886506 O: scp: sftp mode: recursive local dir to local dir 404s 06:31:37.586985688 O: scp: sftp mode: recursive remote dir to local dir 404s 06:31:37.617574372 O: scp: sftp mode: unmatched glob file local->remote 404s 06:31:37.631045802 O: scp: sftp mode: unmatched glob file remote->local 404s 06:31:37.643140268 O: scp: sftp mode: unmatched glob dir recursive local->remote 404s 06:31:37.663095961 O: scp: sftp mode: unmatched glob dir recursive remote->local 404s 06:31:37.678618227 O: scp: sftp mode: shell metacharacters 404s 06:31:37.689408228 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 404s 06:31:37.735679724 O: scp: sftp mode: disallow bad server #0 404s 06:31:37.771229810 O: scp: sftp mode: disallow bad server #1 404s 06:31:37.806014638 O: scp: sftp mode: disallow bad server #2 404s 06:31:37.842231723 O: scp: sftp mode: disallow bad server #3 404s 06:31:37.878093579 O: scp: sftp mode: disallow bad server #4 404s 06:31:37.914170814 O: scp: sftp mode: disallow bad server #5 404s 06:31:37.949374429 O: scp: sftp mode: disallow bad server #6 405s 06:31:37.981357836 O: scp: sftp mode: disallow bad server #7 405s 06:31:38.014715728 O: scp: sftp mode: detect non-directory target 405s 06:31:38.018940468 E: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/copy2: Not a directory 405s 06:31:38.025777495 O: ok scp 405s 06:31:38.026392737 E: run test scp3.sh ... 405s 06:31:38.187257495 O: scp3: scp mode: simple copy remote file to remote file 405s 06:31:38.365643903 O: scp3: scp mode: simple copy remote file to remote dir 405s 06:31:38.501809621 O: scp3: scp mode: recursive remote dir to remote dir 405s 06:31:38.682743379 O: scp3: scp mode: detect non-directory target 405s 06:31:38.952254120 O: scp3: sftp mode: simple copy remote file to remote file 406s 06:31:38.965397006 O: scp3: sftp mode: simple copy remote file to remote dir 406s 06:31:38.980077119 O: scp3: sftp mode: recursive remote dir to remote dir 406s 06:31:39.013103467 O: scp3: sftp mode: detect non-directory target 406s 06:31:39.019481401 E: scp: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/copy2: destination is not a directory 406s 06:31:39.020910187 E: scp: /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/copy2: destination is not a directory 406s 06:31:39.026051486 O: ok scp3 406s 06:31:39.027147637 E: run test scp-uri.sh ... 406s 06:31:39.160920777 O: scp-uri: scp mode: simple copy local file to remote file 406s 06:31:39.169386744 O: scp-uri: scp mode: simple copy remote file to local file 406s 06:31:39.177539096 O: scp-uri: scp mode: simple copy local file to remote dir 406s 06:31:39.186574437 O: scp-uri: scp mode: simple copy remote file to local dir 406s 06:31:39.197771331 O: scp-uri: scp mode: recursive local dir to remote dir 406s 06:31:39.211550380 O: scp-uri: scp mode: recursive remote dir to local dir 406s 06:31:39.225431439 O: scp-uri: sftp mode: simple copy local file to remote file 406s 06:31:39.234853081 O: scp-uri: sftp mode: simple copy remote file to local file 406s 06:31:39.244200830 O: scp-uri: sftp mode: simple copy local file to remote dir 406s 06:31:39.253861517 O: scp-uri: sftp mode: simple copy remote file to local dir 406s 06:31:39.263003140 O: scp-uri: sftp mode: recursive local dir to remote dir 406s 06:31:39.279272905 O: scp-uri: sftp mode: recursive remote dir to local dir 406s 06:31:39.296787674 O: ok scp-uri 406s 06:31:39.298044750 E: run test sftp.sh ... 406s 06:31:39.466452037 O: test basic sftp put/get: buffer_size 5 num_requests 1 420s 06:31:53.872601995 O: test basic sftp put/get: buffer_size 5 num_requests 2 426s 06:31:59.319875343 O: test basic sftp put/get: buffer_size 5 num_requests 10 430s 06:32:03.248689816 O: test basic sftp put/get: buffer_size 1000 num_requests 1 430s 06:32:03.359034860 O: test basic sftp put/get: buffer_size 1000 num_requests 2 430s 06:32:03.402536273 O: test basic sftp put/get: buffer_size 1000 num_requests 10 430s 06:32:03.441260696 O: test basic sftp put/get: buffer_size 32000 num_requests 1 430s 06:32:03.452776652 O: test basic sftp put/get: buffer_size 32000 num_requests 2 430s 06:32:03.464875394 O: test basic sftp put/get: buffer_size 32000 num_requests 10 430s 06:32:03.475456020 O: test basic sftp put/get: buffer_size 64000 num_requests 1 430s 06:32:03.487621199 O: test basic sftp put/get: buffer_size 64000 num_requests 2 430s 06:32:03.498252104 O: test basic sftp put/get: buffer_size 64000 num_requests 10 430s 06:32:03.510256570 O: ok basic sftp put/get 430s 06:32:03.511291847 E: run test sftp-chroot.sh ... 430s 06:32:03.682653174 O: test sftp in chroot: get 430s 06:32:03.909634921 O: test sftp in chroot: match 431s 06:32:04.187257091 O: ok sftp in chroot 431s 06:32:04.193814304 E: run test sftp-cmds.sh ... 431s 06:32:04.354424159 O: sftp commands: lls 431s 06:32:04.362843448 O: sftp commands: lls w/path 431s 06:32:04.368481867 O: sftp commands: ls 431s 06:32:04.376365358 O: sftp commands: shell 431s 06:32:04.380195147 O: sftp commands: pwd 431s 06:32:04.383560728 O: sftp commands: lpwd 431s 06:32:04.387412081 O: sftp commands: quit 431s 06:32:04.390792085 O: sftp commands: help 431s 06:32:04.395429970 O: sftp commands: get 431s 06:32:04.404469966 O: sftp commands: get quoted 431s 06:32:04.416289039 O: sftp commands: get filename with quotes 431s 06:32:04.428412175 O: sftp commands: get filename with spaces 431s 06:32:04.439871587 O: sftp commands: get filename with glob metacharacters 431s 06:32:04.449172495 O: sftp commands: get to directory 431s 06:32:04.459211362 O: sftp commands: glob get to directory 431s 06:32:04.547427665 O: sftp commands: get to local dir 431s 06:32:04.556094786 O: sftp commands: glob get to local dir 431s 06:32:04.599460725 O: sftp commands: put 431s 06:32:04.607824759 O: sftp commands: put filename with quotes 431s 06:32:04.617600707 O: sftp commands: put filename with spaces 431s 06:32:04.628354095 O: sftp commands: put to directory 431s 06:32:04.638070650 O: sftp commands: glob put to directory 431s 06:32:04.647276970 O: sftp commands: put to local dir 431s 06:32:04.656077024 O: sftp commands: glob put to local dir 431s 06:32:04.692585699 O: sftp commands: rename 431s 06:32:04.699345069 O: sftp commands: rename directory 431s 06:32:04.702631235 O: sftp commands: ln 431s 06:32:04.706235231 O: sftp commands: ln -s 431s 06:32:04.711307224 O: sftp commands: cp 431s 06:32:04.719926883 O: sftp commands: mkdir 431s 06:32:04.725132112 O: sftp commands: chdir 431s 06:32:04.729503769 O: sftp commands: rmdir 431s 06:32:04.732486883 O: sftp commands: lmkdir 431s 06:32:04.735675041 O: sftp commands: lchdir 431s 06:32:04.744997327 O: ok sftp commands 431s 06:32:04.746010980 E: run test sftp-badcmds.sh ... 431s 06:32:04.894477034 O: sftp invalid commands: get nonexistent 431s 06:32:04.899329483 O: sftp invalid commands: glob get to nonexistent directory 431s 06:32:04.928738298 O: sftp invalid commands: put nonexistent 431s 06:32:04.933146087 O: sftp invalid commands: glob put to nonexistent directory 431s 06:32:04.939157286 O: sftp invalid commands: rename nonexistent 431s 06:32:04.945363049 O: sftp invalid commands: rename target exists (directory) 431s 06:32:04.952960147 O: sftp invalid commands: glob put files to local file 431s 06:32:04.958648186 O: ok sftp invalid commands 431s 06:32:04.959724301 E: run test sftp-batch.sh ... 432s 06:32:05.093800988 O: sftp batchfile: good commands 432s 06:32:05.099627980 O: sftp batchfile: bad commands 432s 06:32:05.108393783 O: sftp batchfile: comments and blanks 432s 06:32:05.114744088 O: sftp batchfile: junk command 432s 06:32:05.118281544 O: ok sftp batchfile 432s 06:32:05.119264956 E: run test sftp-glob.sh ... 432s 06:32:05.248987597 O: sftp glob: file glob 432s 06:32:05.255734961 O: sftp glob: dir glob 432s 06:32:05.262218918 O: sftp glob: quoted glob 432s 06:32:05.268821222 O: sftp glob: escaped glob 432s 06:32:05.275778305 O: sftp glob: escaped quote 432s 06:32:05.281803872 O: sftp glob: quoted quote 432s 06:32:05.287662369 O: sftp glob: single-quoted quote 432s 06:32:05.294038580 O: sftp glob: escaped space 432s 06:32:05.299419839 O: sftp glob: quoted space 432s 06:32:05.305638384 O: sftp glob: escaped slash 432s 06:32:05.311358781 O: sftp glob: quoted slash 432s 06:32:05.316572599 O: sftp glob: escaped slash at EOL 432s 06:32:05.321685954 O: sftp glob: quoted slash at EOL 432s 06:32:05.327354956 O: sftp glob: escaped slash+quote 432s 06:32:05.333217913 O: sftp glob: quoted slash+quote 432s 06:32:05.339263031 O: ok sftp glob 432s 06:32:05.339750090 E: run test sftp-perm.sh ... 432s 06:32:05.474980770 O: sftp permissions: read-only upload 432s 06:32:05.492071737 O: sftp permissions: read-only setstat 432s 06:32:05.508991267 O: sftp permissions: read-only rm 432s 06:32:05.523133836 O: sftp permissions: read-only mkdir 432s 06:32:05.537252866 O: sftp permissions: read-only rmdir 432s 06:32:05.551901914 O: sftp permissions: read-only posix-rename 432s 06:32:05.567549561 O: sftp permissions: read-only oldrename 432s 06:32:05.584377618 O: sftp permissions: read-only symlink 432s 06:32:05.599914622 O: sftp permissions: read-only hardlink 432s 06:32:05.617135425 O: sftp permissions: explicit open 432s 06:32:05.656682205 O: sftp permissions: explicit read 432s 06:32:05.699823711 O: sftp permissions: explicit write 432s 06:32:05.739080783 O: sftp permissions: explicit lstat 432s 06:32:05.774317499 O: sftp permissions: explicit opendir 432s 06:32:05.810852245 O: sftp permissions: explicit readdir 432s 06:32:05.848888857 O: sftp permissions: explicit setstat 432s 06:32:05.883646411 O: sftp permissions: explicit remove 432s 06:32:05.917174615 O: sftp permissions: explicit mkdir 432s 06:32:05.946884472 O: sftp permissions: explicit rmdir 433s 06:32:05.978518377 O: sftp permissions: explicit rename 433s 06:32:06.012964269 O: sftp permissions: explicit symlink 433s 06:32:06.043524094 O: sftp permissions: explicit hardlink 433s 06:32:06.073670302 O: sftp permissions: explicit statvfs 433s 06:32:06.099812255 E: run test sftp-uri.sh ... 433s 06:32:06.100406781 O: ok sftp permissions 433s 06:32:06.278908522 O: sftp-uri: non-interactive fetch to local file 433s 06:32:06.465772756 O: sftp-uri: non-interactive fetch to local dir 433s 06:32:06.677985090 O: sftp-uri: put to remote directory (trailing slash) 433s 06:32:06.885962572 O: sftp-uri: put to remote directory (no slash) 434s 06:32:07.121145442 E: run test reconfigure.sh ... 434s 06:32:07.122147793 O: ok sftp-uri 447s 06:32:20.191225661 O: ok simple connect after reconfigure 447s 06:32:20.192031552 E: run test dynamic-forward.sh ... 447s 06:32:20.378269329 O: test -D forwarding 448s 06:32:21.302405539 O: test -R forwarding 450s 06:32:23.458518030 O: PermitRemoteOpen=any 451s 06:32:24.582101129 O: PermitRemoteOpen=none 452s 06:32:25.961614754 O: PermitRemoteOpen=explicit 454s 06:32:27.085718278 O: PermitRemoteOpen=disallowed 454s 06:32:27.473917370 E: run test forwarding.sh ... 454s 06:32:27.473241075 O: ok dynamic forwarding 459s 06:32:32.046482604 O: ok local and remote forwarding 459s 06:32:32.047937895 E: run test multiplex.sh ... 460s 06:32:33.282887129 O: test connection multiplexing: setenv 460s 06:32:33.296927816 O: test connection multiplexing: envpass 460s 06:32:33.312068000 O: test connection multiplexing: transfer 460s 06:32:33.362675367 O: test connection multiplexing: transfer -Oproxy 460s 06:32:33.484202189 O: test connection multiplexing: forward 462s 06:32:35.546476313 O: test connection multiplexing: status 0 () 467s 06:32:40.576655580 O: test connection multiplexing: status 0 (-Oproxy) 472s 06:32:45.617194272 O: test connection multiplexing: status 1 () 477s 06:32:50.657063167 O: test connection multiplexing: status 1 (-Oproxy) 482s 06:32:55.699167554 O: test connection multiplexing: status 4 () 487s 06:33:00.735097970 O: test connection multiplexing: status 4 (-Oproxy) 492s 06:33:05.769706254 O: test connection multiplexing: status 5 () 497s 06:33:10.803005162 O: test connection multiplexing: status 5 (-Oproxy) 502s 06:33:15.837595484 O: test connection multiplexing: status 44 () 507s 06:33:20.880823111 O: test connection multiplexing: status 44 (-Oproxy) 512s 06:33:25.915943910 O: test connection multiplexing: cmd check 512s 06:33:25.928686663 O: test connection multiplexing: cmd forward local (TCP) 514s 06:33:27.193221358 O: test connection multiplexing: cmd forward remote (TCP) 515s 06:33:28.477472182 O: test connection multiplexing: cmd forward local (UNIX) 516s 06:33:29.525958236 O: test connection multiplexing: cmd forward remote (UNIX) 517s 06:33:30.569855727 O: test connection multiplexing: cmd exit 517s 06:33:30.585444419 O: test connection multiplexing: cmd stop 528s 06:33:41.655567125 O: ok connection multiplexing 528s 06:33:41.656733325 E: run test reexec.sh ... 528s 06:33:41.834403292 O: test config passing 529s 06:33:42.144304142 O: test reexec fallback 529s 06:33:42.146188810 E: ln: failed to create hard link '/tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 529s 06:33:42.439518562 O: ok reexec tests 529s 06:33:42.440044047 E: run test brokenkeys.sh ... 529s 06:33:42.886893306 O: ok broken keys 529s 06:33:42.888176412 E: run test sshcfgparse.sh ... 530s 06:33:43.027945974 O: reparse minimal config 530s 06:33:43.049217411 O: ssh -W opts 530s 06:33:43.098991686 O: user first match 530s 06:33:43.133198399 O: pubkeyacceptedalgorithms 530s 06:33:43.186509787 O: agentforwarding 530s 06:33:43.225626891 O: command line override 530s 06:33:43.245339174 E: run test cfgparse.sh ... 530s 06:33:43.246365552 O: ok ssh config parse 530s 06:33:43.368447541 O: reparse minimal config 530s 06:33:43.409070428 O: reparse regress config 530s 06:33:43.453564819 O: listenaddress order 530s 06:33:43.502266541 E: run test cfgmatch.sh ... 530s 06:33:43.503323781 O: ok sshd config parse 537s 06:33:50.868424143 O: ok sshd_config match 537s 06:33:50.869208857 E: run test cfgmatchlisten.sh ... 547s 06:34:00.697067936 E: run test percent.sh ... 547s 06:34:00.697894877 O: ok sshd_config matchlisten 547s 06:34:00.888431820 O: percent expansions matchexec percent 549s 06:34:02.904421580 O: percent expansions localcommand percent 551s 06:34:04.763314905 O: percent expansions remotecommand percent 552s 06:34:04.974313772 O: percent expansions controlpath percent 552s 06:34:05.165439404 O: percent expansions identityagent percent 552s 06:34:05.345486833 O: percent expansions forwardagent percent 552s 06:34:05.481573626 O: percent expansions localforward percent 552s 06:34:05.652635334 O: percent expansions remoteforward percent 552s 06:34:05.789249205 O: percent expansions revokedhostkeys percent 552s 06:34:05.925641714 O: percent expansions userknownhostsfile percent 554s 06:34:07.127473723 O: percent expansions controlpath dollar 554s 06:34:07.141557042 O: percent expansions identityagent dollar 554s 06:34:07.153662886 O: percent expansions forwardagent dollar 554s 06:34:07.166212207 O: percent expansions localforward dollar 554s 06:34:07.176906903 O: percent expansions remoteforward dollar 554s 06:34:07.190565319 O: percent expansions userknownhostsfile dollar 554s 06:34:07.319230757 O: percent expansions controlpath tilde 554s 06:34:07.343885281 O: percent expansions identityagent tilde 554s 06:34:07.380664135 O: percent expansions forwardagent tilde 554s 06:34:07.418887188 O: ok percent expansions 554s 06:34:07.420021991 E: run test addrmatch.sh ... 554s 06:34:07.605796277 O: test first entry for user 192.168.0.1 somehost 554s 06:34:07.622273750 O: test negative match for user 192.168.30.1 somehost 554s 06:34:07.650077814 O: test no match for user 19.0.0.1 somehost 554s 06:34:07.676072968 O: test list middle for user 10.255.255.254 somehost 554s 06:34:07.704373838 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 554s 06:34:07.736346773 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 554s 06:34:07.761742601 O: test localaddress for user 19.0.0.1 somehost 554s 06:34:07.788920990 O: test localport for user 19.0.0.1 somehost 554s 06:34:07.814708976 O: test bare IP6 address for user ::1 somehost.example.com 554s 06:34:07.847264552 O: test deny IPv6 for user ::2 somehost.example.com 554s 06:34:07.871979675 O: test IP6 negated for user ::3 somehost 554s 06:34:07.896852706 O: test IP6 no match for user ::4 somehost 554s 06:34:07.923747299 O: test IP6 network for user 2000::1 somehost 554s 06:34:07.951421172 O: test IP6 network for user 2001::1 somehost 555s 06:34:07.983429234 O: test IP6 localaddress for user ::5 somehost 555s 06:34:07.998890119 O: test IP6 localport for user ::5 somehost 555s 06:34:08.025153206 O: test invalid Match address 10.0.1.0/8 555s 06:34:08.044825161 O: test invalid Match localaddress 10.0.1.0/8 555s 06:34:08.057861169 O: test invalid Match address 10.0.0.1/24 555s 06:34:08.072858290 O: test invalid Match localaddress 10.0.0.1/24 555s 06:34:08.088737462 O: test invalid Match address 2000:aa:bb:01::/56 555s 06:34:08.104224783 O: test invalid Match localaddress 2000:aa:bb:01::/56 555s 06:34:08.122361863 O: ok address match 555s 06:34:08.124105437 E: run test localcommand.sh ... 555s 06:34:08.309559731 O: test localcommand: proto localcommand 555s 06:34:08.434889628 O: ok localcommand 555s 06:34:08.439597622 E: run test forcecommand.sh ... 556s 06:34:09.107079197 E: Connection closed. 556s 06:34:09.108559447 E: Connection closed 556s 06:34:09.338535110 E: Connection closed. 556s 06:34:09.341142522 E: Connection closed 556s 06:34:09.475455493 E: run test portnum.sh ... 556s 06:34:09.475390394 O: ok forced command 556s 06:34:09.611148147 O: port number parsing: invalid port 0 556s 06:34:09.626283532 O: port number parsing: invalid port 65536 556s 06:34:09.646030676 O: port number parsing: invalid port 131073 556s 06:34:09.655624518 O: port number parsing: invalid port 2000blah 556s 06:34:09.663672972 O: port number parsing: invalid port blah2000 556s 06:34:09.671837616 O: port number parsing: valid port 1 556s 06:34:09.808487214 O: port number parsing: valid port 22 556s 06:34:09.954305855 O: port number parsing: valid port 2222 557s 06:34:10.093086039 O: port number parsing: valid port 22222 557s 06:34:10.245067247 O: port number parsing: valid port 65535 557s 06:34:10.366350683 O: ok port number parsing 557s 06:34:10.367741613 E: run test keytype.sh ... 557s 06:34:10.506439532 O: keygen ed25519, 512 bits 557s 06:34:10.518211752 O: keygen ed25519-sk, n/a bits 557s 06:34:10.530778060 O: keygen ecdsa, 256 bits 557s 06:34:10.539449529 O: keygen ecdsa, 384 bits 557s 06:34:10.551128746 O: keygen ecdsa, 521 bits 557s 06:34:10.559716280 O: keygen ecdsa-sk, n/a bits 557s 06:34:10.572466631 O: keygen rsa, 2048 bits 557s 06:34:10.715479785 O: keygen rsa, 3072 bits 557s 06:34:10.766708569 O: userkey ed25519-512, hostkey ed25519-512 557s 06:34:10.865210192 O: userkey ed25519-512, hostkey ed25519-512 557s 06:34:10.968473423 O: userkey ed25519-512, hostkey ed25519-512 558s 06:34:11.074568030 O: userkey ed25519-sk, hostkey ed25519-sk 558s 06:34:11.176623609 O: userkey ed25519-sk, hostkey ed25519-sk 558s 06:34:11.287603065 O: userkey ed25519-sk, hostkey ed25519-sk 558s 06:34:11.403013461 O: userkey ecdsa-256, hostkey ecdsa-256 558s 06:34:11.502152663 O: userkey ecdsa-256, hostkey ecdsa-256 558s 06:34:11.600957572 O: userkey ecdsa-256, hostkey ecdsa-256 558s 06:34:11.727030548 O: userkey ecdsa-384, hostkey ecdsa-384 558s 06:34:11.853249609 O: userkey ecdsa-384, hostkey ecdsa-384 558s 06:34:11.956096924 O: userkey ecdsa-384, hostkey ecdsa-384 559s 06:34:12.075448384 O: userkey ecdsa-521, hostkey ecdsa-521 559s 06:34:12.172176859 O: userkey ecdsa-521, hostkey ecdsa-521 559s 06:34:12.262650261 O: userkey ecdsa-521, hostkey ecdsa-521 559s 06:34:12.359285136 O: userkey ecdsa-sk, hostkey ecdsa-sk 559s 06:34:12.453419543 O: userkey ecdsa-sk, hostkey ecdsa-sk 559s 06:34:12.547318497 O: userkey ecdsa-sk, hostkey ecdsa-sk 559s 06:34:12.645977289 O: userkey rsa-2048, hostkey rsa-2048 559s 06:34:12.752008573 O: userkey rsa-2048, hostkey rsa-2048 559s 06:34:12.860578167 O: userkey rsa-2048, hostkey rsa-2048 559s 06:34:12.969124051 O: userkey rsa-3072, hostkey rsa-3072 560s 06:34:13.077363495 O: userkey rsa-3072, hostkey rsa-3072 560s 06:34:13.171966043 O: userkey rsa-3072, hostkey rsa-3072 560s 06:34:13.272401274 E: run test kextype.sh ... 560s 06:34:13.274267319 O: ok login with different key types 560s 06:34:13.468452720 O: kex diffie-hellman-group1-sha1 560s 06:34:13.844064126 O: kex diffie-hellman-group14-sha1 561s 06:34:14.211762510 O: kex diffie-hellman-group14-sha256 561s 06:34:14.629215008 O: kex diffie-hellman-group16-sha512 562s 06:34:15.141489632 O: kex diffie-hellman-group18-sha512 563s 06:34:16.061400946 O: kex diffie-hellman-group-exchange-sha1 563s 06:34:16.890454781 O: kex diffie-hellman-group-exchange-sha256 564s 06:34:17.805299566 O: kex ecdh-sha2-nistp256 565s 06:34:18.229448272 O: kex ecdh-sha2-nistp384 565s 06:34:18.605192634 O: kex ecdh-sha2-nistp521 566s 06:34:19.017564272 O: kex curve25519-sha256 566s 06:34:19.457113797 O: kex curve25519-sha256@libssh.org 566s 06:34:19.928954859 O: kex sntrup761x25519-sha512 567s 06:34:20.386890677 O: kex sntrup761x25519-sha512@openssh.com 567s 06:34:20.844906331 O: kex mlkem768x25519-sha256 568s 06:34:21.328060851 E: run test cert-hostkey.sh ... 568s 06:34:21.328896673 O: ok login with different key exchange algorithms 568s 06:34:21.596527404 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/host_ca_key.pub 568s 06:34:21.597624731 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/host_ca_key2.pub 568s 06:34:21.598613397 O: certified host keys: sign host ed25519 cert 568s 06:34:21.607408290 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 568s 06:34:21.622492252 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 568s 06:34:21.625740940 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 568s 06:34:21.640111746 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 568s 06:34:21.653148439 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 568s 06:34:21.656344853 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 568s 06:34:21.665306602 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 568s 06:34:21.680532121 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 568s 06:34:21.683343050 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 568s 06:34:21.692142328 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 568s 06:34:21.708083048 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 568s 06:34:21.711929672 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 568s 06:34:21.722253844 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 568s 06:34:21.735642461 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 568s 06:34:21.739586991 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 568s 06:34:21.755766213 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 568s 06:34:21.767980211 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 568s 06:34:21.770665724 O: certified host keys: sign host rsa cert 568s 06:34:21.919251371 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 568s 06:34:21.932781366 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 568s 06:34:21.935750202 O: certified host keys: sign host rsa-sha2-256 cert 569s 06:34:22.317831681 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 569s 06:34:22.331682672 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 569s 06:34:22.334729840 O: certified host keys: sign host rsa-sha2-512 cert 569s 06:34:22.431649164 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 569s 06:34:22.446900840 O: Revoking from /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 569s 06:34:22.450124704 O: certified host keys: host ed25519 cert connect 569s 06:34:22.452908080 O: certified host keys: ed25519 basic connect expect success yes 569s 06:34:22.584925072 O: certified host keys: ed25519 empty KRL expect success yes 569s 06:34:22.716389538 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 569s 06:34:22.806208487 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 569s 06:34:22.902151820 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 570s 06:34:22.994286033 O: certified host keys: ed25519 empty plaintext revocation expect success yes 570s 06:34:23.131310341 O: certified host keys: ed25519 plain key plaintext revocation expect success no 570s 06:34:23.209399642 O: certified host keys: ed25519 cert plaintext revocation expect success no 570s 06:34:23.288230048 O: certified host keys: ed25519 CA plaintext revocation expect success no 570s 06:34:23.372953863 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 570s 06:34:23.375738560 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 570s 06:34:23.530562687 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 570s 06:34:23.672910150 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 570s 06:34:23.768265754 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 570s 06:34:23.849636251 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 570s 06:34:23.931022038 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 571s 06:34:24.052574269 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 571s 06:34:24.132382741 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 571s 06:34:24.221978236 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 571s 06:34:24.303644538 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 571s 06:34:24.305433220 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 571s 06:34:24.431687742 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 571s 06:34:24.565605144 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 571s 06:34:24.641705365 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 571s 06:34:24.717561851 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 571s 06:34:24.793109648 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 571s 06:34:24.928164374 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 572s 06:34:25.017133994 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 572s 06:34:25.116478009 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 572s 06:34:25.205188821 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 572s 06:34:25.207848800 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 572s 06:34:25.342374436 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 572s 06:34:25.485513762 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 572s 06:34:25.593152673 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 572s 06:34:25.690714990 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 572s 06:34:25.806342757 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 572s 06:34:25.961597108 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 573s 06:34:26.063000468 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 573s 06:34:26.166938569 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 573s 06:34:26.259156963 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 573s 06:34:26.260687024 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 573s 06:34:26.431484828 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 573s 06:34:26.565367477 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 573s 06:34:26.654836361 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 573s 06:34:26.771690088 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 573s 06:34:26.886724882 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 574s 06:34:27.049452492 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 574s 06:34:27.158917055 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 574s 06:34:27.269987630 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 574s 06:34:27.372446528 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 574s 06:34:27.374367217 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 574s 06:34:27.506615652 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 574s 06:34:27.651475662 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 574s 06:34:27.745398483 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 574s 06:34:27.838665059 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 574s 06:34:27.921965589 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 575s 06:34:28.048137304 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 575s 06:34:28.131695658 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 575s 06:34:28.236377827 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 575s 06:34:28.325805356 O: certified host keys: host rsa cert connect 575s 06:34:28.328756294 O: certified host keys: rsa basic connect expect success yes 575s 06:34:28.461090140 O: certified host keys: rsa empty KRL expect success yes 575s 06:34:28.594693099 O: certified host keys: rsa KRL w/ plain key revoked expect success no 575s 06:34:28.678818486 O: certified host keys: rsa KRL w/ cert revoked expect success no 575s 06:34:28.779899549 O: certified host keys: rsa KRL w/ CA revoked expect success no 575s 06:34:28.879800128 O: certified host keys: rsa empty plaintext revocation expect success yes 576s 06:34:29.029964159 O: certified host keys: rsa plain key plaintext revocation expect success no 576s 06:34:29.120968489 O: certified host keys: rsa cert plaintext revocation expect success no 576s 06:34:29.248316562 O: certified host keys: rsa CA plaintext revocation expect success no 576s 06:34:29.350333687 O: certified host keys: host rsa-sha2-256 cert connect 576s 06:34:29.352364269 O: certified host keys: rsa-sha2-256 basic connect expect success yes 576s 06:34:29.503746410 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 576s 06:34:29.656828906 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 576s 06:34:29.762216333 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 576s 06:34:29.845573419 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 576s 06:34:29.930284123 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 577s 06:34:30.051797283 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 577s 06:34:30.158056854 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 577s 06:34:30.275560151 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 577s 06:34:30.362636689 O: certified host keys: host rsa-sha2-512 cert connect 577s 06:34:30.364947149 O: certified host keys: rsa-sha2-512 basic connect expect success yes 577s 06:34:30.484708111 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 577s 06:34:30.605908738 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 577s 06:34:30.686392439 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 577s 06:34:30.774714679 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 577s 06:34:30.852465687 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 578s 06:34:30.973385520 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 578s 06:34:31.053244630 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 578s 06:34:31.149245261 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 578s 06:34:31.233431439 O: certified host keys: host ed25519 revoked cert 578s 06:34:31.322591547 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 578s 06:34:31.400201685 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 578s 06:34:31.476708301 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 578s 06:34:31.556743950 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 578s 06:34:31.636670552 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 578s 06:34:31.716881741 O: certified host keys: host rsa revoked cert 578s 06:34:31.800445066 O: certified host keys: host rsa-sha2-256 revoked cert 578s 06:34:31.878645016 O: certified host keys: host rsa-sha2-512 revoked cert 578s 06:34:31.960300781 O: certified host keys: host ed25519 revoked cert 579s 06:34:32.036936380 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 579s 06:34:32.118764555 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 579s 06:34:32.192825163 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 579s 06:34:32.271352020 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 579s 06:34:32.350160838 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 579s 06:34:32.432820922 O: certified host keys: host rsa revoked cert 579s 06:34:32.519030249 O: certified host keys: host rsa-sha2-256 revoked cert 579s 06:34:32.595544547 O: certified host keys: host rsa-sha2-512 revoked cert 586s 06:34:39.207140860 O: certified host keys: host ed25519 cert downgrade to raw key 586s 06:34:39.526457547 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 586s 06:34:39.858674240 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 587s 06:34:40.120794945 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 587s 06:34:40.425546319 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 587s 06:34:40.793049835 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 588s 06:34:41.142275424 O: certified host keys: host rsa cert downgrade to raw key 588s 06:34:41.624055292 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 589s 06:34:42.142464973 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 590s 06:34:43.153867877 O: certified host keys: host ed25519 connect wrong cert 590s 06:34:43.239899039 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 590s 06:34:43.351280219 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 590s 06:34:43.427768778 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 590s 06:34:43.512823141 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 590s 06:34:43.594081694 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 590s 06:34:43.686504973 O: certified host keys: host rsa connect wrong cert 590s 06:34:43.933081996 O: certified host keys: host rsa-sha2-256 connect wrong cert 591s 06:34:44.101850048 O: certified host keys: host rsa-sha2-512 connect wrong cert 591s 06:34:44.417860366 O: ok certified host keys 591s 06:34:44.419432225 E: run test cert-userkey.sh ... 591s 06:34:44.829912731 O: certified user keys: sign user ed25519 cert 591s 06:34:44.843709893 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 591s 06:34:44.862102043 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 591s 06:34:44.875465037 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 591s 06:34:44.889808954 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 591s 06:34:44.905772683 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 591s 06:34:44.925692827 O: certified user keys: sign user rsa cert 592s 06:34:45.131118499 O: certified user keys: sign user rsa-sha2-256 cert 592s 06:34:45.217646414 O: certified user keys: sign user rsa-sha2-512 cert 592s 06:34:45.328729189 O: certified user keys: ed25519 missing authorized_principals 592s 06:34:45.451324783 O: certified user keys: ed25519 empty authorized_principals 592s 06:34:45.568937645 O: certified user keys: ed25519 wrong authorized_principals 592s 06:34:45.684293311 O: certified user keys: ed25519 correct authorized_principals 592s 06:34:45.812655655 O: certified user keys: ed25519 authorized_principals bad key opt 592s 06:34:45.933480516 O: certified user keys: ed25519 authorized_principals command=false 593s 06:34:46.058233544 O: certified user keys: ed25519 authorized_principals command=true 593s 06:34:46.189629147 O: certified user keys: ed25519 wrong principals key option 593s 06:34:46.300143632 O: certified user keys: ed25519 correct principals key option 593s 06:34:46.425885635 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 593s 06:34:46.569565944 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 593s 06:34:46.707891245 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 593s 06:34:46.847835263 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 594s 06:34:46.997485879 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 594s 06:34:47.132615772 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 594s 06:34:47.268203468 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 594s 06:34:47.405873369 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 594s 06:34:47.571870347 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 594s 06:34:47.719188307 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 594s 06:34:47.862547822 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 595s 06:34:47.996973841 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 595s 06:34:48.123973794 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 595s 06:34:48.262711811 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 595s 06:34:48.388012694 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 595s 06:34:48.517036947 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 595s 06:34:48.645834173 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 595s 06:34:48.751249916 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 595s 06:34:48.901253616 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 596s 06:34:49.042974456 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 596s 06:34:49.157547613 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 596s 06:34:49.280808838 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 596s 06:34:49.398757219 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 596s 06:34:49.515083905 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 596s 06:34:49.634769462 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 596s 06:34:49.763853161 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 596s 06:34:49.883069048 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 597s 06:34:50.007401841 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 597s 06:34:50.113034907 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 597s 06:34:50.222703179 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 597s 06:34:50.333071219 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 597s 06:34:50.452981712 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 597s 06:34:50.571601432 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 597s 06:34:50.687344648 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 597s 06:34:50.805522646 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 597s 06:34:50.930837963 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 598s 06:34:51.073275349 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 598s 06:34:51.195589326 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 598s 06:34:51.313044650 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 598s 06:34:51.443744114 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 598s 06:34:51.577083109 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 598s 06:34:51.700492228 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 598s 06:34:51.829821525 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 598s 06:34:51.952699020 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 599s 06:34:52.075347247 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 599s 06:34:52.212849301 O: certified user keys: rsa missing authorized_principals 599s 06:34:52.329187500 O: certified user keys: rsa empty authorized_principals 599s 06:34:52.444983473 O: certified user keys: rsa wrong authorized_principals 599s 06:34:52.572524601 O: certified user keys: rsa correct authorized_principals 599s 06:34:52.705208993 O: certified user keys: rsa authorized_principals bad key opt 599s 06:34:52.822189346 O: certified user keys: rsa authorized_principals command=false 599s 06:34:52.964092191 O: certified user keys: rsa authorized_principals command=true 600s 06:34:53.119621659 O: certified user keys: rsa wrong principals key option 600s 06:34:53.246016598 O: certified user keys: rsa correct principals key option 600s 06:34:53.409887154 O: certified user keys: rsa-sha2-256 missing authorized_principals 600s 06:34:53.532488022 O: certified user keys: rsa-sha2-256 empty authorized_principals 600s 06:34:53.640181706 O: certified user keys: rsa-sha2-256 wrong authorized_principals 600s 06:34:53.749000498 O: certified user keys: rsa-sha2-256 correct authorized_principals 600s 06:34:53.866232957 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 600s 06:34:53.967734751 O: certified user keys: rsa-sha2-256 authorized_principals command=false 601s 06:34:54.103903671 O: certified user keys: rsa-sha2-256 authorized_principals command=true 601s 06:34:54.226628432 O: certified user keys: rsa-sha2-256 wrong principals key option 601s 06:34:54.339745130 O: certified user keys: rsa-sha2-256 correct principals key option 601s 06:34:54.470805119 O: certified user keys: rsa-sha2-512 missing authorized_principals 601s 06:34:54.590706961 O: certified user keys: rsa-sha2-512 empty authorized_principals 601s 06:34:54.704548941 O: certified user keys: rsa-sha2-512 wrong authorized_principals 601s 06:34:54.816865970 O: certified user keys: rsa-sha2-512 correct authorized_principals 601s 06:34:54.941978354 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 602s 06:34:55.057132969 O: certified user keys: rsa-sha2-512 authorized_principals command=false 602s 06:34:55.197994971 O: certified user keys: rsa-sha2-512 authorized_principals command=true 602s 06:34:55.339192853 O: certified user keys: rsa-sha2-512 wrong principals key option 602s 06:34:55.464295446 O: certified user keys: rsa-sha2-512 correct principals key option 602s 06:34:55.613201331 O: certified user keys: ed25519 authorized_keys connect 602s 06:34:55.781113035 O: certified user keys: ed25519 authorized_keys revoked key 602s 06:34:55.942342486 O: certified user keys: ed25519 authorized_keys revoked via KRL 603s 06:34:56.081866029 O: certified user keys: ed25519 authorized_keys empty KRL 603s 06:34:56.226004270 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 603s 06:34:56.370196779 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 603s 06:34:56.496040671 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 603s 06:34:56.615697559 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 603s 06:34:56.743726765 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 603s 06:34:56.879511476 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 604s 06:34:57.003356308 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 604s 06:34:57.116861058 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 604s 06:34:57.247342111 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 604s 06:34:57.376964626 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 604s 06:34:57.496092782 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 604s 06:34:57.612071668 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 604s 06:34:57.735620874 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 604s 06:34:57.850246230 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 605s 06:34:57.978212619 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 605s 06:34:58.105040844 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 605s 06:34:58.247097245 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 605s 06:34:58.377504255 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 605s 06:34:58.507193728 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 605s 06:34:58.641654591 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 605s 06:34:58.792544917 O: certified user keys: rsa authorized_keys connect 605s 06:34:58.943942651 O: certified user keys: rsa authorized_keys revoked key 606s 06:34:59.052837639 O: certified user keys: rsa authorized_keys revoked via KRL 606s 06:34:59.171358916 O: certified user keys: rsa authorized_keys empty KRL 606s 06:34:59.305135041 O: certified user keys: rsa-sha2-256 authorized_keys connect 606s 06:34:59.433643574 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 606s 06:34:59.552900724 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 606s 06:34:59.671450230 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 606s 06:34:59.805634779 O: certified user keys: rsa-sha2-512 authorized_keys connect 606s 06:34:59.937081216 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 607s 06:35:00.048584389 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 607s 06:35:00.176241030 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 607s 06:35:00.321571360 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 607s 06:35:00.433620269 O: certified user keys: authorized_keys CA does not authenticate 607s 06:35:00.435517796 O: certified user keys: ensure CA key does not authenticate user 607s 06:35:00.548076063 O: certified user keys: ed25519 TrustedUserCAKeys connect 607s 06:35:00.686924929 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 607s 06:35:00.804937796 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 607s 06:35:00.928710419 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 608s 06:35:01.049450450 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 608s 06:35:01.178487495 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 608s 06:35:01.306013186 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 608s 06:35:01.420162809 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 608s 06:35:01.557026089 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 608s 06:35:01.696607951 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 608s 06:35:01.830353398 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 609s 06:35:01.977084117 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 609s 06:35:02.119830609 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 609s 06:35:02.268041154 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 609s 06:35:02.413721369 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 609s 06:35:02.579439781 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 609s 06:35:02.774502076 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 609s 06:35:02.939515276 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 610s 06:35:03.087496164 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 610s 06:35:03.260591325 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 610s 06:35:03.430481745 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 610s 06:35:03.611938645 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 610s 06:35:03.739361209 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 610s 06:35:03.880735031 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 611s 06:35:04.013461743 O: certified user keys: rsa TrustedUserCAKeys connect 611s 06:35:04.137984817 O: certified user keys: rsa TrustedUserCAKeys revoked key 611s 06:35:04.245023786 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 611s 06:35:04.374836032 O: certified user keys: rsa TrustedUserCAKeys empty KRL 611s 06:35:04.518639800 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 611s 06:35:04.645295396 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 611s 06:35:04.765458429 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 611s 06:35:04.880673227 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 612s 06:35:05.027574116 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 612s 06:35:05.149259366 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 612s 06:35:05.257832099 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 612s 06:35:05.367716550 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 612s 06:35:05.485202565 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 612s 06:35:05.592136513 O: certified user keys: TrustedUserCAKeys CA does not authenticate 612s 06:35:05.594148997 O: certified user keys: ensure CA key does not authenticate user 612s 06:35:05.698098939 O: certified user keys: correct principal auth authorized_keys expect success rsa 612s 06:35:05.867855234 O: certified user keys: correct principal auth authorized_keys expect success ed25519 613s 06:35:06.017715071 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 613s 06:35:06.172403420 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 613s 06:35:06.318332958 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 613s 06:35:06.436650351 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 613s 06:35:06.545609800 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 613s 06:35:06.648987558 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 613s 06:35:06.756403027 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 613s 06:35:06.870474818 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 614s 06:35:06.984963346 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 614s 06:35:07.101803949 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 614s 06:35:07.220846852 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 614s 06:35:07.356891452 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 614s 06:35:07.472223423 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 614s 06:35:07.589443639 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 614s 06:35:07.702224634 O: certified user keys: cert expired auth authorized_keys expect failure rsa 614s 06:35:07.825555210 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 614s 06:35:07.937598810 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 615s 06:35:08.050312493 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 615s 06:35:08.187792197 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 615s 06:35:08.317542608 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 615s 06:35:08.449802780 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 615s 06:35:08.577263093 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 615s 06:35:08.712837924 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 615s 06:35:08.827401014 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 615s 06:35:08.941810780 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 616s 06:35:09.051461049 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 616s 06:35:09.170338536 O: certified user keys: force-command auth authorized_keys expect failure rsa 616s 06:35:09.306466021 O: certified user keys: force-command auth authorized_keys expect failure ed25519 616s 06:35:09.447674095 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 616s 06:35:09.573889496 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 618s 06:35:09.703385924 O: certified user keys: empty principals auth authorized_keys expect success rsa 618s 06:35:09.844960375 O: certified user keys: empty principals auth authorized_keys expect success ed25519 618s 06:35:09.982605598 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 618s 06:35:10.096507519 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 618s 06:35:10.230354864 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 618s 06:35:10.356583546 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 618s 06:35:10.483739145 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 618s 06:35:10.601399634 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 618s 06:35:10.734678235 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 618s 06:35:10.885701790 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 618s 06:35:11.038894066 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 618s 06:35:11.178045041 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 618s 06:35:11.319541386 O: certified user keys: force-command match true auth authorized_keys expect success rsa 618s 06:35:11.458358995 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 618s 06:35:11.611854708 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 618s 06:35:11.763544574 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 618s 06:35:11.899631204 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 619s 06:35:12.053451504 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 619s 06:35:12.180913322 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 619s 06:35:12.338201277 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 619s 06:35:12.492567382 O: certified user keys: user ed25519 connect wrong cert 619s 06:35:12.630868552 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 619s 06:35:12.734315676 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 619s 06:35:12.859487867 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 620s 06:35:12.977969017 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 620s 06:35:13.077627336 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 620s 06:35:13.179217703 O: certified user keys: user rsa connect wrong cert 620s 06:35:13.292000619 O: certified user keys: user rsa-sha2-256 connect wrong cert 620s 06:35:13.420428160 O: certified user keys: user rsa-sha2-512 connect wrong cert 620s 06:35:13.539217042 O: ok certified user keys 620s 06:35:13.540341902 E: run test host-expand.sh ... 620s 06:35:13.760977823 E: run test keys-command.sh ... 620s 06:35:13.761919961 O: ok expand %h and %n 620s 06:35:13.910250059 O: SKIPPED: /var/run/keycommand_openssh-tests.43486 not executable (/var/run mounted noexec?) 620s 06:35:13.918551814 E: run test forward-control.sh ... 622s 06:35:15.365074078 O: check_lfwd done (expecting Y): default configuration 623s 06:35:16.694261456 O: check_rfwd done (expecting Y): default configuration 624s 06:35:17.026886888 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 624s 06:35:17.357907816 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 624s 06:35:17.505922732 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 625s 06:35:18.844760142 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 626s 06:35:19.210668223 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 627s 06:35:20.513119196 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 628s 06:35:21.661814412 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 630s 06:35:23.032200067 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 630s 06:35:23.375835229 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 630s 06:35:23.733758163 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 631s 06:35:24.859703173 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 632s 06:35:24.994282594 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 633s 06:35:26.158463964 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 633s 06:35:26.330928919 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 633s 06:35:26.679661549 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 634s 06:35:27.039258831 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 634s 06:35:27.374436167 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 634s 06:35:27.517712078 O: check_rfwd done (expecting N): AllowTcpForwarding=local 634s 06:35:27.671757712 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 634s 06:35:27.826386687 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 636s 06:35:29.181324741 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 636s 06:35:29.327884179 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 637s 06:35:30.477025148 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 637s 06:35:30.630037025 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 637s 06:35:30.930127869 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 638s 06:35:31.054808691 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 638s 06:35:31.197626234 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 638s 06:35:31.337545054 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 638s 06:35:31.474208053 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 638s 06:35:31.608382654 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 640s 06:35:32.989128489 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 640s 06:35:33.131402976 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 640s 06:35:33.273032952 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 641s 06:35:34.617912594 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 642s 06:35:35.765621238 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 644s 06:35:37.157754295 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 644s 06:35:37.323543438 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 644s 06:35:37.708633264 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 645s 06:35:38.877060390 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 647s 06:35:40.270483425 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 648s 06:35:41.432321862 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 649s 06:35:42.872132926 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 651s 06:35:44.033816071 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 651s 06:35:44.193233530 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 652s 06:35:45.346610474 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 652s 06:35:45.469462147 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 652s 06:35:45.632878513 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 652s 06:35:45.933059560 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 653s 06:35:46.059782346 O: check_lfwd done (expecting N): AllowTcpForwarding=no 653s 06:35:46.176622004 O: check_rfwd done (expecting N): AllowTcpForwarding=no 654s 06:35:47.326309873 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 654s 06:35:47.470160488 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 655s 06:35:48.629780833 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 655s 06:35:48.795903264 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 656s 06:35:49.946062359 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 657s 06:35:50.107845304 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 658s 06:35:51.253498755 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 658s 06:35:51.391521695 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 658s 06:35:51.524448243 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 658s 06:35:51.649439970 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 658s 06:35:51.785113859 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 658s 06:35:51.929466821 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 660s 06:35:53.079954203 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 660s 06:35:53.214263716 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 661s 06:35:54.576543815 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 661s 06:35:54.700201287 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 662s 06:35:54.994205150 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 663s 06:35:56.325836825 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 664s 06:35:57.629401352 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 664s 06:35:57.750532572 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 666s 06:35:59.032885791 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 666s 06:35:59.158401184 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 667s 06:36:00.297975638 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 667s 06:36:00.452718679 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 667s 06:36:00.628186549 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 669s 06:36:01.980377640 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 670s 06:36:03.148841024 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 670s 06:36:03.298204051 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 671s 06:36:04.464583761 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 671s 06:36:04.616525963 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 671s 06:36:04.643808390 E: run test integrity.sh ... 671s 06:36:04.643108340 O: ok sshd control of local and remote forwarding 671s 06:36:04.850513536 O: test integrity: hmac-sha1 @2900 672s 06:36:05.001694064 O: test integrity: hmac-sha1 @2901 672s 06:36:05.159435882 O: test integrity: hmac-sha1 @2902 672s 06:36:05.324263695 O: test integrity: hmac-sha1 @2903 672s 06:36:05.466489616 O: test integrity: hmac-sha1 @2904 672s 06:36:05.608558448 O: test integrity: hmac-sha1 @2905 672s 06:36:05.749839785 O: test integrity: hmac-sha1 @2906 672s 06:36:05.888964849 O: test integrity: hmac-sha1 @2907 673s 06:36:06.015512898 O: test integrity: hmac-sha1 @2908 673s 06:36:06.140000464 O: test integrity: hmac-sha1 @2909 673s 06:36:06.256298940 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 673s 06:36:06.269822175 O: test integrity: hmac-sha1-96 @2900 673s 06:36:06.403758348 O: test integrity: hmac-sha1-96 @2901 673s 06:36:06.544708930 O: test integrity: hmac-sha1-96 @2902 673s 06:36:06.685520949 O: test integrity: hmac-sha1-96 @2903 673s 06:36:06.836371982 O: test integrity: hmac-sha1-96 @2904 674s 06:36:06.984147069 O: test integrity: hmac-sha1-96 @2905 674s 06:36:07.128073569 O: test integrity: hmac-sha1-96 @2906 674s 06:36:07.270241216 O: test integrity: hmac-sha1-96 @2907 674s 06:36:07.407172106 O: test integrity: hmac-sha1-96 @2908 674s 06:36:07.530150581 O: test integrity: hmac-sha1-96 @2909 674s 06:36:07.673499150 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 674s 06:36:07.691825315 O: test integrity: hmac-sha2-256 @2900 674s 06:36:07.848285859 O: test integrity: hmac-sha2-256 @2901 675s 06:36:07.982684603 O: test integrity: hmac-sha2-256 @2902 675s 06:36:08.112548787 O: test integrity: hmac-sha2-256 @2903 675s 06:36:08.237365722 O: test integrity: hmac-sha2-256 @2904 675s 06:36:08.363640641 O: test integrity: hmac-sha2-256 @2905 675s 06:36:08.492281021 O: test integrity: hmac-sha2-256 @2906 675s 06:36:08.625457816 O: test integrity: hmac-sha2-256 @2907 675s 06:36:08.764738120 O: test integrity: hmac-sha2-256 @2908 675s 06:36:08.898600101 O: test integrity: hmac-sha2-256 @2909 676s 06:36:09.021629145 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 676s 06:36:09.036141008 O: test integrity: hmac-sha2-512 @2900 676s 06:36:09.133003920 O: test integrity: hmac-sha2-512 @2901 676s 06:36:09.222215480 O: test integrity: hmac-sha2-512 @2902 676s 06:36:09.313309707 O: test integrity: hmac-sha2-512 @2903 676s 06:36:09.405236846 O: test integrity: hmac-sha2-512 @2904 676s 06:36:09.496966846 O: test integrity: hmac-sha2-512 @2905 676s 06:36:09.590218955 O: test integrity: hmac-sha2-512 @2906 676s 06:36:09.680726619 O: test integrity: hmac-sha2-512 @2907 676s 06:36:09.775063700 O: test integrity: hmac-sha2-512 @2908 676s 06:36:09.890632777 O: test integrity: hmac-sha2-512 @2909 677s 06:36:09.987611695 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 677s 06:36:10.000766693 O: test integrity: hmac-md5 @2900 677s 06:36:10.166239517 O: test integrity: hmac-md5 @2901 677s 06:36:10.307476730 O: test integrity: hmac-md5 @2902 677s 06:36:10.449328044 O: test integrity: hmac-md5 @2903 677s 06:36:10.600993118 O: test integrity: hmac-md5 @2904 677s 06:36:10.744728438 O: test integrity: hmac-md5 @2905 677s 06:36:10.883989860 O: test integrity: hmac-md5 @2906 678s 06:36:11.030464790 O: test integrity: hmac-md5 @2907 678s 06:36:11.187290974 O: test integrity: hmac-md5 @2908 678s 06:36:11.347285048 O: test integrity: hmac-md5 @2909 678s 06:36:11.523437827 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 678s 06:36:11.543244497 O: test integrity: hmac-md5-96 @2900 678s 06:36:11.715085506 O: test integrity: hmac-md5-96 @2901 678s 06:36:11.865410446 O: test integrity: hmac-md5-96 @2902 679s 06:36:11.994178375 O: test integrity: hmac-md5-96 @2903 679s 06:36:12.122649077 O: test integrity: hmac-md5-96 @2904 679s 06:36:12.246749165 O: test integrity: hmac-md5-96 @2905 679s 06:36:12.371673017 O: test integrity: hmac-md5-96 @2906 679s 06:36:12.493059693 O: test integrity: hmac-md5-96 @2907 679s 06:36:12.630075380 O: test integrity: hmac-md5-96 @2908 679s 06:36:12.767789625 O: test integrity: hmac-md5-96 @2909 679s 06:36:12.895284365 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 679s 06:36:12.912146155 O: test integrity: umac-64@openssh.com @2900 680s 06:36:13.051845578 O: test integrity: umac-64@openssh.com @2901 680s 06:36:13.187991580 O: test integrity: umac-64@openssh.com @2902 680s 06:36:13.330553724 O: test integrity: umac-64@openssh.com @2903 680s 06:36:13.452114202 O: test integrity: umac-64@openssh.com @2904 680s 06:36:13.589570276 O: test integrity: umac-64@openssh.com @2905 680s 06:36:13.721107913 O: test integrity: umac-64@openssh.com @2906 680s 06:36:13.843785940 O: test integrity: umac-64@openssh.com @2907 681s 06:36:13.967635304 O: test integrity: umac-64@openssh.com @2908 681s 06:36:14.090076217 O: test integrity: umac-64@openssh.com @2909 681s 06:36:14.205820933 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 681s 06:36:14.219175441 O: test integrity: umac-128@openssh.com @2900 681s 06:36:14.340570577 O: test integrity: umac-128@openssh.com @2901 681s 06:36:14.463295351 O: test integrity: umac-128@openssh.com @2902 681s 06:36:14.584419295 O: test integrity: umac-128@openssh.com @2903 681s 06:36:14.704442695 O: test integrity: umac-128@openssh.com @2904 681s 06:36:14.823818956 O: test integrity: umac-128@openssh.com @2905 681s 06:36:14.945471766 O: test integrity: umac-128@openssh.com @2906 682s 06:36:15.063841669 O: test integrity: umac-128@openssh.com @2907 682s 06:36:15.188659281 O: test integrity: umac-128@openssh.com @2908 682s 06:36:15.308579203 O: test integrity: umac-128@openssh.com @2909 682s 06:36:15.420832513 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 682s 06:36:15.433908255 O: test integrity: hmac-sha1-etm@openssh.com @2900 682s 06:36:15.559239335 O: test integrity: hmac-sha1-etm@openssh.com @2901 682s 06:36:15.682524105 O: test integrity: hmac-sha1-etm@openssh.com @2902 682s 06:36:15.806374836 O: test integrity: hmac-sha1-etm@openssh.com @2903 682s 06:36:15.931605624 O: test integrity: hmac-sha1-etm@openssh.com @2904 683s 06:36:16.063595216 O: test integrity: hmac-sha1-etm@openssh.com @2905 683s 06:36:16.218649500 O: test integrity: hmac-sha1-etm@openssh.com @2906 683s 06:36:16.372461498 O: test integrity: hmac-sha1-etm@openssh.com @2907 683s 06:36:16.511506332 O: test integrity: hmac-sha1-etm@openssh.com @2908 683s 06:36:16.655282115 O: test integrity: hmac-sha1-etm@openssh.com @2909 683s 06:36:16.794585880 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 683s 06:36:16.808656491 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 683s 06:36:16.958227233 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 684s 06:36:17.112312242 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 684s 06:36:17.247285855 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 684s 06:36:17.391753548 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 684s 06:36:17.535336798 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 684s 06:36:17.671261538 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 684s 06:36:17.816353283 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 684s 06:36:17.958214304 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 685s 06:36:18.102347644 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 685s 06:36:18.245107673 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 685s 06:36:18.258091203 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 685s 06:36:18.388680497 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 685s 06:36:18.506047267 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 685s 06:36:18.622238450 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 685s 06:36:18.736034649 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 685s 06:36:18.854780689 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 686s 06:36:18.977731998 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 686s 06:36:19.095472568 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 686s 06:36:19.223794446 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 686s 06:36:19.356630225 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 686s 06:36:19.472800623 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 686s 06:36:19.489181634 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 686s 06:36:19.596685796 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 686s 06:36:19.712412708 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 686s 06:36:19.826402228 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 686s 06:36:19.943893855 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 687s 06:36:20.052618267 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 687s 06:36:20.161208577 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 687s 06:36:20.271707713 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 687s 06:36:20.383987501 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 687s 06:36:20.496511613 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 687s 06:36:20.600645295 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 687s 06:36:20.618605980 O: test integrity: hmac-md5-etm@openssh.com @2900 687s 06:36:20.779819348 O: test integrity: hmac-md5-etm@openssh.com @2901 687s 06:36:20.941688523 O: test integrity: hmac-md5-etm@openssh.com @2902 688s 06:36:21.087117354 O: test integrity: hmac-md5-etm@openssh.com @2903 688s 06:36:21.232224541 O: test integrity: hmac-md5-etm@openssh.com @2904 688s 06:36:21.374584273 O: test integrity: hmac-md5-etm@openssh.com @2905 688s 06:36:21.522908742 O: test integrity: hmac-md5-etm@openssh.com @2906 688s 06:36:21.661667155 O: test integrity: hmac-md5-etm@openssh.com @2907 688s 06:36:21.810816187 O: test integrity: hmac-md5-etm@openssh.com @2908 688s 06:36:21.960560725 O: test integrity: hmac-md5-etm@openssh.com @2909 689s 06:36:22.071574720 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 689s 06:36:22.083589827 O: test integrity: hmac-md5-96-etm@openssh.com @2900 689s 06:36:22.201236229 O: test integrity: hmac-md5-96-etm@openssh.com @2901 689s 06:36:22.348135521 O: test integrity: hmac-md5-96-etm@openssh.com @2902 689s 06:36:22.521824084 O: test integrity: hmac-md5-96-etm@openssh.com @2903 689s 06:36:22.683072979 O: test integrity: hmac-md5-96-etm@openssh.com @2904 689s 06:36:22.850736146 O: test integrity: hmac-md5-96-etm@openssh.com @2905 690s 06:36:23.016473772 O: test integrity: hmac-md5-96-etm@openssh.com @2906 690s 06:36:23.177091273 O: test integrity: hmac-md5-96-etm@openssh.com @2907 690s 06:36:23.349223175 O: test integrity: hmac-md5-96-etm@openssh.com @2908 690s 06:36:23.500479095 O: test integrity: hmac-md5-96-etm@openssh.com @2909 690s 06:36:23.636191451 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 690s 06:36:23.652712746 O: test integrity: umac-64-etm@openssh.com @2900 690s 06:36:23.798261131 O: test integrity: umac-64-etm@openssh.com @2901 690s 06:36:23.939327158 O: test integrity: umac-64-etm@openssh.com @2902 691s 06:36:24.092089770 O: test integrity: umac-64-etm@openssh.com @2903 691s 06:36:24.276251120 O: test integrity: umac-64-etm@openssh.com @2904 691s 06:36:24.449921973 O: test integrity: umac-64-etm@openssh.com @2905 691s 06:36:24.617340852 O: test integrity: umac-64-etm@openssh.com @2906 691s 06:36:24.776773691 O: test integrity: umac-64-etm@openssh.com @2907 691s 06:36:24.922839294 O: test integrity: umac-64-etm@openssh.com @2908 692s 06:36:25.089693555 O: test integrity: umac-64-etm@openssh.com @2909 692s 06:36:25.242149761 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 692s 06:36:25.255790205 O: test integrity: umac-128-etm@openssh.com @2900 692s 06:36:25.397173531 O: test integrity: umac-128-etm@openssh.com @2901 692s 06:36:25.593349502 O: test integrity: umac-128-etm@openssh.com @2902 692s 06:36:25.737244891 O: test integrity: umac-128-etm@openssh.com @2903 692s 06:36:25.873319657 O: test integrity: umac-128-etm@openssh.com @2904 693s 06:36:26.001655870 O: test integrity: umac-128-etm@openssh.com @2905 693s 06:36:26.142207593 O: test integrity: umac-128-etm@openssh.com @2906 693s 06:36:26.291966119 O: test integrity: umac-128-etm@openssh.com @2907 693s 06:36:26.474068976 O: test integrity: umac-128-etm@openssh.com @2908 693s 06:36:26.618188299 O: test integrity: umac-128-etm@openssh.com @2909 693s 06:36:26.757160033 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 693s 06:36:26.773178872 O: test integrity: aes128-gcm@openssh.com @2900 693s 06:36:26.894986506 O: test integrity: aes128-gcm@openssh.com @2901 694s 06:36:27.018069843 O: test integrity: aes128-gcm@openssh.com @2902 694s 06:36:27.146395141 O: test integrity: aes128-gcm@openssh.com @2903 694s 06:36:27.255805351 O: test integrity: aes128-gcm@openssh.com @2904 694s 06:36:27.375104853 O: test integrity: aes128-gcm@openssh.com @2905 694s 06:36:27.487528059 O: test integrity: aes128-gcm@openssh.com @2906 694s 06:36:27.598854235 O: test integrity: aes128-gcm@openssh.com @2907 694s 06:36:27.750094570 O: test integrity: aes128-gcm@openssh.com @2908 694s 06:36:27.870177779 O: test integrity: aes128-gcm@openssh.com @2909 694s 06:36:27.955672626 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 694s 06:36:27.971186509 O: test integrity: aes256-gcm@openssh.com @2900 695s 06:36:28.065722816 O: test integrity: aes256-gcm@openssh.com @2901 695s 06:36:28.160756900 O: test integrity: aes256-gcm@openssh.com @2902 695s 06:36:28.248822368 O: test integrity: aes256-gcm@openssh.com @2903 695s 06:36:28.335303687 O: test integrity: aes256-gcm@openssh.com @2904 695s 06:36:28.426430136 O: test integrity: aes256-gcm@openssh.com @2905 695s 06:36:28.529696020 O: test integrity: aes256-gcm@openssh.com @2906 695s 06:36:28.629270020 O: test integrity: aes256-gcm@openssh.com @2907 695s 06:36:28.714782292 O: test integrity: aes256-gcm@openssh.com @2908 695s 06:36:28.802417923 O: test integrity: aes256-gcm@openssh.com @2909 695s 06:36:28.879996939 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 695s 06:36:28.894872167 O: test integrity: chacha20-poly1305@openssh.com @2900 696s 06:36:28.995333701 O: test integrity: chacha20-poly1305@openssh.com @2901 696s 06:36:29.109373313 O: test integrity: chacha20-poly1305@openssh.com @2902 696s 06:36:29.214884009 O: test integrity: chacha20-poly1305@openssh.com @2903 696s 06:36:29.319349576 O: test integrity: chacha20-poly1305@openssh.com @2904 696s 06:36:29.410735204 O: test integrity: chacha20-poly1305@openssh.com @2905 696s 06:36:29.503149365 O: test integrity: chacha20-poly1305@openssh.com @2906 696s 06:36:29.591395417 O: test integrity: chacha20-poly1305@openssh.com @2907 696s 06:36:29.680021985 O: test integrity: chacha20-poly1305@openssh.com @2908 698s 06:36:29.767637421 O: test integrity: chacha20-poly1305@openssh.com @2909 698s 06:36:29.850068585 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 698s 06:36:29.852016450 O: ok integrity 698s 06:36:29.853314412 E: run test krl.sh ... 698s 06:36:29.989948387 O: key revocation lists: generating test keys 698s 06:36:30.928572622 O: key revocation lists: generating KRLs 698s 06:36:31.033100901 O: key revocation lists: checking revocations for revoked keys 698s 06:36:31.454675174 O: key revocation lists: checking revocations for unrevoked keys 698s 06:36:31.927606917 O: key revocation lists: checking revocations for revoked certs 699s 06:36:32.694307274 O: key revocation lists: checking revocations for unrevoked certs 700s 06:36:33.448988472 O: key revocation lists: testing KRL update 701s 06:36:34.477424941 O: key revocation lists: checking revocations for revoked keys 701s 06:36:34.963789985 O: key revocation lists: checking revocations for unrevoked keys 702s 06:36:35.499515179 O: key revocation lists: checking revocations for revoked certs 703s 06:36:36.312455650 O: key revocation lists: checking revocations for unrevoked certs 704s 06:36:37.291996932 O: ok key revocation lists 704s 06:36:37.293608183 E: run test multipubkey.sh ... 705s 06:36:38.684028774 O: ok multiple pubkey 705s 06:36:38.685137833 E: run test limit-keytype.sh ... 706s 06:36:39.636722271 O: allow rsa,ed25519 707s 06:36:39.996959290 O: allow ed25519 707s 06:36:40.324572493 O: allow cert only 707s 06:36:40.635074731 O: match w/ no match 708s 06:36:40.976119366 O: match w/ matching 708s 06:36:41.413937451 E: run test hostkey-agent.sh ... 708s 06:36:41.414703030 O: ok restrict pubkey type 708s 06:36:41.848724012 O: key type ssh-ed25519 709s 06:36:41.976703129 O: key type sk-ssh-ed25519@openssh.com 709s 06:36:42.115304852 O: key type ecdsa-sha2-nistp256 709s 06:36:42.246206011 O: key type ecdsa-sha2-nistp384 709s 06:36:42.370939207 O: key type ecdsa-sha2-nistp521 709s 06:36:42.512931516 O: key type sk-ecdsa-sha2-nistp256@openssh.com 709s 06:36:42.636458547 O: key type ssh-rsa 709s 06:36:42.763491815 O: cert type ssh-ed25519-cert-v01@openssh.com 709s 06:36:42.918668717 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 710s 06:36:43.087724718 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 710s 06:36:43.263169872 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 710s 06:36:43.427953635 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 710s 06:36:43.598798357 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 710s 06:36:43.787240044 O: cert type ssh-rsa-cert-v01@openssh.com 710s 06:36:43.960812047 O: cert type rsa-sha2-256-cert-v01@openssh.com 711s 06:36:44.118205326 O: cert type rsa-sha2-512-cert-v01@openssh.com 711s 06:36:44.299397883 E: run test hostkey-rotate.sh ... 711s 06:36:44.299925413 O: ok hostkey agent 711s 06:36:44.936479267 O: learn hostkey with StrictHostKeyChecking=no 712s 06:36:45.093018409 O: learn additional hostkeys 712s 06:36:45.304997718 O: learn additional hostkeys, type=ssh-ed25519 712s 06:36:45.497095461 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 712s 06:36:45.675180346 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 712s 06:36:45.849767669 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 713s 06:36:46.001334185 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 713s 06:36:46.167400796 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 713s 06:36:46.318114506 O: learn additional hostkeys, type=ssh-rsa 713s 06:36:46.485555119 O: learn changed non-primary hostkey type=ssh-rsa 713s 06:36:46.767717907 O: learn new primary hostkey 713s 06:36:46.937760424 O: rotate primary hostkey 714s 06:36:47.114148035 O: check rotate primary hostkey 714s 06:36:47.269090004 O: ok hostkey rotate 714s 06:36:47.270175483 E: run test principals-command.sh ... 714s 06:36:47.854653794 O: SKIPPED: /var/run/principals_command_openssh-tests.59514 not executable (/var/run mounted noexec?) 714s 06:36:47.866957258 E: run test cert-file.sh ... 715s 06:36:48.067966173 O: identity cert with no plain public file 715s 06:36:48.205492362 O: CertificateFile with no plain public file 715s 06:36:48.359968869 O: plain keys 715s 06:36:48.498626236 O: untrusted cert 715s 06:36:48.631447937 O: good cert, bad key 715s 06:36:48.771561108 O: single trusted 715s 06:36:48.909093131 O: multiple trusted 716s 06:36:49.508581097 O: ok ssh with certificates 716s 06:36:49.509705842 E: run test cfginclude.sh ... 716s 06:36:49.655226549 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.665762176 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.675028612 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.683038774 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.691666172 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.700069928 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.708934353 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.717450925 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.725715584 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.743293020 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.750397517 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.758408561 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.771110053 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.779103586 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.787614106 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.795475524 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.803766832 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.811978009 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.820791181 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.829070190 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.837698487 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.856224711 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.864212349 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.872804782 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.886483335 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.894922194 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 716s 06:36:49.901092267 O: ok config include 716s 06:36:49.902166932 E: run test servcfginclude.sh ... 717s 06:36:50.299462879 O: ok server config include 717s 06:36:50.300968761 E: run test allow-deny-users.sh ... 718s 06:36:51.415674817 O: ok AllowUsers/DenyUsers 718s 06:36:51.416621662 E: run test authinfo.sh ... 718s 06:36:51.530700043 O: ExposeAuthInfo=no 718s 06:36:51.641388266 O: ExposeAuthInfo=yes 718s 06:36:51.749046989 E: run test sshsig.sh ... 718s 06:36:51.750101621 O: ok authinfo 718s 06:36:51.889254853 O: sshsig: make certificates 718s 06:36:51.921475395 O: sshsig: check signature for ssh-ed25519 719s 06:36:52.302204905 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 719s 06:36:52.723704879 O: sshsig: check signature for ecdsa-sha2-nistp256 720s 06:36:53.091881142 O: sshsig: check signature for ecdsa-sha2-nistp384 720s 06:36:53.496620088 O: sshsig: check signature for ecdsa-sha2-nistp521 720s 06:36:53.905376690 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 721s 06:36:54.253068863 O: sshsig: check signature for ssh-rsa 721s 06:36:54.589370625 O: sshsig: check signature for ssh-ed25519-cert.pub 722s 06:36:55.359378485 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 723s 06:36:56.220248400 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 724s 06:36:57.015139750 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 724s 06:36:57.855489685 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 725s 06:36:58.707266589 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 726s 06:36:59.603904026 O: sshsig: check signature for ssh-rsa-cert.pub 727s 06:37:00.372000839 O: sshsig: match principals 727s 06:37:00.401133401 O: sshsig: nomatch principals 727s 06:37:00.419671810 E: run test knownhosts.sh ... 727s 06:37:00.419742759 O: ok sshsig 728s 06:37:01.456377370 E: run test knownhosts-command.sh ... 728s 06:37:01.457279169 O: ok known hosts 728s 06:37:01.629199509 O: simple connection 728s 06:37:01.761396917 O: no keys 728s 06:37:01.834499369 O: bad exit status 728s 06:37:01.886000048 O: keytype ssh-ed25519 729s 06:37:02.028791118 O: keytype sk-ssh-ed25519@openssh.com 729s 06:37:02.180540980 O: keytype ecdsa-sha2-nistp256 729s 06:37:02.317790633 O: keytype ecdsa-sha2-nistp384 729s 06:37:02.436340025 O: keytype ecdsa-sha2-nistp521 729s 06:37:02.551647308 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 729s 06:37:02.691079703 O: keytype ssh-rsa 729s 06:37:02.821687350 O: ok known hosts command 729s 06:37:02.822333727 E: run test agent-restrict.sh ... 730s 06:37:03.004788466 O: generate keys 730s 06:37:03.073283925 O: prepare client config 730s 06:37:03.087239447 O: prepare known_hosts 730s 06:37:03.091733798 O: prepare server configs 730s 06:37:03.114712620 O: authentication w/o agent 731s 06:37:04.306834535 O: start agent 735s 06:37:08.313077392 O: authentication with agent (no restrict) 736s 06:37:09.560428743 O: unrestricted keylist 737s 06:37:10.304611037 O: authentication with agent (basic restrict) 737s 06:37:10.954891521 O: authentication with agent incorrect key (basic restrict) 738s 06:37:11.408359040 O: keylist (basic restrict) 739s 06:37:12.164081587 O: username 739s 06:37:12.756186382 O: username wildcard 740s 06:37:13.382438151 O: username incorrect 740s 06:37:13.477989108 O: agent restriction honours certificate principal 740s 06:37:13.529337501 O: multihop without agent 741s 06:37:14.222263291 O: multihop agent unrestricted 742s 06:37:14.993292067 O: multihop restricted 742s 06:37:15.777212173 O: multihop username 743s 06:37:16.473129536 O: multihop wildcard username 744s 06:37:17.159979834 O: multihop wrong username 744s 06:37:17.653262878 O: multihop cycle no agent 745s 06:37:18.679479094 O: multihop cycle agent unrestricted 746s 06:37:19.709048478 O: multihop cycle restricted deny 747s 06:37:20.058418749 O: multihop cycle restricted allow 748s 06:37:21.073579907 O: ok agent restrictions 748s 06:37:21.074729718 E: run test hostbased.sh ... 748s 06:37:21.222259495 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 748s 06:37:21.223759016 E: run test channel-timeout.sh ... 748s 06:37:21.385396482 O: no timeout 753s 06:37:26.538553943 O: command timeout 754s 06:37:27.171475114 O: command long timeout 754s 06:37:27.315673837 O: command wildcard timeout 755s 06:37:28.172367430 O: command irrelevant timeout 760s 06:37:33.312469186 O: multiplexed command timeout 771s 06:37:44.211105973 O: irrelevant multiplexed command timeout 786s 06:37:59.438642557 O: global command timeout 808s 06:38:21.234270330 O: sftp no timeout 813s 06:38:26.418467277 O: sftp timeout 814s 06:38:27.173098476 E: Connection closed 814s 06:38:27.175177346 O: sftp irrelevant timeout 819s 06:38:32.321976159 E: run test connection-timeout.sh ... 819s 06:38:32.323269641 O: ok channel timeout 819s 06:38:32.511601881 O: no timeout 825s 06:38:38.636131500 O: timeout 833s 06:38:46.738164945 O: session inhibits timeout 841s 06:38:54.878210822 O: timeout after session 849s 06:39:02.884140947 O: timeout with listeners 858s 06:39:10.997258822 O: ok unused connection timeout 858s 06:39:10.998721082 E: run test match-subsystem.sh ... 859s 06:39:12.237924567 O: ok sshd_config match subsystem 859s 06:39:12.239086686 E: run test agent-pkcs11-restrict.sh ... 859s 06:39:12.368049774 E: run test agent-pkcs11-cert.sh ... 859s 06:39:12.367948793 O: SKIPPED: No PKCS#11 library found 859s 06:39:12.515363134 O: SKIPPED: No PKCS#11 library found 859s 06:39:12.516371213 E: run test penalty.sh ... 859s 06:39:12.710767767 O: test connect 859s 06:39:12.929360085 O: penalty for authentication failure 866s 06:39:19.820416931 O: penalty for no authentication 868s 06:39:21.884608432 O: ok penalties 868s 06:39:21.886086319 E: run test penalty-expire.sh ... 869s 06:39:22.146398057 O: test connect 869s 06:39:22.378271502 O: penalty expiry 882s 06:39:35.819879033 O: ok penalties 882s 06:39:35.821363481 O: set -e ; if test -z "" ; then \ 882s 06:39:35.822870096 O: V="" ; \ 882s 06:39:35.824596054 O: test "x" = "x" || \ 882s 06:39:35.825981010 O: V=/tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 882s 06:39:35.827324234 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 882s 06:39:35.828939735 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 882s 06:39:35.830318540 O: -d /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 882s 06:39:35.831841487 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 882s 06:39:35.833216917 O: -d /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 882s 06:39:35.834581327 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 882s 06:39:35.835967056 O: -d /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 882s 06:39:35.837195493 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 882s 06:39:35.838552292 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 882s 06:39:35.839913620 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 882s 06:39:35.841148227 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 882s 06:39:35.842382264 O: -d /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 882s 06:39:35.843881515 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 882s 06:39:35.845044289 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 882s 06:39:35.846152253 O: if test "x" = "xyes" ; then \ 882s 06:39:35.847319886 O: $V /tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 882s 06:39:35.848741940 O: fi \ 882s 06:39:35.849989060 O: fi 893s 06:39:46.525247337 O: test_sshbuf: ...................................................................................................... 103 tests ok 947s 06:40:40.389182448 O: test_sshkey: .................................................................................... 84 tests ok 947s 06:40:40.398726476 O: test_sshsig: ....... 7 tests ok 947s 06:40:40.656183880 O: test_authopt: .................................................................................................................................................. 146 tests ok 959s 06:40:52.758231157 O: test_bitmap: .. 2 tests ok 959s 06:40:52.760514184 O: test_conversion: . 1 tests ok 964s 06:40:57.386183658 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 964s 06:40:57.429355597 O: test_hostkeys: .................. 18 tests ok 964s 06:40:57.432891131 O: test_match: ...... 6 tests ok 964s 06:40:57.436132084 O: test_misc: ........................................... 43 tests ok 964s 06:40:57.438344977 E: run test putty-transfer.sh ... 964s 06:40:57.884718837 O: plink version 0.83 major 0 minor 83 964s 06:40:57.893880809 O: putty transfer data: compression 0 966s 06:40:59.052209060 O: putty transfer data: compression 1 967s 06:41:00.298101145 O: ok putty transfer data 967s 06:41:00.299291475 E: run test putty-ciphers.sh ... 968s 06:41:01.460653652 O: plink version 0.83 major 0 minor 83 968s 06:41:01.967993822 O: putty ciphers: cipher default mac default 969s 06:41:02.098479119 O: putty ciphers: cipher default mac hmac-sha1 969s 06:41:02.223669472 O: putty ciphers: cipher default mac hmac-sha1-96 969s 06:41:02.351862962 O: putty ciphers: cipher default mac hmac-sha2-256 969s 06:41:02.484420994 O: putty ciphers: cipher default mac hmac-sha2-512 969s 06:41:02.611709756 O: putty ciphers: cipher default mac hmac-md5 969s 06:41:02.739987324 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 969s 06:41:02.877139562 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 970s 06:41:03.042610742 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 970s 06:41:03.186422409 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 970s 06:41:03.320974422 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 970s 06:41:03.454632393 O: putty ciphers: cipher 3des-cbc mac default 970s 06:41:03.594002533 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 970s 06:41:03.725876138 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 970s 06:41:03.855449522 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 971s 06:41:03.990711966 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 971s 06:41:04.122985862 O: putty ciphers: cipher 3des-cbc mac hmac-md5 971s 06:41:04.274053365 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 971s 06:41:04.411709953 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 971s 06:41:04.550937611 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 971s 06:41:04.697535958 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 971s 06:41:04.837580447 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 971s 06:41:04.976644021 O: putty ciphers: cipher aes128-cbc mac default 972s 06:41:05.114844419 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 972s 06:41:05.242278914 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 972s 06:41:05.371441396 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 972s 06:41:05.501733983 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 972s 06:41:05.631447766 O: putty ciphers: cipher aes128-cbc mac hmac-md5 972s 06:41:05.763806765 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 972s 06:41:05.894471144 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 973s 06:41:06.042987662 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 973s 06:41:06.211914175 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 973s 06:41:06.354699691 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 973s 06:41:06.483342265 O: putty ciphers: cipher aes192-cbc mac default 973s 06:41:06.617148739 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 973s 06:41:06.746118513 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 973s 06:41:06.875330642 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 974s 06:41:07.011208977 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 974s 06:41:07.143726739 O: putty ciphers: cipher aes192-cbc mac hmac-md5 974s 06:41:07.287516009 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 974s 06:41:07.417257337 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 974s 06:41:07.549161354 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 974s 06:41:07.688005660 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 974s 06:41:07.817198282 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 974s 06:41:07.950006098 O: putty ciphers: cipher aes256-cbc mac default 975s 06:41:08.083359284 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 975s 06:41:08.212518455 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 975s 06:41:08.342249380 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 975s 06:41:08.475330274 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 975s 06:41:08.604877367 O: putty ciphers: cipher aes256-cbc mac hmac-md5 975s 06:41:08.739081380 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 975s 06:41:08.871670891 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 976s 06:41:09.000227572 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 976s 06:41:09.139835765 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 976s 06:41:09.289394716 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 976s 06:41:09.427934767 O: putty ciphers: cipher aes128-ctr mac default 976s 06:41:09.573423196 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 976s 06:41:09.702238192 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 976s 06:41:09.832607922 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 976s 06:41:09.965245402 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 977s 06:41:10.092200514 O: putty ciphers: cipher aes128-ctr mac hmac-md5 977s 06:41:10.227626100 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 977s 06:41:10.361441706 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 977s 06:41:10.488338654 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 977s 06:41:10.628397982 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 977s 06:41:10.761719445 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 977s 06:41:10.896018880 O: putty ciphers: cipher aes192-ctr mac default 978s 06:41:11.032157871 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 978s 06:41:11.162837055 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 978s 06:41:11.293831351 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 978s 06:41:11.428740843 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 978s 06:41:11.562584263 O: putty ciphers: cipher aes192-ctr mac hmac-md5 978s 06:41:11.711201051 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 978s 06:41:11.849060189 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 979s 06:41:11.989914409 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 979s 06:41:12.129863710 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 979s 06:41:12.270796735 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 979s 06:41:12.400386051 O: putty ciphers: cipher aes256-ctr mac default 979s 06:41:12.535314120 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 979s 06:41:12.669528371 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 979s 06:41:12.808384639 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 979s 06:41:12.945079143 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 980s 06:41:13.088580768 O: putty ciphers: cipher aes256-ctr mac hmac-md5 980s 06:41:13.231291743 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 980s 06:41:13.371285020 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 980s 06:41:13.523344804 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 980s 06:41:13.670024040 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 980s 06:41:13.812571767 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 980s 06:41:13.942081156 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 981s 06:41:14.089716913 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 981s 06:41:14.235112569 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 981s 06:41:14.374658212 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 981s 06:41:14.512269932 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 981s 06:41:14.645784939 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 981s 06:41:14.786949279 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 981s 06:41:14.926827408 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 982s 06:41:15.064190496 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 982s 06:41:15.211601645 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 982s 06:41:15.348718654 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 982s 06:41:15.488165761 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 982s 06:41:15.637179388 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 982s 06:41:15.767221112 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 982s 06:41:15.913070597 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 983s 06:41:16.048508945 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 983s 06:41:16.180949903 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 983s 06:41:16.315404190 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 983s 06:41:16.449022254 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 983s 06:41:16.588708572 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 983s 06:41:16.727421353 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 983s 06:41:16.866250852 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 984s 06:41:16.998447798 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 984s 06:41:17.142780707 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 984s 06:41:17.277599518 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 984s 06:41:17.418376686 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 984s 06:41:17.551018866 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 984s 06:41:17.687498943 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 984s 06:41:17.854498641 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 985s 06:41:17.984208269 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 985s 06:41:18.121436861 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 985s 06:41:18.265045514 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 985s 06:41:18.399746511 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 985s 06:41:18.544966060 E: run test putty-kex.sh ... 985s 06:41:18.544685235 O: ok putty ciphers 986s 06:41:18.992349372 O: plink version 0.83 major 0 minor 83 986s 06:41:19.254442537 O: putty KEX: kex diffie-hellman-group1-sha1 986s 06:41:19.342010450 O: putty KEX: kex diffie-hellman-group14-sha1 986s 06:41:19.458467219 O: putty KEX: kex diffie-hellman-group14-sha256 986s 06:41:19.581293116 O: putty KEX: kex diffie-hellman-group16-sha512 986s 06:41:19.897210016 O: putty KEX: kex diffie-hellman-group18-sha512 988s 06:41:21.541020642 O: putty KEX: kex diffie-hellman-group-exchange-sha1 988s 06:41:21.694744955 O: putty KEX: kex diffie-hellman-group-exchange-sha256 989s 06:41:22.122643634 O: putty KEX: kex ecdh-sha2-nistp256 989s 06:41:22.213416776 O: putty KEX: kex ecdh-sha2-nistp384 989s 06:41:22.327125911 O: putty KEX: kex ecdh-sha2-nistp521 989s 06:41:22.439901076 O: putty KEX: kex curve25519-sha256 989s 06:41:22.534492305 O: putty KEX: kex curve25519-sha256@libssh.org 989s 06:41:22.637769039 O: putty KEX: kex sntrup761x25519-sha512 989s 06:41:22.755633250 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 989s 06:41:22.870025096 O: putty KEX: kex mlkem768x25519-sha256 989s 06:41:22.966609726 O: ok putty KEX 989s 06:41:22.967665100 E: run test conch-ciphers.sh ... 990s 06:41:23.167868751 O: conch ciphers: cipher aes256-ctr 990s 06:41:23.854825678 O: conch ciphers: cipher aes256-cbc 991s 06:41:24.471632980 O: conch ciphers: cipher aes192-ctr 992s 06:41:25.137604485 O: conch ciphers: cipher aes192-cbc 992s 06:41:25.779413403 O: conch ciphers: cipher aes128-ctr 993s 06:41:26.433572169 O: conch ciphers: cipher aes128-cbc 994s 06:41:27.080463626 O: conch ciphers: cipher cast128-cbc 994s 06:41:27.712916675 O: conch ciphers: cipher blowfish 995s 06:41:28.285023437 O: conch ciphers: cipher 3des-cbc 995s 06:41:28.884951223 O: ok conch ciphers 995s 06:41:28.884919555 E: run test dropbear-ciphers.sh ... 996s 06:41:29.015568240 O: Create dropbear key type ed25519 996s 06:41:29.030209196 O: Create dropbear key type rsa 996s 06:41:29.395464380 O: Create dropbear key type ecdsa 996s 06:41:29.440379107 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 996s 06:41:29.666689524 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 996s 06:41:29.866365083 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 997s 06:41:30.074957310 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 997s 06:41:30.272474619 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 997s 06:41:30.482476278 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 997s 06:41:30.690373771 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 997s 06:41:30.876622327 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 998s 06:41:31.085976064 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 998s 06:41:31.282956969 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 998s 06:41:31.503556788 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 998s 06:41:31.709789433 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 998s 06:41:31.926661380 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 999s 06:41:32.140455877 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 999s 06:41:32.358174876 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 999s 06:41:32.570285976 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 999s 06:41:32.783717784 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1000s 06:41:32.991824080 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1000s 06:41:33.244010854 E: run test dropbear-kex.sh ... 1000s 06:41:33.243777527 O: ok dropbear ciphers 1000s 06:41:33.452586967 O: diffie-hellman-group14-sha1 1000s 06:41:33.453991022 O: dropbear kex: kex curve25519-sha256 1000s 06:41:33.654122526 O: dropbear kex: kex curve25519-sha256@libssh.org 1000s 06:41:33.850871216 O: dropbear kex: kex diffie-hellman-group14-sha256 1001s 06:41:34.067707763 O: dropbear kex: kex diffie-hellman-group14-sha1 1001s 06:41:34.284639164 O: ok dropbear kex 1001s 06:41:34.285586561 O: make: Leaving directory '/tmp/autopkgtest.ZBWHA9/autopkgtest_tmp/user/regress' 1001s 06:41:34.287624642 I: Finished with exitcode 0 1001s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1001s info: Looking for files to backup/remove ... 1001s info: Removing files ... 1001s info: Removing crontab ... 1001s info: Removing user `openssh-tests' ... 1002s autopkgtest [06:41:35]: test regress: -----------------------] 1002s autopkgtest [06:41:35]: test regress: - - - - - - - - - - results - - - - - - - - - - 1002s regress PASS 1003s autopkgtest [06:41:36]: test ssh-gssapi: preparing testbed 1113s autopkgtest [06:43:26]: testbed dpkg architecture: ppc64el 1113s autopkgtest [06:43:26]: testbed apt version: 2.9.30ubuntu1 1113s autopkgtest [06:43:26]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1113s autopkgtest [06:43:26]: testbed release detected to be: plucky 1114s autopkgtest [06:43:27]: updating testbed package index (apt update) 1115s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1115s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1115s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1115s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1115s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1115s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 1115s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 1115s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1115s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [127 kB] 1115s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [760 B] 1115s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [432 kB] 1116s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [3292 B] 1116s Fetched 1276 kB in 1s (1016 kB/s) 1117s Reading package lists... 1118s + lsb_release --codename --short 1118s + RELEASE=plucky 1118s + cat 1118s + [ plucky != trusty ] 1118s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 1118s Reading package lists... 1118s Building dependency tree... 1118s Reading state information... 1118s Calculating upgrade... 1118s Calculating upgrade... 1119s The following packages will be upgraded: 1119s curl libcurl3t64-gnutls libcurl4t64 1119s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1119s Need to get 1221 kB of archives. 1119s After this operation, 3072 B disk space will be freed. 1119s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el curl ppc64el 8.12.1-2ubuntu1 [258 kB] 1119s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl4t64 ppc64el 8.12.1-2ubuntu1 [482 kB] 1119s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl3t64-gnutls ppc64el 8.12.1-2ubuntu1 [481 kB] 1120s Fetched 1221 kB in 1s (1417 kB/s) 1120s (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 ... 106371 files and directories currently installed.) 1120s Preparing to unpack .../curl_8.12.1-2ubuntu1_ppc64el.deb ... 1120s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1120s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_ppc64el.deb ... 1120s Unpacking libcurl4t64:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1120s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_ppc64el.deb ... 1120s Unpacking libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1120s Setting up libcurl4t64:ppc64el (8.12.1-2ubuntu1) ... 1120s Setting up libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) ... 1120s Setting up curl (8.12.1-2ubuntu1) ... 1120s Processing triggers for man-db (2.13.0-1) ... 1121s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1121s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 1121s + /usr/lib/apt/apt-helper analyze-pattern ?true 1121s + + sed s/\./\\./g 1121s uname -r 1121s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 1121s + apt list ?obsolete 1121s + tail -n+2 1121s + cut -d/ -f1 1121s + grep -v ^linux-.*6\.12\.0-15-generic.* 1122s + true 1122s + obsolete_pkgs= 1122s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 1122s Reading package lists... 1122s Building dependency tree... 1122s Reading state information... 1123s Solving dependencies... 1123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1123s + grep -q trusty /etc/lsb-release 1123s + [ ! -d /usr/share/doc/unattended-upgrades ] 1123s + [ ! -d /usr/share/doc/lxd ] 1123s + [ ! -d /usr/share/doc/lxd-client ] 1123s + [ ! -d /usr/share/doc/snapd ] 1123s + type iptables 1123s + cat 1123s + chmod 755 /etc/rc.local 1123s + . /etc/rc.local 1123s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 1123s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 1123s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 1123s + uname -m 1123s + [ ppc64le = ppc64le ] 1123s + systemctl is-active keyboard-setup.service 1123s + [ active = failed ] 1123s + [ -d /run/systemd/system ] 1123s + systemd-detect-virt --quiet --vm 1123s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 1123s + cat 1123s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 1123s + echo COMPRESS=lz4 1123s autopkgtest [06:43:36]: upgrading testbed (apt dist-upgrade and autopurge) 1123s Reading package lists... 1124s Building dependency tree... 1124s Reading state information... 1124s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1124s Starting 2 pkgProblemResolver with broken count: 0 1124s Done 1125s Entering ResolveByKeep 1125s 1125s Calculating upgrade... 1125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1126s Reading package lists... 1126s Building dependency tree... 1126s Reading state information... 1127s Starting pkgProblemResolver with broken count: 0 1127s Starting 2 pkgProblemResolver with broken count: 0 1127s Done 1127s Solving dependencies... 1128s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1131s Reading package lists... 1131s Building dependency tree... 1131s Reading state information... 1132s Starting pkgProblemResolver with broken count: 0 1132s Starting 2 pkgProblemResolver with broken count: 0 1132s Done 1132s The following NEW packages will be installed: 1132s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1132s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1132s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1132s openssh-server-gssapi 1133s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1133s Need to get 884 kB of archives. 1133s After this operation, 4081 kB of additional disk space will be used. 1133s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el krb5-config all 2.7 [22.0 kB] 1133s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libgssrpc4t64 ppc64el 1.21.3-4ubuntu1 [65.1 kB] 1133s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libkadm5clnt-mit12 ppc64el 1.21.3-4ubuntu1 [44.0 kB] 1133s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libkdb5-10t64 ppc64el 1.21.3-4ubuntu1 [47.2 kB] 1133s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libkadm5srv-mit12 ppc64el 1.21.3-4ubuntu1 [61.5 kB] 1133s Get:6 http://ftpmaster.internal/ubuntu plucky/universe ppc64el krb5-user ppc64el 1.21.3-4ubuntu1 [116 kB] 1133s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el libevent-2.1-7t64 ppc64el 2.1.12-stable-10 [172 kB] 1133s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libverto1t64 ppc64el 0.3.1-1.2ubuntu3 [12.1 kB] 1133s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el libverto-libevent1t64 ppc64el 0.3.1-1.2ubuntu3 [6490 B] 1133s Get:10 http://ftpmaster.internal/ubuntu plucky/universe ppc64el krb5-kdc ppc64el 1.21.3-4ubuntu1 [221 kB] 1133s Get:11 http://ftpmaster.internal/ubuntu plucky/universe ppc64el krb5-admin-server ppc64el 1.21.3-4ubuntu1 [107 kB] 1133s Get:12 http://ftpmaster.internal/ubuntu plucky/universe ppc64el openssh-client-gssapi all 1:9.9p1-3ubuntu2 [5028 B] 1133s Get:13 http://ftpmaster.internal/ubuntu plucky/universe ppc64el openssh-server-gssapi all 1:9.9p1-3ubuntu2 [5036 B] 1134s Preconfiguring packages ... 1134s Fetched 884 kB in 1s (1294 kB/s) 1134s Selecting previously unselected package krb5-config. 1134s (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 ... 106371 files and directories currently installed.) 1134s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1134s Unpacking krb5-config (2.7) ... 1134s Selecting previously unselected package libgssrpc4t64:ppc64el. 1134s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu1_ppc64el.deb ... 1134s Unpacking libgssrpc4t64:ppc64el (1.21.3-4ubuntu1) ... 1134s Selecting previously unselected package libkadm5clnt-mit12:ppc64el. 1134s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu1_ppc64el.deb ... 1134s Unpacking libkadm5clnt-mit12:ppc64el (1.21.3-4ubuntu1) ... 1134s Selecting previously unselected package libkdb5-10t64:ppc64el. 1134s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu1_ppc64el.deb ... 1134s Unpacking libkdb5-10t64:ppc64el (1.21.3-4ubuntu1) ... 1134s Selecting previously unselected package libkadm5srv-mit12:ppc64el. 1134s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu1_ppc64el.deb ... 1134s Unpacking libkadm5srv-mit12:ppc64el (1.21.3-4ubuntu1) ... 1134s Selecting previously unselected package krb5-user. 1134s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu1_ppc64el.deb ... 1134s Unpacking krb5-user (1.21.3-4ubuntu1) ... 1134s Selecting previously unselected package libevent-2.1-7t64:ppc64el. 1134s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_ppc64el.deb ... 1134s Unpacking libevent-2.1-7t64:ppc64el (2.1.12-stable-10) ... 1134s Selecting previously unselected package libverto1t64:ppc64el. 1134s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_ppc64el.deb ... 1134s Unpacking libverto1t64:ppc64el (0.3.1-1.2ubuntu3) ... 1134s Selecting previously unselected package libverto-libevent1t64:ppc64el. 1134s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_ppc64el.deb ... 1134s Unpacking libverto-libevent1t64:ppc64el (0.3.1-1.2ubuntu3) ... 1134s Selecting previously unselected package krb5-kdc. 1134s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu1_ppc64el.deb ... 1134s Unpacking krb5-kdc (1.21.3-4ubuntu1) ... 1134s Selecting previously unselected package krb5-admin-server. 1134s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu1_ppc64el.deb ... 1134s Unpacking krb5-admin-server (1.21.3-4ubuntu1) ... 1134s Selecting previously unselected package openssh-client-gssapi. 1134s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 1134s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 1134s Selecting previously unselected package openssh-server-gssapi. 1135s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 1135s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1135s Setting up libevent-2.1-7t64:ppc64el (2.1.12-stable-10) ... 1135s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 1135s Setting up libgssrpc4t64:ppc64el (1.21.3-4ubuntu1) ... 1135s Setting up krb5-config (2.7) ... 1135s Setting up libkadm5clnt-mit12:ppc64el (1.21.3-4ubuntu1) ... 1135s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1135s Setting up libkdb5-10t64:ppc64el (1.21.3-4ubuntu1) ... 1135s Setting up libkadm5srv-mit12:ppc64el (1.21.3-4ubuntu1) ... 1135s Setting up krb5-user (1.21.3-4ubuntu1) ... 1135s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1135s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1135s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1135s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1135s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1135s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1135s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1135s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1135s Setting up libverto1t64:ppc64el (0.3.1-1.2ubuntu3) ... 1135s Setting up libverto-libevent1t64:ppc64el (0.3.1-1.2ubuntu3) ... 1135s Setting up krb5-kdc (1.21.3-4ubuntu1) ... 1136s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1136s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1136s Setting up krb5-admin-server (1.21.3-4ubuntu1) ... 1137s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1138s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1138s Processing triggers for man-db (2.13.0-1) ... 1144s autopkgtest [06:43:57]: test ssh-gssapi: [----------------------- 1144s Generating public/private ed25519 key pair. 1144s Your identification has been saved in /root/.ssh/id_ed25519 1144s Your public key has been saved in /root/.ssh/id_ed25519.pub 1144s The key fingerprint is: 1144s SHA256:b6IpTR350/s07CbvUqVWIftSeJkc8bn6ITPUvNTqQ/Q root@autopkgtest 1144s The key's randomart image is: 1144s +--[ED25519 256]--+ 1144s | ..| 1144s | . oo| 1144s | . =.*| 1144s | o o+*=| 1144s | .So . o+X.| 1144s | . ..o ooBoE| 1144s | o . o. X*o | 1144s | . .o o ++B..| 1144s | .o B*o | 1144s +----[SHA256]-----+ 1144s ## Setting up test environment 1144s ## Creating Kerberos realm EXAMPLE.FAKE 1144s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1144s master key name 'K/M@EXAMPLE.FAKE' 1144s ## Creating principals 1144s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1144s Principal "testuser2199@EXAMPLE.FAKE" created. 1144s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1144s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1144s ## Extracting service principal host/sshd-gssapi.example.fake 1144s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1144s 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. 1144s 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. 1144s ## Adjusting /etc/krb5.conf 1144s ## TESTS 1144s 1144s ## TEST test_gssapi_login 1144s ## Configuring sshd for gssapi-with-mic authentication 1145s ## Restarting ssh 1145s ## Obtaining TGT 1145s Password for testuser2199@EXAMPLE.FAKE: 1145s Ticket cache: FILE:/tmp/krb5cc_0 1145s Default principal: testuser2199@EXAMPLE.FAKE 1145s 1145s Valid starting Expires Service principal 1145s 02/22/25 06:43:58 02/22/25 16:43:58 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1145s renew until 02/23/25 06:43:58 1145s 1145s ## ssh'ing into localhost using gssapi-with-mic auth 1145s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1145s Sat Feb 22 06:43:58 UTC 2025 1145s 1145s ## checking that we got a service ticket for ssh (host/) 1145s 02/22/25 06:43:58 02/22/25 16:43:58 host/sshd-gssapi.example.fake@ 1145s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1145s 1145s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1145s Feb 22 06:43:58 sshd-gssapi.example.fake sshd-session[2302]: Accepted gssapi-with-mic for testuser2199 from 127.0.0.1 port 34596 ssh2: testuser2199@EXAMPLE.FAKE 1145s ## PASS test_gssapi_login 1145s 1145s ## TEST test_gssapi_keyex_login 1145s ## Configuring sshd for gssapi-keyex authentication 1145s ## Restarting ssh 1145s ## Obtaining TGT 1145s Password for testuser2199@EXAMPLE.FAKE: 1145s Ticket cache: FILE:/tmp/krb5cc_0 1145s Default principal: testuser2199@EXAMPLE.FAKE 1145s 1145s Valid starting Expires Service principal 1145s 02/22/25 06:43:58 02/22/25 16:43:58 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1145s renew until 02/23/25 06:43:58 1145s 1145s ## ssh'ing into localhost using gssapi-keyex auth 1145s Sat Feb 22 06:43:58 UTC 2025 1145s 1145s ## checking that we got a service ticket for ssh (host/) 1145s 02/22/25 06:43:58 02/22/25 16:43:58 host/sshd-gssapi.example.fake@ 1145s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1145s 1145s ## Checking ssh logs to confirm gssapi-keyex auth was used 1145s Feb 22 06:43:58 sshd-gssapi.example.fake sshd-session[2357]: Accepted gssapi-keyex for testuser2199 from 127.0.0.1 port 34604 ssh2: testuser2199@EXAMPLE.FAKE 1145s ## PASS test_gssapi_keyex_login 1145s 1145s ## TEST test_gssapi_keyex_pubkey_fallback 1145s ## Configuring sshd for gssapi-keyex authentication 1145s ## Restarting ssh 1146s ## Obtaining TGT 1146s Password for testuser2199@EXAMPLE.FAKE: 1146s Ticket cache: FILE:/tmp/krb5cc_0 1146s Default principal: testuser2199@EXAMPLE.FAKE 1146s 1146s Valid starting Expires Service principal 1146s 02/22/25 06:43:59 02/22/25 16:43:59 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1146s renew until 02/23/25 06:43:59 1146s 1146s ## ssh'ing into localhost using gssapi-keyex auth 1146s Sat Feb 22 06:43:59 UTC 2025 1146s 1146s ## checking that we got a service ticket for ssh (host/) 1146s 02/22/25 06:43:59 02/22/25 16:43:59 host/sshd-gssapi.example.fake@ 1146s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1146s 1146s ## Checking ssh logs to confirm publickey auth was used 1146s Feb 22 06:43:59 sshd-gssapi.example.fake sshd-session[2398]: Accepted publickey for testuser2199-2 from 127.0.0.1 port 34610 ssh2: ED25519 SHA256:b6IpTR350/s07CbvUqVWIftSeJkc8bn6ITPUvNTqQ/Q 1146s ## PASS test_gssapi_keyex_pubkey_fallback 1146s 1146s ## ALL TESTS PASSED 1146s ## Cleaning up 1147s autopkgtest [06:44:00]: test ssh-gssapi: -----------------------] 1147s ssh-gssapi PASS 1147s autopkgtest [06:44:00]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1147s autopkgtest [06:44:00]: test socket-activation: preparing testbed 1241s autopkgtest [06:45:34]: testbed dpkg architecture: ppc64el 1241s autopkgtest [06:45:34]: testbed apt version: 2.9.30ubuntu1 1241s autopkgtest [06:45:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1241s autopkgtest [06:45:34]: testbed release detected to be: plucky 1242s autopkgtest [06:45:35]: updating testbed package index (apt update) 1243s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1243s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1243s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1243s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1243s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 1243s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1243s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1243s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 1243s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [127 kB] 1243s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [760 B] 1243s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [432 kB] 1243s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [3292 B] 1243s Fetched 1276 kB in 1s (1407 kB/s) 1244s Reading package lists... 1245s + lsb_release --codename --short 1245s + RELEASE=plucky 1245s + cat 1245s + [ plucky != trusty ] 1245s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 1245s Reading package lists... 1245s Building dependency tree... 1245s Reading state information... 1246s Calculating upgrade... 1246s Calculating upgrade... 1246s The following packages will be upgraded: 1246s curl libcurl3t64-gnutls libcurl4t64 1247s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1247s Need to get 1221 kB of archives. 1247s After this operation, 3072 B disk space will be freed. 1247s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el curl ppc64el 8.12.1-2ubuntu1 [258 kB] 1247s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl4t64 ppc64el 8.12.1-2ubuntu1 [482 kB] 1247s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl3t64-gnutls ppc64el 8.12.1-2ubuntu1 [481 kB] 1247s Fetched 1221 kB in 1s (2037 kB/s) 1248s (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 ... 106371 files and directories currently installed.) 1248s Preparing to unpack .../curl_8.12.1-2ubuntu1_ppc64el.deb ... 1248s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1248s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_ppc64el.deb ... 1248s Unpacking libcurl4t64:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1248s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_ppc64el.deb ... 1248s Unpacking libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1248s Setting up libcurl4t64:ppc64el (8.12.1-2ubuntu1) ... 1248s Setting up libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) ... 1248s Setting up curl (8.12.1-2ubuntu1) ... 1248s Processing triggers for man-db (2.13.0-1) ... 1248s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1249s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 1249s + /usr/lib/apt/apt-helper analyze-pattern ?true 1249s + uname -r 1249s + sed s/\./\\./g 1249s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 1249s + apt list ?obsolete 1249s + tail -n+2 1249s + + grep -v ^linux-.*6\.12\.0-15-generic.* 1249s cut -d/ -f1 1249s + true 1249s + obsolete_pkgs= 1249s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 1249s Reading package lists... 1249s Building dependency tree... 1249s Reading state information... 1250s Solving dependencies... 1250s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1250s + grep -q trusty /etc/lsb-release 1250s + [ ! -d /usr/share/doc/unattended-upgrades ] 1250s + [ ! -d /usr/share/doc/lxd ] 1250s + [ ! -d /usr/share/doc/lxd-client ] 1250s + [ ! -d /usr/share/doc/snapd ] 1250s + type iptables 1250s + cat 1250s + chmod 755 /etc/rc.local 1250s + . /etc/rc.local 1250s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 1250s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 1250s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 1250s + uname -m 1250s + [ ppc64le = ppc64le ] 1250s + systemctl is-active keyboard-setup.service 1250s + [ active = failed ] 1250s + [ -d /run/systemd/system ] 1250s + systemd-detect-virt --quiet --vm 1250s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 1250s + cat 1250s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 1250s + echo COMPRESS=lz4 1250s autopkgtest [06:45:43]: upgrading testbed (apt dist-upgrade and autopurge) 1250s Reading package lists... 1250s Building dependency tree... 1250s Reading state information... 1251s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1251s Starting 2 pkgProblemResolver with broken count: 0 1251s Done 1252s Entering ResolveByKeep 1252s 1252s Calculating upgrade... 1253s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1253s Reading package lists... 1253s Building dependency tree... 1253s Reading state information... 1253s Starting pkgProblemResolver with broken count: 0 1253s Starting 2 pkgProblemResolver with broken count: 0 1253s Done 1254s Solving dependencies... 1254s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1257s Reading package lists... 1257s Building dependency tree... 1257s Reading state information... 1257s Starting pkgProblemResolver with broken count: 0 1257s Starting 2 pkgProblemResolver with broken count: 0 1257s Done 1257s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1262s autopkgtest [06:45:55]: test socket-activation: [----------------------- 1262s Generating public/private ed25519 key pair. 1262s Your identification has been saved in /home/testuser1795/.ssh/id_ed25519 1262s Your public key has been saved in /home/testuser1795/.ssh/id_ed25519.pub 1262s The key fingerprint is: 1262s SHA256:6k2DAMz1TRwJuAg1vTFTRT8tUFBCOscvqax9KD/OT3o testuser1795@autopkgtest 1262s The key's randomart image is: 1262s +--[ED25519 256]--+ 1262s | .o..oo*X*o | 1262s |.o o*. =o+ . | 1262s | .+. *+ + + . | 1262s | ..o o o o | 1262s | . S . | 1262s | o + . | 1262s | =.+ | 1262s | .=o=E. | 1262s | .+B=o | 1262s +----[SHA256]-----+ 1262s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1262s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1263s Disabling 'ssh.service', but its triggering units are still active: 1263s ssh.socket 1263s Stopping 'ssh.service', but its triggering units are still active: 1263s ssh.socket 1264s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1264s Sat Feb 22 06:45:57 UTC 2025 1264s autopkgtest [06:45:57]: test socket-activation: -----------------------] 1265s autopkgtest [06:45:58]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1265s socket-activation PASS 1265s autopkgtest [06:45:58]: test xinetd: preparing testbed 1265s Reading package lists... 1266s Building dependency tree... 1266s Reading state information... 1266s Starting pkgProblemResolver with broken count: 0 1266s Starting 2 pkgProblemResolver with broken count: 0 1266s Done 1266s The following NEW packages will be installed: 1266s xinetd 1266s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1266s Need to get 136 kB of archives. 1266s After this operation, 439 kB of additional disk space will be used. 1266s Get:1 http://ftpmaster.internal/ubuntu plucky/universe ppc64el xinetd ppc64el 1:2.3.15.4-4 [136 kB] 1267s Fetched 136 kB in 0s (378 kB/s) 1267s Selecting previously unselected package xinetd. 1267s (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 ... 106371 files and directories currently installed.) 1267s Preparing to unpack .../xinetd_1%3a2.3.15.4-4_ppc64el.deb ... 1267s Unpacking xinetd (1:2.3.15.4-4) ... 1267s Setting up xinetd (1:2.3.15.4-4) ... 1268s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1268s Processing triggers for man-db (2.13.0-1) ... 1270s autopkgtest [06:46:03]: test xinetd: [----------------------- 1270s Generating public/private ed25519 key pair. 1270s Your identification has been saved in /home/testuser2311/.ssh/id_ed25519 1270s Your public key has been saved in /home/testuser2311/.ssh/id_ed25519.pub 1270s The key fingerprint is: 1270s SHA256:kkTV0gBVb49wqeMy02UXoK1SLipHsQg6f37D1yyJSIw testuser2311@autopkgtest 1270s The key's randomart image is: 1270s +--[ED25519 256]--+ 1270s | o++=. . | 1270s | . . o+ o | 1270s | . o .+ * . | 1270s | . . o + o * o . | 1270s |o o. = S = + o | 1270s | oE o. o = + . | 1270s | ..ooo.++o | 1270s | o.o= ++o | 1270s | .. o . | 1270s +----[SHA256]-----+ 1270s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1270s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1271s Disabling 'ssh.service', but its triggering units are still active: 1271s ssh.socket 1271s Stopping 'ssh.service', but its triggering units are still active: 1271s ssh.socket 1271s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1272s Sat Feb 22 06:46:05 UTC 2025 1272s autopkgtest [06:46:05]: test xinetd: -----------------------] 1273s autopkgtest [06:46:06]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1273s xinetd PASS 1273s autopkgtest [06:46:06]: test systemd-socket-activation: preparing testbed 1396s autopkgtest [06:48:09]: testbed dpkg architecture: ppc64el 1396s autopkgtest [06:48:09]: testbed apt version: 2.9.30ubuntu1 1397s autopkgtest [06:48:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1397s autopkgtest [06:48:10]: testbed release detected to be: plucky 1398s autopkgtest [06:48:11]: updating testbed package index (apt update) 1398s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1398s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1398s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1398s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1398s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 1399s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 1399s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 1399s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 1399s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [127 kB] 1399s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [760 B] 1399s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [432 kB] 1399s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [3292 B] 1399s Fetched 1276 kB in 1s (1399 kB/s) 1400s Reading package lists... 1400s + lsb_release --codename --short 1400s + RELEASE=plucky 1400s + cat 1400s + [ plucky != trusty ] 1400s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 1401s Reading package lists... 1401s Building dependency tree... 1401s Reading state information... 1401s Calculating upgrade... 1402s Calculating upgrade... 1402s The following packages will be upgraded: 1402s curl libcurl3t64-gnutls libcurl4t64 1402s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1402s Need to get 1221 kB of archives. 1402s After this operation, 3072 B disk space will be freed. 1402s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el curl ppc64el 8.12.1-2ubuntu1 [258 kB] 1403s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl4t64 ppc64el 8.12.1-2ubuntu1 [482 kB] 1403s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcurl3t64-gnutls ppc64el 8.12.1-2ubuntu1 [481 kB] 1403s Fetched 1221 kB in 1s (2031 kB/s) 1403s (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 ... 106371 files and directories currently installed.) 1403s Preparing to unpack .../curl_8.12.1-2ubuntu1_ppc64el.deb ... 1403s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1404s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_ppc64el.deb ... 1404s Unpacking libcurl4t64:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1404s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_ppc64el.deb ... 1404s Unpacking libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 1404s Setting up libcurl4t64:ppc64el (8.12.1-2ubuntu1) ... 1404s Setting up libcurl3t64-gnutls:ppc64el (8.12.1-2ubuntu1) ... 1404s Setting up curl (8.12.1-2ubuntu1) ... 1404s Processing triggers for man-db (2.13.0-1) ... 1404s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1405s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 1405s + /usr/lib/apt/apt-helper analyze-pattern ?true 1405s + uname -r 1405s + sed s/\./\\./g 1405s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 1405s + apt list ?obsolete 1405s + tail -n+2 1405s + + grep -v ^linux-.*6\.12\.0-15-generic.* 1405s cut -d/ -f1 1405s + true 1405s + obsolete_pkgs= 1405s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 1405s Reading package lists... 1405s Building dependency tree... 1405s Reading state information... 1405s Solving dependencies... 1406s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1406s + grep -q trusty /etc/lsb-release 1406s + [ ! -d /usr/share/doc/unattended-upgrades ] 1406s + [ ! -d /usr/share/doc/lxd ] 1406s + [ ! -d /usr/share/doc/lxd-client ] 1406s + [ ! -d /usr/share/doc/snapd ] 1406s + type iptables 1406s + cat 1406s + chmod 755 /etc/rc.local 1406s + . /etc/rc.local 1406s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 1406s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 1406s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 1406s + uname -m 1406s + [ ppc64le = ppc64le ] 1406s + systemctl is-active keyboard-setup.service 1406s + [ active = failed ] 1406s + [ -d /run/systemd/system ] 1406s + systemd-detect-virt --quiet --vm 1406s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 1406s + cat 1406s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 1406s + echo COMPRESS=lz4 1406s autopkgtest [06:48:19]: upgrading testbed (apt dist-upgrade and autopurge) 1406s Reading package lists... 1406s Building dependency tree... 1406s Reading state information... 1407s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1407s Starting 2 pkgProblemResolver with broken count: 0 1407s Done 1407s Entering ResolveByKeep 1407s 1408s Calculating upgrade... 1408s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1408s Reading package lists... 1408s Building dependency tree... 1408s Reading state information... 1408s Starting pkgProblemResolver with broken count: 0 1408s Starting 2 pkgProblemResolver with broken count: 0 1408s Done 1409s Solving dependencies... 1409s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1412s Reading package lists... 1413s Building dependency tree... 1413s Reading state information... 1413s Starting pkgProblemResolver with broken count: 0 1413s Starting 2 pkgProblemResolver with broken count: 0 1413s Done 1413s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1416s autopkgtest [06:48:29]: test systemd-socket-activation: [----------------------- 1417s Stopping ssh.service... 1417s Checking that ssh.socket is active and listening... 1417s Checking that ssh.service is inactive/dead... 1417s Checking that a connection attempt activates ssh.service... 1417s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1417s Checking that sshd can be re-executed... 1417s Checking sshd can run in debug mode... 1418s debug1: PAM: establishing credentials 1418s debug1: permanently_set_uid: 0/0 1418s debug3: Copy environment: XDG_SESSION_ID=7 1418s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1418s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1418s debug3: Copy environment: XDG_SESSION_TYPE=tty 1418s debug3: Copy environment: XDG_SESSION_CLASS=user 1418s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1418s debug3: Copy environment: TERM=linux 1418s debug3: Copy environment: http_proxy=http://squid.internal:3128 1418s debug3: Copy environment: https_proxy=http://squid.internal:3128 1418s debug3: Copy environment: no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com 1418s debug3: Copy environment: LANG=C.UTF-8 1418s Environment: 1418s LANG=C.UTF-8 1418s USER=root 1418s LOGNAME=root 1418s HOME=/root 1418s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1418s SHELL=/bin/bash 1418s XDG_SESSION_ID=7 1418s XDG_RUNTIME_DIR=/run/user/0 1418s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1418s XDG_SESSION_TYPE=tty 1418s XDG_SESSION_CLASS=user 1418s TERM=linux 1418s http_proxy=http://squid.internal:3128 1418s https_proxy=http://squid.internal:3128 1418s 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 1418s SSH_CLIENT=::1 36274 22 1418s SSH_CONNECTION=::1 36274 ::1 22 1418s Done. 1418s autopkgtest [06:48:31]: test systemd-socket-activation: -----------------------] 1419s systemd-socket-activation PASS 1419s autopkgtest [06:48:32]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1419s autopkgtest [06:48:32]: test sshd-socket-generator: preparing testbed 1419s Reading package lists... 1419s Building dependency tree... 1419s Reading state information... 1420s Starting pkgProblemResolver with broken count: 0 1420s Starting 2 pkgProblemResolver with broken count: 0 1420s Done 1420s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1421s autopkgtest [06:48:34]: test sshd-socket-generator: [----------------------- 1421s test_default...PASS 1421s test_custom_port...PASS 1421s test_default_and_custom_port...PASS 1421s test_mutiple_custom_ports...PASS 1421s test_custom_listenaddress...PASS 1421s test_custom_listenaddress_and_port...PASS 1421s test_custom_ipv6_listenaddress...PASS 1421s test_custom_family_ipv4...PASS 1421s test_custom_family_ipv6...PASS 1421s test_custom_port_and_family_ipv4...PASS 1421s test_custom_port_and_family_ipv6...PASS 1421s test_match_on_port...PASS 1422s autopkgtest [06:48:35]: test sshd-socket-generator: -----------------------] 1422s sshd-socket-generator PASS 1422s autopkgtest [06:48:35]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1423s autopkgtest [06:48:36]: @@@@@@@@@@@@@@@@@@@@ summary 1423s regress PASS 1423s ssh-gssapi PASS 1423s socket-activation PASS 1423s xinetd PASS 1423s systemd-socket-activation PASS 1423s sshd-socket-generator PASS 1427s nova [W] Using flock in prodstack6-ppc64el 1427s Creating nova instance adt-plucky-ppc64el-openssh-20250222-062453-juju-7f2275-prod-proposed-migration-environment-15-c39324e7-8f46-47bd-b384-99d85058aa0b from image adt/ubuntu-plucky-ppc64el-server-20250222.img (UUID fe93544b-49e9-47e0-b80a-745acc8cd85b)... 1427s nova [W] Timed out waiting for 24c63296-9e1d-44fe-8487-01b23d01ab74 to get deleted. 1427s nova [W] Using flock in prodstack6-ppc64el 1427s Creating nova instance adt-plucky-ppc64el-openssh-20250222-062453-juju-7f2275-prod-proposed-migration-environment-15-c39324e7-8f46-47bd-b384-99d85058aa0b from image adt/ubuntu-plucky-ppc64el-server-20250222.img (UUID fe93544b-49e9-47e0-b80a-745acc8cd85b)... 1427s nova [W] Timed out waiting for ab111fc4-54ff-4b75-b2dc-e591ab7a1101 to get deleted. 1427s nova [W] Using flock in prodstack6-ppc64el 1427s Creating nova instance adt-plucky-ppc64el-openssh-20250222-062453-juju-7f2275-prod-proposed-migration-environment-15-c39324e7-8f46-47bd-b384-99d85058aa0b from image adt/ubuntu-plucky-ppc64el-server-20250222.img (UUID fe93544b-49e9-47e0-b80a-745acc8cd85b)... 1427s nova [W] Timed out waiting for a19170d8-7a78-471d-a7e1-faa59104d2ab to get deleted. 1427s nova [W] Using flock in prodstack6-ppc64el 1427s Creating nova instance adt-plucky-ppc64el-openssh-20250222-062453-juju-7f2275-prod-proposed-migration-environment-15-c39324e7-8f46-47bd-b384-99d85058aa0b from image adt/ubuntu-plucky-ppc64el-server-20250222.img (UUID fe93544b-49e9-47e0-b80a-745acc8cd85b)... 1427s nova [W] Timed out waiting for ca8e4907-3098-4880-a8d9-3aa41c7111b3 to get deleted.