0s autopkgtest [18:44:07]: starting date and time: 2025-01-18 18:44:07+0000 0s autopkgtest [18:44:07]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [18:44:07]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.lktejtob/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:libedit --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=libedit/3.1-20250104-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-8.secgroup --name adt-plucky-ppc64el-openssh-20250118-180904-juju-7f2275-prod-proposed-migration-environment-2-91add195-da1a-4da5-a0f2-eac6b6a8715b --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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/ 109s autopkgtest [18:45:56]: testbed dpkg architecture: ppc64el 109s autopkgtest [18:45:56]: testbed apt version: 2.9.18 109s autopkgtest [18:45:56]: @@@@@@@@@@@@@@@@@@@@ test bed setup 109s autopkgtest [18:45:56]: testbed release detected to be: None 110s autopkgtest [18:45:57]: updating testbed package index (apt update) 110s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 111s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 111s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 111s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 111s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 111s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.6 kB] 111s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [795 kB] 111s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [152 kB] 111s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [261 kB] 111s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [756 B] 111s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [901 kB] 111s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [14.5 kB] 111s Fetched 2222 kB in 1s (2089 kB/s) 112s Reading package lists... 113s + lsb_release --codename --short 113s + RELEASE=plucky 113s + cat 113s + [ plucky != trusty ] 113s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s Calculating upgrade... 113s The following packages will be upgraded: 113s apport apport-core-dump-handler bash-completion ed libicu74 pci.ids 113s python3-apport python3-problem-report 114s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 114s Need to get 12.0 MB of archives. 114s After this operation, 1024 B of additional disk space will be used. 114s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-problem-report all 2.31.0-0ubuntu3 [25.3 kB] 114s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-apport all 2.31.0-0ubuntu3 [93.1 kB] 114s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport-core-dump-handler all 2.31.0-0ubuntu3 [18.3 kB] 114s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport all 2.31.0-0ubuntu3 [83.0 kB] 114s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libicu74 ppc64el 74.2-1ubuntu6 [11.2 MB] 115s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el bash-completion all 1:2.16.0-6 [214 kB] 115s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el ed ppc64el 1.21-1 [65.4 kB] 115s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el pci.ids all 0.0~2025.01.13-1 [282 kB] 115s Fetched 12.0 MB in 1s (9383 kB/s) 115s (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 ... 74042 files and directories currently installed.) 115s Preparing to unpack .../0-python3-problem-report_2.31.0-0ubuntu3_all.deb ... 115s Unpacking python3-problem-report (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 115s Preparing to unpack .../1-python3-apport_2.31.0-0ubuntu3_all.deb ... 116s Unpacking python3-apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 116s Preparing to unpack .../2-apport-core-dump-handler_2.31.0-0ubuntu3_all.deb ... 116s Unpacking apport-core-dump-handler (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 116s Preparing to unpack .../3-apport_2.31.0-0ubuntu3_all.deb ... 116s Unpacking apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 116s Preparing to unpack .../4-libicu74_74.2-1ubuntu6_ppc64el.deb ... 116s Unpacking libicu74:ppc64el (74.2-1ubuntu6) over (74.2-1ubuntu4) ... 116s Preparing to unpack .../5-bash-completion_1%3a2.16.0-6_all.deb ... 116s Unpacking bash-completion (1:2.16.0-6) over (1:2.16.0-3) ... 116s Preparing to unpack .../6-ed_1.21-1_ppc64el.deb ... 116s Unpacking ed (1.21-1) over (1.20.2-2) ... 116s Preparing to unpack .../7-pci.ids_0.0~2025.01.13-1_all.deb ... 116s Unpacking pci.ids (0.0~2025.01.13-1) over (0.0~2024.11.25-1) ... 116s Setting up pci.ids (0.0~2025.01.13-1) ... 116s Setting up python3-problem-report (2.31.0-0ubuntu3) ... 116s Setting up python3-apport (2.31.0-0ubuntu3) ... 116s Setting up ed (1.21-1) ... 116s Setting up bash-completion (1:2.16.0-6) ... 116s Setting up libicu74:ppc64el (74.2-1ubuntu6) ... 116s Setting up apport-core-dump-handler (2.31.0-0ubuntu3) ... 118s Setting up apport (2.31.0-0ubuntu3) ... 118s apport-autoreport.service is a disabled or a static unit not running, not starting it. 118s Processing triggers for install-info (7.1.1-1) ... 118s Processing triggers for libc-bin (2.40-4ubuntu1) ... 118s Processing triggers for man-db (2.13.0-1) ... 119s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 119s + /usr/lib/apt/apt-helper analyze-pattern ?true 119s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 119s Reading package lists... 119s Building dependency tree... 119s Reading state information... 120s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 120s + grep -q trusty /etc/lsb-release 120s + [ ! -d /usr/share/doc/unattended-upgrades ] 120s + [ ! -d /usr/share/doc/lxd ] 120s + [ ! -d /usr/share/doc/lxd-client ] 120s + [ ! -d /usr/share/doc/snapd ] 120s + type iptables 120s + cat 120s + chmod 755 /etc/rc.local 120s + . /etc/rc.local 120s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 120s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 120s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 120s + uname -m 120s + [ ppc64le = ppc64le ] 120s + systemctl is-active keyboard-setup.service 120s + [ active = failed ] 120s + [ -d /run/systemd/system ] 120s + systemd-detect-virt --quiet --vm 120s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 120s + cat 120s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 120s + echo COMPRESS=lz4 120s + sync 120s autopkgtest [18:46:07]: upgrading testbed (apt dist-upgrade and autopurge) 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 121s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 121s Starting 2 pkgProblemResolver with broken count: 0 121s Done 121s Entering ResolveByKeep 121s 121s The following packages will be upgraded: 121s libedit2 122s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 122s Need to get 124 kB of archives. 122s After this operation, 0 B of additional disk space will be used. 122s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libedit2 ppc64el 3.1-20250104-1 [124 kB] 122s Fetched 124 kB in 0s (363 kB/s) 122s (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 ... 74042 files and directories currently installed.) 122s Preparing to unpack .../libedit2_3.1-20250104-1_ppc64el.deb ... 122s Unpacking libedit2:ppc64el (3.1-20250104-1) over (3.1-20240808-1) ... 122s Setting up libedit2:ppc64el (3.1-20250104-1) ... 122s Processing triggers for man-db (2.13.0-1) ... 122s Processing triggers for libc-bin (2.40-4ubuntu1) ... 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s Starting pkgProblemResolver with broken count: 0 123s Starting 2 pkgProblemResolver with broken count: 0 123s Done 124s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s autopkgtest [18:46:11]: rebooting testbed after setup commands that affected boot 152s autopkgtest [18:46:39]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 155s autopkgtest [18:46:42]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 160s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (dsc) [3480 B] 160s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (tar) [1965 kB] 160s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (asc) [833 B] 160s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (diff) [210 kB] 161s gpgv: Signature made Thu Nov 21 14:24:07 2024 UTC 161s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 161s gpgv: Can't check signature: No public key 161s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu2.dsc: no acceptable signature found 161s autopkgtest [18:46:48]: testing package openssh version 1:9.9p1-3ubuntu2 162s autopkgtest [18:46:49]: build not needed 165s autopkgtest [18:46:52]: test regress: preparing testbed 165s Reading package lists... 165s Building dependency tree... 165s Reading state information... 166s Starting pkgProblemResolver with broken count: 0 166s Starting 2 pkgProblemResolver with broken count: 0 166s Done 166s The following NEW packages will be installed: 166s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 166s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 166s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 166s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 166s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 166s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 166s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 166s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 166s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 166s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 166s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 166s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 166s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 166s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 166s python3-packaging python3-pyasn1 python3-pyasn1-modules 166s python3-service-identity python3-twisted python3-zope.interface wdiff 166s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 166s Need to get 8784 kB of archives. 166s After this operation, 41.9 MB of additional disk space will be used. 166s Get:1 http://ftpmaster.internal/ubuntu plucky/universe ppc64el libtommath1 ppc64el 1.3.0-1 [77.7 kB] 166s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el libtomcrypt1 ppc64el 1.18.2+dfsg-7build1 [503 kB] 166s Get:3 http://ftpmaster.internal/ubuntu plucky/universe ppc64el dropbear-bin ppc64el 2024.86-2 [183 kB] 166s Get:4 http://ftpmaster.internal/ubuntu plucky/universe ppc64el dropbear all 2024.86-2 [8664 B] 166s Get:5 http://ftpmaster.internal/ubuntu plucky/universe ppc64el libhavege2 ppc64el 1.9.14-1ubuntu2 [31.2 kB] 166s Get:6 http://ftpmaster.internal/ubuntu plucky/universe ppc64el haveged ppc64el 1.9.14-1ubuntu2 [33.8 kB] 166s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-dirlist-perl all 0.05-3 [7286 B] 166s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-which-perl all 1.27-2 [12.5 kB] 166s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-homedir-perl all 1.006-2 [37.0 kB] 166s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-touch-perl all 0.12-2 [7498 B] 166s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el libio-pty-perl ppc64el 1:1.20-1build3 [32.0 kB] 166s Get:12 http://ftpmaster.internal/ubuntu plucky/main ppc64el libipc-run-perl all 20231003.0-2 [91.5 kB] 167s Get:13 http://ftpmaster.internal/ubuntu plucky/main ppc64el libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 167s Get:14 http://ftpmaster.internal/ubuntu plucky/main ppc64el libclass-xsaccessor-perl ppc64el 1.19-4build5 [35.1 kB] 167s Get:15 http://ftpmaster.internal/ubuntu plucky/main ppc64el libb-hooks-op-check-perl ppc64el 0.22-3build2 [9884 B] 167s Get:16 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdynaloader-functions-perl all 0.004-1 [11.4 kB] 167s Get:17 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdevel-callchecker-perl ppc64el 0.009-1build1 [14.1 kB] 167s Get:18 http://ftpmaster.internal/ubuntu plucky/main ppc64el libparams-classify-perl ppc64el 0.015-2build6 [22.2 kB] 167s Get:19 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmodule-runtime-perl all 0.016-2 [16.4 kB] 167s Get:20 http://ftpmaster.internal/ubuntu plucky/main ppc64el libimport-into-perl all 1.002005-2 [10.7 kB] 167s Get:21 http://ftpmaster.internal/ubuntu plucky/main ppc64el librole-tiny-perl all 2.002004-1 [16.3 kB] 167s Get:22 http://ftpmaster.internal/ubuntu plucky/main ppc64el libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 167s Get:23 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmoo-perl all 2.005005-1 [47.4 kB] 167s Get:24 http://ftpmaster.internal/ubuntu plucky/main ppc64el libencode-locale-perl all 1.05-3 [11.6 kB] 167s Get:25 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtimedate-perl all 2.3300-2 [34.0 kB] 167s Get:26 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-date-perl all 6.06-1 [10.2 kB] 167s Get:27 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-listing-perl all 6.16-1 [11.3 kB] 167s Get:28 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-tagset-perl all 3.24-1 [14.1 kB] 167s Get:29 http://ftpmaster.internal/ubuntu plucky/main ppc64el liburi-perl all 5.30-1 [94.4 kB] 167s Get:30 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-parser-perl ppc64el 3.83-1build1 [91.8 kB] 167s Get:31 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-tree-perl all 5.07-3 [200 kB] 167s Get:32 http://ftpmaster.internal/ubuntu plucky/main ppc64el libclone-perl ppc64el 0.47-1 [11.1 kB] 167s Get:33 http://ftpmaster.internal/ubuntu plucky/main ppc64el libio-html-perl all 1.004-3 [15.9 kB] 167s Get:34 http://ftpmaster.internal/ubuntu plucky/main ppc64el liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 167s Get:35 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 167s Get:36 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-cookies-perl all 6.11-1 [18.2 kB] 167s Get:37 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-negotiate-perl all 6.01-2 [12.4 kB] 167s Get:38 http://ftpmaster.internal/ubuntu plucky/main ppc64el perl-openssl-defaults ppc64el 7build3 [6628 B] 167s Get:39 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnet-ssleay-perl ppc64el 1.94-2 [323 kB] 167s Get:40 http://ftpmaster.internal/ubuntu plucky/main ppc64el libio-socket-ssl-perl all 2.089-1 [200 kB] 167s Get:41 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnet-http-perl all 6.23-1 [22.3 kB] 167s Get:42 http://ftpmaster.internal/ubuntu plucky/main ppc64el liblwp-protocol-https-perl all 6.14-1 [9040 B] 167s Get:43 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtry-tiny-perl all 0.32-1 [21.2 kB] 167s Get:44 http://ftpmaster.internal/ubuntu plucky/main ppc64el libwww-robotrules-perl all 6.02-1 [12.6 kB] 167s Get:45 http://ftpmaster.internal/ubuntu plucky/main ppc64el libwww-perl all 6.77-1 [138 kB] 167s Get:46 http://ftpmaster.internal/ubuntu plucky/main ppc64el patchutils ppc64el 0.4.2-1build3 [86.6 kB] 167s Get:47 http://ftpmaster.internal/ubuntu plucky/main ppc64el wdiff ppc64el 1.2.2-7 [29.5 kB] 167s Get:48 http://ftpmaster.internal/ubuntu plucky/main ppc64el devscripts all 2.24.9 [1076 kB] 167s Get:49 http://ftpmaster.internal/ubuntu plucky/universe ppc64el putty-tools ppc64el 0.82-2 [878 kB] 167s Get:50 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-hamcrest all 2.1.0-1 [28.1 kB] 167s Get:51 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-pyasn1 all 0.6.0-1 [57.8 kB] 167s Get:52 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-pyasn1-modules all 0.4.1-1 [80.2 kB] 167s Get:53 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-service-identity all 24.1.0-1 [11.2 kB] 167s Get:54 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-automat all 24.8.1-1 [35.5 kB] 167s Get:55 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-constantly all 23.10.4-2 [13.9 kB] 167s Get:56 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-hyperlink all 21.0.0-6 [68.0 kB] 167s Get:57 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-packaging all 24.2-1 [51.5 kB] 167s Get:58 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-incremental all 24.7.2-3 [18.6 kB] 167s Get:59 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-zope.interface ppc64el 7.2-1 [142 kB] 167s Get:60 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-twisted all 24.11.0-1 [2066 kB] 167s Get:61 http://ftpmaster.internal/ubuntu plucky/universe ppc64el openssh-tests ppc64el 1:9.9p1-3ubuntu2 [1540 kB] 168s Fetched 8784 kB in 1s (6367 kB/s) 168s Selecting previously unselected package libtommath1:ppc64el. 168s (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 ... 74042 files and directories currently installed.) 168s Preparing to unpack .../00-libtommath1_1.3.0-1_ppc64el.deb ... 168s Unpacking libtommath1:ppc64el (1.3.0-1) ... 168s Selecting previously unselected package libtomcrypt1:ppc64el. 168s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_ppc64el.deb ... 168s Unpacking libtomcrypt1:ppc64el (1.18.2+dfsg-7build1) ... 168s Selecting previously unselected package dropbear-bin. 168s Preparing to unpack .../02-dropbear-bin_2024.86-2_ppc64el.deb ... 168s Unpacking dropbear-bin (2024.86-2) ... 168s Selecting previously unselected package dropbear. 168s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 168s Unpacking dropbear (2024.86-2) ... 168s Selecting previously unselected package libhavege2:ppc64el. 168s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_ppc64el.deb ... 168s Unpacking libhavege2:ppc64el (1.9.14-1ubuntu2) ... 168s Selecting previously unselected package haveged. 168s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_ppc64el.deb ... 168s Unpacking haveged (1.9.14-1ubuntu2) ... 168s Selecting previously unselected package libfile-dirlist-perl. 168s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 168s Unpacking libfile-dirlist-perl (0.05-3) ... 168s Selecting previously unselected package libfile-which-perl. 168s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 168s Unpacking libfile-which-perl (1.27-2) ... 168s Selecting previously unselected package libfile-homedir-perl. 168s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 168s Unpacking libfile-homedir-perl (1.006-2) ... 168s Selecting previously unselected package libfile-touch-perl. 168s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 168s Unpacking libfile-touch-perl (0.12-2) ... 168s Selecting previously unselected package libio-pty-perl. 168s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_ppc64el.deb ... 168s Unpacking libio-pty-perl (1:1.20-1build3) ... 168s Selecting previously unselected package libipc-run-perl. 168s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 168s Unpacking libipc-run-perl (20231003.0-2) ... 168s Selecting previously unselected package libclass-method-modifiers-perl. 168s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 168s Unpacking libclass-method-modifiers-perl (2.15-1) ... 168s Selecting previously unselected package libclass-xsaccessor-perl. 168s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_ppc64el.deb ... 168s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 168s Selecting previously unselected package libb-hooks-op-check-perl:ppc64el. 168s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_ppc64el.deb ... 168s Unpacking libb-hooks-op-check-perl:ppc64el (0.22-3build2) ... 168s Selecting previously unselected package libdynaloader-functions-perl. 168s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 168s Unpacking libdynaloader-functions-perl (0.004-1) ... 168s Selecting previously unselected package libdevel-callchecker-perl:ppc64el. 168s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_ppc64el.deb ... 168s Unpacking libdevel-callchecker-perl:ppc64el (0.009-1build1) ... 168s Selecting previously unselected package libparams-classify-perl:ppc64el. 168s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_ppc64el.deb ... 168s Unpacking libparams-classify-perl:ppc64el (0.015-2build6) ... 168s Selecting previously unselected package libmodule-runtime-perl. 168s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 168s Unpacking libmodule-runtime-perl (0.016-2) ... 168s Selecting previously unselected package libimport-into-perl. 168s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 168s Unpacking libimport-into-perl (1.002005-2) ... 168s Selecting previously unselected package librole-tiny-perl. 168s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 168s Unpacking librole-tiny-perl (2.002004-1) ... 168s Selecting previously unselected package libsub-quote-perl. 168s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 168s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 168s Selecting previously unselected package libmoo-perl. 168s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 168s Unpacking libmoo-perl (2.005005-1) ... 168s Selecting previously unselected package libencode-locale-perl. 168s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 168s Unpacking libencode-locale-perl (1.05-3) ... 168s Selecting previously unselected package libtimedate-perl. 168s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 168s Unpacking libtimedate-perl (2.3300-2) ... 168s Selecting previously unselected package libhttp-date-perl. 168s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 168s Unpacking libhttp-date-perl (6.06-1) ... 168s Selecting previously unselected package libfile-listing-perl. 168s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 168s Unpacking libfile-listing-perl (6.16-1) ... 168s Selecting previously unselected package libhtml-tagset-perl. 168s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 168s Unpacking libhtml-tagset-perl (3.24-1) ... 168s Selecting previously unselected package liburi-perl. 168s Preparing to unpack .../28-liburi-perl_5.30-1_all.deb ... 168s Unpacking liburi-perl (5.30-1) ... 168s Selecting previously unselected package libhtml-parser-perl:ppc64el. 168s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_ppc64el.deb ... 168s Unpacking libhtml-parser-perl:ppc64el (3.83-1build1) ... 168s Selecting previously unselected package libhtml-tree-perl. 168s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 168s Unpacking libhtml-tree-perl (5.07-3) ... 168s Selecting previously unselected package libclone-perl:ppc64el. 168s Preparing to unpack .../31-libclone-perl_0.47-1_ppc64el.deb ... 168s Unpacking libclone-perl:ppc64el (0.47-1) ... 168s Selecting previously unselected package libio-html-perl. 168s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 168s Unpacking libio-html-perl (1.004-3) ... 168s Selecting previously unselected package liblwp-mediatypes-perl. 168s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 168s Unpacking liblwp-mediatypes-perl (6.04-2) ... 169s Selecting previously unselected package libhttp-message-perl. 169s Preparing to unpack .../34-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 169s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 169s Selecting previously unselected package libhttp-cookies-perl. 169s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 169s Unpacking libhttp-cookies-perl (6.11-1) ... 169s Selecting previously unselected package libhttp-negotiate-perl. 169s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 169s Unpacking libhttp-negotiate-perl (6.01-2) ... 169s Selecting previously unselected package perl-openssl-defaults:ppc64el. 169s Preparing to unpack .../37-perl-openssl-defaults_7build3_ppc64el.deb ... 169s Unpacking perl-openssl-defaults:ppc64el (7build3) ... 169s Selecting previously unselected package libnet-ssleay-perl:ppc64el. 169s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_ppc64el.deb ... 169s Unpacking libnet-ssleay-perl:ppc64el (1.94-2) ... 169s Selecting previously unselected package libio-socket-ssl-perl. 169s Preparing to unpack .../39-libio-socket-ssl-perl_2.089-1_all.deb ... 169s Unpacking libio-socket-ssl-perl (2.089-1) ... 169s Selecting previously unselected package libnet-http-perl. 169s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 169s Unpacking libnet-http-perl (6.23-1) ... 169s Selecting previously unselected package liblwp-protocol-https-perl. 169s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 169s Unpacking liblwp-protocol-https-perl (6.14-1) ... 169s Selecting previously unselected package libtry-tiny-perl. 169s Preparing to unpack .../42-libtry-tiny-perl_0.32-1_all.deb ... 169s Unpacking libtry-tiny-perl (0.32-1) ... 169s Selecting previously unselected package libwww-robotrules-perl. 169s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 169s Unpacking libwww-robotrules-perl (6.02-1) ... 169s Selecting previously unselected package libwww-perl. 169s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 169s Unpacking libwww-perl (6.77-1) ... 169s Selecting previously unselected package patchutils. 169s Preparing to unpack .../45-patchutils_0.4.2-1build3_ppc64el.deb ... 169s Unpacking patchutils (0.4.2-1build3) ... 169s Selecting previously unselected package wdiff. 169s Preparing to unpack .../46-wdiff_1.2.2-7_ppc64el.deb ... 169s Unpacking wdiff (1.2.2-7) ... 169s Selecting previously unselected package devscripts. 169s Preparing to unpack .../47-devscripts_2.24.9_all.deb ... 169s Unpacking devscripts (2.24.9) ... 169s Selecting previously unselected package putty-tools. 169s Preparing to unpack .../48-putty-tools_0.82-2_ppc64el.deb ... 169s Unpacking putty-tools (0.82-2) ... 169s Selecting previously unselected package python3-hamcrest. 169s Preparing to unpack .../49-python3-hamcrest_2.1.0-1_all.deb ... 169s Unpacking python3-hamcrest (2.1.0-1) ... 169s Selecting previously unselected package python3-pyasn1. 169s Preparing to unpack .../50-python3-pyasn1_0.6.0-1_all.deb ... 169s Unpacking python3-pyasn1 (0.6.0-1) ... 169s Selecting previously unselected package python3-pyasn1-modules. 169s Preparing to unpack .../51-python3-pyasn1-modules_0.4.1-1_all.deb ... 169s Unpacking python3-pyasn1-modules (0.4.1-1) ... 169s Selecting previously unselected package python3-service-identity. 169s Preparing to unpack .../52-python3-service-identity_24.1.0-1_all.deb ... 169s Unpacking python3-service-identity (24.1.0-1) ... 169s Selecting previously unselected package python3-automat. 169s Preparing to unpack .../53-python3-automat_24.8.1-1_all.deb ... 169s Unpacking python3-automat (24.8.1-1) ... 169s Selecting previously unselected package python3-constantly. 169s Preparing to unpack .../54-python3-constantly_23.10.4-2_all.deb ... 169s Unpacking python3-constantly (23.10.4-2) ... 169s Selecting previously unselected package python3-hyperlink. 169s Preparing to unpack .../55-python3-hyperlink_21.0.0-6_all.deb ... 169s Unpacking python3-hyperlink (21.0.0-6) ... 169s Selecting previously unselected package python3-packaging. 169s Preparing to unpack .../56-python3-packaging_24.2-1_all.deb ... 169s Unpacking python3-packaging (24.2-1) ... 169s Selecting previously unselected package python3-incremental. 169s Preparing to unpack .../57-python3-incremental_24.7.2-3_all.deb ... 169s Unpacking python3-incremental (24.7.2-3) ... 169s Selecting previously unselected package python3-zope.interface. 169s Preparing to unpack .../58-python3-zope.interface_7.2-1_ppc64el.deb ... 169s Unpacking python3-zope.interface (7.2-1) ... 169s Selecting previously unselected package python3-twisted. 169s Preparing to unpack .../59-python3-twisted_24.11.0-1_all.deb ... 169s Unpacking python3-twisted (24.11.0-1) ... 169s Selecting previously unselected package openssh-tests. 169s Preparing to unpack .../60-openssh-tests_1%3a9.9p1-3ubuntu2_ppc64el.deb ... 169s Unpacking openssh-tests (1:9.9p1-3ubuntu2) ... 169s Setting up wdiff (1.2.2-7) ... 169s Setting up libfile-which-perl (1.27-2) ... 169s Setting up libdynaloader-functions-perl (0.004-1) ... 169s Setting up libclass-method-modifiers-perl (2.15-1) ... 169s Setting up libio-pty-perl (1:1.20-1build3) ... 169s Setting up python3-zope.interface (7.2-1) ... 170s Setting up libclone-perl:ppc64el (0.47-1) ... 170s Setting up libtommath1:ppc64el (1.3.0-1) ... 170s Setting up libhtml-tagset-perl (3.24-1) ... 170s Setting up python3-automat (24.8.1-1) ... 170s Setting up liblwp-mediatypes-perl (6.04-2) ... 170s Setting up libtry-tiny-perl (0.32-1) ... 170s Setting up perl-openssl-defaults:ppc64el (7build3) ... 170s Setting up libencode-locale-perl (1.05-3) ... 170s Setting up python3-hamcrest (2.1.0-1) ... 170s Setting up putty-tools (0.82-2) ... 170s Setting up libhavege2:ppc64el (1.9.14-1ubuntu2) ... 170s Setting up patchutils (0.4.2-1build3) ... 170s Setting up python3-packaging (24.2-1) ... 170s Setting up python3-incremental (24.7.2-3) ... 170s Setting up python3-hyperlink (21.0.0-6) ... 170s Setting up libio-html-perl (1.004-3) ... 170s Setting up libb-hooks-op-check-perl:ppc64el (0.22-3build2) ... 170s Setting up libipc-run-perl (20231003.0-2) ... 170s Setting up libtimedate-perl (2.3300-2) ... 170s Setting up librole-tiny-perl (2.002004-1) ... 170s Setting up python3-pyasn1 (0.6.0-1) ... 170s Setting up python3-constantly (23.10.4-2) ... 171s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 171s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 171s Setting up libfile-dirlist-perl (0.05-3) ... 171s Setting up libfile-homedir-perl (1.006-2) ... 171s Setting up liburi-perl (5.30-1) ... 171s Setting up libfile-touch-perl (0.12-2) ... 171s Setting up libnet-ssleay-perl:ppc64el (1.94-2) ... 171s Setting up libtomcrypt1:ppc64el (1.18.2+dfsg-7build1) ... 171s Setting up libhttp-date-perl (6.06-1) ... 171s Setting up haveged (1.9.14-1ubuntu2) ... 171s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 171s Setting up dropbear-bin (2024.86-2) ... 171s Setting up libfile-listing-perl (6.16-1) ... 171s Setting up libnet-http-perl (6.23-1) ... 171s Setting up libdevel-callchecker-perl:ppc64el (0.009-1build1) ... 171s Setting up dropbear (2024.86-2) ... 171s Converting existing OpenSSH RSA host key to Dropbear format. 171s Key is a ssh-rsa key 171s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 171s 3072 SHA256:qdEOVqdrwj35Y6SH00Q8iuXWCw4khUeunJFMvStTScQ /etc/dropbear/dropbear_rsa_host_key (RSA) 171s +---[RSA 3072]----+ 171s | .*o | 171s | o.oE | 171s | ++.o... | 171s | ..+=o.++ | 171s | +++=S+ . | 171s | oo+*=o+ | 171s | o++BB . | 171s | o=o* | 171s | +.. | 171s +----[SHA256]-----+ 171s Converting existing OpenSSH ECDSA host key to Dropbear format. 171s Key is a ecdsa-sha2-nistp256 key 171s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 171s 256 SHA256:F8CAvetrm3Aw+Fi9SN/YwaSgRXjofr2VszVmzxHhqb4 /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 171s +---[ECDSA 256]---+ 171s | o.o.o. | 171s | o.o . .. . | 171s | . .o .. .. o | 171s | .+ o.+ .+ | 171s | .o =.o.S... . | 171s | .=.=o=+o* . | 171s | ..+.=ooB + . | 171s | o+.. . o | 171s | .+o E. | 171s +----[SHA256]-----+ 171s Converting existing OpenSSH ED25519 host key to Dropbear format. 171s Key is a ssh-ed25519 key 171s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 171s 256 SHA256:x5OJEwdDRCoIa/VE/OGKhpN/wQ/K3xqsWFuq8E3NK2k /etc/dropbear/dropbear_ed25519_host_key (ED25519) 171s +--[ED25519 256]--+ 171s |. .oo +* | 171s | o..o. o o | 171s |... ..+ o . | 171s |. . o = o | 171s | o o . S * | 171s | + o.B o . | 171s |. =.o=* | 171s |..o*E.oo | 171s | oo=++o. | 171s +----[SHA256]-----+ 172s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 172s Setting up python3-pyasn1-modules (0.4.1-1) ... 172s Setting up python3-service-identity (24.1.0-1) ... 173s Setting up libwww-robotrules-perl (6.02-1) ... 173s Setting up libhtml-parser-perl:ppc64el (3.83-1build1) ... 173s Setting up libio-socket-ssl-perl (2.089-1) ... 173s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 173s Setting up libhttp-negotiate-perl (6.01-2) ... 173s Setting up libhttp-cookies-perl (6.11-1) ... 173s Setting up libhtml-tree-perl (5.07-3) ... 173s Setting up libparams-classify-perl:ppc64el (0.015-2build6) ... 173s Setting up libmodule-runtime-perl (0.016-2) ... 173s Setting up python3-twisted (24.11.0-1) ... 176s Setting up libimport-into-perl (1.002005-2) ... 176s Setting up libmoo-perl (2.005005-1) ... 176s Setting up openssh-tests (1:9.9p1-3ubuntu2) ... 176s Setting up liblwp-protocol-https-perl (6.14-1) ... 176s Setting up libwww-perl (6.77-1) ... 176s Setting up devscripts (2.24.9) ... 176s Processing triggers for install-info (7.1.1-1) ... 176s Processing triggers for libc-bin (2.40-4ubuntu1) ... 176s Processing triggers for man-db (2.13.0-1) ... 179s autopkgtest [18:47:06]: test regress: [----------------------- 179s info: Adding user `openssh-tests' ... 179s info: Selecting UID/GID from range 1000 to 59999 ... 179s info: Adding new group `openssh-tests' (1001) ... 179s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 179s info: Creating home directory `/home/openssh-tests' ... 179s info: Copying files from `/etc/skel' ... 179s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 179s info: Adding user `openssh-tests' to group `users' ... 179s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 179s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 180s I: annotate-output 2.24.9 180s I: prefix='%H:%M:%S.%N ' 180s 18:47:07.675430672 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user 180s 18:47:07.710515390 O: make: Entering directory '/tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress' 180s 18:47:07.712677599 E: + /usr/bin/ssh -Q key 180s 18:47:07.711612101 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/valgrind-out 180s 18:47:07.713754576 E: + grep -q ^ssh-rsa 180s 18:47:07.713933623 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 180s 18:47:07.714930193 O: ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 180s 18:47:07.716142226 O: tr '\n' '\r' /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 180s 18:47:07.717069045 O: ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 180s 18:47:07.718075136 O: awk '{print $0 "\r"}' /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 180s 18:47:07.719064490 O: ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 180s 18:47:07.719899478 O: fi 180s 18:47:07.723159146 E: + ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2.prv 180s 18:47:07.724658984 E: + diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv 180s 18:47:07.728619037 E: + tr \n \r 180s 18:47:07.730484289 E: + ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 180s 18:47:07.731239033 E: + diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv 180s 18:47:07.734371480 E: + awk {print $0 "\r"} /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2.prv 180s 18:47:07.736543547 E: + ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 180s 18:47:07.737756539 E: + diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv 180s 18:47:07.741196107 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 180s 18:47:07.742984408 O: cat /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t2.out ; \ 180s 18:47:07.743868004 O: chmod 600 /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t2.out ; \ 180s 18:47:07.744659197 O: ssh-keygen -yf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 180s 18:47:07.746789234 E: + /usr/bin/ssh -Q key 180s 18:47:07.745972978 O: fi 180s 18:47:07.747715312 E: + grep -q ^ssh-rsa 180s 18:47:07.748565328 E: + cat /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.prv 180s 18:47:07.749375472 E: + chmod 600 /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t2.out 180s 18:47:07.750293574 E: + ssh-keygen -yf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t2.out 180s 18:47:07.751177802 E: + diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub 180s 18:47:07.754591980 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 180s 18:47:07.756260806 O: ssh-keygen -ef /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t3.out ; \ 180s 18:47:07.757192789 O: ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 180s 18:47:07.758047054 O: fi 180s 18:47:07.755268148 E: + /usr/bin/ssh+ -Q key 180s 18:47:07.758971492 E: grep -q ^ssh-rsa 180s 18:47:07.760234338 E: + ssh-keygen -ef /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub 180s 18:47:07.764612343 E: + ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t3.out 180s 18:47:07.765495410 E: + diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub 180s 18:47:07.767088041 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 180s 18:47:07.767866045 E: + /usr/bin/ssh -Q key 180s 18:47:07.768748486 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 180s 18:47:07.769603929 O: awk '{print $2}' | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t4.ok ; \ 180s 18:47:07.770997986 E: + grep -q ^ssh-rsa 180s 18:47:07.771450187 O: fi 180s 18:47:07.774696558 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub 180s 18:47:07.775409213 E: + awk {print $2} 180s 18:47:07.776151929 E: + diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t4.ok 180s 18:47:07.777627904 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 180s 18:47:07.779291808 E: + /usr/bin/ssh -Q key 180s 18:47:07.778624146 O: ssh-keygen -Bf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 180s 18:47:07.780063451 E: + grep -q ^ssh-rsa 180s 18:47:07.780880357 O: awk '{print $2}' | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t5.ok ; \ 180s 18:47:07.781625756 O: fi 180s 18:47:07.783286226 E: + ssh-keygen -Bf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub 180s 18:47:07.786852545 E: + awk {print $2} 180s 18:47:07.787739162 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 180s 18:47:07.789441131 O: ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t6.out1 ; \ 180s 18:47:07.790512261 O: ssh-keygen -if /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t6.out2 ; \ 180s 18:47:07.788401664 E: + diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t5.ok 180s 18:47:07.792076830 E: + /usr/bin/ssh -Q key 180s 18:47:07.791407308 O: chmod 600 /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t6.out1 ; \ 180s 18:47:07.792818857 E: + grep -q ^ssh-dss 180s 18:47:07.793063724 O: ssh-keygen -yf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t6.out2 ; \ 180s 18:47:07.793832894 O: fi 180s 18:47:07.795353185 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 180s 18:47:07.794740904 E: + + /usr/bin/ssh -Q key 180s 18:47:07.797056086 E: grep -q ^ssh-dss 180s 18:47:07.797801843 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t7.out ; \ 180s 18:47:07.798550498 O: fi 180s 18:47:07.799296556 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 180s 18:47:07.799908095 E: + + /usr/bin/ssh -Q key 180s 18:47:07.801271412 E: grep -q ^ssh-dss 180s 18:47:07.800656910 O: ssh-keygen -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 180s 18:47:07.802029972 O: ssh-keygen -Bf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 180s 18:47:07.802805310 O: fi 180s 18:47:07.804197193 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 180s 18:47:07.804962459 E: + ssh+ -Q key 180s 18:47:07.805561105 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t8.out ; \ 180s 18:47:07.806163670 E: grep -q ^ssh-dss 180s 18:47:07.806773982 O: fi 180s 18:47:07.810323500 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 180s 18:47:07.810914597 E: + ssh+ -Q key 180s 18:47:07.812281593 E: grep -q ^ssh-dss 180s 18:47:07.811658634 O: ssh-keygen -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 180s 18:47:07.813059467 O: ssh-keygen -Bf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 180s 18:47:07.813724256 O: fi 180s 18:47:07.815799431 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 180s 18:47:07.816556521 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t9.out 180s 18:47:07.823501554 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 180s 18:47:07.824294572 O: ssh-keygen -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t9.out > /dev/null 180s 18:47:07.831256265 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 180s 18:47:07.832077449 O: ssh-keygen -Bf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t9.out > /dev/null 180s 18:47:07.838828451 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t10.out 180s 18:47:07.844878068 O: ssh-keygen -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t10.out > /dev/null 180s 18:47:07.848654486 O: ssh-keygen -Bf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t10.out > /dev/null 180s 18:47:07.853071947 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 180s 18:47:07.852942179 E: + /usr/bin/ssh -Q key 180s 18:47:07.853942160 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 180s 18:47:07.854736812 E: + grep -q ^ssh-dss 180s 18:47:07.855414658 O: awk '{print $2}' | diff - /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t11.ok ; \ 180s 18:47:07.856198779 O: fi 180s 18:47:07.857547209 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t12.out 180s 18:47:07.861581441 O: ssh-keygen -lf /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 180s 18:47:07.869203802 E: run test connect.sh ... 181s 18:47:08.564583906 O: ok simple connect 181s 18:47:08.565252078 E: run test proxy-connect.sh ... 181s 18:47:08.699496445 O: plain username comp=no 181s 18:47:08.803529121 O: plain username comp=yes 181s 18:47:08.911695131 O: username with style 182s 18:47:09.024599203 E: run test sshfp-connect.sh ... 182s 18:47:09.024958820 O: ok proxy connect 182s 18:47:09.155750230 E: run test connect-privsep.sh ... 182s 18:47:09.156436679 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 183s 18:47:10.705969609 O: ok proxy connect with privsep 183s 18:47:10.706730926 E: run test connect-uri.sh ... 183s 18:47:10.875612674 O: uri connect: no trailing slash 184s 18:47:11.043954636 O: uri connect: trailing slash 184s 18:47:11.240614551 O: uri connect: with path name 184s 18:47:11.266777744 E: run test proto-version.sh ... 184s 18:47:11.266947601 O: ok uri connect 184s 18:47:11.414079340 O: ok sshd version with different protocol combinations 184s 18:47:11.414791994 E: run test proto-mismatch.sh ... 184s 18:47:11.567323582 O: ok protocol version mismatch 184s 18:47:11.568086054 E: run test exit-status.sh ... 184s 18:47:11.694085437 O: test remote exit status: status 0 189s 18:47:16.915063426 O: test remote exit status: status 1 195s 18:47:22.134857269 O: test remote exit status: status 4 200s 18:47:27.358436933 O: test remote exit status: status 5 205s 18:47:32.571439625 O: test remote exit status: status 44 210s 18:47:37.790738058 O: ok remote exit status 210s 18:47:37.791632187 E: run test exit-status-signal.sh ... 211s 18:47:38.923173238 O: ok exit status on signal 211s 18:47:38.924286697 E: run test envpass.sh ... 212s 18:47:39.080152096 O: test environment passing: pass env, don't accept 212s 18:47:39.198459834 O: test environment passing: setenv, don't accept 212s 18:47:39.316253342 O: test environment passing: don't pass env, accept 212s 18:47:39.428071575 O: test environment passing: pass single env, accept single env 212s 18:47:39.542298088 O: test environment passing: pass multiple env, accept multiple env 212s 18:47:39.653972835 O: test environment passing: setenv, accept 212s 18:47:39.764408163 O: test environment passing: setenv, first match wins 212s 18:47:39.879882686 O: test environment passing: server setenv wins 213s 18:47:39.993110075 O: test environment passing: server setenv wins 213s 18:47:40.100795812 O: ok environment passing 213s 18:47:40.101703602 E: run test transfer.sh ... 214s 18:47:41.180481185 O: ok transfer data 214s 18:47:41.180747200 E: run test banner.sh ... 214s 18:47:41.310434517 O: test banner: missing banner file 214s 18:47:41.422264387 O: test banner: size 0 214s 18:47:41.530876594 O: test banner: size 10 214s 18:47:41.655873503 O: test banner: size 100 214s 18:47:41.782941205 O: test banner: size 1000 214s 18:47:41.905091342 O: test banner: size 10000 215s 18:47:42.023436442 O: test banner: size 100000 215s 18:47:42.139415585 O: test banner: suppress banner (-q) 215s 18:47:42.245981089 O: ok banner 215s 18:47:42.246140943 E: run test rekey.sh ... 215s 18:47:42.397226294 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 215s 18:47:42.525537114 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 215s 18:47:42.673255031 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 215s 18:47:42.798492271 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 216s 18:47:42.976065622 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 216s 18:47:43.336005653 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 216s 18:47:43.715099240 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 217s 18:47:44.088145550 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 217s 18:47:44.200903575 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 217s 18:47:44.317642617 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 217s 18:47:44.439157483 O: client rekey KexAlgorithms=curve25519-sha256 217s 18:47:44.582472178 O: client rekey KexAlgorithms=sntrup761x25519-sha512 217s 18:47:44.761635000 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 217s 18:47:44.946166913 O: client rekey KexAlgorithms=mlkem768x25519-sha256 218s 18:47:45.097443477 O: client rekey Ciphers=3des-cbc 218s 18:47:45.243333855 O: client rekey Ciphers=aes128-cbc 218s 18:47:45.394960216 O: client rekey Ciphers=aes192-cbc 218s 18:47:45.551235693 O: client rekey Ciphers=aes256-cbc 218s 18:47:45.701007724 O: client rekey Ciphers=aes128-ctr 218s 18:47:45.852277731 O: client rekey Ciphers=aes192-ctr 219s 18:47:46.004972746 O: client rekey Ciphers=aes256-ctr 219s 18:47:46.158419926 O: client rekey Ciphers=aes128-gcm@openssh.com 221s 18:47:48.145324223 O: client rekey Ciphers=aes256-gcm@openssh.com 223s 18:47:50.340457048 O: client rekey Ciphers=chacha20-poly1305@openssh.com 225s 18:47:52.958312789 O: client rekey MACs=hmac-sha1 226s 18:47:53.106882235 O: client rekey MACs=hmac-sha1-96 226s 18:47:53.253566131 O: client rekey MACs=hmac-sha2-256 226s 18:47:53.395778067 O: client rekey MACs=hmac-sha2-512 226s 18:47:53.532353285 O: client rekey MACs=hmac-md5 226s 18:47:53.672942860 O: client rekey MACs=hmac-md5-96 226s 18:47:53.826893873 O: client rekey MACs=umac-64@openssh.com 227s 18:47:53.972231137 O: client rekey MACs=umac-128@openssh.com 227s 18:47:54.124448643 O: client rekey MACs=hmac-sha1-etm@openssh.com 227s 18:47:54.280599715 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 227s 18:47:54.434534386 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 227s 18:47:54.592195102 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 227s 18:47:54.739210975 O: client rekey MACs=hmac-md5-etm@openssh.com 227s 18:47:54.887169567 O: client rekey MACs=hmac-md5-96-etm@openssh.com 228s 18:47:55.033046217 O: client rekey MACs=umac-64-etm@openssh.com 228s 18:47:55.186774031 O: client rekey MACs=umac-128-etm@openssh.com 228s 18:47:55.335893673 O: client rekeylimit 16 228s 18:47:55.498988675 O: client rekeylimit 1k 228s 18:47:55.643133486 O: client rekeylimit 128k 228s 18:47:55.819815500 O: client rekeylimit 256k 229s 18:47:56.037923662 O: client rekeylimit default 5 244s 18:48:11.150213168 O: client rekeylimit default 10 264s 18:48:31.257388913 O: client rekeylimit default 5 no data 279s 18:48:46.378572690 O: client rekeylimit default 10 no data 299s 18:49:06.500579001 O: server rekeylimit 16 299s 18:49:06.687960808 O: server rekeylimit 1k 299s 18:49:06.843545773 O: server rekeylimit 128k 300s 18:49:07.013382071 O: server rekeylimit 256k 300s 18:49:07.246182871 O: server rekeylimit default 5 no data 315s 18:49:22.377661137 O: server rekeylimit default 10 no data 335s 18:49:42.498966794 O: rekeylimit parsing: bytes 335s 18:49:42.569196462 O: rekeylimit parsing: time 335s 18:49:42.634118998 O: ok rekey 335s 18:49:42.634222031 E: run test dhgex.sh ... 335s 18:49:42.762605819 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 335s 18:49:42.869344383 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 335s 18:49:42.979310883 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 336s 18:49:43.089366374 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 336s 18:49:43.191395581 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 336s 18:49:43.291336646 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 336s 18:49:43.391482905 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 336s 18:49:43.494490314 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 336s 18:49:43.603025222 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 336s 18:49:43.738213999 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 336s 18:49:43.877055988 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 337s 18:49:44.007410443 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 337s 18:49:44.153248044 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 337s 18:49:44.303519293 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 337s 18:49:44.451876431 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 337s 18:49:44.594856562 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 337s 18:49:44.730875280 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 337s 18:49:44.881802638 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 338s 18:49:45.022367488 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 338s 18:49:45.201201526 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 338s 18:49:45.378424396 O: ok dhgex 338s 18:49:45.379338732 E: run test stderr-data.sh ... 338s 18:49:45.493775219 O: test stderr data transfer: () 344s 18:49:51.732887288 O: test stderr data transfer: (-n) 350s 18:49:57.971396297 O: ok stderr data transfer 350s 18:49:57.972271963 E: run test stderr-after-eof.sh ... 353s 18:50:00.221923418 O: ok stderr data after eof 353s 18:50:00.221987258 E: run test broken-pipe.sh ... 353s 18:50:00.388188104 O: ok broken pipe test 353s 18:50:00.388534497 E: run test try-ciphers.sh ... 353s 18:50:00.530409730 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 353s 18:50:00.664830536 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 353s 18:50:00.773545194 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 353s 18:50:00.887660608 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 354s 18:50:01.004196640 O: test try ciphers: cipher 3des-cbc mac hmac-md5 354s 18:50:01.114367682 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 354s 18:50:01.231813165 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 354s 18:50:01.353400540 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 354s 18:50:01.472302962 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 354s 18:50:01.583727450 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 354s 18:50:01.701698026 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 354s 18:50:01.816250923 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 354s 18:50:01.935369753 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 355s 18:50:02.049883614 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 355s 18:50:02.178665959 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 355s 18:50:02.298061584 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 355s 18:50:02.420108074 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 355s 18:50:02.534517050 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 355s 18:50:02.648737218 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 355s 18:50:02.773006681 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 355s 18:50:02.892465013 O: test try ciphers: cipher aes128-cbc mac hmac-md5 356s 18:50:03.011583934 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 356s 18:50:03.128919861 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 356s 18:50:03.252808306 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 356s 18:50:03.377566434 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 356s 18:50:03.496394740 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 356s 18:50:03.614501244 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 356s 18:50:03.727062782 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 356s 18:50:03.836319679 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 356s 18:50:03.949246325 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 357s 18:50:04.069747620 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 357s 18:50:04.188477745 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 357s 18:50:04.309861470 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 357s 18:50:04.430532395 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 357s 18:50:04.548778943 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 357s 18:50:04.669497057 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 357s 18:50:04.786625285 O: test try ciphers: cipher aes192-cbc mac hmac-md5 357s 18:50:04.898904887 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 358s 18:50:05.010590383 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 358s 18:50:05.125556686 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 358s 18:50:05.248599919 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 358s 18:50:05.368744355 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 358s 18:50:05.485846042 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 358s 18:50:05.596377581 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 358s 18:50:05.713718603 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 358s 18:50:05.836193679 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 358s 18:50:05.954482318 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 359s 18:50:06.075127824 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 359s 18:50:06.201836180 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 359s 18:50:06.319994852 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 359s 18:50:06.437218908 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 359s 18:50:06.560260490 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 359s 18:50:06.682436040 O: test try ciphers: cipher aes256-cbc mac hmac-md5 359s 18:50:06.799816731 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 359s 18:50:06.914904876 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 360s 18:50:07.032599053 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 360s 18:50:07.146931687 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 360s 18:50:07.263952323 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 360s 18:50:07.387422208 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 360s 18:50:07.505197536 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 360s 18:50:07.624731880 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 360s 18:50:07.746611017 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 360s 18:50:07.865002649 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 361s 18:50:07.983698288 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 361s 18:50:08.106260338 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 361s 18:50:08.219229244 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 361s 18:50:08.334938463 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 361s 18:50:08.450405784 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 361s 18:50:08.565501311 O: test try ciphers: cipher aes128-ctr mac hmac-md5 361s 18:50:08.677565406 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 361s 18:50:08.793926829 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 361s 18:50:08.908807645 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 362s 18:50:09.026320454 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 362s 18:50:09.144111706 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 362s 18:50:09.271383052 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 362s 18:50:09.391118308 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 362s 18:50:09.508831202 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 362s 18:50:09.629608972 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 362s 18:50:09.750068480 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 362s 18:50:09.873638569 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 363s 18:50:10.007841869 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 363s 18:50:10.138875286 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 363s 18:50:10.268992526 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 363s 18:50:10.401956687 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 363s 18:50:10.533793452 O: test try ciphers: cipher aes192-ctr mac hmac-md5 363s 18:50:10.655998061 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 363s 18:50:10.777670057 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 364s 18:50:10.900426282 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 364s 18:50:11.027047378 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 364s 18:50:11.154642878 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 364s 18:50:11.277079310 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 364s 18:50:11.411457312 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 364s 18:50:11.538197229 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 364s 18:50:11.675273723 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 364s 18:50:11.798455936 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 364s 18:50:11.917966597 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 365s 18:50:12.045484090 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 365s 18:50:12.173474083 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 365s 18:50:12.300761838 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 365s 18:50:12.435443054 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 365s 18:50:12.561543817 O: test try ciphers: cipher aes256-ctr mac hmac-md5 365s 18:50:12.684868803 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 365s 18:50:12.808374377 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 365s 18:50:12.930052220 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 366s 18:50:13.057409180 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 366s 18:50:13.180916497 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 366s 18:50:13.308142330 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 366s 18:50:13.436440689 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 366s 18:50:13.565953828 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 366s 18:50:13.696411383 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 366s 18:50:13.826895513 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 366s 18:50:13.956188203 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 367s 18:50:14.092645379 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 367s 18:50:14.226321527 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 367s 18:50:14.353141898 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 367s 18:50:14.476048275 O: ok try ciphers 367s 18:50:14.476601078 E: run test yes-head.sh ... 370s 18:50:17.612807537 O: ok yes pipe head 370s 18:50:17.613839378 E: run test login-timeout.sh ... 386s 18:50:33.007679467 O: ok connect after login grace timeout 386s 18:50:33.009078471 E: run test agent.sh ... 390s 18:50:37.156914763 O: ok simple agent test 390s 18:50:37.157361653 E: run test agent-getpeereid.sh ... 390s 18:50:37.311280884 O: ok disallow agent attach from other uid 390s 18:50:37.311775674 E: run test agent-timeout.sh ... 410s 18:50:57.499001240 O: ok agent timeout test 410s 18:50:57.499997969 E: run test agent-ptrace.sh ... 410s 18:50:57.622438559 O: skipped (gdb not found) 410s 18:50:57.622512279 E: run test agent-subprocess.sh ... 420s 18:51:07.778805529 O: ok agent subprocess 420s 18:51:07.779115561 E: run test keyscan.sh ... 421s 18:51:08.586887736 O: ok keyscan 421s 18:51:08.587972388 E: run test keygen-change.sh ... 427s 18:51:14.101090906 O: ok change passphrase for key 427s 18:51:14.101649489 E: run test keygen-comment.sh ... 428s 18:51:15.637916886 O: ok Comment extraction from private key 428s 18:51:15.638928376 E: run test keygen-convert.sh ... 432s 18:51:19.548242066 O: ok convert keys 432s 18:51:19.548865728 E: run test keygen-knownhosts.sh ... 432s 18:51:19.811644540 O: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 18:51:19.812704439 O: Original contents retained as /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts.old 432s 18:51:19.829683619 O: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 18:51:19.830816086 O: Original contents retained as /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts.old 432s 18:51:19.836319406 O: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 18:51:19.837278947 O: Original contents retained as /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts.old 432s 18:51:19.848270081 O: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 18:51:19.849208999 O: Original contents retained as /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hosts.old 432s 18:51:19.871801614 O: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hashed updated. 432s 18:51:19.872782776 O: Original contents retained as /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/kh.hashed.old 432s 18:51:19.876467674 O: ok ssh-keygen known_hosts 432s 18:51:19.876932453 E: run test keygen-moduli.sh ... 435s 18:51:22.005448717 O: ok keygen moduli 435s 18:51:22.006504036 E: run test keygen-sshfp.sh ... 435s 18:51:22.177309525 O: ok keygen-sshfp 435s 18:51:22.178213774 E: run test key-options.sh ... 435s 18:51:22.309064650 O: key option command="echo bar" 435s 18:51:22.422052362 O: key option no-pty,command="echo bar" 435s 18:51:22.533176486 O: key option pty default 435s 18:51:22.650404847 O: key option pty no-pty 435s 18:51:22.758668938 O: key option pty restrict 435s 18:51:22.868697558 O: key option pty restrict,pty 435s 18:51:22.985223878 O: key option environment 436s 18:51:23.264864229 O: key option from="127.0.0.1" 436s 18:51:23.681501993 O: key option from="127.0.0.0/8" 436s 18:51:23.978188842 O: key option expiry-time default 437s 18:51:24.095506716 O: key option expiry-time invalid 437s 18:51:24.224527559 O: key option expiry-time expired 437s 18:51:24.350039273 O: key option expiry-time valid 437s 18:51:24.481496296 O: ok key options 437s 18:51:24.482090981 E: run test scp.sh ... 437s 18:51:24.623286410 O: scp: scp mode: simple copy local file to local file 437s 18:51:24.636698686 O: scp: scp mode: simple copy local file to remote file 437s 18:51:24.648426906 O: scp: scp mode: simple copy remote file to local file 437s 18:51:24.657993302 O: scp: scp mode: copy local file to remote file in place 437s 18:51:24.668092972 O: scp: scp mode: copy remote file to local file in place 437s 18:51:24.678383007 O: scp: scp mode: copy local file to remote file clobber 437s 18:51:24.687461079 O: -rw-r--r-- 1 openssh-tests openssh-tests 1248304 Jan 18 18:51 /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/copy 437s 18:51:24.688504115 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1248304 Jan 18 18:51 /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/data 437s 18:51:24.690002657 O: scp: scp mode: copy remote file to local file clobber 437s 18:51:24.701071099 O: scp: scp mode: simple copy local file to remote dir 437s 18:51:24.712373884 O: scp: scp mode: simple copy local file to local dir 437s 18:51:24.722771389 O: scp: scp mode: simple copy remote file to local dir 437s 18:51:24.732450151 O: scp: scp mode: recursive local dir to remote dir 437s 18:51:24.757311028 O: scp: scp mode: recursive local dir to local dir 437s 18:51:24.777384539 O: scp: scp mode: recursive remote dir to local dir 437s 18:51:24.804744073 O: scp: scp mode: unmatched glob file local->remote 437s 18:51:24.815138089 O: scp: scp mode: unmatched glob file remote->local 437s 18:51:24.822470954 O: scp: scp mode: unmatched glob dir recursive local->remote 437s 18:51:24.840406015 O: scp: scp mode: unmatched glob dir recursive remote->local 437s 18:51:24.849282892 O: scp: scp mode: shell metacharacters 437s 18:51:24.857159832 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 437s 18:51:24.893691532 O: scp: scp mode: disallow bad server #0 437s 18:51:24.914321285 O: scp: scp mode: disallow bad server #1 437s 18:51:24.934643961 O: scp: scp mode: disallow bad server #2 437s 18:51:24.953403103 O: scp: scp mode: disallow bad server #3 437s 18:51:24.973665171 O: scp: scp mode: disallow bad server #4 438s 18:51:24.994271440 O: scp: scp mode: disallow bad server #5 438s 18:51:25.013551780 O: scp: scp mode: disallow bad server #6 438s 18:51:25.034723048 O: scp: scp mode: disallow bad server #7 438s 18:51:25.058965569 O: scp: scp mode: detect non-directory target 438s 18:51:25.061848358 E: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/copy2: Not a directory 438s 18:51:25.063490746 O: scp: sftp mode: simple copy local file to local file 438s 18:51:25.071860371 O: scp: sftp mode: simple copy local file to remote file 438s 18:51:25.082369992 O: scp: sftp mode: simple copy remote file to local file 438s 18:51:25.090984630 O: scp: sftp mode: copy local file to remote file in place 438s 18:51:25.102635741 O: scp: sftp mode: copy remote file to local file in place 438s 18:51:25.112492714 O: scp: sftp mode: copy local file to remote file clobber 438s 18:51:25.124361826 O: -rw-r--r-- 1 openssh-tests openssh-tests 1248304 Jan 18 18:51 /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/copy 438s 18:51:25.125301703 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1248304 Jan 18 18:51 /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/data 438s 18:51:25.127152052 O: scp: sftp mode: copy remote file to local file clobber 438s 18:51:25.137947665 O: scp: sftp mode: simple copy local file to remote dir 438s 18:51:25.148320758 O: scp: sftp mode: simple copy local file to local dir 438s 18:51:25.158567350 O: scp: sftp mode: simple copy remote file to local dir 438s 18:51:25.168649388 O: scp: sftp mode: recursive local dir to remote dir 438s 18:51:25.192751602 O: scp: sftp mode: recursive local dir to local dir 438s 18:51:25.213447574 O: scp: sftp mode: recursive remote dir to local dir 438s 18:51:25.242690161 O: scp: sftp mode: unmatched glob file local->remote 438s 18:51:25.252534380 O: scp: sftp mode: unmatched glob file remote->local 438s 18:51:25.262267459 O: scp: sftp mode: unmatched glob dir recursive local->remote 438s 18:51:25.278474271 O: scp: sftp mode: unmatched glob dir recursive remote->local 438s 18:51:25.291914004 O: scp: sftp mode: shell metacharacters 438s 18:51:25.299238045 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 438s 18:51:25.342987293 O: scp: sftp mode: disallow bad server #0 438s 18:51:25.369682358 O: scp: sftp mode: disallow bad server #1 438s 18:51:25.397062890 O: scp: sftp mode: disallow bad server #2 438s 18:51:25.422957318 O: scp: sftp mode: disallow bad server #3 438s 18:51:25.455236831 O: scp: sftp mode: disallow bad server #4 438s 18:51:25.482959045 O: scp: sftp mode: disallow bad server #5 438s 18:51:25.516456151 O: scp: sftp mode: disallow bad server #6 438s 18:51:25.543928378 O: scp: sftp mode: disallow bad server #7 438s 18:51:25.573456589 O: scp: sftp mode: detect non-directory target 438s 18:51:25.577070992 E: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/copy2: Not a directory 438s 18:51:25.582459045 O: ok scp 438s 18:51:25.583657854 E: run test scp3.sh ... 438s 18:51:25.711137479 O: scp3: scp mode: simple copy remote file to remote file 438s 18:51:25.849487924 O: scp3: scp mode: simple copy remote file to remote dir 439s 18:51:25.997895780 O: scp3: scp mode: recursive remote dir to remote dir 439s 18:51:26.168372379 O: scp3: scp mode: detect non-directory target 439s 18:51:26.415202431 O: scp3: sftp mode: simple copy remote file to remote file 439s 18:51:26.425951349 O: scp3: sftp mode: simple copy remote file to remote dir 439s 18:51:26.439294723 O: scp3: sftp mode: recursive remote dir to remote dir 439s 18:51:26.470417983 O: scp3: sftp mode: detect non-directory target 439s 18:51:26.475768349 E: scp: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/copy2: destination is not a directory 439s 18:51:26.477396429 E: scp: /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/copy2: destination is not a directory 439s 18:51:26.482844928 O: ok scp3 439s 18:51:26.483031213 E: run test scp-uri.sh ... 439s 18:51:26.613190157 O: scp-uri: scp mode: simple copy local file to remote file 439s 18:51:26.620742227 O: scp-uri: scp mode: simple copy remote file to local file 439s 18:51:26.628171852 O: scp-uri: scp mode: simple copy local file to remote dir 439s 18:51:26.637610568 O: scp-uri: scp mode: simple copy remote file to local dir 439s 18:51:26.646904212 O: scp-uri: scp mode: recursive local dir to remote dir 439s 18:51:26.657449995 O: scp-uri: scp mode: recursive remote dir to local dir 439s 18:51:26.669444187 O: scp-uri: sftp mode: simple copy local file to remote file 439s 18:51:26.677077155 O: scp-uri: sftp mode: simple copy remote file to local file 439s 18:51:26.683975446 O: scp-uri: sftp mode: simple copy local file to remote dir 439s 18:51:26.693141873 O: scp-uri: sftp mode: simple copy remote file to local dir 439s 18:51:26.702436043 O: scp-uri: sftp mode: recursive local dir to remote dir 439s 18:51:26.713048749 O: scp-uri: sftp mode: recursive remote dir to local dir 439s 18:51:26.730812848 E: run test sftp.sh ... 439s 18:51:26.730076782 O: ok scp-uri 439s 18:51:26.853326079 O: test basic sftp put/get: buffer_size 5 num_requests 1 456s 18:51:43.301016723 O: test basic sftp put/get: buffer_size 5 num_requests 2 461s 18:51:48.733288587 O: test basic sftp put/get: buffer_size 5 num_requests 10 465s 18:51:52.932340872 O: test basic sftp put/get: buffer_size 1000 num_requests 1 466s 18:51:53.010993488 O: test basic sftp put/get: buffer_size 1000 num_requests 2 466s 18:51:53.059547710 O: test basic sftp put/get: buffer_size 1000 num_requests 10 466s 18:51:53.091978314 O: test basic sftp put/get: buffer_size 32000 num_requests 1 466s 18:51:53.105115974 O: test basic sftp put/get: buffer_size 32000 num_requests 2 466s 18:51:53.116598088 O: test basic sftp put/get: buffer_size 32000 num_requests 10 466s 18:51:53.131113898 O: test basic sftp put/get: buffer_size 64000 num_requests 1 466s 18:51:53.145279186 O: test basic sftp put/get: buffer_size 64000 num_requests 2 466s 18:51:53.157837287 O: test basic sftp put/get: buffer_size 64000 num_requests 10 466s 18:51:53.171153917 O: ok basic sftp put/get 466s 18:51:53.172065768 E: run test sftp-chroot.sh ... 466s 18:51:53.341123517 O: test sftp in chroot: get 466s 18:51:53.560243482 O: test sftp in chroot: match 466s 18:51:53.807335021 O: ok sftp in chroot 466s 18:51:53.814677592 E: run test sftp-cmds.sh ... 466s 18:51:53.946900005 O: sftp commands: lls 466s 18:51:53.952982216 O: sftp commands: lls w/path 466s 18:51:53.958451688 O: sftp commands: ls 466s 18:51:53.966061651 O: sftp commands: shell 466s 18:51:53.969992381 O: sftp commands: pwd 466s 18:51:53.973060739 O: sftp commands: lpwd 466s 18:51:53.975846007 O: sftp commands: quit 466s 18:51:53.979062584 O: sftp commands: help 466s 18:51:53.981974364 O: sftp commands: get 466s 18:51:53.989874113 O: sftp commands: get quoted 467s 18:51:53.998428487 O: sftp commands: get filename with quotes 467s 18:51:54.009490152 O: sftp commands: get filename with spaces 467s 18:51:54.017721171 O: sftp commands: get filename with glob metacharacters 467s 18:51:54.025529971 O: sftp commands: get to directory 467s 18:51:54.032891688 O: sftp commands: glob get to directory 467s 18:51:54.156269033 O: sftp commands: get to local dir 467s 18:51:54.162757781 O: sftp commands: glob get to local dir 467s 18:51:54.215154433 O: sftp commands: put 467s 18:51:54.221663762 O: sftp commands: put filename with quotes 467s 18:51:54.229472013 O: sftp commands: put filename with spaces 467s 18:51:54.241448759 O: sftp commands: put to directory 467s 18:51:54.250149171 O: sftp commands: glob put to directory 467s 18:51:54.259116110 O: sftp commands: put to local dir 467s 18:51:54.266618398 O: sftp commands: glob put to local dir 467s 18:51:54.300158204 O: sftp commands: rename 467s 18:51:54.304900871 O: sftp commands: rename directory 467s 18:51:54.312751410 O: sftp commands: ln 467s 18:51:54.315965764 O: sftp commands: ln -s 467s 18:51:54.320162884 O: sftp commands: cp 467s 18:51:54.327348628 O: sftp commands: mkdir 467s 18:51:54.330553875 O: sftp commands: chdir 467s 18:51:54.333224740 O: sftp commands: rmdir 467s 18:51:54.336388351 O: sftp commands: lmkdir 467s 18:51:54.343902482 O: sftp commands: lchdir 467s 18:51:54.352848936 O: ok sftp commands 467s 18:51:54.353254232 E: run test sftp-badcmds.sh ... 467s 18:51:54.482758572 O: sftp invalid commands: get nonexistent 467s 18:51:54.487202706 O: sftp invalid commands: glob get to nonexistent directory 467s 18:51:54.500977189 O: sftp invalid commands: put nonexistent 467s 18:51:54.505561095 O: sftp invalid commands: glob put to nonexistent directory 467s 18:51:54.510176358 O: sftp invalid commands: rename nonexistent 467s 18:51:54.516589247 O: sftp invalid commands: rename target exists (directory) 467s 18:51:54.523296611 O: sftp invalid commands: glob put files to local file 467s 18:51:54.528923307 O: ok sftp invalid commands 467s 18:51:54.529732255 E: run test sftp-batch.sh ... 467s 18:51:54.653268567 O: sftp batchfile: good commands 467s 18:51:54.663189539 O: sftp batchfile: bad commands 467s 18:51:54.673543800 O: sftp batchfile: comments and blanks 467s 18:51:54.679549666 O: sftp batchfile: junk command 467s 18:51:54.683600038 O: ok sftp batchfile 467s 18:51:54.684281763 E: run test sftp-glob.sh ... 467s 18:51:54.817407441 O: sftp glob: file glob 467s 18:51:54.826373530 O: sftp glob: dir glob 467s 18:51:54.832065393 O: sftp glob: quoted glob 467s 18:51:54.837886441 O: sftp glob: escaped glob 467s 18:51:54.844806065 O: sftp glob: escaped quote 467s 18:51:54.849451363 O: sftp glob: quoted quote 467s 18:51:54.854520732 O: sftp glob: single-quoted quote 467s 18:51:54.859391972 O: sftp glob: escaped space 467s 18:51:54.864456407 O: sftp glob: quoted space 467s 18:51:54.869432397 O: sftp glob: escaped slash 467s 18:51:54.875297821 O: sftp glob: quoted slash 467s 18:51:54.881925815 O: sftp glob: escaped slash at EOL 467s 18:51:54.886765452 O: sftp glob: quoted slash at EOL 467s 18:51:54.891631903 O: sftp glob: escaped slash+quote 467s 18:51:54.896582754 O: sftp glob: quoted slash+quote 467s 18:51:54.902609174 O: ok sftp glob 467s 18:51:54.902722330 E: run test sftp-perm.sh ... 468s 18:51:55.028661749 O: sftp permissions: read-only upload 468s 18:51:55.043872716 O: sftp permissions: read-only setstat 468s 18:51:55.065599350 O: sftp permissions: read-only rm 468s 18:51:55.082317308 O: sftp permissions: read-only mkdir 468s 18:51:55.094081107 O: sftp permissions: read-only rmdir 468s 18:51:55.106534940 O: sftp permissions: read-only posix-rename 468s 18:51:55.122617220 O: sftp permissions: read-only oldrename 468s 18:51:55.135750093 O: sftp permissions: read-only symlink 468s 18:51:55.152191903 O: sftp permissions: read-only hardlink 468s 18:51:55.164118339 O: sftp permissions: explicit open 468s 18:51:55.196744330 O: sftp permissions: explicit read 468s 18:51:55.229730762 O: sftp permissions: explicit write 468s 18:51:55.259400230 O: sftp permissions: explicit lstat 468s 18:51:55.286662661 O: sftp permissions: explicit opendir 468s 18:51:55.314667739 O: sftp permissions: explicit readdir 468s 18:51:55.343393109 O: sftp permissions: explicit setstat 468s 18:51:55.368867709 O: sftp permissions: explicit remove 468s 18:51:55.391604985 O: sftp permissions: explicit mkdir 468s 18:51:55.410968005 O: sftp permissions: explicit rmdir 468s 18:51:55.435001153 O: sftp permissions: explicit rename 468s 18:51:55.462821284 O: sftp permissions: explicit symlink 468s 18:51:55.481414079 O: sftp permissions: explicit hardlink 468s 18:51:55.504484394 O: sftp permissions: explicit statvfs 468s 18:51:55.525429426 O: ok sftp permissions 468s 18:51:55.525654020 E: run test sftp-uri.sh ... 468s 18:51:55.687486776 O: sftp-uri: non-interactive fetch to local file 468s 18:51:55.864966290 O: sftp-uri: non-interactive fetch to local dir 469s 18:51:56.068037304 O: sftp-uri: put to remote directory (trailing slash) 469s 18:51:56.244798168 O: sftp-uri: put to remote directory (no slash) 469s 18:51:56.463762514 O: ok sftp-uri 469s 18:51:56.463951469 E: run test reconfigure.sh ... 482s 18:52:09.450281526 O: ok simple connect after reconfigure 482s 18:52:09.450740643 E: run test dynamic-forward.sh ... 482s 18:52:09.611332164 O: test -D forwarding 483s 18:52:10.452771882 O: test -R forwarding 484s 18:52:11.458327177 O: PermitRemoteOpen=any 485s 18:52:12.457705957 O: PermitRemoteOpen=none 485s 18:52:12.798344483 O: PermitRemoteOpen=explicit 486s 18:52:13.812224952 O: PermitRemoteOpen=disallowed 487s 18:52:14.167546833 O: ok dynamic forwarding 487s 18:52:14.167661940 E: run test forwarding.sh ... 491s 18:52:18.243020219 O: ok local and remote forwarding 491s 18:52:18.243373480 E: run test multiplex.sh ... 492s 18:52:19.422881412 O: test connection multiplexing: setenv 492s 18:52:19.435163636 O: test connection multiplexing: envpass 492s 18:52:19.447506982 O: test connection multiplexing: transfer 492s 18:52:19.488759544 O: test connection multiplexing: transfer -Oproxy 492s 18:52:19.574135622 O: test connection multiplexing: forward 494s 18:52:21.623357862 O: test connection multiplexing: status 0 () 499s 18:52:26.648945702 O: test connection multiplexing: status 0 (-Oproxy) 504s 18:52:31.675394506 O: test connection multiplexing: status 1 () 509s 18:52:36.705778918 O: test connection multiplexing: status 1 (-Oproxy) 514s 18:52:41.735542133 O: test connection multiplexing: status 4 () 519s 18:52:46.766202677 O: test connection multiplexing: status 4 (-Oproxy) 524s 18:52:51.804210263 O: test connection multiplexing: status 5 () 529s 18:52:56.834851667 O: test connection multiplexing: status 5 (-Oproxy) 534s 18:53:01.860584458 O: test connection multiplexing: status 44 () 539s 18:53:06.888832751 O: test connection multiplexing: status 44 (-Oproxy) 544s 18:53:11.917006081 O: test connection multiplexing: cmd check 544s 18:53:11.926307453 O: test connection multiplexing: cmd forward local (TCP) 546s 18:53:13.161224330 O: test connection multiplexing: cmd forward remote (TCP) 547s 18:53:14.394504459 O: test connection multiplexing: cmd forward local (UNIX) 548s 18:53:15.426818679 O: test connection multiplexing: cmd forward remote (UNIX) 549s 18:53:16.456811125 O: test connection multiplexing: cmd exit 549s 18:53:16.466620587 O: test connection multiplexing: cmd stop 560s 18:53:27.519631293 O: ok connection multiplexing 560s 18:53:27.520336511 E: run test reexec.sh ... 560s 18:53:27.649661126 O: test config passing 560s 18:53:27.924193329 O: test reexec fallback 560s 18:53:27.925668170 E: ln: failed to create hard link '/tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 561s 18:53:28.211516869 O: ok reexec tests 561s 18:53:28.212146080 E: run test brokenkeys.sh ... 561s 18:53:28.626466727 O: ok broken keys 561s 18:53:28.627527401 E: run test sshcfgparse.sh ... 561s 18:53:28.767190758 O: reparse minimal config 561s 18:53:28.788667385 O: ssh -W opts 561s 18:53:28.837670094 O: user first match 561s 18:53:28.873690015 O: pubkeyacceptedalgorithms 561s 18:53:28.922257845 O: agentforwarding 561s 18:53:28.959743627 O: command line override 561s 18:53:28.980939893 O: ok ssh config parse 561s 18:53:28.981281726 E: run test cfgparse.sh ... 562s 18:53:29.110463764 O: reparse minimal config 562s 18:53:29.152299973 O: reparse regress config 562s 18:53:29.195410553 O: listenaddress order 562s 18:53:29.245569367 O: ok sshd config parse 562s 18:53:29.245935983 E: run test cfgmatch.sh ... 569s 18:53:36.428613621 O: ok sshd_config match 569s 18:53:36.428916546 E: run test cfgmatchlisten.sh ... 578s 18:53:45.761417256 O: ok sshd_config matchlisten 578s 18:53:45.761590316 E: run test percent.sh ... 578s 18:53:45.894480714 O: percent expansions matchexec percent 580s 18:53:47.447394686 O: percent expansions localcommand percent 581s 18:53:48.898737983 O: percent expansions remotecommand percent 582s 18:53:49.051720096 O: percent expansions controlpath percent 582s 18:53:49.199463430 O: percent expansions identityagent percent 582s 18:53:49.344705860 O: percent expansions forwardagent percent 582s 18:53:49.482655009 O: percent expansions localforward percent 582s 18:53:49.620446097 O: percent expansions remoteforward percent 582s 18:53:49.773554370 O: percent expansions revokedhostkeys percent 582s 18:53:49.923625937 O: percent expansions userknownhostsfile percent 584s 18:53:51.125221677 O: percent expansions controlpath dollar 584s 18:53:51.137458135 O: percent expansions identityagent dollar 584s 18:53:51.151123784 O: percent expansions forwardagent dollar 584s 18:53:51.167465473 O: percent expansions localforward dollar 584s 18:53:51.181445835 O: percent expansions remoteforward dollar 584s 18:53:51.194183833 O: percent expansions userknownhostsfile dollar 584s 18:53:51.309903430 O: percent expansions controlpath tilde 584s 18:53:51.335738233 O: percent expansions identityagent tilde 584s 18:53:51.360489264 O: percent expansions forwardagent tilde 584s 18:53:51.386032418 O: ok percent expansions 584s 18:53:51.386152840 E: run test addrmatch.sh ... 584s 18:53:51.535800536 O: test first entry for user 192.168.0.1 somehost 584s 18:53:51.552841104 O: test negative match for user 192.168.30.1 somehost 584s 18:53:51.567465631 O: test no match for user 19.0.0.1 somehost 584s 18:53:51.583971398 O: test list middle for user 10.255.255.254 somehost 584s 18:53:51.599491593 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 584s 18:53:51.615899386 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 584s 18:53:51.631045949 O: test localaddress for user 19.0.0.1 somehost 584s 18:53:51.645876018 O: test localport for user 19.0.0.1 somehost 584s 18:53:51.663243951 O: test bare IP6 address for user ::1 somehost.example.com 584s 18:53:51.679059708 O: test deny IPv6 for user ::2 somehost.example.com 584s 18:53:51.695113654 O: test IP6 negated for user ::3 somehost 584s 18:53:51.710480272 O: test IP6 no match for user ::4 somehost 584s 18:53:51.726953044 O: test IP6 network for user 2000::1 somehost 584s 18:53:51.741413851 O: test IP6 network for user 2001::1 somehost 584s 18:53:51.757008299 O: test IP6 localaddress for user ::5 somehost 584s 18:53:51.771471615 O: test IP6 localport for user ::5 somehost 584s 18:53:51.786330028 O: test invalid Match address 10.0.1.0/8 584s 18:53:51.796422106 O: test invalid Match localaddress 10.0.1.0/8 584s 18:53:51.807699964 O: test invalid Match address 10.0.0.1/24 584s 18:53:51.820137779 O: test invalid Match localaddress 10.0.0.1/24 584s 18:53:51.831740533 O: test invalid Match address 2000:aa:bb:01::/56 584s 18:53:51.844521628 O: test invalid Match localaddress 2000:aa:bb:01::/56 584s 18:53:51.857521822 O: ok address match 584s 18:53:51.858032294 E: run test localcommand.sh ... 585s 18:53:51.996397636 O: test localcommand: proto localcommand 585s 18:53:52.105718944 O: ok localcommand 585s 18:53:52.107013449 E: run test forcecommand.sh ... 585s 18:53:52.753434134 E: Connection closed. 585s 18:53:52.755394994 E: Connection closed 586s 18:53:52.992832032 E: Connection closed. 586s 18:53:52.995146230 E: Connection closed 586s 18:53:53.125631108 O: ok forced command 586s 18:53:53.125968491 E: run test portnum.sh ... 586s 18:53:53.264785998 O: port number parsing: invalid port 0 586s 18:53:53.273037039 O: port number parsing: invalid port 65536 586s 18:53:53.280958685 O: port number parsing: invalid port 131073 586s 18:53:53.289388550 O: port number parsing: invalid port 2000blah 586s 18:53:53.298186461 O: port number parsing: invalid port blah2000 586s 18:53:53.306532831 O: port number parsing: valid port 1 586s 18:53:53.425528647 O: port number parsing: valid port 22 586s 18:53:53.540155325 O: port number parsing: valid port 2222 586s 18:53:53.656546444 O: port number parsing: valid port 22222 586s 18:53:53.771791526 O: port number parsing: valid port 65535 586s 18:53:53.884401938 O: ok port number parsing 586s 18:53:53.885683070 E: run test keytype.sh ... 587s 18:53:54.034989263 O: keygen ed25519, 512 bits 587s 18:53:54.045926425 O: keygen ed25519-sk, n/a bits 587s 18:53:54.059307691 O: keygen ecdsa, 256 bits 587s 18:53:54.069812887 O: keygen ecdsa, 384 bits 587s 18:53:54.081735089 O: keygen ecdsa, 521 bits 587s 18:53:54.090456055 O: keygen ecdsa-sk, n/a bits 587s 18:53:54.102277919 O: keygen rsa, 2048 bits 587s 18:53:54.219417549 O: keygen rsa, 3072 bits 587s 18:53:54.432517581 O: userkey ed25519-512, hostkey ed25519-512 587s 18:53:54.528146301 O: userkey ed25519-512, hostkey ed25519-512 587s 18:53:54.624160088 O: userkey ed25519-512, hostkey ed25519-512 587s 18:53:54.730602810 O: userkey ed25519-sk, hostkey ed25519-sk 587s 18:53:54.839579031 O: userkey ed25519-sk, hostkey ed25519-sk 587s 18:53:54.950973612 O: userkey ed25519-sk, hostkey ed25519-sk 588s 18:53:55.069306048 O: userkey ecdsa-256, hostkey ecdsa-256 588s 18:53:55.162532723 O: userkey ecdsa-256, hostkey ecdsa-256 588s 18:53:55.254298294 O: userkey ecdsa-256, hostkey ecdsa-256 588s 18:53:55.357915225 O: userkey ecdsa-384, hostkey ecdsa-384 588s 18:53:55.455270044 O: userkey ecdsa-384, hostkey ecdsa-384 588s 18:53:55.548186758 O: userkey ecdsa-384, hostkey ecdsa-384 588s 18:53:55.655839418 O: userkey ecdsa-521, hostkey ecdsa-521 588s 18:53:55.749529040 O: userkey ecdsa-521, hostkey ecdsa-521 588s 18:53:55.839664599 O: userkey ecdsa-521, hostkey ecdsa-521 588s 18:53:55.932183337 O: userkey ecdsa-sk, hostkey ecdsa-sk 589s 18:53:56.031478178 O: userkey ecdsa-sk, hostkey ecdsa-sk 589s 18:53:56.125549344 O: userkey ecdsa-sk, hostkey ecdsa-sk 589s 18:53:56.226715290 O: userkey rsa-2048, hostkey rsa-2048 589s 18:53:56.322375948 O: userkey rsa-2048, hostkey rsa-2048 589s 18:53:56.417297641 O: userkey rsa-2048, hostkey rsa-2048 589s 18:53:56.516483334 O: userkey rsa-3072, hostkey rsa-3072 589s 18:53:56.619791016 O: userkey rsa-3072, hostkey rsa-3072 589s 18:53:56.715542388 O: userkey rsa-3072, hostkey rsa-3072 589s 18:53:56.815367073 E: run test kextype.sh ... 589s 18:53:56.815240010 O: ok login with different key types 589s 18:53:56.959925119 O: kex diffie-hellman-group1-sha1 590s 18:53:57.313352926 O: kex diffie-hellman-group14-sha1 590s 18:53:57.688593257 O: kex diffie-hellman-group14-sha256 591s 18:53:58.060401215 O: kex diffie-hellman-group16-sha512 591s 18:53:58.494770463 O: kex diffie-hellman-group18-sha512 592s 18:53:59.145622868 O: kex diffie-hellman-group-exchange-sha1 592s 18:53:59.840387352 O: kex diffie-hellman-group-exchange-sha256 593s 18:54:00.551185921 O: kex ecdh-sha2-nistp256 593s 18:54:00.899424843 O: kex ecdh-sha2-nistp384 594s 18:54:01.250961175 O: kex ecdh-sha2-nistp521 594s 18:54:01.610070501 O: kex curve25519-sha256 595s 18:54:02.011594973 O: kex curve25519-sha256@libssh.org 595s 18:54:02.418001627 O: kex sntrup761x25519-sha512 595s 18:54:02.861356703 O: kex sntrup761x25519-sha512@openssh.com 596s 18:54:03.294417752 O: kex mlkem768x25519-sha256 596s 18:54:03.685255837 O: ok login with different key exchange algorithms 596s 18:54:03.686415991 E: run test cert-hostkey.sh ... 597s 18:54:04.148673989 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/host_ca_key.pub 597s 18:54:04.149745794 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/host_ca_key2.pub 597s 18:54:04.150517474 O: certified host keys: sign host ed25519 cert 597s 18:54:04.157444943 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 597s 18:54:04.168158539 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 597s 18:54:04.171203518 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 597s 18:54:04.182981277 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 597s 18:54:04.196162906 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 597s 18:54:04.198642996 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 597s 18:54:04.206494642 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 597s 18:54:04.219063217 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 597s 18:54:04.221778694 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 597s 18:54:04.230212321 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 597s 18:54:04.242690506 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 597s 18:54:04.245785507 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 597s 18:54:04.254439429 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 597s 18:54:04.268009006 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 597s 18:54:04.270757617 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 597s 18:54:04.284045923 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 597s 18:54:04.297059987 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 597s 18:54:04.301130443 O: certified host keys: sign host rsa cert 597s 18:54:04.451680677 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 597s 18:54:04.463209237 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 597s 18:54:04.466022021 O: certified host keys: sign host rsa-sha2-256 cert 597s 18:54:04.967437439 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 597s 18:54:04.980074363 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 597s 18:54:04.983095509 O: certified host keys: sign host rsa-sha2-512 cert 598s 18:54:05.266165884 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 598s 18:54:05.279098630 O: Revoking from /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 598s 18:54:05.281734648 O: certified host keys: host ed25519 cert connect 598s 18:54:05.283487068 O: certified host keys: ed25519 basic connect expect success yes 598s 18:54:05.406757944 O: certified host keys: ed25519 empty KRL expect success yes 598s 18:54:05.526710508 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 598s 18:54:05.601684520 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 598s 18:54:05.684403931 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 598s 18:54:05.759687244 O: certified host keys: ed25519 empty plaintext revocation expect success yes 598s 18:54:05.895596461 O: certified host keys: ed25519 plain key plaintext revocation expect success no 598s 18:54:05.969956310 O: certified host keys: ed25519 cert plaintext revocation expect success no 599s 18:54:06.047826810 O: certified host keys: ed25519 CA plaintext revocation expect success no 599s 18:54:06.135299604 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 599s 18:54:06.137016112 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 599s 18:54:06.264178691 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 599s 18:54:06.393457267 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 599s 18:54:06.475263902 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 599s 18:54:06.557578012 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 599s 18:54:06.639188886 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 599s 18:54:06.768084406 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 599s 18:54:06.851449545 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 599s 18:54:06.948220425 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 600s 18:54:07.029389590 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 600s 18:54:07.031017971 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 600s 18:54:07.160941840 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 600s 18:54:07.287552731 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 600s 18:54:07.362871826 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 600s 18:54:07.438822488 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 600s 18:54:07.512892302 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 600s 18:54:07.635991934 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 600s 18:54:07.723669262 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 600s 18:54:07.811266660 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 600s 18:54:07.888909291 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 600s 18:54:07.890352918 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 601s 18:54:08.034619220 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 601s 18:54:08.163169520 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 601s 18:54:08.253855497 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 601s 18:54:08.337791798 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 601s 18:54:08.422011720 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 601s 18:54:08.561304211 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 601s 18:54:08.643134665 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 601s 18:54:08.731620289 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 601s 18:54:08.813397375 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 601s 18:54:08.816330780 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 601s 18:54:08.947991135 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 602s 18:54:09.074853275 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 602s 18:54:09.155090343 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 602s 18:54:09.233461971 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 602s 18:54:09.313797960 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 602s 18:54:09.442963499 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 602s 18:54:09.523232540 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 602s 18:54:09.615226124 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 602s 18:54:09.694221152 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 602s 18:54:09.695419769 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 602s 18:54:09.823323642 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 602s 18:54:09.950592161 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 603s 18:54:10.031264012 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 603s 18:54:10.116109788 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 603s 18:54:10.202040830 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 603s 18:54:10.328860491 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 603s 18:54:10.413276037 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 603s 18:54:10.511059471 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 603s 18:54:10.597826044 O: certified host keys: host rsa cert connect 603s 18:54:10.599435321 O: certified host keys: rsa basic connect expect success yes 603s 18:54:10.728123544 O: certified host keys: rsa empty KRL expect success yes 603s 18:54:10.867035560 O: certified host keys: rsa KRL w/ plain key revoked expect success no 603s 18:54:10.948797532 O: certified host keys: rsa KRL w/ cert revoked expect success no 604s 18:54:11.031702408 O: certified host keys: rsa KRL w/ CA revoked expect success no 604s 18:54:11.115589548 O: certified host keys: rsa empty plaintext revocation expect success yes 604s 18:54:11.250247401 O: certified host keys: rsa plain key plaintext revocation expect success no 604s 18:54:11.335296697 O: certified host keys: rsa cert plaintext revocation expect success no 604s 18:54:11.441722527 O: certified host keys: rsa CA plaintext revocation expect success no 604s 18:54:11.526712742 O: certified host keys: host rsa-sha2-256 cert connect 604s 18:54:11.530432323 O: certified host keys: rsa-sha2-256 basic connect expect success yes 604s 18:54:11.656934780 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 604s 18:54:11.785919595 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 604s 18:54:11.867487613 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 604s 18:54:11.949036841 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 605s 18:54:12.025044850 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 605s 18:54:12.148831936 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 605s 18:54:12.233203659 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 605s 18:54:12.331043907 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 605s 18:54:12.414455032 O: certified host keys: host rsa-sha2-512 cert connect 605s 18:54:12.416383112 O: certified host keys: rsa-sha2-512 basic connect expect success yes 605s 18:54:12.546869612 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 605s 18:54:12.677200859 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 605s 18:54:12.761626281 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 605s 18:54:12.840433327 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 605s 18:54:12.922005526 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 606s 18:54:13.050694740 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 606s 18:54:13.130542666 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 606s 18:54:13.228410578 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 606s 18:54:13.319402071 O: certified host keys: host ed25519 revoked cert 606s 18:54:13.404836065 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 606s 18:54:13.491502765 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 606s 18:54:13.576195806 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 606s 18:54:13.667367097 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 606s 18:54:13.754000156 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 606s 18:54:13.841803246 O: certified host keys: host rsa revoked cert 606s 18:54:13.931163047 O: certified host keys: host rsa-sha2-256 revoked cert 607s 18:54:14.014122113 O: certified host keys: host rsa-sha2-512 revoked cert 607s 18:54:14.102020843 O: certified host keys: host ed25519 revoked cert 607s 18:54:14.182583177 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 607s 18:54:14.267576686 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 607s 18:54:14.347335738 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 607s 18:54:14.432203817 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 607s 18:54:14.514750554 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 607s 18:54:14.600772860 O: certified host keys: host rsa revoked cert 607s 18:54:14.685595444 O: certified host keys: host rsa-sha2-256 revoked cert 607s 18:54:14.765195301 O: certified host keys: host rsa-sha2-512 revoked cert 614s 18:54:21.193940880 O: certified host keys: host ed25519 cert downgrade to raw key 614s 18:54:21.471811381 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 614s 18:54:21.750438829 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 615s 18:54:22.019935848 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 615s 18:54:22.295910676 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 615s 18:54:22.566566503 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 615s 18:54:22.850179615 O: certified host keys: host rsa cert downgrade to raw key 616s 18:54:23.471382907 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 616s 18:54:23.952852798 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 617s 18:54:24.366484392 O: certified host keys: host ed25519 connect wrong cert 617s 18:54:24.456300727 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 617s 18:54:24.556778721 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 617s 18:54:24.640951976 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 617s 18:54:24.735075904 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 617s 18:54:24.827894346 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 617s 18:54:24.924436255 O: certified host keys: host rsa connect wrong cert 618s 18:54:25.297609940 O: certified host keys: host rsa-sha2-256 connect wrong cert 618s 18:54:25.617124529 O: certified host keys: host rsa-sha2-512 connect wrong cert 618s 18:54:25.873772875 O: ok certified host keys 618s 18:54:25.873980043 E: run test cert-userkey.sh ... 619s 18:54:26.561624919 O: certified user keys: sign user ed25519 cert 619s 18:54:26.578437180 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 619s 18:54:26.597831788 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 619s 18:54:26.612254906 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 619s 18:54:26.626395494 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 619s 18:54:26.642403463 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 619s 18:54:26.660422006 O: certified user keys: sign user rsa cert 619s 18:54:26.833704459 O: certified user keys: sign user rsa-sha2-256 cert 619s 18:54:26.984841813 O: certified user keys: sign user rsa-sha2-512 cert 620s 18:54:27.225566591 O: certified user keys: ed25519 missing authorized_principals 620s 18:54:27.340964820 O: certified user keys: ed25519 empty authorized_principals 620s 18:54:27.452997453 O: certified user keys: ed25519 wrong authorized_principals 620s 18:54:27.567087625 O: certified user keys: ed25519 correct authorized_principals 620s 18:54:27.696509004 O: certified user keys: ed25519 authorized_principals bad key opt 620s 18:54:27.811620263 O: certified user keys: ed25519 authorized_principals command=false 620s 18:54:27.941353781 O: certified user keys: ed25519 authorized_principals command=true 621s 18:54:28.070980484 O: certified user keys: ed25519 wrong principals key option 621s 18:54:28.190528967 O: certified user keys: ed25519 correct principals key option 621s 18:54:28.326297656 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 621s 18:54:28.445155659 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 621s 18:54:28.557870084 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 621s 18:54:28.669476568 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 621s 18:54:28.792402832 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 621s 18:54:28.909804261 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 622s 18:54:29.028790944 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 622s 18:54:29.152703287 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 622s 18:54:29.266231906 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 622s 18:54:29.395552370 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 622s 18:54:29.511663596 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 622s 18:54:29.624385341 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 622s 18:54:29.743792379 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 622s 18:54:29.867211188 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 622s 18:54:29.986774280 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 623s 18:54:30.114663889 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 623s 18:54:30.247530955 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 623s 18:54:30.379320576 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 623s 18:54:30.520152007 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 623s 18:54:30.652227489 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 623s 18:54:30.772445217 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 623s 18:54:30.897826845 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 624s 18:54:31.024215451 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 624s 18:54:31.148690267 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 624s 18:54:31.277425921 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 624s 18:54:31.414675266 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 624s 18:54:31.533356978 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 624s 18:54:31.663444859 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 624s 18:54:31.786703754 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 624s 18:54:31.910984196 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 625s 18:54:32.034136225 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 625s 18:54:32.164160959 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 625s 18:54:32.294423199 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 625s 18:54:32.426002099 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 625s 18:54:32.565856054 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 625s 18:54:32.688367724 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 625s 18:54:32.823388429 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 625s 18:54:32.946604744 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 626s 18:54:33.060702698 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 626s 18:54:33.177006845 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 626s 18:54:33.308361412 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 626s 18:54:33.422820866 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 626s 18:54:33.545785651 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 626s 18:54:33.673703739 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 626s 18:54:33.793955148 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 626s 18:54:33.925628783 O: certified user keys: rsa missing authorized_principals 627s 18:54:34.039339747 O: certified user keys: rsa empty authorized_principals 627s 18:54:34.153863085 O: certified user keys: rsa wrong authorized_principals 627s 18:54:34.268467807 O: certified user keys: rsa correct authorized_principals 627s 18:54:34.397702421 O: certified user keys: rsa authorized_principals bad key opt 627s 18:54:34.511767798 O: certified user keys: rsa authorized_principals command=false 627s 18:54:34.641150799 O: certified user keys: rsa authorized_principals command=true 627s 18:54:34.775105143 O: certified user keys: rsa wrong principals key option 627s 18:54:34.882623534 O: certified user keys: rsa correct principals key option 628s 18:54:35.009830156 O: certified user keys: rsa-sha2-256 missing authorized_principals 628s 18:54:35.123057437 O: certified user keys: rsa-sha2-256 empty authorized_principals 628s 18:54:35.239038864 O: certified user keys: rsa-sha2-256 wrong authorized_principals 628s 18:54:35.355408379 O: certified user keys: rsa-sha2-256 correct authorized_principals 628s 18:54:35.487954830 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 628s 18:54:35.606382480 O: certified user keys: rsa-sha2-256 authorized_principals command=false 628s 18:54:35.741992311 O: certified user keys: rsa-sha2-256 authorized_principals command=true 628s 18:54:35.875537540 O: certified user keys: rsa-sha2-256 wrong principals key option 628s 18:54:35.998611300 O: certified user keys: rsa-sha2-256 correct principals key option 629s 18:54:36.128759897 O: certified user keys: rsa-sha2-512 missing authorized_principals 629s 18:54:36.244373599 O: certified user keys: rsa-sha2-512 empty authorized_principals 629s 18:54:36.361401627 O: certified user keys: rsa-sha2-512 wrong authorized_principals 629s 18:54:36.477022422 O: certified user keys: rsa-sha2-512 correct authorized_principals 629s 18:54:36.602210627 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 629s 18:54:36.712920240 O: certified user keys: rsa-sha2-512 authorized_principals command=false 629s 18:54:36.840956248 O: certified user keys: rsa-sha2-512 authorized_principals command=true 629s 18:54:36.970686813 O: certified user keys: rsa-sha2-512 wrong principals key option 630s 18:54:37.086465464 O: certified user keys: rsa-sha2-512 correct principals key option 630s 18:54:37.220536416 O: certified user keys: ed25519 authorized_keys connect 630s 18:54:37.343472705 O: certified user keys: ed25519 authorized_keys revoked key 630s 18:54:37.461842524 O: certified user keys: ed25519 authorized_keys revoked via KRL 630s 18:54:37.587636205 O: certified user keys: ed25519 authorized_keys empty KRL 630s 18:54:37.725859003 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 630s 18:54:37.846676558 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 630s 18:54:37.957741047 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 631s 18:54:38.070960680 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 631s 18:54:38.192881597 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 631s 18:54:38.313735209 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 631s 18:54:38.442925340 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 631s 18:54:38.569187302 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 631s 18:54:38.704118812 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 631s 18:54:38.824257767 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 631s 18:54:38.939519928 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 632s 18:54:39.055378499 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 632s 18:54:39.179192061 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 632s 18:54:39.304112134 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 632s 18:54:39.427521024 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 632s 18:54:39.558718732 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 632s 18:54:39.689966952 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 632s 18:54:39.813189520 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 632s 18:54:39.932416861 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 633s 18:54:40.051512202 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 633s 18:54:40.175699199 O: certified user keys: rsa authorized_keys connect 633s 18:54:40.295449571 O: certified user keys: rsa authorized_keys revoked key 633s 18:54:40.404610117 O: certified user keys: rsa authorized_keys revoked via KRL 633s 18:54:40.510329250 O: certified user keys: rsa authorized_keys empty KRL 633s 18:54:40.635965518 O: certified user keys: rsa-sha2-256 authorized_keys connect 633s 18:54:40.763062970 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 633s 18:54:40.869141738 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 633s 18:54:40.978477558 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 634s 18:54:41.100027890 O: certified user keys: rsa-sha2-512 authorized_keys connect 634s 18:54:41.223689019 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 634s 18:54:41.326707248 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 634s 18:54:41.430606916 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 634s 18:54:41.556028590 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 634s 18:54:41.657450841 O: certified user keys: authorized_keys CA does not authenticate 634s 18:54:41.660955026 O: certified user keys: ensure CA key does not authenticate user 634s 18:54:41.756386280 O: certified user keys: ed25519 TrustedUserCAKeys connect 634s 18:54:41.883157973 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 634s 18:54:41.982207974 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 635s 18:54:42.086922360 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 635s 18:54:42.202510067 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 635s 18:54:42.328629397 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 635s 18:54:42.451274070 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 635s 18:54:42.576591605 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 635s 18:54:42.710583119 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 635s 18:54:42.836932823 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 635s 18:54:42.959605041 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 636s 18:54:43.083914406 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 636s 18:54:43.217373432 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 636s 18:54:43.347090762 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 636s 18:54:43.469119575 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 636s 18:54:43.592331783 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 636s 18:54:43.725477479 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 636s 18:54:43.851932775 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 636s 18:54:43.966677856 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 637s 18:54:44.079265225 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 637s 18:54:44.208263075 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 637s 18:54:44.337950855 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 637s 18:54:44.459544766 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 637s 18:54:44.587303555 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 637s 18:54:44.716565976 O: certified user keys: rsa TrustedUserCAKeys connect 637s 18:54:44.835895463 O: certified user keys: rsa TrustedUserCAKeys revoked key 637s 18:54:44.947915631 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 638s 18:54:45.066726513 O: certified user keys: rsa TrustedUserCAKeys empty KRL 638s 18:54:45.196018224 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 638s 18:54:45.320528234 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 638s 18:54:45.427290157 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 638s 18:54:45.537823682 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 638s 18:54:45.670813464 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 638s 18:54:45.803567624 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 638s 18:54:45.924121432 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 639s 18:54:46.044548847 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 639s 18:54:46.173384751 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 639s 18:54:46.295167496 O: certified user keys: TrustedUserCAKeys CA does not authenticate 639s 18:54:46.298011822 O: certified user keys: ensure CA key does not authenticate user 639s 18:54:46.402304327 O: certified user keys: correct principal auth authorized_keys expect success rsa 639s 18:54:46.534703145 O: certified user keys: correct principal auth authorized_keys expect success ed25519 639s 18:54:46.662774478 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 639s 18:54:46.799290596 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 639s 18:54:46.939785935 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 640s 18:54:47.053291801 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 640s 18:54:47.158868300 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 640s 18:54:47.275389061 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 640s 18:54:47.387533299 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 640s 18:54:47.500133489 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 640s 18:54:47.613342948 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 640s 18:54:47.736890457 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 640s 18:54:47.866935355 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 640s 18:54:47.981199512 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 641s 18:54:48.093357130 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 641s 18:54:48.217931064 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 641s 18:54:48.348723692 O: certified user keys: cert expired auth authorized_keys expect failure rsa 641s 18:54:48.462768342 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 641s 18:54:48.576221571 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 641s 18:54:48.700668256 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 641s 18:54:48.826838848 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 641s 18:54:48.970259462 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 642s 18:54:49.109105728 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 642s 18:54:49.251912417 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 642s 18:54:49.396070469 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 642s 18:54:49.512419254 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 642s 18:54:49.628906956 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 642s 18:54:49.759285172 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 642s 18:54:49.885730165 O: certified user keys: force-command auth authorized_keys expect failure rsa 643s 18:54:50.021705063 O: certified user keys: force-command auth authorized_keys expect failure ed25519 643s 18:54:50.155034190 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 643s 18:54:50.295791264 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 643s 18:54:50.436357124 O: certified user keys: empty principals auth authorized_keys expect success rsa 643s 18:54:50.566232128 O: certified user keys: empty principals auth authorized_keys expect success ed25519 643s 18:54:50.693694132 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 643s 18:54:50.824004726 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 643s 18:54:50.951037815 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 644s 18:54:51.075763949 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 644s 18:54:51.204822292 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 644s 18:54:51.320156772 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 644s 18:54:51.433309657 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 644s 18:54:51.559268866 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 644s 18:54:51.694540239 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 644s 18:54:51.813642717 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 644s 18:54:51.929460471 O: certified user keys: force-command match true auth authorized_keys expect success rsa 645s 18:54:52.064476367 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 645s 18:54:52.197809455 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 645s 18:54:52.330688308 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 645s 18:54:52.477312045 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 645s 18:54:52.603315358 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 645s 18:54:52.724634860 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 645s 18:54:52.839348459 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 645s 18:54:52.963814747 O: certified user keys: user ed25519 connect wrong cert 646s 18:54:53.081010987 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 646s 18:54:53.179739498 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 646s 18:54:53.283710380 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 646s 18:54:53.387750139 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 646s 18:54:53.491155214 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 646s 18:54:53.591747849 O: certified user keys: user rsa connect wrong cert 646s 18:54:53.717433272 O: certified user keys: user rsa-sha2-256 connect wrong cert 646s 18:54:53.835743657 O: certified user keys: user rsa-sha2-512 connect wrong cert 646s 18:54:53.940322403 E: run test host-expand.sh ... 646s 18:54:53.939522591 O: ok certified user keys 647s 18:54:54.197762340 O: ok expand %h and %n 647s 18:54:54.198513129 E: run test keys-command.sh ... 647s 18:54:54.350926474 O: SKIPPED: /var/run/keycommand_openssh-tests.43458 not executable (/var/run mounted noexec?) 647s 18:54:54.358746043 E: run test forward-control.sh ... 648s 18:54:55.827018865 O: check_lfwd done (expecting Y): default configuration 649s 18:54:56.169897654 O: check_rfwd done (expecting Y): default configuration 649s 18:54:56.498835927 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 650s 18:54:57.841753958 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 651s 18:54:58.981863702 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 653s 18:55:00.303067401 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 654s 18:55:01.651736209 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 655s 18:55:02.998347741 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 656s 18:55:03.137832094 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 656s 18:55:03.475370212 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 657s 18:55:04.786690415 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 658s 18:55:05.131186586 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 658s 18:55:05.263333883 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 658s 18:55:05.386491311 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 658s 18:55:05.530892390 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 658s 18:55:05.658714564 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 660s 18:55:07.002714448 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 661s 18:55:08.345545650 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 661s 18:55:08.675954989 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 661s 18:55:08.794553507 O: check_rfwd done (expecting N): AllowTcpForwarding=local 661s 18:55:08.921180286 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 662s 18:55:09.040456905 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 663s 18:55:10.337047873 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 663s 18:55:10.462148222 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 663s 18:55:10.586151189 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 663s 18:55:10.713888495 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 664s 18:55:11.002589147 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 664s 18:55:11.118825031 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 664s 18:55:11.246253344 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 664s 18:55:11.366770848 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 665s 18:55:12.493648761 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 665s 18:55:12.619588457 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 666s 18:55:13.935451844 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 667s 18:55:14.057540099 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 667s 18:55:14.186652246 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 668s 18:55:15.526797978 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 669s 18:55:16.656925157 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 671s 18:55:18.025466848 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 671s 18:55:18.155822307 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 672s 18:55:19.493085903 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 672s 18:55:19.624112591 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 673s 18:55:20.967845672 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 674s 18:55:21.099806876 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 674s 18:55:21.446006660 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 675s 18:55:22.580801361 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 675s 18:55:22.711413841 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 675s 18:55:22.841107558 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 675s 18:55:22.972880475 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 676s 18:55:23.105374100 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 677s 18:55:24.437709512 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 678s 18:55:25.574525715 O: check_lfwd done (expecting N): AllowTcpForwarding=no 678s 18:55:25.709162590 O: check_rfwd done (expecting N): AllowTcpForwarding=no 679s 18:55:26.847824084 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 679s 18:55:26.969028019 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 681s 18:55:28.109198713 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 681s 18:55:28.241450447 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 682s 18:55:29.379088157 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 682s 18:55:29.504965360 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 682s 18:55:29.636630949 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 682s 18:55:29.763025998 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 683s 18:55:30.897950443 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 684s 18:55:31.023714543 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 684s 18:55:31.153665225 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 684s 18:55:31.276881291 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 685s 18:55:32.408496198 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 685s 18:55:32.537687107 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 685s 18:55:32.874206751 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 686s 18:55:33.010608317 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 686s 18:55:33.314032861 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 686s 18:55:33.658800300 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 688s 18:55:34.990038481 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 688s 18:55:35.125075608 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 688s 18:55:35.472387732 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 688s 18:55:35.615845924 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 688s 18:55:35.752932610 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 688s 18:55:35.898017709 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 689s 18:55:36.037664407 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 689s 18:55:36.389532183 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 689s 18:55:36.524295119 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 689s 18:55:36.648383174 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 690s 18:55:37.783686123 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 690s 18:55:37.912622836 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 690s 18:55:37.929185605 O: ok sshd control of local and remote forwarding 690s 18:55:37.930355298 E: run test integrity.sh ... 691s 18:55:38.094024092 O: test integrity: hmac-sha1 @2900 691s 18:55:38.225568079 O: test integrity: hmac-sha1 @2901 691s 18:55:38.357092765 O: test integrity: hmac-sha1 @2902 691s 18:55:38.489864178 O: test integrity: hmac-sha1 @2903 691s 18:55:38.629281220 O: test integrity: hmac-sha1 @2904 691s 18:55:38.769958529 O: test integrity: hmac-sha1 @2905 691s 18:55:38.905004331 O: test integrity: hmac-sha1 @2906 692s 18:55:39.032924332 O: test integrity: hmac-sha1 @2907 692s 18:55:39.160399167 O: test integrity: hmac-sha1 @2908 692s 18:55:39.285589656 O: test integrity: hmac-sha1 @2909 692s 18:55:39.404277866 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 692s 18:55:39.417353495 O: test integrity: hmac-sha1-96 @2900 692s 18:55:39.549102183 O: test integrity: hmac-sha1-96 @2901 692s 18:55:39.686365893 O: test integrity: hmac-sha1-96 @2902 692s 18:55:39.814033183 O: test integrity: hmac-sha1-96 @2903 692s 18:55:39.943604070 O: test integrity: hmac-sha1-96 @2904 693s 18:55:40.065593911 O: test integrity: hmac-sha1-96 @2905 693s 18:55:40.195347446 O: test integrity: hmac-sha1-96 @2906 693s 18:55:40.322274650 O: test integrity: hmac-sha1-96 @2907 693s 18:55:40.450311660 O: test integrity: hmac-sha1-96 @2908 693s 18:55:40.568385919 O: test integrity: hmac-sha1-96 @2909 693s 18:55:40.679721739 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 693s 18:55:40.696366208 O: test integrity: hmac-sha2-256 @2900 693s 18:55:40.836898679 O: test integrity: hmac-sha2-256 @2901 693s 18:55:40.958608626 O: test integrity: hmac-sha2-256 @2902 694s 18:55:41.080005806 O: test integrity: hmac-sha2-256 @2903 694s 18:55:41.202930798 O: test integrity: hmac-sha2-256 @2904 694s 18:55:41.328744385 O: test integrity: hmac-sha2-256 @2905 694s 18:55:41.452521685 O: test integrity: hmac-sha2-256 @2906 694s 18:55:41.573377589 O: test integrity: hmac-sha2-256 @2907 694s 18:55:41.692579748 O: test integrity: hmac-sha2-256 @2908 694s 18:55:41.814488917 O: test integrity: hmac-sha2-256 @2909 694s 18:55:41.931262413 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 694s 18:55:41.944970511 O: test integrity: hmac-sha2-512 @2900 695s 18:55:42.040880712 O: test integrity: hmac-sha2-512 @2901 695s 18:55:42.136010812 O: test integrity: hmac-sha2-512 @2902 695s 18:55:42.228791672 O: test integrity: hmac-sha2-512 @2903 695s 18:55:42.330580193 O: test integrity: hmac-sha2-512 @2904 695s 18:55:42.422873966 O: test integrity: hmac-sha2-512 @2905 695s 18:55:42.518232141 O: test integrity: hmac-sha2-512 @2906 695s 18:55:42.610626855 O: test integrity: hmac-sha2-512 @2907 695s 18:55:42.703200011 O: test integrity: hmac-sha2-512 @2908 695s 18:55:42.796383134 O: test integrity: hmac-sha2-512 @2909 695s 18:55:42.877791573 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 695s 18:55:42.891387109 O: test integrity: hmac-md5 @2900 696s 18:55:43.011303883 O: test integrity: hmac-md5 @2901 696s 18:55:43.130273355 O: test integrity: hmac-md5 @2902 696s 18:55:43.257820372 O: test integrity: hmac-md5 @2903 696s 18:55:43.388453344 O: test integrity: hmac-md5 @2904 696s 18:55:43.521646069 O: test integrity: hmac-md5 @2905 696s 18:55:43.650335008 O: test integrity: hmac-md5 @2906 696s 18:55:43.784883260 O: test integrity: hmac-md5 @2907 696s 18:55:43.916983402 O: test integrity: hmac-md5 @2908 697s 18:55:44.036516489 O: test integrity: hmac-md5 @2909 697s 18:55:44.148198088 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 697s 18:55:44.163084380 O: test integrity: hmac-md5-96 @2900 697s 18:55:44.289331440 O: test integrity: hmac-md5-96 @2901 697s 18:55:44.428519024 O: test integrity: hmac-md5-96 @2902 697s 18:55:44.557356118 O: test integrity: hmac-md5-96 @2903 697s 18:55:44.682963448 O: test integrity: hmac-md5-96 @2904 697s 18:55:44.807122671 O: test integrity: hmac-md5-96 @2905 697s 18:55:44.926729806 O: test integrity: hmac-md5-96 @2906 698s 18:55:45.057314908 O: test integrity: hmac-md5-96 @2907 698s 18:55:45.179140750 O: test integrity: hmac-md5-96 @2908 698s 18:55:45.310650186 O: test integrity: hmac-md5-96 @2909 698s 18:55:45.424778828 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 698s 18:55:45.440454980 O: test integrity: umac-64@openssh.com @2900 698s 18:55:45.570858546 O: test integrity: umac-64@openssh.com @2901 698s 18:55:45.688193563 O: test integrity: umac-64@openssh.com @2902 698s 18:55:45.813866150 O: test integrity: umac-64@openssh.com @2903 698s 18:55:45.941430087 O: test integrity: umac-64@openssh.com @2904 699s 18:55:46.070695881 O: test integrity: umac-64@openssh.com @2905 699s 18:55:46.198947408 O: test integrity: umac-64@openssh.com @2906 699s 18:55:46.321567063 O: test integrity: umac-64@openssh.com @2907 699s 18:55:46.441807184 O: test integrity: umac-64@openssh.com @2908 699s 18:55:46.567804756 O: test integrity: umac-64@openssh.com @2909 699s 18:55:46.682023353 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 699s 18:55:46.695873239 O: test integrity: umac-128@openssh.com @2900 699s 18:55:46.822978263 O: test integrity: umac-128@openssh.com @2901 699s 18:55:46.949566808 O: test integrity: umac-128@openssh.com @2902 700s 18:55:47.069505692 O: test integrity: umac-128@openssh.com @2903 700s 18:55:47.195365750 O: test integrity: umac-128@openssh.com @2904 700s 18:55:47.318679443 O: test integrity: umac-128@openssh.com @2905 700s 18:55:47.434842248 O: test integrity: umac-128@openssh.com @2906 700s 18:55:47.559597911 O: test integrity: umac-128@openssh.com @2907 700s 18:55:47.672980684 O: test integrity: umac-128@openssh.com @2908 700s 18:55:47.786778905 O: test integrity: umac-128@openssh.com @2909 700s 18:55:47.894362236 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 700s 18:55:47.911297995 O: test integrity: hmac-sha1-etm@openssh.com @2900 701s 18:55:48.037188818 O: test integrity: hmac-sha1-etm@openssh.com @2901 701s 18:55:48.164747715 O: test integrity: hmac-sha1-etm@openssh.com @2902 701s 18:55:48.295251083 O: test integrity: hmac-sha1-etm@openssh.com @2903 701s 18:55:48.416745861 O: test integrity: hmac-sha1-etm@openssh.com @2904 701s 18:55:48.543624571 O: test integrity: hmac-sha1-etm@openssh.com @2905 701s 18:55:48.667472284 O: test integrity: hmac-sha1-etm@openssh.com @2906 701s 18:55:48.786521353 O: test integrity: hmac-sha1-etm@openssh.com @2907 701s 18:55:48.906931128 O: test integrity: hmac-sha1-etm@openssh.com @2908 702s 18:55:49.025071607 O: test integrity: hmac-sha1-etm@openssh.com @2909 702s 18:55:49.132702919 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 702s 18:55:49.145401608 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 702s 18:55:49.267167298 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 702s 18:55:49.392556888 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 702s 18:55:49.518564047 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 702s 18:55:49.647561220 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 702s 18:55:49.777499538 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 702s 18:55:49.905063587 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 703s 18:55:50.034151428 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 703s 18:55:50.166601768 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 703s 18:55:50.291123960 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 703s 18:55:50.413627245 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 703s 18:55:50.428040356 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 703s 18:55:50.549973483 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 703s 18:55:50.691000320 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 703s 18:55:50.827592772 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 703s 18:55:50.958212177 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 704s 18:55:51.084297666 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 704s 18:55:51.209674249 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 704s 18:55:51.333508198 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 704s 18:55:51.460141810 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 704s 18:55:51.586034834 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 704s 18:55:51.703298891 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 704s 18:55:51.716214064 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 704s 18:55:51.810048315 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 704s 18:55:51.901484773 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 704s 18:55:51.992683509 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 705s 18:55:52.093652003 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 705s 18:55:52.187724139 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 705s 18:55:52.282538415 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 705s 18:55:52.388429462 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 705s 18:55:52.480057243 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 705s 18:55:52.574231528 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 705s 18:55:52.657635634 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 705s 18:55:52.671961633 O: test integrity: hmac-md5-etm@openssh.com @2900 705s 18:55:52.808445484 O: test integrity: hmac-md5-etm@openssh.com @2901 705s 18:55:52.947890079 O: test integrity: hmac-md5-etm@openssh.com @2902 706s 18:55:53.079774022 O: test integrity: hmac-md5-etm@openssh.com @2903 706s 18:55:53.205356934 O: test integrity: hmac-md5-etm@openssh.com @2904 706s 18:55:53.340489477 O: test integrity: hmac-md5-etm@openssh.com @2905 706s 18:55:53.471202491 O: test integrity: hmac-md5-etm@openssh.com @2906 706s 18:55:53.619542546 O: test integrity: hmac-md5-etm@openssh.com @2907 706s 18:55:53.754141845 O: test integrity: hmac-md5-etm@openssh.com @2908 706s 18:55:53.887791277 O: test integrity: hmac-md5-etm@openssh.com @2909 707s 18:55:54.010918284 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 707s 18:55:54.026679512 O: test integrity: hmac-md5-96-etm@openssh.com @2900 707s 18:55:54.151538023 O: test integrity: hmac-md5-96-etm@openssh.com @2901 707s 18:55:54.280116887 O: test integrity: hmac-md5-96-etm@openssh.com @2902 707s 18:55:54.412160612 O: test integrity: hmac-md5-96-etm@openssh.com @2903 707s 18:55:54.548029270 O: test integrity: hmac-md5-96-etm@openssh.com @2904 707s 18:55:54.673419551 O: test integrity: hmac-md5-96-etm@openssh.com @2905 707s 18:55:54.805391963 O: test integrity: hmac-md5-96-etm@openssh.com @2906 707s 18:55:54.937103969 O: test integrity: hmac-md5-96-etm@openssh.com @2907 708s 18:55:55.062061094 O: test integrity: hmac-md5-96-etm@openssh.com @2908 708s 18:55:55.203945158 O: test integrity: hmac-md5-96-etm@openssh.com @2909 708s 18:55:55.321493948 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 708s 18:55:55.336282323 O: test integrity: umac-64-etm@openssh.com @2900 708s 18:55:55.472900944 O: test integrity: umac-64-etm@openssh.com @2901 708s 18:55:55.608309783 O: test integrity: umac-64-etm@openssh.com @2902 708s 18:55:55.737621581 O: test integrity: umac-64-etm@openssh.com @2903 708s 18:55:55.860213965 O: test integrity: umac-64-etm@openssh.com @2904 708s 18:55:55.988988656 O: test integrity: umac-64-etm@openssh.com @2905 709s 18:55:56.114029600 O: test integrity: umac-64-etm@openssh.com @2906 709s 18:55:56.247343002 O: test integrity: umac-64-etm@openssh.com @2907 709s 18:55:56.373539885 O: test integrity: umac-64-etm@openssh.com @2908 709s 18:55:56.502984410 O: test integrity: umac-64-etm@openssh.com @2909 709s 18:55:56.630362269 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 709s 18:55:56.645570221 O: test integrity: umac-128-etm@openssh.com @2900 709s 18:55:56.791249506 O: test integrity: umac-128-etm@openssh.com @2901 709s 18:55:56.926506533 O: test integrity: umac-128-etm@openssh.com @2902 710s 18:55:57.055320969 O: test integrity: umac-128-etm@openssh.com @2903 710s 18:55:57.180741343 O: test integrity: umac-128-etm@openssh.com @2904 710s 18:55:57.310511486 O: test integrity: umac-128-etm@openssh.com @2905 710s 18:55:57.435522004 O: test integrity: umac-128-etm@openssh.com @2906 710s 18:55:57.579010844 O: test integrity: umac-128-etm@openssh.com @2907 710s 18:55:57.707267710 O: test integrity: umac-128-etm@openssh.com @2908 710s 18:55:57.841359092 O: test integrity: umac-128-etm@openssh.com @2909 710s 18:55:57.963182024 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 711s 18:55:57.987450604 O: test integrity: aes128-gcm@openssh.com @2900 711s 18:55:58.087796197 O: test integrity: aes128-gcm@openssh.com @2901 711s 18:55:58.179546479 O: test integrity: aes128-gcm@openssh.com @2902 711s 18:55:58.275001591 O: test integrity: aes128-gcm@openssh.com @2903 711s 18:55:58.377448530 O: test integrity: aes128-gcm@openssh.com @2904 711s 18:55:58.475195283 O: test integrity: aes128-gcm@openssh.com @2905 711s 18:55:58.571090860 O: test integrity: aes128-gcm@openssh.com @2906 711s 18:55:58.662398089 O: test integrity: aes128-gcm@openssh.com @2907 711s 18:55:58.752413423 O: test integrity: aes128-gcm@openssh.com @2908 711s 18:55:58.848814396 O: test integrity: aes128-gcm@openssh.com @2909 711s 18:55:58.930418491 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 711s 18:55:58.943894281 O: test integrity: aes256-gcm@openssh.com @2900 712s 18:55:59.037951872 O: test integrity: aes256-gcm@openssh.com @2901 712s 18:55:59.141483596 O: test integrity: aes256-gcm@openssh.com @2902 712s 18:55:59.233797123 O: test integrity: aes256-gcm@openssh.com @2903 712s 18:55:59.328299394 O: test integrity: aes256-gcm@openssh.com @2904 712s 18:55:59.417578408 O: test integrity: aes256-gcm@openssh.com @2905 712s 18:55:59.506399382 O: test integrity: aes256-gcm@openssh.com @2906 712s 18:55:59.600603843 O: test integrity: aes256-gcm@openssh.com @2907 712s 18:55:59.698053129 O: test integrity: aes256-gcm@openssh.com @2908 712s 18:55:59.796767627 O: test integrity: aes256-gcm@openssh.com @2909 712s 18:55:59.882545229 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 712s 18:55:59.900459648 O: test integrity: chacha20-poly1305@openssh.com @2900 713s 18:55:59.997832329 O: test integrity: chacha20-poly1305@openssh.com @2901 713s 18:56:00.094709898 O: test integrity: chacha20-poly1305@openssh.com @2902 713s 18:56:00.185429431 O: test integrity: chacha20-poly1305@openssh.com @2903 713s 18:56:00.283158830 O: test integrity: chacha20-poly1305@openssh.com @2904 713s 18:56:00.374804121 O: test integrity: chacha20-poly1305@openssh.com @2905 713s 18:56:00.465883283 O: test integrity: chacha20-poly1305@openssh.com @2906 713s 18:56:00.558913998 O: test integrity: chacha20-poly1305@openssh.com @2907 713s 18:56:00.652036377 O: test integrity: chacha20-poly1305@openssh.com @2908 713s 18:56:00.743684958 O: test integrity: chacha20-poly1305@openssh.com @2909 713s 18:56:00.831600354 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 713s 18:56:00.833450373 O: ok integrity 713s 18:56:00.833596687 E: run test krl.sh ... 713s 18:56:00.988143332 O: key revocation lists: generating test keys 715s 18:56:02.268367670 O: key revocation lists: generating KRLs 715s 18:56:02.373785175 O: key revocation lists: checking revocations for revoked keys 715s 18:56:02.853532023 O: key revocation lists: checking revocations for unrevoked keys 716s 18:56:03.352995891 O: key revocation lists: checking revocations for revoked certs 717s 18:56:04.263231744 O: key revocation lists: checking revocations for unrevoked certs 718s 18:56:05.097286365 O: key revocation lists: testing KRL update 719s 18:56:06.093521896 O: key revocation lists: checking revocations for revoked keys 719s 18:56:06.594193237 O: key revocation lists: checking revocations for unrevoked keys 720s 18:56:07.084776599 O: key revocation lists: checking revocations for revoked certs 720s 18:56:07.895316160 O: key revocation lists: checking revocations for unrevoked certs 721s 18:56:08.691350797 O: ok key revocation lists 721s 18:56:08.691345696 E: run test multipubkey.sh ... 722s 18:56:09.984476915 O: ok multiple pubkey 722s 18:56:09.984734638 E: run test limit-keytype.sh ... 724s 18:56:11.175884537 O: allow rsa,ed25519 724s 18:56:11.502851591 O: allow ed25519 724s 18:56:11.770753428 O: allow cert only 725s 18:56:12.056139898 O: match w/ no match 725s 18:56:12.346415047 O: match w/ matching 725s 18:56:12.681123310 O: ok restrict pubkey type 725s 18:56:12.681407083 E: run test hostkey-agent.sh ... 726s 18:56:13.101134892 O: key type ssh-ed25519 726s 18:56:13.204082765 O: key type sk-ssh-ed25519@openssh.com 726s 18:56:13.309253756 O: key type ecdsa-sha2-nistp256 726s 18:56:13.411303110 O: key type ecdsa-sha2-nistp384 726s 18:56:13.516371234 O: key type ecdsa-sha2-nistp521 726s 18:56:13.617033069 O: key type sk-ecdsa-sha2-nistp256@openssh.com 726s 18:56:13.717297439 O: key type ssh-rsa 726s 18:56:13.831022645 O: cert type ssh-ed25519-cert-v01@openssh.com 726s 18:56:13.969637543 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 727s 18:56:14.109450758 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 727s 18:56:14.248126916 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 727s 18:56:14.403512384 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 727s 18:56:14.549463148 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 727s 18:56:14.708342372 O: cert type ssh-rsa-cert-v01@openssh.com 727s 18:56:14.848092044 O: cert type rsa-sha2-256-cert-v01@openssh.com 728s 18:56:14.998811782 O: cert type rsa-sha2-512-cert-v01@openssh.com 728s 18:56:15.151611012 O: ok hostkey agent 728s 18:56:15.152662623 E: run test hostkey-rotate.sh ... 729s 18:56:16.179822279 O: learn hostkey with StrictHostKeyChecking=no 729s 18:56:16.312983019 O: learn additional hostkeys 729s 18:56:16.471522629 O: learn additional hostkeys, type=ssh-ed25519 729s 18:56:16.603362366 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 729s 18:56:16.729623430 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 729s 18:56:16.849903268 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 729s 18:56:16.974207084 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 730s 18:56:17.098348398 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 730s 18:56:17.219804003 O: learn additional hostkeys, type=ssh-rsa 730s 18:56:17.349130955 O: learn changed non-primary hostkey type=ssh-rsa 730s 18:56:17.633116085 O: learn new primary hostkey 730s 18:56:17.770386852 O: rotate primary hostkey 730s 18:56:17.905721623 O: check rotate primary hostkey 731s 18:56:18.031086846 O: ok hostkey rotate 731s 18:56:18.031289867 E: run test principals-command.sh ... 731s 18:56:18.631373376 O: SKIPPED: /var/run/principals_command_openssh-tests.59765 not executable (/var/run mounted noexec?) 731s 18:56:18.638221896 E: run test cert-file.sh ... 731s 18:56:18.827555716 O: identity cert with no plain public file 731s 18:56:18.959619240 O: CertificateFile with no plain public file 732s 18:56:19.084064801 O: plain keys 732s 18:56:19.195645175 O: untrusted cert 732s 18:56:19.311792689 O: good cert, bad key 732s 18:56:19.431711703 O: single trusted 732s 18:56:19.561547179 O: multiple trusted 733s 18:56:20.076505708 O: ok ssh with certificates 733s 18:56:20.077836114 E: run test cfginclude.sh ... 733s 18:56:20.216286559 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.227105364 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.235882035 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.244258057 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.252930092 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.261365702 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.270702496 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.279247152 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.288143837 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.305964951 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.313406998 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.321559634 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.335926120 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.345291930 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.353798940 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.362544243 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.371124723 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.379593508 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.388272392 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.397058032 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.405990732 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.424991973 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.433441343 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.443537711 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.458454376 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.467368197 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 733s 18:56:20.471983629 O: ok config include 733s 18:56:20.472885101 E: run test servcfginclude.sh ... 733s 18:56:20.853854927 O: ok server config include 733s 18:56:20.854218547 E: run test allow-deny-users.sh ... 734s 18:56:21.985242976 O: ok AllowUsers/DenyUsers 734s 18:56:21.986245833 E: run test authinfo.sh ... 735s 18:56:22.131683661 O: ExposeAuthInfo=no 735s 18:56:22.243421655 O: ExposeAuthInfo=yes 735s 18:56:22.352095517 O: ok authinfo 735s 18:56:22.353024398 E: run test sshsig.sh ... 735s 18:56:22.497897756 O: sshsig: make certificates 735s 18:56:22.530539405 O: sshsig: check signature for ssh-ed25519 735s 18:56:22.895602646 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 736s 18:56:23.296612103 O: sshsig: check signature for ecdsa-sha2-nistp256 736s 18:56:23.653531513 O: sshsig: check signature for ecdsa-sha2-nistp384 737s 18:56:24.078952172 O: sshsig: check signature for ecdsa-sha2-nistp521 737s 18:56:24.516629932 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 737s 18:56:24.876414968 O: sshsig: check signature for ssh-rsa 738s 18:56:25.233646773 O: sshsig: check signature for ssh-ed25519-cert.pub 739s 18:56:26.031343375 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 739s 18:56:26.848020387 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 740s 18:56:27.573994294 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 741s 18:56:28.389684532 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 742s 18:56:29.194634802 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 743s 18:56:29.994578849 O: sshsig: check signature for ssh-rsa-cert.pub 743s 18:56:30.759788152 O: sshsig: match principals 743s 18:56:30.783042824 O: sshsig: nomatch principals 743s 18:56:30.796374205 O: ok sshsig 743s 18:56:30.796712321 E: run test knownhosts.sh ... 744s 18:56:31.675283952 O: ok known hosts 744s 18:56:31.675609474 E: run test knownhosts-command.sh ... 744s 18:56:31.815243037 O: simple connection 744s 18:56:31.929770528 O: no keys 745s 18:56:31.998947531 O: bad exit status 745s 18:56:32.045457195 O: keytype ssh-ed25519 745s 18:56:32.154388760 O: keytype sk-ssh-ed25519@openssh.com 745s 18:56:32.267432090 O: keytype ecdsa-sha2-nistp256 745s 18:56:32.372923468 O: keytype ecdsa-sha2-nistp384 745s 18:56:32.483416751 O: keytype ecdsa-sha2-nistp521 745s 18:56:32.593028281 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 745s 18:56:32.703701740 O: keytype ssh-rsa 745s 18:56:32.815154153 O: ok known hosts command 745s 18:56:32.815184736 E: run test agent-restrict.sh ... 745s 18:56:32.951972640 O: generate keys 746s 18:56:33.024519201 O: prepare client config 746s 18:56:33.038051848 O: prepare known_hosts 746s 18:56:33.042674755 O: prepare server configs 746s 18:56:33.060630639 O: authentication w/o agent 747s 18:56:34.146147873 O: start agent 751s 18:56:38.152731402 O: authentication with agent (no restrict) 752s 18:56:39.189323326 O: unrestricted keylist 752s 18:56:39.756990825 O: authentication with agent (basic restrict) 753s 18:56:40.288539359 O: authentication with agent incorrect key (basic restrict) 753s 18:56:40.610814670 O: keylist (basic restrict) 754s 18:56:41.180885741 O: username 754s 18:56:41.719849068 O: username wildcard 755s 18:56:42.264377525 O: username incorrect 755s 18:56:42.339172981 O: agent restriction honours certificate principal 755s 18:56:42.374648635 O: multihop without agent 756s 18:56:43.029228098 O: multihop agent unrestricted 756s 18:56:43.694201395 O: multihop restricted 757s 18:56:44.380107881 O: multihop username 758s 18:56:45.044261632 O: multihop wildcard username 758s 18:56:45.719619431 O: multihop wrong username 759s 18:56:46.168075934 O: multihop cycle no agent 760s 18:56:47.189249197 O: multihop cycle agent unrestricted 761s 18:56:48.169188467 O: multihop cycle restricted deny 761s 18:56:48.491553688 O: multihop cycle restricted allow 762s 18:56:49.474400685 O: ok agent restrictions 762s 18:56:49.475371201 E: run test hostbased.sh ... 762s 18:56:49.613972526 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 762s 18:56:49.614348608 E: run test channel-timeout.sh ... 762s 18:56:49.759357188 O: no timeout 767s 18:56:54.865452924 O: command timeout 768s 18:56:55.534863489 O: command long timeout 768s 18:56:55.644779019 O: command wildcard timeout 769s 18:56:56.535636250 O: command irrelevant timeout 774s 18:57:01.659039217 O: multiplexed command timeout 785s 18:57:12.561352023 O: irrelevant multiplexed command timeout 800s 18:57:27.705279778 O: global command timeout 822s 18:57:49.567967913 O: sftp no timeout 827s 18:57:54.702957671 O: sftp timeout 828s 18:57:55.535284897 E: Connection closed 828s 18:57:55.535669487 O: sftp irrelevant timeout 833s 18:58:00.665130956 O: ok channel timeout 833s 18:58:00.666280538 E: run test connection-timeout.sh ... 833s 18:58:00.794350676 O: no timeout 838s 18:58:05.909494195 O: timeout 847s 18:58:14.012493790 O: session inhibits timeout 855s 18:58:22.137022465 O: timeout after session 863s 18:58:30.143043514 O: timeout with listeners 871s 18:58:38.262794148 O: ok unused connection timeout 871s 18:58:38.263851746 E: run test match-subsystem.sh ... 872s 18:58:39.411028028 O: ok sshd_config match subsystem 872s 18:58:39.411414183 E: run test agent-pkcs11-restrict.sh ... 872s 18:58:39.554166972 O: SKIPPED: No PKCS#11 library found 872s 18:58:39.554281323 E: run test agent-pkcs11-cert.sh ... 872s 18:58:39.674266073 O: SKIPPED: No PKCS#11 library found 872s 18:58:39.674504440 E: run test penalty.sh ... 872s 18:58:39.835716166 O: test connect 873s 18:58:40.047993589 O: penalty for authentication failure 879s 18:58:46.976026577 O: penalty for no authentication 882s 18:58:49.033220093 O: ok penalties 882s 18:58:49.033284165 E: run test penalty-expire.sh ... 882s 18:58:49.208078715 O: test connect 882s 18:58:49.416599664 O: penalty expiry 895s 18:59:02.847157123 O: ok penalties 895s 18:59:02.848403120 O: set -e ; if test -z "" ; then \ 895s 18:59:02.849527181 O: V="" ; \ 895s 18:59:02.850503751 O: test "x" = "x" || \ 895s 18:59:02.851357386 O: V=/tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 895s 18:59:02.852213881 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 895s 18:59:02.853084992 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 895s 18:59:02.853914716 O: -d /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 895s 18:59:02.854794323 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 895s 18:59:02.855633798 O: -d /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 895s 18:59:02.856486313 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 895s 18:59:02.857268911 O: -d /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 895s 18:59:02.858163960 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 895s 18:59:02.858990262 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 895s 18:59:02.859786541 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 895s 18:59:02.860617562 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 895s 18:59:02.861452542 O: -d /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 895s 18:59:02.862487731 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 895s 18:59:02.863285662 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 895s 18:59:02.864034367 O: if test "x" = "xyes" ; then \ 895s 18:59:02.864845219 O: $V /tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 895s 18:59:02.865600848 O: fi \ 895s 18:59:02.866477370 O: fi 905s 18:59:12.190973649 O: test_sshbuf: ...................................................................................................... 103 tests ok 956s 19:00:03.181984890 O: test_sshkey: .................................................................................... 84 tests ok 956s 19:00:03.191712769 O: test_sshsig: ....... 7 tests ok 956s 19:00:03.445746209 O: test_authopt: .................................................................................................................................................. 146 tests ok 968s 19:00:15.546828024 O: test_bitmap: .. 2 tests ok 968s 19:00:15.549912572 O: test_conversion: . 1 tests ok 973s 19:00:20.029232410 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 973s 19:00:20.067423370 O: test_hostkeys: .................. 18 tests ok 973s 19:00:20.070434723 O: test_match: ...... 6 tests ok 973s 19:00:20.073276638 O: test_misc: ........................................... 43 tests ok 973s 19:00:20.075031230 E: run test putty-transfer.sh ... 973s 19:00:20.512238256 O: plink version 0.82 major 0 minor 82 973s 19:00:20.522370581 O: putty transfer data: compression 0 974s 19:00:21.656635219 O: putty transfer data: compression 1 975s 19:00:22.788359458 O: ok putty transfer data 975s 19:00:22.789325326 E: run test putty-ciphers.sh ... 976s 19:00:23.355280293 O: plink version 0.82 major 0 minor 82 976s 19:00:23.830292019 O: putty ciphers: cipher default mac default 976s 19:00:23.974682007 O: putty ciphers: cipher default mac hmac-sha1 977s 19:00:24.112621032 O: putty ciphers: cipher default mac hmac-sha1-96 977s 19:00:24.247822253 O: putty ciphers: cipher default mac hmac-sha2-256 977s 19:00:24.394634911 O: putty ciphers: cipher default mac hmac-sha2-512 977s 19:00:24.551805370 O: putty ciphers: cipher default mac hmac-md5 977s 19:00:24.707045633 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 977s 19:00:24.848060245 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 978s 19:00:24.984740415 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 978s 19:00:25.120831008 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 978s 19:00:25.261382483 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 978s 19:00:25.405074823 O: putty ciphers: cipher 3des-cbc mac default 978s 19:00:25.544489168 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 978s 19:00:25.682622066 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 978s 19:00:25.827868875 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 978s 19:00:25.973613599 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 979s 19:00:26.113136562 O: putty ciphers: cipher 3des-cbc mac hmac-md5 979s 19:00:26.252971212 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 979s 19:00:26.392517925 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 979s 19:00:26.533208976 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 979s 19:00:26.674450936 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 979s 19:00:26.812839572 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 979s 19:00:26.963928777 O: putty ciphers: cipher aes128-cbc mac default 980s 19:00:27.106725478 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 980s 19:00:27.245943884 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 980s 19:00:27.382550409 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 980s 19:00:27.524612702 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 980s 19:00:27.667046883 O: putty ciphers: cipher aes128-cbc mac hmac-md5 980s 19:00:27.806795843 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 980s 19:00:27.945090131 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 981s 19:00:28.080886574 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 981s 19:00:28.235819820 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 981s 19:00:28.374160754 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 981s 19:00:28.517311810 O: putty ciphers: cipher aes192-cbc mac default 981s 19:00:28.662471666 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 981s 19:00:28.805979426 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 981s 19:00:28.954821806 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 982s 19:00:29.099736048 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 982s 19:00:29.250589478 O: putty ciphers: cipher aes192-cbc mac hmac-md5 982s 19:00:29.388811198 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 982s 19:00:29.528014088 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 982s 19:00:29.664471262 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 982s 19:00:29.827894394 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 982s 19:00:29.977002652 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 983s 19:00:30.113574168 O: putty ciphers: cipher aes256-cbc mac default 983s 19:00:30.259081200 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 983s 19:00:30.397327199 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 983s 19:00:30.535889450 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 983s 19:00:30.676103029 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 983s 19:00:30.823225613 O: putty ciphers: cipher aes256-cbc mac hmac-md5 983s 19:00:30.960520536 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 984s 19:00:31.101497404 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 984s 19:00:31.240410658 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 984s 19:00:31.383200616 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 984s 19:00:31.521518386 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 984s 19:00:31.657439116 O: putty ciphers: cipher aes128-ctr mac default 984s 19:00:31.798027314 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 984s 19:00:31.928817437 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 985s 19:00:32.064122921 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 985s 19:00:32.201997702 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 985s 19:00:32.336138009 O: putty ciphers: cipher aes128-ctr mac hmac-md5 985s 19:00:32.468452381 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 985s 19:00:32.602519676 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 985s 19:00:32.732304574 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 985s 19:00:32.880109601 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 986s 19:00:33.014305914 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 986s 19:00:33.148326073 O: putty ciphers: cipher aes192-ctr mac default 986s 19:00:33.284929002 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 986s 19:00:33.417932022 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 986s 19:00:33.552261018 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 986s 19:00:33.688556947 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 986s 19:00:33.820858464 O: putty ciphers: cipher aes192-ctr mac hmac-md5 986s 19:00:33.957343893 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 987s 19:00:34.093480849 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 987s 19:00:34.223890719 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 987s 19:00:34.360695273 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 987s 19:00:34.493163820 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 987s 19:00:34.626016067 O: putty ciphers: cipher aes256-ctr mac default 987s 19:00:34.765450736 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 987s 19:00:34.906621407 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 988s 19:00:35.050006384 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 988s 19:00:35.189616538 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 988s 19:00:35.325399091 O: putty ciphers: cipher aes256-ctr mac hmac-md5 988s 19:00:35.458601314 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 988s 19:00:35.595015869 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 988s 19:00:35.734668598 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 988s 19:00:35.867800688 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 989s 19:00:36.001594509 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 989s 19:00:36.134623761 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 989s 19:00:36.269456424 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 989s 19:00:36.408039757 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 989s 19:00:36.542889203 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 989s 19:00:36.679665821 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 989s 19:00:36.815286063 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 989s 19:00:36.946522233 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 990s 19:00:37.083616230 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 990s 19:00:37.218124283 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 990s 19:00:37.352050846 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 990s 19:00:37.486360663 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 990s 19:00:37.616573918 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 990s 19:00:37.758017906 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 990s 19:00:37.887587811 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 991s 19:00:38.025657907 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 991s 19:00:38.161053245 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 991s 19:00:38.294768907 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 991s 19:00:38.427424561 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 991s 19:00:38.564738990 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 991s 19:00:38.701871900 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 991s 19:00:38.835607409 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 991s 19:00:38.970218065 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 992s 19:00:39.101847267 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 992s 19:00:39.236936105 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 992s 19:00:39.369886130 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 992s 19:00:39.500769562 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 992s 19:00:39.631500803 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 992s 19:00:39.767467475 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 992s 19:00:39.899422320 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 993s 19:00:40.037274729 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 993s 19:00:40.177161543 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 993s 19:00:40.320891208 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 993s 19:00:40.458762495 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 993s 19:00:40.592117605 O: ok putty ciphers 993s 19:00:40.592420986 E: run test putty-kex.sh ... 994s 19:00:41.363391213 O: plink version 0.82 major 0 minor 82 994s 19:00:41.614638925 O: putty KEX: kex diffie-hellman-group1-sha1 994s 19:00:41.702611124 O: putty KEX: kex diffie-hellman-group14-sha1 994s 19:00:41.823167493 O: putty KEX: kex diffie-hellman-group14-sha256 994s 19:00:41.943368739 O: putty KEX: kex diffie-hellman-group16-sha512 995s 19:00:42.248592207 O: putty KEX: kex diffie-hellman-group18-sha512 996s 19:00:43.871467893 O: putty KEX: kex diffie-hellman-group-exchange-sha1 997s 19:00:43.996431994 O: putty KEX: kex diffie-hellman-group-exchange-sha256 997s 19:00:44.324518644 O: putty KEX: kex ecdh-sha2-nistp256 997s 19:00:44.410731252 O: putty KEX: kex ecdh-sha2-nistp384 997s 19:00:44.504085771 O: putty KEX: kex ecdh-sha2-nistp521 997s 19:00:44.611086003 O: putty KEX: kex curve25519-sha256 997s 19:00:44.696882461 O: putty KEX: kex curve25519-sha256@libssh.org 997s 19:00:44.790014901 O: putty KEX: kex sntrup761x25519-sha512 997s 19:00:44.904699555 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 998s 19:00:45.028994359 O: ok putty KEX 998s 19:00:45.030018724 E: run test conch-ciphers.sh ... 998s 19:00:45.211088987 O: conch ciphers: cipher aes256-ctr 998s 19:00:45.807206001 O: conch ciphers: cipher aes256-cbc 999s 19:00:46.394118737 O: conch ciphers: cipher aes192-ctr 1000s 19:00:46.993413278 O: conch ciphers: cipher aes192-cbc 1000s 19:00:47.592319793 O: conch ciphers: cipher aes128-ctr 1001s 19:00:48.195289361 O: conch ciphers: cipher aes128-cbc 1001s 19:00:48.820237053 O: conch ciphers: cipher cast128-cbc 1002s 19:00:49.420729485 O: conch ciphers: cipher blowfish 1003s 19:00:50.022968602 O: conch ciphers: cipher 3des-cbc 1003s 19:00:50.621113667 O: ok conch ciphers 1003s 19:00:50.621836779 E: run test dropbear-ciphers.sh ... 1003s 19:00:50.758001886 O: Create dropbear key type ed25519 1003s 19:00:50.776539559 O: Create dropbear key type rsa 1004s 19:00:51.017641557 O: Create dropbear key type ecdsa 1004s 19:00:51.066742709 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 1004s 19:00:51.273866875 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 1004s 19:00:51.472764030 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 1004s 19:00:51.673231851 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1004s 19:00:51.881062027 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1005s 19:00:52.093120864 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1005s 19:00:52.296613252 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 1005s 19:00:52.498729853 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 1005s 19:00:52.717666108 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 1005s 19:00:52.928288859 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1006s 19:00:53.133405494 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1006s 19:00:53.334618340 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1006s 19:00:53.549362955 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 1006s 19:00:53.750596540 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 1006s 19:00:53.959641747 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 1007s 19:00:54.174085136 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1007s 19:00:54.380087714 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1007s 19:00:54.609333460 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1007s 19:00:54.830876607 O: ok dropbear ciphers 1007s 19:00:54.831797239 E: run test dropbear-kex.sh ... 1008s 19:00:55.004231818 O: diffie-hellman-group14-sha1 1008s 19:00:55.005082747 O: dropbear kex: kex curve25519-sha256 1008s 19:00:55.214874946 O: dropbear kex: kex curve25519-sha256@libssh.org 1008s 19:00:55.423655689 O: dropbear kex: kex diffie-hellman-group14-sha256 1008s 19:00:55.631654497 O: dropbear kex: kex diffie-hellman-group14-sha1 1008s 19:00:55.846378000 O: ok dropbear kex 1008s 19:00:55.847532999 O: make: Leaving directory '/tmp/autopkgtest.Dp44JS/autopkgtest_tmp/user/regress' 1008s 19:00:55.848854883 I: Finished with exitcode 0 1008s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1008s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 1009s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1009s info: Looking for files to backup/remove ... 1009s info: Removing files ... 1009s info: Removing crontab ... 1009s info: Removing user `openssh-tests' ... 1010s autopkgtest [19:00:57]: test regress: -----------------------] 1010s autopkgtest [19:00:57]: test regress: - - - - - - - - - - results - - - - - - - - - - 1010s regress PASS 1011s autopkgtest [19:00:58]: test ssh-gssapi: preparing testbed 1083s autopkgtest [19:02:10]: testbed dpkg architecture: ppc64el 1084s autopkgtest [19:02:11]: testbed apt version: 2.9.18 1084s autopkgtest [19:02:11]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1084s autopkgtest [19:02:11]: testbed release detected to be: plucky 1085s autopkgtest [19:02:12]: updating testbed package index (apt update) 1085s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1085s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1086s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1086s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1086s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [822 kB] 1086s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 1086s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [152 kB] 1086s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.6 kB] 1086s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [261 kB] 1086s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [756 B] 1086s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [910 kB] 1086s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [14.5 kB] 1086s Fetched 2259 kB in 1s (2087 kB/s) 1087s Reading package lists... 1088s + lsb_release --codename --short 1088s + RELEASE=plucky 1088s + cat 1088s + [ plucky != trusty ] 1088s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 1088s Reading package lists... 1088s Building dependency tree... 1088s Reading state information... 1088s Calculating upgrade... 1088s The following packages will be upgraded: 1088s apport apport-core-dump-handler bash-completion ed libicu74 pci.ids 1088s python3-apport python3-problem-report 1089s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1089s Need to get 12.0 MB of archives. 1089s After this operation, 1024 B of additional disk space will be used. 1089s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-problem-report all 2.31.0-0ubuntu3 [25.3 kB] 1089s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-apport all 2.31.0-0ubuntu3 [93.1 kB] 1089s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport-core-dump-handler all 2.31.0-0ubuntu3 [18.3 kB] 1089s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport all 2.31.0-0ubuntu3 [83.0 kB] 1089s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libicu74 ppc64el 74.2-1ubuntu6 [11.2 MB] 1089s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el bash-completion all 1:2.16.0-6 [214 kB] 1090s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el ed ppc64el 1.21-1 [65.4 kB] 1090s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el pci.ids all 0.0~2025.01.13-1 [282 kB] 1090s Fetched 12.0 MB in 1s (10.4 MB/s) 1090s (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 ... 74042 files and directories currently installed.) 1090s Preparing to unpack .../0-python3-problem-report_2.31.0-0ubuntu3_all.deb ... 1090s Unpacking python3-problem-report (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1090s Preparing to unpack .../1-python3-apport_2.31.0-0ubuntu3_all.deb ... 1090s Unpacking python3-apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1090s Preparing to unpack .../2-apport-core-dump-handler_2.31.0-0ubuntu3_all.deb ... 1090s Unpacking apport-core-dump-handler (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1090s Preparing to unpack .../3-apport_2.31.0-0ubuntu3_all.deb ... 1090s Unpacking apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1090s Preparing to unpack .../4-libicu74_74.2-1ubuntu6_ppc64el.deb ... 1090s Unpacking libicu74:ppc64el (74.2-1ubuntu6) over (74.2-1ubuntu4) ... 1091s Preparing to unpack .../5-bash-completion_1%3a2.16.0-6_all.deb ... 1091s Unpacking bash-completion (1:2.16.0-6) over (1:2.16.0-3) ... 1091s Preparing to unpack .../6-ed_1.21-1_ppc64el.deb ... 1091s Unpacking ed (1.21-1) over (1.20.2-2) ... 1091s Preparing to unpack .../7-pci.ids_0.0~2025.01.13-1_all.deb ... 1091s Unpacking pci.ids (0.0~2025.01.13-1) over (0.0~2024.11.25-1) ... 1091s Setting up pci.ids (0.0~2025.01.13-1) ... 1091s Setting up python3-problem-report (2.31.0-0ubuntu3) ... 1091s Setting up python3-apport (2.31.0-0ubuntu3) ... 1091s Setting up ed (1.21-1) ... 1091s Setting up bash-completion (1:2.16.0-6) ... 1091s Setting up libicu74:ppc64el (74.2-1ubuntu6) ... 1091s Setting up apport-core-dump-handler (2.31.0-0ubuntu3) ... 1092s Setting up apport (2.31.0-0ubuntu3) ... 1093s apport-autoreport.service is a disabled or a static unit not running, not starting it. 1093s Processing triggers for install-info (7.1.1-1) ... 1093s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1093s Processing triggers for man-db (2.13.0-1) ... 1094s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 1094s + /usr/lib/apt/apt-helper analyze-pattern ?true 1094s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 1094s Reading package lists... 1094s Building dependency tree... 1094s Reading state information... 1095s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 1095s + grep -q trusty /etc/lsb-release 1095s + [ ! -d /usr/share/doc/unattended-upgrades ] 1095s + [ ! -d /usr/share/doc/lxd ] 1095s + [ ! -d /usr/share/doc/lxd-client ] 1095s + [ ! -d /usr/share/doc/snapd ] 1095s + type iptables 1095s + cat 1095s + chmod 755 /etc/rc.local 1095s + . /etc/rc.local 1095s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 1095s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 1095s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 1095s + uname -m 1095s + [ ppc64le = ppc64le ] 1095s + systemctl is-active keyboard-setup.service 1095s + [ active = failed ] 1095s + [ -d /run/systemd/system ] 1095s + systemd-detect-virt --quiet --vm 1095s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 1095s + cat 1095s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 1095s + echo COMPRESS=lz4 1095s + sync 1095s autopkgtest [19:02:22]: upgrading testbed (apt dist-upgrade and autopurge) 1095s Reading package lists... 1095s Building dependency tree... 1095s Reading state information... 1096s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1096s Starting 2 pkgProblemResolver with broken count: 0 1096s Done 1096s Entering ResolveByKeep 1096s 1096s The following packages will be upgraded: 1096s libedit2 1097s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1097s Need to get 124 kB of archives. 1097s After this operation, 0 B of additional disk space will be used. 1097s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libedit2 ppc64el 3.1-20250104-1 [124 kB] 1097s Fetched 124 kB in 0s (360 kB/s) 1097s (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 ... 74042 files and directories currently installed.) 1097s Preparing to unpack .../libedit2_3.1-20250104-1_ppc64el.deb ... 1097s Unpacking libedit2:ppc64el (3.1-20250104-1) over (3.1-20240808-1) ... 1097s Setting up libedit2:ppc64el (3.1-20250104-1) ... 1097s Processing triggers for man-db (2.13.0-1) ... 1097s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1098s Reading package lists... 1098s Building dependency tree... 1098s Reading state information... 1098s Starting pkgProblemResolver with broken count: 0 1098s Starting 2 pkgProblemResolver with broken count: 0 1098s Done 1099s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1099s autopkgtest [19:02:26]: rebooting testbed after setup commands that affected boot 1132s Reading package lists... 1132s Building dependency tree... 1132s Reading state information... 1132s Starting pkgProblemResolver with broken count: 0 1132s Starting 2 pkgProblemResolver with broken count: 0 1132s Done 1133s The following NEW packages will be installed: 1133s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1133s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1133s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1133s 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, 4073 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-3 [65.2 kB] 1133s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libkadm5clnt-mit12 ppc64el 1.21.3-3 [44.0 kB] 1133s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libkdb5-10t64 ppc64el 1.21.3-3 [46.9 kB] 1133s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libkadm5srv-mit12 ppc64el 1.21.3-3 [61.4 kB] 1133s Get:6 http://ftpmaster.internal/ubuntu plucky/universe ppc64el krb5-user ppc64el 1.21.3-3 [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-3 [220 kB] 1133s Get:11 http://ftpmaster.internal/ubuntu plucky/universe ppc64el krb5-admin-server ppc64el 1.21.3-3 [107 kB] 1133s Get:12 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-client-gssapi all 1:9.9p1-3ubuntu2 [5028 B] 1133s Get:13 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-server-gssapi all 1:9.9p1-3ubuntu2 [5036 B] 1134s Preconfiguring packages ... 1134s Fetched 884 kB in 1s (1503 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 ... 74042 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-3_ppc64el.deb ... 1134s Unpacking libgssrpc4t64:ppc64el (1.21.3-3) ... 1134s Selecting previously unselected package libkadm5clnt-mit12:ppc64el. 1134s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_ppc64el.deb ... 1134s Unpacking libkadm5clnt-mit12:ppc64el (1.21.3-3) ... 1134s Selecting previously unselected package libkdb5-10t64:ppc64el. 1134s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_ppc64el.deb ... 1134s Unpacking libkdb5-10t64:ppc64el (1.21.3-3) ... 1134s Selecting previously unselected package libkadm5srv-mit12:ppc64el. 1134s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_ppc64el.deb ... 1134s Unpacking libkadm5srv-mit12:ppc64el (1.21.3-3) ... 1134s Selecting previously unselected package krb5-user. 1134s Preparing to unpack .../05-krb5-user_1.21.3-3_ppc64el.deb ... 1134s Unpacking krb5-user (1.21.3-3) ... 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-3_ppc64el.deb ... 1134s Unpacking krb5-kdc (1.21.3-3) ... 1134s Selecting previously unselected package krb5-admin-server. 1134s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_ppc64el.deb ... 1134s Unpacking krb5-admin-server (1.21.3-3) ... 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. 1134s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 1134s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1134s Setting up libevent-2.1-7t64:ppc64el (2.1.12-stable-10) ... 1134s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 1134s Setting up libgssrpc4t64:ppc64el (1.21.3-3) ... 1134s Setting up krb5-config (2.7) ... 1134s Setting up libkadm5clnt-mit12:ppc64el (1.21.3-3) ... 1134s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 1134s Setting up libkdb5-10t64:ppc64el (1.21.3-3) ... 1134s Setting up libkadm5srv-mit12:ppc64el (1.21.3-3) ... 1134s Setting up krb5-user (1.21.3-3) ... 1134s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1134s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1134s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1134s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1134s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1134s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1134s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1134s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1134s Setting up libverto1t64:ppc64el (0.3.1-1.2ubuntu3) ... 1134s Setting up libverto-libevent1t64:ppc64el (0.3.1-1.2ubuntu3) ... 1134s Setting up krb5-kdc (1.21.3-3) ... 1135s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1135s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1135s Setting up krb5-admin-server (1.21.3-3) ... 1136s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1136s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1136s Processing triggers for man-db (2.13.0-1) ... 1143s autopkgtest [19:03:10]: test ssh-gssapi: [----------------------- 1143s Generating public/private ed25519 key pair. 1143s Your identification has been saved in /root/.ssh/id_ed25519 1143s Your public key has been saved in /root/.ssh/id_ed25519.pub 1143s The key fingerprint is: 1143s SHA256:Dr6zlk2l/FTBWGHoN2ImC5XqjeFrAqCKcML+t/hLSzk root@autopkgtest 1143s The key's randomart image is: 1143s +--[ED25519 256]--+ 1143s | . =+. | 1143s | o o.o | 1143s | o . . | 1143s |. + . * + | 1143s |o. o.*SB + . | 1143s |+.o .=o* . | 1143s |=o . E.=.o | 1143s |o. =.O.. . | 1143s | .ooO=o | 1143s +----[SHA256]-----+ 1143s ## Setting up test environment 1143s ## Creating Kerberos realm EXAMPLE.FAKE 1143s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1143s master key name 'K/M@EXAMPLE.FAKE' 1143s ## Creating principals 1143s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1143s Principal "testuser1711@EXAMPLE.FAKE" created. 1143s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1143s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1143s ## Extracting service principal host/sshd-gssapi.example.fake 1143s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1143s 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. 1143s 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. 1143s ## Adjusting /etc/krb5.conf 1143s ## TESTS 1143s 1143s ## TEST test_gssapi_login 1143s ## Configuring sshd for gssapi-with-mic authentication 1143s ## Restarting ssh 1144s ## Obtaining TGT 1144s Password for testuser1711@EXAMPLE.FAKE: 1144s Ticket cache: FILE:/tmp/krb5cc_0 1144s Default principal: testuser1711@EXAMPLE.FAKE 1144s 1144s Valid starting Expires Service principal 1144s 01/18/25 19:03:11 01/19/25 05:03:11 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1144s renew until 01/19/25 19:03:11 1144s 1144s ## ssh'ing into localhost using gssapi-with-mic auth 1144s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1144s Sat Jan 18 19:03:11 UTC 2025 1144s 1144s ## checking that we got a service ticket for ssh (host/) 1144s 01/18/25 19:03:11 01/19/25 05:03:11 host/sshd-gssapi.example.fake@ 1144s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1144s 1144s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1144s Jan 18 19:03:11 sshd-gssapi.example.fake sshd-session[1814]: Accepted gssapi-with-mic for testuser1711 from 127.0.0.1 port 58898 ssh2: testuser1711@EXAMPLE.FAKE 1144s ## PASS test_gssapi_login 1144s 1144s ## TEST test_gssapi_keyex_login 1144s ## Configuring sshd for gssapi-keyex authentication 1144s ## Restarting ssh 1144s ## Obtaining TGT 1144s Password for testuser1711@EXAMPLE.FAKE: 1144s Ticket cache: FILE:/tmp/krb5cc_0 1144s Default principal: testuser1711@EXAMPLE.FAKE 1144s 1144s Valid starting Expires Service principal 1144s 01/18/25 19:03:11 01/19/25 05:03:11 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1144s renew until 01/19/25 19:03:11 1144s 1144s ## ssh'ing into localhost using gssapi-keyex auth 1144s Sat Jan 18 19:03:12 UTC 2025 1144s 1144s ## checking that we got a service ticket for ssh (host/) 1144s 01/18/25 19:03:11 01/19/25 05:03:11 host/sshd-gssapi.example.fake@ 1144s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1144s 1144s ## Checking ssh logs to confirm gssapi-keyex auth was used 1144s Jan 18 19:03:11 sshd-gssapi.example.fake sshd-session[1869]: Accepted gssapi-keyex for testuser1711 from 127.0.0.1 port 58900 ssh2: testuser1711@EXAMPLE.FAKE 1144s ## PASS test_gssapi_keyex_login 1144s 1144s ## TEST test_gssapi_keyex_pubkey_fallback 1144s ## Configuring sshd for gssapi-keyex authentication 1144s ## Restarting ssh 1144s ## Obtaining TGT 1144s Password for testuser1711@EXAMPLE.FAKE: 1144s Ticket cache: FILE:/tmp/krb5cc_0 1144s Default principal: testuser1711@EXAMPLE.FAKE 1144s 1144s Valid starting Expires Service principal 1144s 01/18/25 19:03:12 01/19/25 05:03:12 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1144s renew until 01/19/25 19:03:12 1144s 1144s ## ssh'ing into localhost using gssapi-keyex auth 1145s Sat Jan 18 19:03:12 UTC 2025 1145s 1145s ## checking that we got a service ticket for ssh (host/) 1145s 01/18/25 19:03:12 01/19/25 05:03:12 host/sshd-gssapi.example.fake@ 1145s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1145s 1145s ## Checking ssh logs to confirm publickey auth was used 1145s Jan 18 19:03:12 sshd-gssapi.example.fake sshd-session[1910]: Accepted publickey for testuser1711-2 from 127.0.0.1 port 58902 ssh2: ED25519 SHA256:Dr6zlk2l/FTBWGHoN2ImC5XqjeFrAqCKcML+t/hLSzk 1145s ## PASS test_gssapi_keyex_pubkey_fallback 1145s 1145s ## ALL TESTS PASSED 1145s ## Cleaning up 1145s autopkgtest [19:03:12]: test ssh-gssapi: -----------------------] 1145s ssh-gssapi PASS 1145s autopkgtest [19:03:12]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1146s autopkgtest [19:03:13]: test socket-activation: preparing testbed 1220s autopkgtest [19:04:27]: testbed dpkg architecture: ppc64el 1220s autopkgtest [19:04:27]: testbed apt version: 2.9.18 1221s autopkgtest [19:04:28]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1221s autopkgtest [19:04:28]: testbed release detected to be: plucky 1222s autopkgtest [19:04:29]: updating testbed package index (apt update) 1222s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1222s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1222s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1222s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1222s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [152 kB] 1222s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 1222s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.6 kB] 1222s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [822 kB] 1223s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [261 kB] 1223s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [756 B] 1223s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [910 kB] 1223s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [14.5 kB] 1223s Fetched 2259 kB in 1s (2196 kB/s) 1224s Reading package lists... 1224s + lsb_release --codename --short 1224s + RELEASE=plucky 1224s + cat 1224s + [ plucky != trusty ] 1224s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 1224s Reading package lists... 1225s Building dependency tree... 1225s Reading state information... 1225s Calculating upgrade... 1225s The following packages will be upgraded: 1225s apport apport-core-dump-handler bash-completion ed libicu74 pci.ids 1225s python3-apport python3-problem-report 1225s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1225s Need to get 12.0 MB of archives. 1225s After this operation, 1024 B of additional disk space will be used. 1225s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-problem-report all 2.31.0-0ubuntu3 [25.3 kB] 1225s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-apport all 2.31.0-0ubuntu3 [93.1 kB] 1225s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport-core-dump-handler all 2.31.0-0ubuntu3 [18.3 kB] 1225s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport all 2.31.0-0ubuntu3 [83.0 kB] 1225s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libicu74 ppc64el 74.2-1ubuntu6 [11.2 MB] 1226s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el bash-completion all 1:2.16.0-6 [214 kB] 1226s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el ed ppc64el 1.21-1 [65.4 kB] 1226s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el pci.ids all 0.0~2025.01.13-1 [282 kB] 1227s Fetched 12.0 MB in 1s (9865 kB/s) 1227s (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 ... 74042 files and directories currently installed.) 1227s Preparing to unpack .../0-python3-problem-report_2.31.0-0ubuntu3_all.deb ... 1227s Unpacking python3-problem-report (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1227s Preparing to unpack .../1-python3-apport_2.31.0-0ubuntu3_all.deb ... 1227s Unpacking python3-apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1227s Preparing to unpack .../2-apport-core-dump-handler_2.31.0-0ubuntu3_all.deb ... 1227s Unpacking apport-core-dump-handler (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1227s Preparing to unpack .../3-apport_2.31.0-0ubuntu3_all.deb ... 1227s Unpacking apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1227s Preparing to unpack .../4-libicu74_74.2-1ubuntu6_ppc64el.deb ... 1227s Unpacking libicu74:ppc64el (74.2-1ubuntu6) over (74.2-1ubuntu4) ... 1227s Preparing to unpack .../5-bash-completion_1%3a2.16.0-6_all.deb ... 1227s Unpacking bash-completion (1:2.16.0-6) over (1:2.16.0-3) ... 1227s Preparing to unpack .../6-ed_1.21-1_ppc64el.deb ... 1227s Unpacking ed (1.21-1) over (1.20.2-2) ... 1227s Preparing to unpack .../7-pci.ids_0.0~2025.01.13-1_all.deb ... 1227s Unpacking pci.ids (0.0~2025.01.13-1) over (0.0~2024.11.25-1) ... 1228s Setting up pci.ids (0.0~2025.01.13-1) ... 1228s Setting up python3-problem-report (2.31.0-0ubuntu3) ... 1228s Setting up python3-apport (2.31.0-0ubuntu3) ... 1228s Setting up ed (1.21-1) ... 1228s Setting up bash-completion (1:2.16.0-6) ... 1228s Setting up libicu74:ppc64el (74.2-1ubuntu6) ... 1228s Setting up apport-core-dump-handler (2.31.0-0ubuntu3) ... 1229s Setting up apport (2.31.0-0ubuntu3) ... 1229s apport-autoreport.service is a disabled or a static unit not running, not starting it. 1229s Processing triggers for install-info (7.1.1-1) ... 1230s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1230s Processing triggers for man-db (2.13.0-1) ... 1230s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 1230s + /usr/lib/apt/apt-helper analyze-pattern ?true 1230s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 1230s Reading package lists... 1230s Building dependency tree... 1230s Reading state information... 1231s + grep -q trusty /etc/lsb-release 1231s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 1231s + [ ! -d /usr/share/doc/unattended-upgrades ] 1231s + [ ! -d /usr/share/doc/lxd ] 1231s + [ ! -d /usr/share/doc/lxd-client ] 1231s + [ ! -d /usr/share/doc/snapd ] 1231s + type iptables 1231s + cat 1231s + chmod 755 /etc/rc.local 1231s + . /etc/rc.local 1231s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 1231s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 1231s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 1231s + uname -m 1231s + [ ppc64le = ppc64le ] 1231s + systemctl is-active keyboard-setup.service 1231s + [ active = failed ] 1231s + [ -d /run/systemd/system ] 1231s + systemd-detect-virt --quiet --vm 1231s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 1231s + cat 1231s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 1231s + echo COMPRESS=lz4 1231s + sync 1231s autopkgtest [19:04:38]: upgrading testbed (apt dist-upgrade and autopurge) 1231s Reading package lists... 1231s Building dependency tree... 1231s Reading state information... 1231s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1231s Starting 2 pkgProblemResolver with broken count: 0 1231s Done 1232s Entering ResolveByKeep 1232s 1232s The following packages will be upgraded: 1232s libedit2 1232s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1232s Need to get 124 kB of archives. 1232s After this operation, 0 B of additional disk space will be used. 1232s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libedit2 ppc64el 3.1-20250104-1 [124 kB] 1233s Fetched 124 kB in 0s (352 kB/s) 1233s (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 ... 74042 files and directories currently installed.) 1233s Preparing to unpack .../libedit2_3.1-20250104-1_ppc64el.deb ... 1233s Unpacking libedit2:ppc64el (3.1-20250104-1) over (3.1-20240808-1) ... 1233s Setting up libedit2:ppc64el (3.1-20250104-1) ... 1233s Processing triggers for man-db (2.13.0-1) ... 1233s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1233s Reading package lists... 1233s Building dependency tree... 1233s Reading state information... 1234s Starting pkgProblemResolver with broken count: 0 1234s Starting 2 pkgProblemResolver with broken count: 0 1234s Done 1234s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1234s autopkgtest [19:04:41]: rebooting testbed after setup commands that affected boot 1267s Reading package lists... 1267s Building dependency tree... 1267s Reading state information... 1267s Starting pkgProblemResolver with broken count: 0 1267s Starting 2 pkgProblemResolver with broken count: 0 1267s Done 1267s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1270s autopkgtest [19:05:17]: test socket-activation: [----------------------- 1270s Generating public/private ed25519 key pair. 1270s Your identification has been saved in /home/testuser1321/.ssh/id_ed25519 1270s Your public key has been saved in /home/testuser1321/.ssh/id_ed25519.pub 1270s The key fingerprint is: 1270s SHA256:17Ge/5kjIoPIswyIjDEhwHmaII7buSt3sgeFs0D1GEs testuser1321@autopkgtest 1270s The key's randomart image is: 1270s +--[ED25519 256]--+ 1270s |o oE | 1270s |++..= | 1270s |O ++ . . | 1270s |+=o . . o | 1270s |o+ = S . o | 1270s |=o* . . . | 1270s |oo +. . . o | 1270s |. + =+ . o . o .o| 1270s | oo* oo o . o+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. 1271s Sat Jan 18 19:05:18 UTC 2025 1272s autopkgtest [19:05:19]: test socket-activation: -----------------------] 1272s socket-activation PASS 1272s autopkgtest [19:05:19]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1272s autopkgtest [19:05:19]: test xinetd: preparing testbed 1272s Reading package lists... 1273s Building dependency tree... 1273s Reading state information... 1273s Starting pkgProblemResolver with broken count: 0 1273s Starting 2 pkgProblemResolver with broken count: 0 1273s Done 1273s The following NEW packages will be installed: 1273s xinetd 1273s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1273s Need to get 136 kB of archives. 1273s After this operation, 439 kB of additional disk space will be used. 1273s Get:1 http://ftpmaster.internal/ubuntu plucky/universe ppc64el xinetd ppc64el 1:2.3.15.4-4 [136 kB] 1274s Fetched 136 kB in 0s (370 kB/s) 1274s Selecting previously unselected package xinetd. 1274s (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 ... 74042 files and directories currently installed.) 1274s Preparing to unpack .../xinetd_1%3a2.3.15.4-4_ppc64el.deb ... 1274s Unpacking xinetd (1:2.3.15.4-4) ... 1274s Setting up xinetd (1:2.3.15.4-4) ... 1274s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1275s Processing triggers for man-db (2.13.0-1) ... 1277s autopkgtest [19:05:24]: test xinetd: [----------------------- 1277s Generating public/private ed25519 key pair. 1277s Your identification has been saved in /home/testuser1837/.ssh/id_ed25519 1277s Your public key has been saved in /home/testuser1837/.ssh/id_ed25519.pub 1277s The key fingerprint is: 1277s SHA256:msYjiMe/FeNFEumQUrHPB/DlSclKRdtXoNL23wj99GE testuser1837@autopkgtest 1277s The key's randomart image is: 1277s +--[ED25519 256]--+ 1277s | .+o.==. ... | 1277s | . o+o=o= . . | 1277s | ..++.* = . | 1277s | oo+ o o . | 1277s | = S o .E.| 1277s | o . o B o.=o| 1277s |. + . O o +| 1277s | . . + . | 1277s | o. | 1277s +----[SHA256]-----+ 1277s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1277s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1278s Disabling 'ssh.service', but its triggering units are still active: 1278s ssh.socket 1278s Stopping 'ssh.service', but its triggering units are still active: 1278s ssh.socket 1278s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1278s Sat Jan 18 19:05:25 UTC 2025 1279s autopkgtest [19:05:26]: test xinetd: -----------------------] 1279s xinetd PASS 1279s autopkgtest [19:05:26]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1279s autopkgtest [19:05:26]: test systemd-socket-activation: preparing testbed 1356s autopkgtest [19:06:43]: testbed dpkg architecture: ppc64el 1356s autopkgtest [19:06:43]: testbed apt version: 2.9.18 1357s autopkgtest [19:06:44]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1357s autopkgtest [19:06:44]: testbed release detected to be: plucky 1358s autopkgtest [19:06:45]: updating testbed package index (apt update) 1358s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1358s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1358s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1358s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1358s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.6 kB] 1358s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [152 kB] 1358s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [822 kB] 1358s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 1358s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [261 kB] 1358s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [756 B] 1358s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [910 kB] 1359s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [14.5 kB] 1359s Fetched 2259 kB in 1s (2112 kB/s) 1360s Reading package lists... 1360s + lsb_release --codename --short 1360s + RELEASE=plucky 1360s + cat 1360s + [ plucky != trusty ] 1360s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 1360s Reading package lists... 1361s Building dependency tree... 1361s Reading state information... 1361s Calculating upgrade... 1361s The following packages will be upgraded: 1361s apport apport-core-dump-handler bash-completion ed libicu74 pci.ids 1361s python3-apport python3-problem-report 1361s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1361s Need to get 12.0 MB of archives. 1361s After this operation, 1024 B of additional disk space will be used. 1361s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-problem-report all 2.31.0-0ubuntu3 [25.3 kB] 1361s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-apport all 2.31.0-0ubuntu3 [93.1 kB] 1361s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport-core-dump-handler all 2.31.0-0ubuntu3 [18.3 kB] 1361s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el apport all 2.31.0-0ubuntu3 [83.0 kB] 1361s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libicu74 ppc64el 74.2-1ubuntu6 [11.2 MB] 1362s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el bash-completion all 1:2.16.0-6 [214 kB] 1362s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el ed ppc64el 1.21-1 [65.4 kB] 1362s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el pci.ids all 0.0~2025.01.13-1 [282 kB] 1363s Fetched 12.0 MB in 1s (9100 kB/s) 1363s (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 ... 74042 files and directories currently installed.) 1363s Preparing to unpack .../0-python3-problem-report_2.31.0-0ubuntu3_all.deb ... 1363s Unpacking python3-problem-report (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1363s Preparing to unpack .../1-python3-apport_2.31.0-0ubuntu3_all.deb ... 1363s Unpacking python3-apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1363s Preparing to unpack .../2-apport-core-dump-handler_2.31.0-0ubuntu3_all.deb ... 1363s Unpacking apport-core-dump-handler (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1363s Preparing to unpack .../3-apport_2.31.0-0ubuntu3_all.deb ... 1363s Unpacking apport (2.31.0-0ubuntu3) over (2.31.0-0ubuntu2) ... 1363s Preparing to unpack .../4-libicu74_74.2-1ubuntu6_ppc64el.deb ... 1363s Unpacking libicu74:ppc64el (74.2-1ubuntu6) over (74.2-1ubuntu4) ... 1364s Preparing to unpack .../5-bash-completion_1%3a2.16.0-6_all.deb ... 1364s Unpacking bash-completion (1:2.16.0-6) over (1:2.16.0-3) ... 1364s Preparing to unpack .../6-ed_1.21-1_ppc64el.deb ... 1364s Unpacking ed (1.21-1) over (1.20.2-2) ... 1364s Preparing to unpack .../7-pci.ids_0.0~2025.01.13-1_all.deb ... 1364s Unpacking pci.ids (0.0~2025.01.13-1) over (0.0~2024.11.25-1) ... 1364s Setting up pci.ids (0.0~2025.01.13-1) ... 1364s Setting up python3-problem-report (2.31.0-0ubuntu3) ... 1364s Setting up python3-apport (2.31.0-0ubuntu3) ... 1364s Setting up ed (1.21-1) ... 1364s Setting up bash-completion (1:2.16.0-6) ... 1364s Setting up libicu74:ppc64el (74.2-1ubuntu6) ... 1364s Setting up apport-core-dump-handler (2.31.0-0ubuntu3) ... 1365s Setting up apport (2.31.0-0ubuntu3) ... 1366s apport-autoreport.service is a disabled or a static unit not running, not starting it. 1366s Processing triggers for install-info (7.1.1-1) ... 1366s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1366s Processing triggers for man-db (2.13.0-1) ... 1367s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 1367s + /usr/lib/apt/apt-helper analyze-pattern ?true 1367s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 1367s Reading package lists... 1367s Building dependency tree... 1367s Reading state information... 1367s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 1367s + grep -q trusty /etc/lsb-release 1367s + [ ! -d /usr/share/doc/unattended-upgrades ] 1367s + [ ! -d /usr/share/doc/lxd ] 1367s + [ ! -d /usr/share/doc/lxd-client ] 1367s + [ ! -d /usr/share/doc/snapd ] 1367s + type iptables 1367s + cat 1367s + chmod 755 /etc/rc.local 1367s + . /etc/rc.local 1367s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 1367s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 1367s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 1367s + uname -m 1367s + [ ppc64le = ppc64le ] 1368s + systemctl is-active keyboard-setup.service 1368s + [ active = failed ] 1368s + [ -d /run/systemd/system ] 1368s + systemd-detect-virt --quiet --vm 1368s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 1368s + cat 1368s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 1368s + echo COMPRESS=lz4 1368s + sync 1368s autopkgtest [19:06:55]: upgrading testbed (apt dist-upgrade and autopurge) 1368s Reading package lists... 1368s Building dependency tree... 1368s Reading state information... 1368s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1368s Starting 2 pkgProblemResolver with broken count: 0 1368s Done 1369s Entering ResolveByKeep 1369s 1369s The following packages will be upgraded: 1369s libedit2 1369s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1369s Need to get 124 kB of archives. 1369s After this operation, 0 B of additional disk space will be used. 1369s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libedit2 ppc64el 3.1-20250104-1 [124 kB] 1370s Fetched 124 kB in 0s (363 kB/s) 1370s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 74042 files and directories currently installed.) 1370s Preparing to unpack .../libedit2_3.1-20250104-1_ppc64el.deb ... 1370s Unpacking libedit2:ppc64el (3.1-20250104-1) over (3.1-20240808-1) ... 1370s Setting up libedit2:ppc64el (3.1-20250104-1) ... 1370s Processing triggers for man-db (2.13.0-1) ... 1370s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1371s Reading package lists... 1371s Building dependency tree... 1371s Reading state information... 1371s Starting pkgProblemResolver with broken count: 0 1371s Starting 2 pkgProblemResolver with broken count: 0 1371s Done 1372s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1372s autopkgtest [19:06:59]: rebooting testbed after setup commands that affected boot 1406s Reading package lists... 1407s Building dependency tree... 1407s Reading state information... 1407s Starting pkgProblemResolver with broken count: 0 1407s Starting 2 pkgProblemResolver with broken count: 0 1407s Done 1407s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1410s autopkgtest [19:07:37]: test systemd-socket-activation: [----------------------- 1410s Stopping ssh.service... 1410s Checking that ssh.socket is active and listening... 1410s Checking that ssh.service is inactive/dead... 1410s Checking that a connection attempt activates ssh.service... 1410s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1411s Checking that sshd can be re-executed... 1411s Checking sshd can run in debug mode... 1411s debug1: PAM: establishing credentials 1411s debug1: permanently_set_uid: 0/0 1411s debug3: Copy environment: XDG_SESSION_ID=5 1411s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1411s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1411s debug3: Copy environment: XDG_SESSION_TYPE=tty 1411s debug3: Copy environment: XDG_SESSION_CLASS=user 1411s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1411s debug3: Copy environment: TERM=linux 1411s debug3: Copy environment: http_proxy=http://squid.internal:3128 1411s debug3: Copy environment: https_proxy=http://squid.internal:3128 1411s 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 1411s debug3: Copy environment: LANG=C.UTF-8 1411s Environment: 1411s LANG=C.UTF-8 1411s USER=root 1411s LOGNAME=root 1411s HOME=/root 1411s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1411s SHELL=/bin/bash 1411s XDG_SESSION_ID=5 1411s XDG_RUNTIME_DIR=/run/user/0 1411s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1411s XDG_SESSION_TYPE=tty 1411s XDG_SESSION_CLASS=user 1411s TERM=linux 1411s http_proxy=http://squid.internal:3128 1411s https_proxy=http://squid.internal:3128 1411s 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 1411s SSH_CLIENT=::1 34296 22 1411s SSH_CONNECTION=::1 34296 ::1 22 1411s Done. 1411s autopkgtest [19:07:38]: test systemd-socket-activation: -----------------------] 1412s autopkgtest [19:07:39]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1412s systemd-socket-activation PASS 1412s autopkgtest [19:07:39]: test sshd-socket-generator: preparing testbed 1412s Reading package lists... 1412s Building dependency tree... 1412s 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. 1414s autopkgtest [19:07:41]: test sshd-socket-generator: [----------------------- 1414s test_default...PASS 1414s test_custom_port...PASS 1414s test_default_and_custom_port...PASS 1414s test_mutiple_custom_ports...PASS 1414s test_custom_listenaddress...PASS 1414s test_custom_listenaddress_and_port...PASS 1414s test_custom_ipv6_listenaddress...PASS 1414s test_custom_family_ipv4...PASS 1414s test_custom_family_ipv6...PASS 1414s test_custom_port_and_family_ipv4...PASS 1414s test_custom_port_and_family_ipv6...PASS 1414s test_match_on_port...PASS 1415s autopkgtest [19:07:42]: test sshd-socket-generator: -----------------------] 1415s sshd-socket-generator PASS 1415s autopkgtest [19:07:42]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1416s autopkgtest [19:07:43]: @@@@@@@@@@@@@@@@@@@@ summary 1416s regress PASS 1416s ssh-gssapi PASS 1416s socket-activation PASS 1416s xinetd PASS 1416s systemd-socket-activation PASS 1416s sshd-socket-generator PASS 1421s nova [W] Using flock in prodstack6-ppc64el 1421s Creating nova instance adt-plucky-ppc64el-openssh-20250118-180904-juju-7f2275-prod-proposed-migration-environment-2-91add195-da1a-4da5-a0f2-eac6b6a8715b from image adt/ubuntu-plucky-ppc64el-server-20250118.img (UUID ae4ff9d6-d4c8-4087-90be-b2adeb15025d)... 1421s nova [W] Timed out waiting for 6c9ce8ac-5616-4f9d-917a-f4c0ca463c1e to get deleted. 1421s nova [W] Using flock in prodstack6-ppc64el 1421s Creating nova instance adt-plucky-ppc64el-openssh-20250118-180904-juju-7f2275-prod-proposed-migration-environment-2-91add195-da1a-4da5-a0f2-eac6b6a8715b from image adt/ubuntu-plucky-ppc64el-server-20250118.img (UUID ae4ff9d6-d4c8-4087-90be-b2adeb15025d)... 1421s nova [W] Timed out waiting for 817b368c-2cd5-4a79-9f96-91e3c86c53ef to get deleted. 1421s nova [W] Using flock in prodstack6-ppc64el 1421s Creating nova instance adt-plucky-ppc64el-openssh-20250118-180904-juju-7f2275-prod-proposed-migration-environment-2-91add195-da1a-4da5-a0f2-eac6b6a8715b from image adt/ubuntu-plucky-ppc64el-server-20250118.img (UUID ae4ff9d6-d4c8-4087-90be-b2adeb15025d)... 1421s nova [W] Timed out waiting for 897f203b-980b-4bb2-b6c4-411b1b4e3879 to get deleted. 1421s nova [W] Using flock in prodstack6-ppc64el 1421s Creating nova instance adt-plucky-ppc64el-openssh-20250118-180904-juju-7f2275-prod-proposed-migration-environment-2-91add195-da1a-4da5-a0f2-eac6b6a8715b from image adt/ubuntu-plucky-ppc64el-server-20250118.img (UUID ae4ff9d6-d4c8-4087-90be-b2adeb15025d)... 1421s nova [W] Timed out waiting for 09db70e0-9580-4c3c-84d8-8c6645a9224d to get deleted.