0s autopkgtest [07:07:54]: starting date and time: 2025-01-20 07:07:54+0000 0s autopkgtest [07:07:54]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [07:07:54]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.fzkxeuoz/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 builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-25.secgroup --name adt-plucky-amd64-openssh-20250120-040318-juju-7f2275-prod-proposed-migration-environment-15-3bfec6a5-d6d1-446f-8262-58af0569baf2 --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-amd64 -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/ 61s autopkgtest [07:08:55]: testbed dpkg architecture: amd64 61s autopkgtest [07:08:55]: testbed apt version: 2.9.18 61s autopkgtest [07:08:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 61s autopkgtest [07:08:55]: testbed release detected to be: None 62s autopkgtest [07:08:56]: updating testbed package index (apt update) 62s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 63s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 63s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 63s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 63s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 63s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [145 kB] 63s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [820 kB] 63s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 63s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [182 kB] 63s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [269 kB] 63s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 63s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 63s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [365 kB] 63s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [891 kB] 63s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [4116 B] 63s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [24.6 kB] 63s Fetched 2841 kB in 1s (2936 kB/s) 64s Reading package lists... 65s Reading package lists... 65s Building dependency tree... 65s Reading state information... 65s Calculating upgrade... 65s The following packages will be upgraded: 65s netcat-openbsd python3-more-itertools 65s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 65s Need to get 103 kB of archives. 65s After this operation, 13.3 kB of additional disk space will be used. 65s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 netcat-openbsd amd64 1.228-1 [44.9 kB] 65s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-more-itertools all 10.6.0-1 [57.7 kB] 66s Fetched 103 kB in 0s (287 kB/s) 66s (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 ... 89449 files and directories currently installed.) 66s Preparing to unpack .../netcat-openbsd_1.228-1_amd64.deb ... 66s Unpacking netcat-openbsd (1.228-1) over (1.226-1.1) ... 66s Preparing to unpack .../python3-more-itertools_10.6.0-1_all.deb ... 66s Unpacking python3-more-itertools (10.6.0-1) over (10.5.0-1) ... 66s Setting up python3-more-itertools (10.6.0-1) ... 66s Setting up netcat-openbsd (1.228-1) ... 66s Processing triggers for man-db (2.13.0-1) ... 67s 67s Running kernel seems to be up-to-date. 67s 67s No services need to be restarted. 67s 67s No containers need to be restarted. 67s 67s No user sessions are running outdated binaries. 67s 67s No VM guests are running outdated hypervisor (qemu) binaries on this host. 68s Reading package lists... 68s Building dependency tree... 68s Reading state information... 68s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 68s autopkgtest [07:09:02]: upgrading testbed (apt dist-upgrade and autopurge) 68s Reading package lists... 69s Building dependency tree... 69s Reading state information... 69s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 69s Starting 2 pkgProblemResolver with broken count: 0 69s Done 69s Entering ResolveByKeep 69s 69s The following packages will be upgraded: 69s libedit2 70s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s Need to get 110 kB of archives. 70s After this operation, 0 B of additional disk space will be used. 70s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libedit2 amd64 3.1-20250104-1 [110 kB] 70s Fetched 110 kB in 0s (317 kB/s) 70s (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 ... 89449 files and directories currently installed.) 70s Preparing to unpack .../libedit2_3.1-20250104-1_amd64.deb ... 70s Unpacking libedit2:amd64 (3.1-20250104-1) over (3.1-20240808-1) ... 70s Setting up libedit2:amd64 (3.1-20250104-1) ... 70s Processing triggers for man-db (2.13.0-1) ... 71s Processing triggers for libc-bin (2.40-4ubuntu1) ... 71s 71s Running kernel seems to be up-to-date. 71s 71s No services need to be restarted. 71s 71s No containers need to be restarted. 71s 71s No user sessions are running outdated binaries. 71s 71s No VM guests are running outdated hypervisor (qemu) binaries on this host. 72s Reading package lists... 72s Building dependency tree... 72s Reading state information... 72s Starting pkgProblemResolver with broken count: 0 72s Starting 2 pkgProblemResolver with broken count: 0 72s Done 73s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 75s autopkgtest [07:09:09]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 75s autopkgtest [07:09:09]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 80s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (dsc) [3480 B] 80s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (tar) [1965 kB] 80s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (asc) [833 B] 80s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.9p1-3ubuntu2 (diff) [210 kB] 80s gpgv: Signature made Thu Nov 21 14:24:07 2024 UTC 80s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 80s gpgv: Can't check signature: No public key 80s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu2.dsc: no acceptable signature found 81s autopkgtest [07:09:15]: testing package openssh version 1:9.9p1-3ubuntu2 83s autopkgtest [07:09:17]: build not needed 85s autopkgtest [07:09:19]: test regress: preparing testbed 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Starting pkgProblemResolver with broken count: 0 86s Starting 2 pkgProblemResolver with broken count: 0 86s Done 86s The following NEW packages will be installed: 86s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 86s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 86s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 86s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 86s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 86s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 86s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 86s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 86s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 86s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 86s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 86s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 86s openssh-tests patchutils perl-openssl-defaults putty-tools wdiff 86s 0 upgraded, 50 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 5890 kB of archives. 86s After this operation, 22.3 MB of additional disk space will be used. 86s Get:1 http://ftpmaster.internal/ubuntu plucky/universe amd64 libtommath1 amd64 1.3.0-1 [67.2 kB] 87s Get:2 http://ftpmaster.internal/ubuntu plucky/universe amd64 libtomcrypt1 amd64 1.18.2+dfsg-7build1 [384 kB] 87s Get:3 http://ftpmaster.internal/ubuntu plucky/universe amd64 dropbear-bin amd64 2024.86-2 [165 kB] 87s Get:4 http://ftpmaster.internal/ubuntu plucky/universe amd64 dropbear all 2024.86-2 [8664 B] 87s Get:5 http://ftpmaster.internal/ubuntu plucky/universe amd64 libhavege2 amd64 1.9.14-1ubuntu2 [24.8 kB] 87s Get:6 http://ftpmaster.internal/ubuntu plucky/universe amd64 haveged amd64 1.9.14-1ubuntu2 [33.0 kB] 87s Get:7 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-dirlist-perl all 0.05-3 [7286 B] 87s Get:8 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-which-perl all 1.27-2 [12.5 kB] 87s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-homedir-perl all 1.006-2 [37.0 kB] 87s Get:10 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-touch-perl all 0.12-2 [7498 B] 87s Get:11 http://ftpmaster.internal/ubuntu plucky/main amd64 libio-pty-perl amd64 1:1.20-1build3 [31.4 kB] 87s Get:12 http://ftpmaster.internal/ubuntu plucky/main amd64 libipc-run-perl all 20231003.0-2 [91.5 kB] 87s Get:13 http://ftpmaster.internal/ubuntu plucky/main amd64 libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 87s Get:14 http://ftpmaster.internal/ubuntu plucky/main amd64 libclass-xsaccessor-perl amd64 1.19-4build5 [33.0 kB] 87s Get:15 http://ftpmaster.internal/ubuntu plucky/main amd64 libb-hooks-op-check-perl amd64 0.22-3build2 [9624 B] 87s Get:16 http://ftpmaster.internal/ubuntu plucky/main amd64 libdynaloader-functions-perl all 0.004-1 [11.4 kB] 87s Get:17 http://ftpmaster.internal/ubuntu plucky/main amd64 libdevel-callchecker-perl amd64 0.009-1build1 [14.2 kB] 87s Get:18 http://ftpmaster.internal/ubuntu plucky/main amd64 libparams-classify-perl amd64 0.015-2build6 [20.1 kB] 87s Get:19 http://ftpmaster.internal/ubuntu plucky/main amd64 libmodule-runtime-perl all 0.016-2 [16.4 kB] 87s Get:20 http://ftpmaster.internal/ubuntu plucky/main amd64 libimport-into-perl all 1.002005-2 [10.7 kB] 87s Get:21 http://ftpmaster.internal/ubuntu plucky/main amd64 librole-tiny-perl all 2.002004-1 [16.3 kB] 87s Get:22 http://ftpmaster.internal/ubuntu plucky/main amd64 libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 87s Get:23 http://ftpmaster.internal/ubuntu plucky/main amd64 libmoo-perl all 2.005005-1 [47.4 kB] 87s Get:24 http://ftpmaster.internal/ubuntu plucky/main amd64 libencode-locale-perl all 1.05-3 [11.6 kB] 87s Get:25 http://ftpmaster.internal/ubuntu plucky/main amd64 libtimedate-perl all 2.3300-2 [34.0 kB] 87s Get:26 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-date-perl all 6.06-1 [10.2 kB] 87s Get:27 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-listing-perl all 6.16-1 [11.3 kB] 87s Get:28 http://ftpmaster.internal/ubuntu plucky/main amd64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 87s Get:29 http://ftpmaster.internal/ubuntu plucky/main amd64 liburi-perl all 5.30-1 [94.4 kB] 87s Get:30 http://ftpmaster.internal/ubuntu plucky/main amd64 libhtml-parser-perl amd64 3.83-1build1 [86.2 kB] 87s Get:31 http://ftpmaster.internal/ubuntu plucky/main amd64 libhtml-tree-perl all 5.07-3 [200 kB] 87s Get:32 http://ftpmaster.internal/ubuntu plucky/main amd64 libclone-perl amd64 0.47-1 [10.7 kB] 87s Get:33 http://ftpmaster.internal/ubuntu plucky/main amd64 libio-html-perl all 1.004-3 [15.9 kB] 87s Get:34 http://ftpmaster.internal/ubuntu plucky/main amd64 liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 87s Get:35 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 87s Get:36 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-cookies-perl all 6.11-1 [18.2 kB] 87s Get:37 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-negotiate-perl all 6.01-2 [12.4 kB] 87s Get:38 http://ftpmaster.internal/ubuntu plucky/main amd64 perl-openssl-defaults amd64 7build3 [6626 B] 87s Get:39 http://ftpmaster.internal/ubuntu plucky/main amd64 libnet-ssleay-perl amd64 1.94-2 [317 kB] 87s Get:40 http://ftpmaster.internal/ubuntu plucky/main amd64 libio-socket-ssl-perl all 2.089-1 [200 kB] 87s Get:41 http://ftpmaster.internal/ubuntu plucky/main amd64 libnet-http-perl all 6.23-1 [22.3 kB] 87s Get:42 http://ftpmaster.internal/ubuntu plucky/main amd64 liblwp-protocol-https-perl all 6.14-1 [9040 B] 87s Get:43 http://ftpmaster.internal/ubuntu plucky/main amd64 libtry-tiny-perl all 0.32-1 [21.2 kB] 87s Get:44 http://ftpmaster.internal/ubuntu plucky/main amd64 libwww-robotrules-perl all 6.02-1 [12.6 kB] 87s Get:45 http://ftpmaster.internal/ubuntu plucky/main amd64 libwww-perl all 6.77-1 [138 kB] 87s Get:46 http://ftpmaster.internal/ubuntu plucky/main amd64 patchutils amd64 0.4.2-1build3 [77.0 kB] 87s Get:47 http://ftpmaster.internal/ubuntu plucky/main amd64 wdiff amd64 1.2.2-7 [29.6 kB] 87s Get:48 http://ftpmaster.internal/ubuntu plucky/main amd64 devscripts all 2.25.1 [1054 kB] 87s Get:49 http://ftpmaster.internal/ubuntu plucky/universe amd64 putty-tools amd64 0.82-2 [789 kB] 87s Get:50 http://ftpmaster.internal/ubuntu plucky/universe amd64 openssh-tests amd64 1:9.9p1-3ubuntu2 [1512 kB] 88s Fetched 5890 kB in 1s (5002 kB/s) 88s Selecting previously unselected package libtommath1:amd64. 88s (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 ... 89449 files and directories currently installed.) 88s Preparing to unpack .../00-libtommath1_1.3.0-1_amd64.deb ... 88s Unpacking libtommath1:amd64 (1.3.0-1) ... 88s Selecting previously unselected package libtomcrypt1:amd64. 88s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_amd64.deb ... 88s Unpacking libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ... 88s Selecting previously unselected package dropbear-bin. 88s Preparing to unpack .../02-dropbear-bin_2024.86-2_amd64.deb ... 88s Unpacking dropbear-bin (2024.86-2) ... 88s Selecting previously unselected package dropbear. 88s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 88s Unpacking dropbear (2024.86-2) ... 88s Selecting previously unselected package libhavege2:amd64. 88s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_amd64.deb ... 88s Unpacking libhavege2:amd64 (1.9.14-1ubuntu2) ... 88s Selecting previously unselected package haveged. 88s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_amd64.deb ... 88s Unpacking haveged (1.9.14-1ubuntu2) ... 88s Selecting previously unselected package libfile-dirlist-perl. 88s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 88s Unpacking libfile-dirlist-perl (0.05-3) ... 88s Selecting previously unselected package libfile-which-perl. 88s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 88s Unpacking libfile-which-perl (1.27-2) ... 88s Selecting previously unselected package libfile-homedir-perl. 88s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 88s Unpacking libfile-homedir-perl (1.006-2) ... 88s Selecting previously unselected package libfile-touch-perl. 88s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 88s Unpacking libfile-touch-perl (0.12-2) ... 88s Selecting previously unselected package libio-pty-perl. 88s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_amd64.deb ... 88s Unpacking libio-pty-perl (1:1.20-1build3) ... 88s Selecting previously unselected package libipc-run-perl. 88s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 88s Unpacking libipc-run-perl (20231003.0-2) ... 88s Selecting previously unselected package libclass-method-modifiers-perl. 88s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 88s Unpacking libclass-method-modifiers-perl (2.15-1) ... 88s Selecting previously unselected package libclass-xsaccessor-perl. 88s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_amd64.deb ... 88s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 88s Selecting previously unselected package libb-hooks-op-check-perl:amd64. 88s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_amd64.deb ... 88s Unpacking libb-hooks-op-check-perl:amd64 (0.22-3build2) ... 88s Selecting previously unselected package libdynaloader-functions-perl. 88s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 88s Unpacking libdynaloader-functions-perl (0.004-1) ... 88s Selecting previously unselected package libdevel-callchecker-perl:amd64. 88s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_amd64.deb ... 88s Unpacking libdevel-callchecker-perl:amd64 (0.009-1build1) ... 88s Selecting previously unselected package libparams-classify-perl:amd64. 88s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_amd64.deb ... 88s Unpacking libparams-classify-perl:amd64 (0.015-2build6) ... 88s Selecting previously unselected package libmodule-runtime-perl. 88s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 88s Unpacking libmodule-runtime-perl (0.016-2) ... 88s Selecting previously unselected package libimport-into-perl. 88s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 88s Unpacking libimport-into-perl (1.002005-2) ... 88s Selecting previously unselected package librole-tiny-perl. 88s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 88s Unpacking librole-tiny-perl (2.002004-1) ... 88s Selecting previously unselected package libsub-quote-perl. 88s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 88s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 88s Selecting previously unselected package libmoo-perl. 88s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 88s Unpacking libmoo-perl (2.005005-1) ... 88s Selecting previously unselected package libencode-locale-perl. 88s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 88s Unpacking libencode-locale-perl (1.05-3) ... 88s Selecting previously unselected package libtimedate-perl. 88s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 88s Unpacking libtimedate-perl (2.3300-2) ... 88s Selecting previously unselected package libhttp-date-perl. 88s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 88s Unpacking libhttp-date-perl (6.06-1) ... 88s Selecting previously unselected package libfile-listing-perl. 88s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 88s Unpacking libfile-listing-perl (6.16-1) ... 88s Selecting previously unselected package libhtml-tagset-perl. 88s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 88s Unpacking libhtml-tagset-perl (3.24-1) ... 88s Selecting previously unselected package liburi-perl. 88s Preparing to unpack .../28-liburi-perl_5.30-1_all.deb ... 88s Unpacking liburi-perl (5.30-1) ... 88s Selecting previously unselected package libhtml-parser-perl:amd64. 88s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_amd64.deb ... 88s Unpacking libhtml-parser-perl:amd64 (3.83-1build1) ... 88s Selecting previously unselected package libhtml-tree-perl. 88s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 88s Unpacking libhtml-tree-perl (5.07-3) ... 88s Selecting previously unselected package libclone-perl:amd64. 88s Preparing to unpack .../31-libclone-perl_0.47-1_amd64.deb ... 88s Unpacking libclone-perl:amd64 (0.47-1) ... 88s Selecting previously unselected package libio-html-perl. 88s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 88s Unpacking libio-html-perl (1.004-3) ... 88s Selecting previously unselected package liblwp-mediatypes-perl. 88s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 88s Unpacking liblwp-mediatypes-perl (6.04-2) ... 88s Selecting previously unselected package libhttp-message-perl. 88s Preparing to unpack .../34-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 88s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 88s Selecting previously unselected package libhttp-cookies-perl. 88s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 88s Unpacking libhttp-cookies-perl (6.11-1) ... 88s Selecting previously unselected package libhttp-negotiate-perl. 88s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 88s Unpacking libhttp-negotiate-perl (6.01-2) ... 88s Selecting previously unselected package perl-openssl-defaults:amd64. 89s Preparing to unpack .../37-perl-openssl-defaults_7build3_amd64.deb ... 89s Unpacking perl-openssl-defaults:amd64 (7build3) ... 89s Selecting previously unselected package libnet-ssleay-perl:amd64. 89s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_amd64.deb ... 89s Unpacking libnet-ssleay-perl:amd64 (1.94-2) ... 89s Selecting previously unselected package libio-socket-ssl-perl. 89s Preparing to unpack .../39-libio-socket-ssl-perl_2.089-1_all.deb ... 89s Unpacking libio-socket-ssl-perl (2.089-1) ... 89s Selecting previously unselected package libnet-http-perl. 89s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 89s Unpacking libnet-http-perl (6.23-1) ... 89s Selecting previously unselected package liblwp-protocol-https-perl. 89s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 89s Unpacking liblwp-protocol-https-perl (6.14-1) ... 89s Selecting previously unselected package libtry-tiny-perl. 89s Preparing to unpack .../42-libtry-tiny-perl_0.32-1_all.deb ... 89s Unpacking libtry-tiny-perl (0.32-1) ... 89s Selecting previously unselected package libwww-robotrules-perl. 89s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 89s Unpacking libwww-robotrules-perl (6.02-1) ... 89s Selecting previously unselected package libwww-perl. 89s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 89s Unpacking libwww-perl (6.77-1) ... 89s Selecting previously unselected package patchutils. 89s Preparing to unpack .../45-patchutils_0.4.2-1build3_amd64.deb ... 89s Unpacking patchutils (0.4.2-1build3) ... 89s Selecting previously unselected package wdiff. 89s Preparing to unpack .../46-wdiff_1.2.2-7_amd64.deb ... 89s Unpacking wdiff (1.2.2-7) ... 89s Selecting previously unselected package devscripts. 89s Preparing to unpack .../47-devscripts_2.25.1_all.deb ... 89s Unpacking devscripts (2.25.1) ... 89s Selecting previously unselected package putty-tools. 89s Preparing to unpack .../48-putty-tools_0.82-2_amd64.deb ... 89s Unpacking putty-tools (0.82-2) ... 89s Selecting previously unselected package openssh-tests. 89s Preparing to unpack .../49-openssh-tests_1%3a9.9p1-3ubuntu2_amd64.deb ... 89s Unpacking openssh-tests (1:9.9p1-3ubuntu2) ... 89s Setting up wdiff (1.2.2-7) ... 89s Setting up libfile-which-perl (1.27-2) ... 89s Setting up libdynaloader-functions-perl (0.004-1) ... 89s Setting up libclass-method-modifiers-perl (2.15-1) ... 89s Setting up libio-pty-perl (1:1.20-1build3) ... 89s Setting up libclone-perl:amd64 (0.47-1) ... 89s Setting up libtommath1:amd64 (1.3.0-1) ... 89s Setting up libhtml-tagset-perl (3.24-1) ... 89s Setting up liblwp-mediatypes-perl (6.04-2) ... 89s Setting up libtry-tiny-perl (0.32-1) ... 89s Setting up perl-openssl-defaults:amd64 (7build3) ... 89s Setting up libencode-locale-perl (1.05-3) ... 89s Setting up putty-tools (0.82-2) ... 89s Setting up libhavege2:amd64 (1.9.14-1ubuntu2) ... 89s Setting up patchutils (0.4.2-1build3) ... 89s Setting up openssh-tests (1:9.9p1-3ubuntu2) ... 89s Setting up libio-html-perl (1.004-3) ... 89s Setting up libb-hooks-op-check-perl:amd64 (0.22-3build2) ... 89s Setting up libipc-run-perl (20231003.0-2) ... 89s Setting up libtimedate-perl (2.3300-2) ... 89s Setting up librole-tiny-perl (2.002004-1) ... 89s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 89s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 89s Setting up libfile-dirlist-perl (0.05-3) ... 89s Setting up libfile-homedir-perl (1.006-2) ... 89s Setting up liburi-perl (5.30-1) ... 89s Setting up libfile-touch-perl (0.12-2) ... 89s Setting up libnet-ssleay-perl:amd64 (1.94-2) ... 89s Setting up libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ... 89s Setting up libhttp-date-perl (6.06-1) ... 89s Setting up haveged (1.9.14-1ubuntu2) ... 89s invoke-rc.d: policy-rc.d denied execution of start. 89s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 90s /usr/sbin/policy-rc.d returned 101, not running 'start haveged.service' 90s Setting up dropbear-bin (2024.86-2) ... 90s Setting up libfile-listing-perl (6.16-1) ... 90s Setting up libnet-http-perl (6.23-1) ... 90s Setting up libdevel-callchecker-perl:amd64 (0.009-1build1) ... 90s Setting up dropbear (2024.86-2) ... 90s Converting existing OpenSSH RSA host key to Dropbear format. 90s Key is a ssh-rsa key 90s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 90s 3072 SHA256:hFkoY5ZUvsPkALXzK3fcQ2mheFWGfSf+w9UEidqrBro /etc/dropbear/dropbear_rsa_host_key (RSA) 90s +---[RSA 3072]----+ 90s | .ooo... oo..o | 90s | .*o.+ .oo + o| 90s | o+o= . oo o +.| 90s | B + o.o. . o| 90s | B S + ....| 90s | =.+ . o.| 90s | . o.o.o. .| 90s | o.. o. | 90s | E. . | 90s +----[SHA256]-----+ 90s Converting existing OpenSSH ECDSA host key to Dropbear format. 90s Key is a ecdsa-sha2-nistp256 key 90s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 90s 256 SHA256:9BcOnIj6TxDOAocpUcOzI4jx0qNKTTVT51mnWKU85tw /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 90s +---[ECDSA 256]---+ 90s |.oo .. . o.o | 90s |..o+ + .oo*.+ | 90s |+++oo = o++*. | 90s |=.*+ + o .+oo. | 90s | +ooo + S .ooE | 90s |.. . o . . | 90s |o . . | 90s |. o | 90s | . | 90s +----[SHA256]-----+ 90s Converting existing OpenSSH ED25519 host key to Dropbear format. 90s Key is a ssh-ed25519 key 90s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 90s 256 SHA256:Geeq5Jq4zmJ9PuAzuMmHlNXeEKZvZMU1YJB80HY9ZIs /etc/dropbear/dropbear_ed25519_host_key (ED25519) 90s +--[ED25519 256]--+ 90s | .o*ooooo | 90s | =.* .+o. | 90s | + = oE... | 90s | o = = | 90s | o = o S . | 90s | o . + . . | 90s |. = o . . | 90s |o=.B * . | 90s |.*B.Bo+ | 90s +----[SHA256]-----+ 90s invoke-rc.d: policy-rc.d denied execution of start. 90s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 90s /usr/sbin/policy-rc.d returned 101, not running 'start dropbear.service' 90s Setting up libwww-robotrules-perl (6.02-1) ... 90s Setting up libhtml-parser-perl:amd64 (3.83-1build1) ... 90s Setting up libio-socket-ssl-perl (2.089-1) ... 90s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 90s Setting up libhttp-negotiate-perl (6.01-2) ... 90s Setting up libhttp-cookies-perl (6.11-1) ... 90s Setting up libhtml-tree-perl (5.07-3) ... 90s Setting up libparams-classify-perl:amd64 (0.015-2build6) ... 90s Setting up libmodule-runtime-perl (0.016-2) ... 90s Setting up libimport-into-perl (1.002005-2) ... 90s Setting up libmoo-perl (2.005005-1) ... 90s Setting up liblwp-protocol-https-perl (6.14-1) ... 90s Setting up libwww-perl (6.77-1) ... 90s Setting up devscripts (2.25.1) ... 90s Processing triggers for install-info (7.1.1-1) ... 90s Processing triggers for libc-bin (2.40-4ubuntu1) ... 90s Processing triggers for man-db (2.13.0-1) ... 92s 92s Running kernel seems to be up-to-date. 92s 92s No services need to be restarted. 92s 92s No containers need to be restarted. 92s 92s No user sessions are running outdated binaries. 92s 92s No VM guests are running outdated hypervisor (qemu) binaries on this host. 94s autopkgtest [07:09:28]: test regress: [----------------------- 94s info: Adding user `openssh-tests' ... 94s info: Selecting UID/GID from range 1000 to 59999 ... 94s info: Adding new group `openssh-tests' (1001) ... 94s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 94s info: Creating home directory `/home/openssh-tests' ... 94s info: Copying files from `/etc/skel' ... 94s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 94s info: Adding user `openssh-tests' to group `users' ... 94s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 94s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 95s I: annotate-output 2.25.1 95s I: prefix='%H:%M:%S.%N ' 95s 07:09:29.492753793 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user 95s 07:09:29.528526714 O: make: Entering directory '/tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress' 95s 07:09:29.531590603 E: + /usr/bin/ssh -Q key 95s 07:09:29.532966696 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/valgrind-out 95s 07:09:29.537136752 E: + grep -q ^ssh-rsa 95s 07:09:29.537652698 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 95s 07:09:29.542523931 O: ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 95s 07:09:29.544141043 O: tr '\n' '\r' /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 95s 07:09:29.546724287 O: ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 95s 07:09:29.550171515 O: awk '{print $0 "\r"}' /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 95s 07:09:29.552896688 E: + ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2.prv 95s 07:09:29.555397200 E: + diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv 95s 07:09:29.556976735 O: ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 95s 07:09:29.559504971 O: fi 95s 07:09:29.563402253 E: + tr \n \r 95s 07:09:29.570703764 E: + + ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 95s 07:09:29.572115090 E: diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv 95s 07:09:29.577389347 E: + awk {print $0 "\r"} /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2.prv 95s 07:09:29.582643988 E: + ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 95s 07:09:29.585199338 E: + diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv 95s 07:09:29.593633526 E: + /usr/bin/ssh -Q key 95s 07:09:29.594555296 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 95s 07:09:29.599454333 E: + grep -q ^ssh-rsa 95s 07:09:29.600777052 O: cat /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t2.out ; \ 95s 07:09:29.602435741 O: chmod 600 /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t2.out ; \ 95s 07:09:29.605296849 O: ssh-keygen -yf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 95s 07:09:29.607841312 O: fi 95s 07:09:29.610533118 E: + cat /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.prv 95s 07:09:29.611925920 E: + chmod 600 /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t2.out 95s 07:09:29.615792311 E: + ssh-keygen -yf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t2.out 95s 07:09:29.618725286 E: + diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub 95s 07:09:29.624403691 E: + + /usr/bin/ssh -Q key 95s 07:09:29.627546713 E: grep -q ^ssh-rsa 95s 07:09:29.628286360 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 95s 07:09:29.634490264 E: + ssh-keygen -ef /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub 95s 07:09:29.634091309 O: ssh-keygen -ef /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t3.out ; \ 95s 07:09:29.639458603 O: ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 95s 07:09:29.641443196 O: fi 95s 07:09:29.646523264 E: + ssh-keygen -if+ /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t3.out 95s 07:09:29.648290905 E: diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub 95s 07:09:29.655563991 E: + /usr/bin/ssh -Q key 95s 07:09:29.659453485 E: + grep -q ^ssh-rsa 95s 07:09:29.655931133 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 95s 07:09:29.663358275 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 95s 07:09:29.664718251 E: + ssh-keygen -E+ md5 -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub 95s 07:09:29.669465335 O: awk '{print $2}' | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t4.ok ; \ 95s 07:09:29.671703037 E: awk {print $2} 95s 07:09:29.674605682 E: + diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t4.ok 95s 07:09:29.675250357 O: fi 95s 07:09:29.678641937 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 95s 07:09:29.681493193 O: ssh-keygen -Bf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 95s 07:09:29.679901660 E: + + /usr/bin/ssh -Q key 95s 07:09:29.683399706 E: grep -q ^ssh-rsa 95s 07:09:29.684730484 O: awk '{print $2}' | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t5.ok ; \ 95s 07:09:29.687283648 O: fi 95s 07:09:29.688270699 E: + + awk {print $2} 95s 07:09:29.690571145 E: ssh-keygen -Bf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub 95s 07:09:29.693794555 E: + diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t5.ok 95s 07:09:29.694196067 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 95s 07:09:29.695424697 E: + /usr/bin/ssh -Q key 95s 07:09:29.696502508 O: ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t6.out1 ; \ 95s 07:09:29.698838639 E: + grep -q ^ssh-dss 95s 07:09:29.700147555 O: ssh-keygen -if /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t6.out2 ; \ 95s 07:09:29.703948674 E: + /usr/bin/ssh -Q key 95s 07:09:29.704583958 O: chmod 600 /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t6.out1 ; \ 95s 07:09:29.706040989 E: + grep -q ^ssh-dss 95s 07:09:29.707664997 O: ssh-keygen -yf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t6.out2 ; \ 95s 07:09:29.711432187 E: + /usr/bin/ssh -Q key 95s 07:09:29.709180394 O: fi 95s 07:09:29.714050914 E: + grep -q ^ssh-dss 95s 07:09:29.714366827 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 95s 07:09:29.715526719 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t7.out ; \ 95s 07:09:29.716673960 O: fi 95s 07:09:29.717750247 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 95s 07:09:29.718940820 O: ssh-keygen -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 95s 07:09:29.719870678 E: + ssh -Q key 95s 07:09:29.721045684 E: + grep -q ^ssh-dss 95s 07:09:29.722855262 O: ssh-keygen -Bf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 95s 07:09:29.725839978 O: fi 95s 07:09:29.729151391 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 95s 07:09:29.727429125 E: + ssh -Q key 95s 07:09:29.731609735 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t8.out ; \ 95s 07:09:29.733114817 E: + grep -q ^ssh-dss 95s 07:09:29.732795816 O: fi 95s 07:09:29.734333284 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 95s 07:09:29.735459608 O: ssh-keygen -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 95s 07:09:29.736668081 O: ssh-keygen -Bf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 95s 07:09:29.737727158 O: fi 95s 07:09:29.738860588 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 95s 07:09:29.739945997 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t9.out 95s 07:09:29.746495874 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 95s 07:09:29.747629495 O: ssh-keygen -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t9.out > /dev/null 95s 07:09:29.757230591 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 95s 07:09:29.758374736 O: ssh-keygen -Bf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t9.out > /dev/null 95s 07:09:29.767784670 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t10.out 95s 07:09:29.773572779 O: ssh-keygen -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t10.out > /dev/null 95s 07:09:29.779288070 O: ssh-keygen -Bf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t10.out > /dev/null 95s 07:09:29.783911167 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 95s 07:09:29.786232543 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 95s 07:09:29.785097480 E: + /usr/bin/ssh -Q key 95s 07:09:29.787402798 O: awk '{print $2}' | diff - /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t11.ok ; \ 95s 07:09:29.788381128 E: + grep -q ^ssh-dss 95s 07:09:29.789431425 O: fi 95s 07:09:29.792008975 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t12.out 95s 07:09:29.797529645 O: ssh-keygen -lf /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 95s 07:09:29.805666565 E: run test connect.sh ... 96s 07:09:30.547074891 O: ok simple connect 96s 07:09:30.546996220 E: run test proxy-connect.sh ... 96s 07:09:30.683636348 O: plain username comp=no 96s 07:09:30.798434644 O: plain username comp=yes 96s 07:09:30.920413637 O: username with style 97s 07:09:31.034988296 O: ok proxy connect 97s 07:09:31.035740671 E: run test sshfp-connect.sh ... 97s 07:09:31.165901898 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 97s 07:09:31.165962366 E: run test connect-privsep.sh ... 98s 07:09:32.806769433 O: ok proxy connect with privsep 98s 07:09:32.807065331 E: run test connect-uri.sh ... 99s 07:09:32.972825155 O: uri connect: no trailing slash 99s 07:09:33.180298195 O: uri connect: trailing slash 99s 07:09:33.388330595 O: uri connect: with path name 99s 07:09:33.413185909 O: ok uri connect 99s 07:09:33.413310188 E: run test proto-version.sh ... 99s 07:09:33.566257669 O: ok sshd version with different protocol combinations 99s 07:09:33.567240823 E: run test proto-mismatch.sh ... 99s 07:09:33.713643637 O: ok protocol version mismatch 99s 07:09:33.714121919 E: run test exit-status.sh ... 99s 07:09:33.843035189 O: test remote exit status: status 0 105s 07:09:39.087498939 O: test remote exit status: status 1 110s 07:09:44.317608111 O: test remote exit status: status 4 115s 07:09:49.544396986 O: test remote exit status: status 5 121s 07:09:55.049093961 O: test remote exit status: status 44 126s 07:10:00.301026961 O: ok remote exit status 126s 07:10:00.303550302 E: run test exit-status-signal.sh ... 127s 07:10:01.445900898 E: run test envpass.sh ... 127s 07:10:01.444500817 O: ok exit status on signal 127s 07:10:01.579031966 O: test environment passing: pass env, don't accept 127s 07:10:01.692957887 O: test environment passing: setenv, don't accept 127s 07:10:01.807759671 O: test environment passing: don't pass env, accept 127s 07:10:01.919116697 O: test environment passing: pass single env, accept single env 128s 07:10:02.031832815 O: test environment passing: pass multiple env, accept multiple env 128s 07:10:02.143234450 O: test environment passing: setenv, accept 128s 07:10:02.254548768 O: test environment passing: setenv, first match wins 128s 07:10:02.364985023 O: test environment passing: server setenv wins 128s 07:10:02.482803120 O: test environment passing: server setenv wins 128s 07:10:02.595815639 E: run test transfer.sh ... 128s 07:10:02.596004510 O: ok environment passing 129s 07:10:03.731201714 O: ok transfer data 129s 07:10:03.732231332 E: run test banner.sh ... 129s 07:10:03.857492496 O: test banner: missing banner file 130s 07:10:03.982325196 O: test banner: size 0 130s 07:10:04.105827234 O: test banner: size 10 130s 07:10:04.235520221 O: test banner: size 100 130s 07:10:04.366383717 O: test banner: size 1000 130s 07:10:04.505555917 O: test banner: size 10000 130s 07:10:04.648071132 O: test banner: size 100000 130s 07:10:04.771560630 O: test banner: suppress banner (-q) 130s 07:10:04.892914454 O: ok banner 130s 07:10:04.893163055 E: run test rekey.sh ... 131s 07:10:05.058834755 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 131s 07:10:05.214249860 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 131s 07:10:05.369126162 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 131s 07:10:05.527118814 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 131s 07:10:05.709655974 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 132s 07:10:06.030693650 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 132s 07:10:06.372454397 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 132s 07:10:06.701717374 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 132s 07:10:06.827381221 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 132s 07:10:06.952596485 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 133s 07:10:07.085120062 O: client rekey KexAlgorithms=curve25519-sha256 133s 07:10:07.227082624 O: client rekey KexAlgorithms=sntrup761x25519-sha512 133s 07:10:07.380909591 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 133s 07:10:07.542719925 O: client rekey KexAlgorithms=mlkem768x25519-sha256 133s 07:10:07.677864061 O: client rekey Ciphers=3des-cbc 133s 07:10:07.823299501 O: client rekey Ciphers=aes128-cbc 133s 07:10:07.959707585 O: client rekey Ciphers=aes192-cbc 134s 07:10:08.099090636 O: client rekey Ciphers=aes256-cbc 134s 07:10:08.237192540 O: client rekey Ciphers=aes128-ctr 134s 07:10:08.380659523 O: client rekey Ciphers=aes192-ctr 134s 07:10:08.520968266 O: client rekey Ciphers=aes256-ctr 134s 07:10:08.660508916 O: client rekey Ciphers=aes128-gcm@openssh.com 136s 07:10:10.614448528 O: client rekey Ciphers=aes256-gcm@openssh.com 138s 07:10:12.748026318 O: client rekey Ciphers=chacha20-poly1305@openssh.com 141s 07:10:15.199785205 O: client rekey MACs=hmac-sha1 141s 07:10:15.341362528 O: client rekey MACs=hmac-sha1-96 141s 07:10:15.488125409 O: client rekey MACs=hmac-sha2-256 141s 07:10:15.626240417 O: client rekey MACs=hmac-sha2-512 141s 07:10:15.771196569 O: client rekey MACs=hmac-md5 141s 07:10:15.916213412 O: client rekey MACs=hmac-md5-96 142s 07:10:16.062531867 O: client rekey MACs=umac-64@openssh.com 142s 07:10:16.207487809 O: client rekey MACs=umac-128@openssh.com 142s 07:10:16.357450124 O: client rekey MACs=hmac-sha1-etm@openssh.com 142s 07:10:16.500292428 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 142s 07:10:16.648698717 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 142s 07:10:16.797176417 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 142s 07:10:16.939759243 O: client rekey MACs=hmac-md5-etm@openssh.com 143s 07:10:17.080750841 O: client rekey MACs=hmac-md5-96-etm@openssh.com 143s 07:10:17.229428018 O: client rekey MACs=umac-64-etm@openssh.com 143s 07:10:17.379091112 O: client rekey MACs=umac-128-etm@openssh.com 143s 07:10:17.525050498 O: client rekeylimit 16 143s 07:10:17.707609867 O: client rekeylimit 1k 143s 07:10:17.855438010 O: client rekeylimit 128k 144s 07:10:18.004434209 O: client rekeylimit 256k 144s 07:10:18.181109802 O: client rekeylimit default 5 159s 07:10:33.309305492 O: client rekeylimit default 10 179s 07:10:53.454403277 O: client rekeylimit default 5 no data 194s 07:11:08.880925646 O: client rekeylimit default 10 no data 215s 07:11:29.006814359 O: server rekeylimit 16 215s 07:11:29.200257365 O: server rekeylimit 1k 215s 07:11:29.369927152 O: server rekeylimit 128k 215s 07:11:29.562163445 O: server rekeylimit 256k 215s 07:11:29.763037232 O: server rekeylimit default 5 no data 231s 07:11:44.892222042 O: server rekeylimit default 10 no data 251s 07:12:05.027033875 O: rekeylimit parsing: bytes 251s 07:12:05.110058520 O: rekeylimit parsing: time 251s 07:12:05.179956808 O: ok rekey 251s 07:12:05.181018457 E: run test dhgex.sh ... 251s 07:12:05.336915327 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 251s 07:12:05.723561011 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 251s 07:12:05.851521409 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 251s 07:12:05.966587617 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 252s 07:12:06.083755314 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 252s 07:12:06.203655908 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 252s 07:12:06.319998514 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 252s 07:12:06.438280731 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 252s 07:12:06.561423162 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 252s 07:12:06.732273732 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 252s 07:12:06.901710140 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 253s 07:12:07.075002695 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 253s 07:12:07.235104996 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 253s 07:12:07.385711221 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 253s 07:12:07.540553101 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 253s 07:12:07.690314166 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 253s 07:12:07.840361408 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 254s 07:12:07.996066552 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 254s 07:12:08.168672946 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 254s 07:12:08.353109156 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 254s 07:12:08.536934060 O: ok dhgex 254s 07:12:08.537956774 E: run test stderr-data.sh ... 254s 07:12:08.661723892 O: test stderr data transfer: () 260s 07:12:14.909401616 O: test stderr data transfer: (-n) 267s 07:12:21.157453111 O: ok stderr data transfer 267s 07:12:21.158121670 E: run test stderr-after-eof.sh ... 269s 07:12:23.428582987 O: ok stderr data after eof 269s 07:12:23.428321289 E: run test broken-pipe.sh ... 269s 07:12:23.597691153 O: ok broken pipe test 269s 07:12:23.598819975 E: run test try-ciphers.sh ... 269s 07:12:23.763430081 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 269s 07:12:23.912302053 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 270s 07:12:24.050142093 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 270s 07:12:24.181666777 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 270s 07:12:24.308691999 O: test try ciphers: cipher 3des-cbc mac hmac-md5 270s 07:12:24.438172703 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 270s 07:12:24.565034968 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 270s 07:12:24.690878114 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 270s 07:12:24.825020132 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 270s 07:12:24.956164287 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 271s 07:12:25.087507822 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 271s 07:12:25.239467990 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 271s 07:12:25.367360567 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 271s 07:12:25.499008170 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 271s 07:12:25.629520487 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 271s 07:12:25.755746986 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 271s 07:12:25.893788652 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 272s 07:12:26.040254231 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 272s 07:12:26.192073948 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 272s 07:12:26.331984456 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 272s 07:12:26.472490911 O: test try ciphers: cipher aes128-cbc mac hmac-md5 272s 07:12:26.618922379 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 272s 07:12:26.753880593 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 272s 07:12:26.880118954 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 273s 07:12:27.005017232 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 273s 07:12:27.133887677 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 273s 07:12:27.258264297 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 273s 07:12:27.390049666 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 273s 07:12:27.524489471 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 273s 07:12:27.649853201 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 273s 07:12:27.777666040 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 273s 07:12:27.906144893 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 274s 07:12:28.045022067 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 274s 07:12:28.174069914 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 274s 07:12:28.297694290 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 274s 07:12:28.434077562 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 274s 07:12:28.568934528 O: test try ciphers: cipher aes192-cbc mac hmac-md5 274s 07:12:28.711611309 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 274s 07:12:28.845184821 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 274s 07:12:28.969703445 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 275s 07:12:29.095899512 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 275s 07:12:29.240068703 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 275s 07:12:29.367662650 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 275s 07:12:29.493755811 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 275s 07:12:29.620191879 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 275s 07:12:29.754514844 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 275s 07:12:29.893279953 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 276s 07:12:30.019407373 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 276s 07:12:30.151158167 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 276s 07:12:30.273299973 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 276s 07:12:30.397119712 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 276s 07:12:30.518089632 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 276s 07:12:30.650597346 O: test try ciphers: cipher aes256-cbc mac hmac-md5 276s 07:12:30.786383724 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 276s 07:12:30.909429978 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 277s 07:12:31.038692770 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 277s 07:12:31.175117705 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 277s 07:12:31.298371330 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 277s 07:12:31.426185617 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 277s 07:12:31.552436607 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 277s 07:12:31.689000442 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 277s 07:12:31.821189363 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 277s 07:12:31.974333226 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 278s 07:12:32.114988047 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 278s 07:12:32.258280979 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 278s 07:12:32.406166809 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 278s 07:12:32.549914916 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 278s 07:12:32.694985142 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 278s 07:12:32.835056081 O: test try ciphers: cipher aes128-ctr mac hmac-md5 279s 07:12:32.985216913 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 279s 07:12:33.139653835 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 279s 07:12:33.287453986 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 279s 07:12:33.436975022 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 279s 07:12:33.583064356 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 279s 07:12:33.711640878 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 279s 07:12:33.848521200 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 279s 07:12:33.973198691 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 280s 07:12:34.099567932 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 280s 07:12:34.238274390 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 280s 07:12:34.383168468 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 280s 07:12:34.530036324 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 280s 07:12:34.662155565 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 280s 07:12:34.798717946 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 280s 07:12:34.927014476 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 281s 07:12:35.054505187 O: test try ciphers: cipher aes192-ctr mac hmac-md5 281s 07:12:35.177512548 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 281s 07:12:35.299992564 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 281s 07:12:35.420578669 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 281s 07:12:35.550719970 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 281s 07:12:35.673529957 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 281s 07:12:35.796657523 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 281s 07:12:35.922103917 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 282s 07:12:36.044960509 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 282s 07:12:36.175812987 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 282s 07:12:36.308561992 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 282s 07:12:36.441837401 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 282s 07:12:36.585425452 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 282s 07:12:36.712868711 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 282s 07:12:36.843732170 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 282s 07:12:36.975885307 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 283s 07:12:37.111552317 O: test try ciphers: cipher aes256-ctr mac hmac-md5 283s 07:12:37.250386318 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 283s 07:12:37.376824646 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 283s 07:12:37.509909088 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 283s 07:12:37.634679823 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 283s 07:12:37.767009127 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 283s 07:12:37.889483427 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 284s 07:12:38.018546922 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 284s 07:12:38.149831467 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 284s 07:12:38.275708046 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 284s 07:12:38.410598171 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 284s 07:12:38.560664698 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 284s 07:12:38.696929176 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 284s 07:12:38.842720980 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 285s 07:12:38.977765135 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 285s 07:12:39.113383578 O: ok try ciphers 285s 07:12:39.113646790 E: run test yes-head.sh ... 288s 07:12:42.247377856 O: ok yes pipe head 288s 07:12:42.248438169 E: run test login-timeout.sh ... 303s 07:12:57.648886886 O: ok connect after login grace timeout 303s 07:12:57.649366860 E: run test agent.sh ... 307s 07:13:01.483707703 O: ok simple agent test 307s 07:13:01.484801832 E: run test agent-getpeereid.sh ... 307s 07:13:01.633158221 O: ok disallow agent attach from other uid 307s 07:13:01.634433152 E: run test agent-timeout.sh ... 327s 07:13:21.817091944 O: ok agent timeout test 327s 07:13:21.818018617 E: run test agent-ptrace.sh ... 327s 07:13:21.957916782 O: skipped (gdb not found) 327s 07:13:21.958980875 E: run test agent-subprocess.sh ... 338s 07:13:32.125840240 O: ok agent subprocess 338s 07:13:32.126968332 E: run test keyscan.sh ... 339s 07:13:32.769315730 O: ok keyscan 339s 07:13:32.769600595 E: run test keygen-change.sh ... 342s 07:13:36.655860024 O: ok change passphrase for key 342s 07:13:36.657085988 E: run test keygen-comment.sh ... 344s 07:13:38.529814433 O: ok Comment extraction from private key 344s 07:13:38.530271018 E: run test keygen-convert.sh ... 347s 07:13:41.423578003 O: ok convert keys 347s 07:13:41.424138181 E: run test keygen-knownhosts.sh ... 347s 07:13:41.684728022 O: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts updated. 347s 07:13:41.685868994 O: Original contents retained as /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts.old 347s 07:13:41.701308077 O: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts updated. 347s 07:13:41.702493855 O: Original contents retained as /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts.old 347s 07:13:41.707765858 O: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts updated. 347s 07:13:41.708848541 O: Original contents retained as /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts.old 347s 07:13:41.719591459 O: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts updated. 347s 07:13:41.720673751 O: Original contents retained as /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hosts.old 347s 07:13:41.741041781 O: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hashed updated. 347s 07:13:41.742146621 O: Original contents retained as /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/kh.hashed.old 347s 07:13:41.745666449 O: ok ssh-keygen known_hosts 347s 07:13:41.746154861 E: run test keygen-moduli.sh ... 349s 07:13:43.551253710 O: ok keygen moduli 349s 07:13:43.552291079 E: run test keygen-sshfp.sh ... 349s 07:13:43.719017100 O: ok keygen-sshfp 349s 07:13:43.719495398 E: run test key-options.sh ... 349s 07:13:43.854039619 O: key option command="echo bar" 350s 07:13:44.231953811 O: key option no-pty,command="echo bar" 350s 07:13:44.353527395 O: key option pty default 350s 07:13:44.479906474 O: key option pty no-pty 350s 07:13:44.612826850 O: key option pty restrict 350s 07:13:44.728327851 O: key option pty restrict,pty 350s 07:13:44.858126733 O: key option environment 351s 07:13:45.145995330 O: key option from="127.0.0.1" 351s 07:13:45.581505916 O: key option from="127.0.0.0/8" 351s 07:13:45.908557145 O: key option expiry-time default 352s 07:13:46.044327842 O: key option expiry-time invalid 352s 07:13:46.158457543 O: key option expiry-time expired 352s 07:13:46.276454044 O: key option expiry-time valid 352s 07:13:46.413699556 O: ok key options 352s 07:13:46.414753824 E: run test scp.sh ... 352s 07:13:46.573272571 O: scp: scp mode: simple copy local file to local file 352s 07:13:46.583159539 O: scp: scp mode: simple copy local file to remote file 352s 07:13:46.592734531 O: scp: scp mode: simple copy remote file to local file 352s 07:13:46.602141463 O: scp: scp mode: copy local file to remote file in place 352s 07:13:46.612944868 O: scp: scp mode: copy remote file to local file in place 352s 07:13:46.623712908 O: scp: scp mode: copy local file to remote file clobber 352s 07:13:46.633565902 O: -rw-r--r-- 1 openssh-tests openssh-tests 1125464 Jan 20 07:13 /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/copy 352s 07:13:46.634662113 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1125464 Jan 20 07:13 /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/data 352s 07:13:46.636239412 O: scp: scp mode: copy remote file to local file clobber 352s 07:13:46.647914706 O: scp: scp mode: simple copy local file to remote dir 352s 07:13:46.659147842 O: scp: scp mode: simple copy local file to local dir 352s 07:13:46.669444697 O: scp: scp mode: simple copy remote file to local dir 352s 07:13:46.681074951 O: scp: scp mode: recursive local dir to remote dir 352s 07:13:46.701545878 O: scp: scp mode: recursive local dir to local dir 352s 07:13:46.722865064 O: scp: scp mode: recursive remote dir to local dir 352s 07:13:46.748866592 O: scp: scp mode: unmatched glob file local->remote 352s 07:13:46.759198572 O: scp: scp mode: unmatched glob file remote->local 352s 07:13:46.765457434 O: scp: scp mode: unmatched glob dir recursive local->remote 352s 07:13:46.782041136 O: scp: scp mode: unmatched glob dir recursive remote->local 352s 07:13:46.791409328 O: scp: scp mode: shell metacharacters 352s 07:13:46.800267984 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 352s 07:13:46.840040501 O: scp: scp mode: disallow bad server #0 352s 07:13:46.862081831 O: scp: scp mode: disallow bad server #1 352s 07:13:46.883446424 O: scp: scp mode: disallow bad server #2 352s 07:13:46.907129415 O: scp: scp mode: disallow bad server #3 352s 07:13:46.929697521 O: scp: scp mode: disallow bad server #4 352s 07:13:46.952187124 O: scp: scp mode: disallow bad server #5 353s 07:13:46.974019349 O: scp: scp mode: disallow bad server #6 353s 07:13:46.995414113 O: scp: scp mode: disallow bad server #7 353s 07:13:47.017067991 O: scp: scp mode: detect non-directory target 353s 07:13:47.021058919 E: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/copy2: Not a directory 353s 07:13:47.022922263 O: scp: sftp mode: simple copy local file to local file 353s 07:13:47.031320069 O: scp: sftp mode: simple copy local file to remote file 353s 07:13:47.043565715 O: scp: sftp mode: simple copy remote file to local file 353s 07:13:47.054358048 O: scp: sftp mode: copy local file to remote file in place 353s 07:13:47.067933031 O: scp: sftp mode: copy remote file to local file in place 353s 07:13:47.081217599 O: scp: sftp mode: copy local file to remote file clobber 353s 07:13:47.093083202 O: -rw-r--r-- 1 openssh-tests openssh-tests 1125464 Jan 20 07:13 /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/copy 353s 07:13:47.094410727 O: -rw-rw-r-- 1 openssh-tests openssh-tests 1125464 Jan 20 07:13 /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/data 353s 07:13:47.096086755 O: scp: sftp mode: copy remote file to local file clobber 353s 07:13:47.110774960 O: scp: sftp mode: simple copy local file to remote dir 353s 07:13:47.124592875 O: scp: sftp mode: simple copy local file to local dir 353s 07:13:47.136551219 O: scp: sftp mode: simple copy remote file to local dir 353s 07:13:47.150072372 O: scp: sftp mode: recursive local dir to remote dir 353s 07:13:47.175050456 O: scp: sftp mode: recursive local dir to local dir 353s 07:13:47.195512808 O: scp: sftp mode: recursive remote dir to local dir 353s 07:13:47.224542870 O: scp: sftp mode: unmatched glob file local->remote 353s 07:13:47.237081022 O: scp: sftp mode: unmatched glob file remote->local 353s 07:13:47.247181481 O: scp: sftp mode: unmatched glob dir recursive local->remote 353s 07:13:47.266132444 O: scp: sftp mode: unmatched glob dir recursive remote->local 353s 07:13:47.279284798 O: scp: sftp mode: shell metacharacters 353s 07:13:47.288293392 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 353s 07:13:47.327415041 O: scp: sftp mode: disallow bad server #0 353s 07:13:47.360174055 O: scp: sftp mode: disallow bad server #1 353s 07:13:47.391767128 O: scp: sftp mode: disallow bad server #2 353s 07:13:47.423781738 O: scp: sftp mode: disallow bad server #3 353s 07:13:47.455050191 O: scp: sftp mode: disallow bad server #4 353s 07:13:47.486617219 O: scp: sftp mode: disallow bad server #5 353s 07:13:47.519139420 O: scp: sftp mode: disallow bad server #6 353s 07:13:47.556278557 O: scp: sftp mode: disallow bad server #7 353s 07:13:47.588770588 O: scp: sftp mode: detect non-directory target 353s 07:13:47.592499841 E: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/copy2: Not a directory 353s 07:13:47.597870763 O: ok scp 353s 07:13:47.598799352 E: run test scp3.sh ... 353s 07:13:47.731767375 O: scp3: scp mode: simple copy remote file to remote file 353s 07:13:47.896393797 O: scp3: scp mode: simple copy remote file to remote dir 354s 07:13:48.063935017 O: scp3: scp mode: recursive remote dir to remote dir 354s 07:13:48.259784796 O: scp3: scp mode: detect non-directory target 354s 07:13:48.525232127 O: scp3: sftp mode: simple copy remote file to remote file 354s 07:13:48.537735077 O: scp3: sftp mode: simple copy remote file to remote dir 354s 07:13:48.550977808 O: scp3: sftp mode: recursive remote dir to remote dir 354s 07:13:48.583101426 O: scp3: sftp mode: detect non-directory target 354s 07:13:48.589576586 E: scp: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/copy2: destination is not a directory 354s 07:13:48.591151425 E: scp: /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/copy2: destination is not a directory 354s 07:13:48.599510947 O: ok scp3 354s 07:13:48.600731722 E: run test scp-uri.sh ... 354s 07:13:48.740677932 O: scp-uri: scp mode: simple copy local file to remote file 354s 07:13:48.748321055 O: scp-uri: scp mode: simple copy remote file to local file 354s 07:13:48.755953705 O: scp-uri: scp mode: simple copy local file to remote dir 354s 07:13:48.764929402 O: scp-uri: scp mode: simple copy remote file to local dir 354s 07:13:48.774062150 O: scp-uri: scp mode: recursive local dir to remote dir 354s 07:13:48.784647963 O: scp-uri: scp mode: recursive remote dir to local dir 354s 07:13:48.794845306 O: scp-uri: sftp mode: simple copy local file to remote file 354s 07:13:48.803975270 O: scp-uri: sftp mode: simple copy remote file to local file 354s 07:13:48.812060986 O: scp-uri: sftp mode: simple copy local file to remote dir 354s 07:13:48.822622436 O: scp-uri: sftp mode: simple copy remote file to local dir 354s 07:13:48.832663680 O: scp-uri: sftp mode: recursive local dir to remote dir 354s 07:13:48.844628439 O: scp-uri: sftp mode: recursive remote dir to local dir 354s 07:13:48.858956093 O: ok scp-uri 354s 07:13:48.859971001 E: run test sftp.sh ... 355s 07:13:48.986863324 O: test basic sftp put/get: buffer_size 5 num_requests 1 363s 07:13:57.529121915 O: test basic sftp put/get: buffer_size 5 num_requests 2 369s 07:14:02.446747495 O: test basic sftp put/get: buffer_size 5 num_requests 10 370s 07:14:04.792552115 O: test basic sftp put/get: buffer_size 1000 num_requests 1 370s 07:14:04.837197975 O: test basic sftp put/get: buffer_size 1000 num_requests 2 370s 07:14:04.868958391 O: test basic sftp put/get: buffer_size 1000 num_requests 10 370s 07:14:04.888668441 O: test basic sftp put/get: buffer_size 32000 num_requests 1 370s 07:14:04.898454268 O: test basic sftp put/get: buffer_size 32000 num_requests 2 370s 07:14:04.907501067 O: test basic sftp put/get: buffer_size 32000 num_requests 10 370s 07:14:04.916443597 O: test basic sftp put/get: buffer_size 64000 num_requests 1 370s 07:14:04.925536396 O: test basic sftp put/get: buffer_size 64000 num_requests 2 370s 07:14:04.934429649 O: test basic sftp put/get: buffer_size 64000 num_requests 10 370s 07:14:04.945982459 O: ok basic sftp put/get 370s 07:14:04.946851228 E: run test sftp-chroot.sh ... 371s 07:14:05.112665244 O: test sftp in chroot: get 371s 07:14:05.339040168 O: test sftp in chroot: match 371s 07:14:05.613267388 O: ok sftp in chroot 371s 07:14:05.621581548 E: run test sftp-cmds.sh ... 371s 07:14:05.758963073 O: sftp commands: lls 371s 07:14:05.763990078 O: sftp commands: lls w/path 371s 07:14:05.772010542 O: sftp commands: ls 371s 07:14:05.781298832 O: sftp commands: shell 371s 07:14:05.784865281 O: sftp commands: pwd 371s 07:14:05.788706646 O: sftp commands: lpwd 371s 07:14:05.792793542 O: sftp commands: quit 371s 07:14:05.796453636 O: sftp commands: help 371s 07:14:05.800846487 O: sftp commands: get 371s 07:14:05.809665158 O: sftp commands: get quoted 371s 07:14:05.819855336 O: sftp commands: get filename with quotes 371s 07:14:05.831451765 O: sftp commands: get filename with spaces 371s 07:14:05.843787018 O: sftp commands: get filename with glob metacharacters 371s 07:14:05.851841636 O: sftp commands: get to directory 371s 07:14:05.859874799 O: sftp commands: glob get to directory 371s 07:14:05.945308220 O: sftp commands: get to local dir 371s 07:14:05.953823069 O: sftp commands: glob get to local dir 372s 07:14:05.995236985 O: sftp commands: put 372s 07:14:06.004649517 O: sftp commands: put filename with quotes 372s 07:14:06.013301170 O: sftp commands: put filename with spaces 372s 07:14:06.024308646 O: sftp commands: put to directory 372s 07:14:06.033429351 O: sftp commands: glob put to directory 372s 07:14:06.042370065 O: sftp commands: put to local dir 372s 07:14:06.051322987 O: sftp commands: glob put to local dir 372s 07:14:06.076464186 O: sftp commands: rename 372s 07:14:06.081186511 O: sftp commands: rename directory 372s 07:14:06.084539010 O: sftp commands: ln 372s 07:14:06.088861811 O: sftp commands: ln -s 372s 07:14:06.092900910 O: sftp commands: cp 372s 07:14:06.099150005 O: sftp commands: mkdir 372s 07:14:06.102601108 O: sftp commands: chdir 372s 07:14:06.105871609 O: sftp commands: rmdir 372s 07:14:06.108948818 O: sftp commands: lmkdir 372s 07:14:06.112420341 O: sftp commands: lchdir 372s 07:14:06.120317458 O: ok sftp commands 372s 07:14:06.121276012 E: run test sftp-badcmds.sh ... 372s 07:14:06.248077065 O: sftp invalid commands: get nonexistent 372s 07:14:06.252537122 O: sftp invalid commands: glob get to nonexistent directory 372s 07:14:06.266510664 O: sftp invalid commands: put nonexistent 372s 07:14:06.270609566 O: sftp invalid commands: glob put to nonexistent directory 372s 07:14:06.275646391 O: sftp invalid commands: rename nonexistent 372s 07:14:06.281958916 O: sftp invalid commands: rename target exists (directory) 372s 07:14:06.289855120 O: sftp invalid commands: glob put files to local file 372s 07:14:06.295464038 O: ok sftp invalid commands 372s 07:14:06.296292901 E: run test sftp-batch.sh ... 372s 07:14:06.427622696 O: sftp batchfile: good commands 372s 07:14:06.435394748 O: sftp batchfile: bad commands 372s 07:14:06.445012147 O: sftp batchfile: comments and blanks 372s 07:14:06.451198364 O: sftp batchfile: junk command 372s 07:14:06.455391914 O: ok sftp batchfile 372s 07:14:06.456116144 E: run test sftp-glob.sh ... 372s 07:14:06.587129776 O: sftp glob: file glob 372s 07:14:06.595131547 O: sftp glob: dir glob 372s 07:14:06.601584400 O: sftp glob: quoted glob 372s 07:14:06.609171472 O: sftp glob: escaped glob 372s 07:14:06.616682576 O: sftp glob: escaped quote 372s 07:14:06.622581916 O: sftp glob: quoted quote 372s 07:14:06.628525166 O: sftp glob: single-quoted quote 372s 07:14:06.634464338 O: sftp glob: escaped space 372s 07:14:06.640434168 O: sftp glob: quoted space 372s 07:14:06.646327489 O: sftp glob: escaped slash 372s 07:14:06.652325263 O: sftp glob: quoted slash 372s 07:14:06.660057591 O: sftp glob: escaped slash at EOL 372s 07:14:06.666676434 O: sftp glob: quoted slash at EOL 372s 07:14:06.671253632 O: sftp glob: escaped slash+quote 372s 07:14:06.677629195 O: sftp glob: quoted slash+quote 372s 07:14:06.684704770 O: ok sftp glob 372s 07:14:06.685524448 E: run test sftp-perm.sh ... 372s 07:14:06.810470619 O: sftp permissions: read-only upload 372s 07:14:06.826543346 O: sftp permissions: read-only setstat 372s 07:14:06.842611544 O: sftp permissions: read-only rm 372s 07:14:06.856646794 O: sftp permissions: read-only mkdir 372s 07:14:06.869202815 O: sftp permissions: read-only rmdir 372s 07:14:06.883450205 O: sftp permissions: read-only posix-rename 372s 07:14:06.898077266 O: sftp permissions: read-only oldrename 372s 07:14:06.911904901 O: sftp permissions: read-only symlink 372s 07:14:06.925718004 O: sftp permissions: read-only hardlink 372s 07:14:06.939487608 O: sftp permissions: explicit open 373s 07:14:06.969901961 O: sftp permissions: explicit read 373s 07:14:07.000587628 O: sftp permissions: explicit write 373s 07:14:07.033808977 O: sftp permissions: explicit lstat 373s 07:14:07.063762068 O: sftp permissions: explicit opendir 373s 07:14:07.092026481 O: sftp permissions: explicit readdir 373s 07:14:07.120196886 O: sftp permissions: explicit setstat 373s 07:14:07.158085614 O: sftp permissions: explicit remove 373s 07:14:07.192084582 O: sftp permissions: explicit mkdir 373s 07:14:07.220503497 O: sftp permissions: explicit rmdir 373s 07:14:07.256571470 O: sftp permissions: explicit rename 373s 07:14:07.291906953 O: sftp permissions: explicit symlink 373s 07:14:07.324927195 O: sftp permissions: explicit hardlink 373s 07:14:07.358036888 O: sftp permissions: explicit statvfs 373s 07:14:07.383280241 E: run test sftp-uri.sh ... 373s 07:14:07.382298682 O: ok sftp permissions 373s 07:14:07.569933007 O: sftp-uri: non-interactive fetch to local file 373s 07:14:07.791674358 O: sftp-uri: non-interactive fetch to local dir 374s 07:14:08.015772683 O: sftp-uri: put to remote directory (trailing slash) 374s 07:14:08.242591751 O: sftp-uri: put to remote directory (no slash) 374s 07:14:08.493020588 O: ok sftp-uri 374s 07:14:08.493021930 E: run test reconfigure.sh ... 387s 07:14:21.560320576 O: ok simple connect after reconfigure 387s 07:14:21.561229151 E: run test dynamic-forward.sh ... 387s 07:14:21.741028243 O: test -D forwarding 388s 07:14:22.606587577 O: test -R forwarding 389s 07:14:23.707764790 O: PermitRemoteOpen=any 390s 07:14:24.744097855 O: PermitRemoteOpen=none 391s 07:14:25.087057527 O: PermitRemoteOpen=explicit 392s 07:14:26.245100657 O: PermitRemoteOpen=disallowed 392s 07:14:26.603111373 O: ok dynamic forwarding 392s 07:14:26.604118048 E: run test forwarding.sh ... 396s 07:14:30.768823389 O: ok local and remote forwarding 396s 07:14:30.768901693 E: run test multiplex.sh ... 397s 07:14:31.947748561 O: test connection multiplexing: setenv 398s 07:14:31.960301566 O: test connection multiplexing: envpass 398s 07:14:31.972123646 O: test connection multiplexing: transfer 398s 07:14:32.007004186 O: test connection multiplexing: transfer -Oproxy 398s 07:14:32.089936540 O: test connection multiplexing: forward 400s 07:14:34.135559304 O: test connection multiplexing: status 0 () 405s 07:14:39.164080622 O: test connection multiplexing: status 0 (-Oproxy) 410s 07:14:44.203714982 O: test connection multiplexing: status 1 () 415s 07:14:49.235569838 O: test connection multiplexing: status 1 (-Oproxy) 420s 07:14:54.268614563 O: test connection multiplexing: status 4 () 425s 07:14:59.310052018 O: test connection multiplexing: status 4 (-Oproxy) 430s 07:15:04.355819806 O: test connection multiplexing: status 5 () 435s 07:15:09.386833642 O: test connection multiplexing: status 5 (-Oproxy) 440s 07:15:14.417522068 O: test connection multiplexing: status 44 () 445s 07:15:19.444742835 O: test connection multiplexing: status 44 (-Oproxy) 450s 07:15:24.476015255 O: test connection multiplexing: cmd check 450s 07:15:24.484594213 O: test connection multiplexing: cmd forward local (TCP) 452s 07:15:25.980547877 O: test connection multiplexing: cmd forward remote (TCP) 453s 07:15:27.219476615 O: test connection multiplexing: cmd forward local (UNIX) 454s 07:15:28.255265304 O: test connection multiplexing: cmd forward remote (UNIX) 455s 07:15:29.288775074 O: test connection multiplexing: cmd exit 455s 07:15:29.299245384 O: test connection multiplexing: cmd stop 466s 07:15:40.351385958 O: ok connection multiplexing 466s 07:15:40.351599618 E: run test reexec.sh ... 466s 07:15:40.478791243 O: test config passing 466s 07:15:40.754274481 O: test reexec fallback 466s 07:15:40.755311720 E: ln: failed to create hard link '/tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Invalid cross-device link 467s 07:15:41.043414443 O: ok reexec tests 467s 07:15:41.044658150 E: run test brokenkeys.sh ... 467s 07:15:41.438971902 O: ok broken keys 467s 07:15:41.439222401 E: run test sshcfgparse.sh ... 467s 07:15:41.571734798 O: reparse minimal config 467s 07:15:41.587791529 O: ssh -W opts 467s 07:15:41.633771859 O: user first match 467s 07:15:41.666498974 O: pubkeyacceptedalgorithms 467s 07:15:41.721307625 O: agentforwarding 467s 07:15:41.762146999 O: command line override 467s 07:15:41.785119417 O: ok ssh config parse 467s 07:15:41.786296574 E: run test cfgparse.sh ... 467s 07:15:41.917169208 O: reparse minimal config 468s 07:15:41.952616235 O: reparse regress config 468s 07:15:41.987401950 O: listenaddress order 468s 07:15:42.031473268 O: ok sshd config parse 468s 07:15:42.032214807 E: run test cfgmatch.sh ... 475s 07:15:49.181120394 O: ok sshd_config match 475s 07:15:49.181236207 E: run test cfgmatchlisten.sh ... 484s 07:15:58.650852286 O: ok sshd_config matchlisten 484s 07:15:58.650944425 E: run test percent.sh ... 484s 07:15:58.781038234 O: percent expansions matchexec percent 486s 07:16:00.257671493 O: percent expansions localcommand percent 487s 07:16:01.698275687 O: percent expansions remotecommand percent 487s 07:16:01.828521141 O: percent expansions controlpath percent 488s 07:16:01.956701518 O: percent expansions identityagent percent 488s 07:16:02.083259001 O: percent expansions forwardagent percent 488s 07:16:02.210727781 O: percent expansions localforward percent 488s 07:16:02.337196481 O: percent expansions remoteforward percent 488s 07:16:02.464200716 O: percent expansions revokedhostkeys percent 488s 07:16:02.591282314 O: percent expansions userknownhostsfile percent 489s 07:16:03.807971728 O: percent expansions controlpath dollar 489s 07:16:03.820160379 O: percent expansions identityagent dollar 489s 07:16:03.832120070 O: percent expansions forwardagent dollar 489s 07:16:03.844140802 O: percent expansions localforward dollar 489s 07:16:03.856153109 O: percent expansions remoteforward dollar 489s 07:16:03.868017604 O: percent expansions userknownhostsfile dollar 490s 07:16:03.991249452 O: percent expansions controlpath tilde 490s 07:16:04.015227464 O: percent expansions identityagent tilde 490s 07:16:04.040142550 O: percent expansions forwardagent tilde 490s 07:16:04.066063236 O: ok percent expansions 490s 07:16:04.067150737 E: run test addrmatch.sh ... 490s 07:16:04.202655981 O: test first entry for user 192.168.0.1 somehost 490s 07:16:04.215786697 O: test negative match for user 192.168.30.1 somehost 490s 07:16:04.228114326 O: test no match for user 19.0.0.1 somehost 490s 07:16:04.240512665 O: test list middle for user 10.255.255.254 somehost 490s 07:16:04.252506793 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 490s 07:16:04.264582248 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 490s 07:16:04.276989835 O: test localaddress for user 19.0.0.1 somehost 490s 07:16:04.291871502 O: test localport for user 19.0.0.1 somehost 490s 07:16:04.304893669 O: test bare IP6 address for user ::1 somehost.example.com 490s 07:16:04.317450339 O: test deny IPv6 for user ::2 somehost.example.com 490s 07:16:04.329656305 O: test IP6 negated for user ::3 somehost 490s 07:16:04.342316727 O: test IP6 no match for user ::4 somehost 490s 07:16:04.354085177 O: test IP6 network for user 2000::1 somehost 490s 07:16:04.366207890 O: test IP6 network for user 2001::1 somehost 490s 07:16:04.378436729 O: test IP6 localaddress for user ::5 somehost 490s 07:16:04.396386858 O: test IP6 localport for user ::5 somehost 490s 07:16:04.414638521 O: test invalid Match address 10.0.1.0/8 490s 07:16:04.428148985 O: test invalid Match localaddress 10.0.1.0/8 490s 07:16:04.440453462 O: test invalid Match address 10.0.0.1/24 490s 07:16:04.450829272 O: test invalid Match localaddress 10.0.0.1/24 490s 07:16:04.463456122 O: test invalid Match address 2000:aa:bb:01::/56 490s 07:16:04.475874619 O: test invalid Match localaddress 2000:aa:bb:01::/56 490s 07:16:04.489704742 O: ok address match 490s 07:16:04.490666270 E: run test localcommand.sh ... 490s 07:16:04.631626715 O: test localcommand: proto localcommand 490s 07:16:04.754079857 O: ok localcommand 490s 07:16:04.754093122 E: run test forcecommand.sh ... 491s 07:16:05.436101175 E: Connection closed 491s 07:16:05.437748636 E: Connection closed. 491s 07:16:05.724553819 E: Connection closed 491s 07:16:05.879605300 O: ok forced command 491s 07:16:05.881414477 E: run test portnum.sh ... 492s 07:16:06.031779034 O: port number parsing: invalid port 0 492s 07:16:06.040136397 O: port number parsing: invalid port 65536 492s 07:16:06.048194759 O: port number parsing: invalid port 131073 492s 07:16:06.055936479 O: port number parsing: invalid port 2000blah 492s 07:16:06.063654564 O: port number parsing: invalid port blah2000 492s 07:16:06.071483014 O: port number parsing: valid port 1 492s 07:16:06.187830557 O: port number parsing: valid port 22 492s 07:16:06.314954541 O: port number parsing: valid port 2222 492s 07:16:06.429438546 O: port number parsing: valid port 22222 492s 07:16:06.553042676 O: port number parsing: valid port 65535 492s 07:16:06.689990320 O: ok port number parsing 492s 07:16:06.691754895 E: run test keytype.sh ... 492s 07:16:06.827549518 O: keygen ed25519, 512 bits 492s 07:16:06.840038739 O: keygen ed25519-sk, n/a bits 492s 07:16:06.855179969 O: keygen ecdsa, 256 bits 492s 07:16:06.864664484 O: keygen ecdsa, 384 bits 492s 07:16:06.874009704 O: keygen ecdsa, 521 bits 492s 07:16:06.886068444 O: keygen ecdsa-sk, n/a bits 492s 07:16:06.901799108 O: keygen rsa, 2048 bits 492s 07:16:06.934651415 O: keygen rsa, 3072 bits 493s 07:16:07.109497618 O: userkey ed25519-512, hostkey ed25519-512 493s 07:16:07.216681136 O: userkey ed25519-512, hostkey ed25519-512 493s 07:16:07.323544445 O: userkey ed25519-512, hostkey ed25519-512 493s 07:16:07.437168120 O: userkey ed25519-sk, hostkey ed25519-sk 493s 07:16:07.543934891 O: userkey ed25519-sk, hostkey ed25519-sk 493s 07:16:07.644573259 O: userkey ed25519-sk, hostkey ed25519-sk 493s 07:16:07.752218908 O: userkey ecdsa-256, hostkey ecdsa-256 493s 07:16:07.849007876 O: userkey ecdsa-256, hostkey ecdsa-256 493s 07:16:07.950275301 O: userkey ecdsa-256, hostkey ecdsa-256 494s 07:16:08.068630432 O: userkey ecdsa-384, hostkey ecdsa-384 494s 07:16:08.180365765 O: userkey ecdsa-384, hostkey ecdsa-384 494s 07:16:08.302126081 O: userkey ecdsa-384, hostkey ecdsa-384 494s 07:16:08.425697609 O: userkey ecdsa-521, hostkey ecdsa-521 494s 07:16:08.530614308 O: userkey ecdsa-521, hostkey ecdsa-521 494s 07:16:08.627564560 O: userkey ecdsa-521, hostkey ecdsa-521 494s 07:16:08.729782624 O: userkey ecdsa-sk, hostkey ecdsa-sk 494s 07:16:08.829157357 O: userkey ecdsa-sk, hostkey ecdsa-sk 494s 07:16:08.930043771 O: userkey ecdsa-sk, hostkey ecdsa-sk 495s 07:16:09.040085104 O: userkey rsa-2048, hostkey rsa-2048 495s 07:16:09.140622344 O: userkey rsa-2048, hostkey rsa-2048 495s 07:16:09.239366605 O: userkey rsa-2048, hostkey rsa-2048 495s 07:16:09.345160497 O: userkey rsa-3072, hostkey rsa-3072 495s 07:16:09.455519227 O: userkey rsa-3072, hostkey rsa-3072 495s 07:16:09.569796929 O: userkey rsa-3072, hostkey rsa-3072 495s 07:16:09.701028835 O: ok login with different key types 495s 07:16:09.702080951 E: run test kextype.sh ... 495s 07:16:09.878591626 O: kex diffie-hellman-group1-sha1 496s 07:16:10.357696834 O: kex diffie-hellman-group14-sha1 496s 07:16:10.884052709 O: kex diffie-hellman-group14-sha256 497s 07:16:11.364721129 O: kex diffie-hellman-group16-sha512 497s 07:16:11.932476452 O: kex diffie-hellman-group18-sha512 498s 07:16:12.682593956 O: kex diffie-hellman-group-exchange-sha1 499s 07:16:13.401781664 O: kex diffie-hellman-group-exchange-sha256 500s 07:16:14.098890079 O: kex ecdh-sha2-nistp256 500s 07:16:14.532529406 O: kex ecdh-sha2-nistp384 501s 07:16:15.002879639 O: kex ecdh-sha2-nistp521 501s 07:16:15.481840866 O: kex curve25519-sha256 502s 07:16:16.027093864 O: kex curve25519-sha256@libssh.org 502s 07:16:16.554884141 O: kex sntrup761x25519-sha512 503s 07:16:17.074637511 O: kex sntrup761x25519-sha512@openssh.com 503s 07:16:17.612055488 O: kex mlkem768x25519-sha256 504s 07:16:18.046556993 O: ok login with different key exchange algorithms 504s 07:16:18.048139216 E: run test cert-hostkey.sh ... 504s 07:16:18.464118779 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/host_ca_key.pub 504s 07:16:18.465705290 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/host_ca_key2.pub 504s 07:16:18.467137799 O: certified host keys: sign host ed25519 cert 504s 07:16:18.473279156 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 504s 07:16:18.484164000 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 504s 07:16:18.487547830 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 504s 07:16:18.498953793 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 504s 07:16:18.510669625 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 504s 07:16:18.513771277 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 504s 07:16:18.523524726 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 504s 07:16:18.537584034 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 504s 07:16:18.540791510 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 504s 07:16:18.551237014 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 504s 07:16:18.563438241 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 504s 07:16:18.567326129 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 504s 07:16:18.574589244 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 504s 07:16:18.587925200 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 504s 07:16:18.592112671 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 504s 07:16:18.605158492 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 504s 07:16:18.614517265 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 504s 07:16:18.617655604 O: certified host keys: sign host rsa cert 504s 07:16:18.884730221 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 504s 07:16:18.894076250 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 504s 07:16:18.897867863 O: certified host keys: sign host rsa-sha2-256 cert 505s 07:16:19.101687299 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 505s 07:16:19.113105545 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 505s 07:16:19.116113935 O: certified host keys: sign host rsa-sha2-512 cert 505s 07:16:19.279521236 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 505s 07:16:19.291753692 O: Revoking from /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 505s 07:16:19.295519086 O: certified host keys: host ed25519 cert connect 505s 07:16:19.297536501 O: certified host keys: ed25519 basic connect expect success yes 505s 07:16:19.429950389 O: certified host keys: ed25519 empty KRL expect success yes 505s 07:16:19.551710778 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 505s 07:16:19.609794610 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 505s 07:16:19.670234619 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 505s 07:16:19.731999157 O: certified host keys: ed25519 empty plaintext revocation expect success yes 505s 07:16:19.851841925 O: certified host keys: ed25519 plain key plaintext revocation expect success no 505s 07:16:19.911192208 O: certified host keys: ed25519 cert plaintext revocation expect success no 506s 07:16:19.971678542 O: certified host keys: ed25519 CA plaintext revocation expect success no 506s 07:16:20.033665786 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 506s 07:16:20.035616227 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 506s 07:16:20.165713255 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 506s 07:16:20.291190406 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 506s 07:16:20.356067886 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 506s 07:16:20.417280035 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 506s 07:16:20.479329847 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 506s 07:16:20.599822649 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 506s 07:16:20.660667490 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 506s 07:16:20.725404161 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 506s 07:16:20.787781064 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 506s 07:16:20.789809320 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 506s 07:16:20.910732183 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 507s 07:16:21.026892088 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 507s 07:16:21.085747525 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 507s 07:16:21.143704607 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 507s 07:16:21.203664467 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 507s 07:16:21.320982497 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 507s 07:16:21.379307847 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 507s 07:16:21.442317314 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 507s 07:16:21.501909537 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 507s 07:16:21.503957920 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 507s 07:16:21.626749170 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 507s 07:16:21.750005727 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 507s 07:16:21.812677261 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 507s 07:16:21.876455405 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 507s 07:16:21.938757419 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 508s 07:16:22.057043999 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 508s 07:16:22.122207561 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 508s 07:16:22.192233951 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 508s 07:16:22.255115699 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 508s 07:16:22.257419092 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 508s 07:16:22.380958064 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 508s 07:16:22.503778774 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 508s 07:16:22.566668798 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 508s 07:16:22.628494042 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 508s 07:16:22.691271270 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 508s 07:16:22.812728758 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 508s 07:16:22.873877094 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 508s 07:16:22.943264226 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 509s 07:16:23.004075202 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 509s 07:16:23.006261400 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 509s 07:16:23.127955218 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 509s 07:16:23.249432116 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 509s 07:16:23.314252944 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 509s 07:16:23.378709372 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 509s 07:16:23.441219414 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 509s 07:16:23.557936976 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 509s 07:16:23.620072058 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 509s 07:16:23.692191772 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 509s 07:16:23.755231559 O: certified host keys: host rsa cert connect 509s 07:16:23.757160724 O: certified host keys: rsa basic connect expect success yes 509s 07:16:23.881237220 O: certified host keys: rsa empty KRL expect success yes 510s 07:16:24.008017828 O: certified host keys: rsa KRL w/ plain key revoked expect success no 510s 07:16:24.073600017 O: certified host keys: rsa KRL w/ cert revoked expect success no 510s 07:16:24.135924055 O: certified host keys: rsa KRL w/ CA revoked expect success no 510s 07:16:24.199652629 O: certified host keys: rsa empty plaintext revocation expect success yes 510s 07:16:24.324289893 O: certified host keys: rsa plain key plaintext revocation expect success no 510s 07:16:24.391481257 O: certified host keys: rsa cert plaintext revocation expect success no 510s 07:16:24.466616873 O: certified host keys: rsa CA plaintext revocation expect success no 510s 07:16:24.531992632 O: certified host keys: host rsa-sha2-256 cert connect 510s 07:16:24.533964003 O: certified host keys: rsa-sha2-256 basic connect expect success yes 510s 07:16:24.652546985 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 510s 07:16:24.770801992 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 510s 07:16:24.831776433 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 510s 07:16:24.892759991 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 511s 07:16:24.952546494 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 511s 07:16:25.070829500 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 511s 07:16:25.130951930 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 511s 07:16:25.201930026 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 511s 07:16:25.262003864 O: certified host keys: host rsa-sha2-512 cert connect 511s 07:16:25.264172610 O: certified host keys: rsa-sha2-512 basic connect expect success yes 511s 07:16:25.375302484 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 511s 07:16:25.490149296 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 511s 07:16:25.550491168 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 511s 07:16:25.610742994 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 511s 07:16:25.670504699 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 511s 07:16:25.783150877 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 511s 07:16:25.843923803 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 511s 07:16:25.915517855 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 512s 07:16:25.984319371 O: certified host keys: host ed25519 revoked cert 512s 07:16:26.043585908 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 512s 07:16:26.108749580 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 512s 07:16:26.171293416 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 512s 07:16:26.236540922 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 512s 07:16:26.303114402 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 512s 07:16:26.369202668 O: certified host keys: host rsa revoked cert 512s 07:16:26.435196418 O: certified host keys: host rsa-sha2-256 revoked cert 512s 07:16:26.497088614 O: certified host keys: host rsa-sha2-512 revoked cert 512s 07:16:26.565793892 O: certified host keys: host ed25519 revoked cert 512s 07:16:26.627303492 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 512s 07:16:26.690399284 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 512s 07:16:26.749120487 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 512s 07:16:26.811632084 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 512s 07:16:26.874254324 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 512s 07:16:26.938114625 O: certified host keys: host rsa revoked cert 513s 07:16:27.003701387 O: certified host keys: host rsa-sha2-256 revoked cert 513s 07:16:27.064393037 O: certified host keys: host rsa-sha2-512 revoked cert 518s 07:16:32.701242754 O: certified host keys: host ed25519 cert downgrade to raw key 518s 07:16:32.936735143 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 519s 07:16:33.179243748 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 519s 07:16:33.413614811 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 519s 07:16:33.662702757 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 519s 07:16:33.922683929 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 520s 07:16:34.167132659 O: certified host keys: host rsa cert downgrade to raw key 520s 07:16:34.510902672 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 520s 07:16:34.864243295 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 521s 07:16:35.268265939 O: certified host keys: host ed25519 connect wrong cert 521s 07:16:35.335298380 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 521s 07:16:35.413401787 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 521s 07:16:35.476625308 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 521s 07:16:35.547142061 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 521s 07:16:35.618065914 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 521s 07:16:35.694274307 O: certified host keys: host rsa connect wrong cert 522s 07:16:36.175998884 O: certified host keys: host rsa-sha2-256 connect wrong cert 522s 07:16:36.310474514 O: certified host keys: host rsa-sha2-512 connect wrong cert 522s 07:16:36.666112307 O: ok certified host keys 522s 07:16:36.666982190 E: run test cert-userkey.sh ... 522s 07:16:36.852518877 O: certified user keys: sign user ed25519 cert 522s 07:16:36.863793319 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 522s 07:16:36.877740117 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 522s 07:16:36.888563176 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 522s 07:16:36.900115460 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 522s 07:16:36.911464178 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 522s 07:16:36.925682486 O: certified user keys: sign user rsa cert 523s 07:16:37.178046204 O: certified user keys: sign user rsa-sha2-256 cert 523s 07:16:37.264307049 O: certified user keys: sign user rsa-sha2-512 cert 523s 07:16:37.520257485 O: certified user keys: ed25519 missing authorized_principals 523s 07:16:37.607147049 O: certified user keys: ed25519 empty authorized_principals 523s 07:16:37.694636359 O: certified user keys: ed25519 wrong authorized_principals 523s 07:16:37.782047064 O: certified user keys: ed25519 correct authorized_principals 523s 07:16:37.897218811 O: certified user keys: ed25519 authorized_principals bad key opt 524s 07:16:37.982703997 O: certified user keys: ed25519 authorized_principals command=false 524s 07:16:38.098414574 O: certified user keys: ed25519 authorized_principals command=true 524s 07:16:38.218288908 O: certified user keys: ed25519 wrong principals key option 524s 07:16:38.322782600 O: certified user keys: ed25519 correct principals key option 524s 07:16:38.459215428 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 524s 07:16:38.563935968 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 524s 07:16:38.666377296 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 524s 07:16:38.771673569 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 524s 07:16:38.900133939 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 525s 07:16:39.005665546 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 525s 07:16:39.131902427 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 525s 07:16:39.260027691 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 525s 07:16:39.350141721 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 525s 07:16:39.479283929 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 525s 07:16:39.590036817 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 525s 07:16:39.698843668 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 525s 07:16:39.807726237 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 525s 07:16:39.935982171 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 526s 07:16:40.040170010 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 526s 07:16:40.164394110 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 526s 07:16:40.296526139 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 526s 07:16:40.383730041 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 526s 07:16:40.504144238 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 526s 07:16:40.606938371 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 526s 07:16:40.710867637 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 526s 07:16:40.814886940 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 526s 07:16:40.942613466 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 527s 07:16:41.044105592 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 527s 07:16:41.167995524 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 527s 07:16:41.295916309 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 527s 07:16:41.393949551 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 527s 07:16:41.520041653 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 527s 07:16:41.611952660 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 527s 07:16:41.705094415 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 527s 07:16:41.799657261 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 527s 07:16:41.919325140 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 528s 07:16:42.014075581 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 528s 07:16:42.132446486 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 528s 07:16:42.254129849 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 528s 07:16:42.352779945 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 528s 07:16:42.481971922 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 528s 07:16:42.572759091 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 528s 07:16:42.662728074 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 528s 07:16:42.753880808 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 528s 07:16:42.871300710 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 529s 07:16:42.963138908 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 529s 07:16:43.085720822 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 529s 07:16:43.210665505 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 529s 07:16:43.308951196 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 529s 07:16:43.433873638 O: certified user keys: rsa missing authorized_principals 529s 07:16:43.519770057 O: certified user keys: rsa empty authorized_principals 529s 07:16:43.604864508 O: certified user keys: rsa wrong authorized_principals 529s 07:16:43.689389958 O: certified user keys: rsa correct authorized_principals 529s 07:16:43.804182795 O: certified user keys: rsa authorized_principals bad key opt 529s 07:16:43.888304622 O: certified user keys: rsa authorized_principals command=false 530s 07:16:44.003282720 O: certified user keys: rsa authorized_principals command=true 530s 07:16:44.121450465 O: certified user keys: rsa wrong principals key option 530s 07:16:44.207161167 O: certified user keys: rsa correct principals key option 530s 07:16:44.327959642 O: certified user keys: rsa-sha2-256 missing authorized_principals 530s 07:16:44.417909791 O: certified user keys: rsa-sha2-256 empty authorized_principals 530s 07:16:44.510993877 O: certified user keys: rsa-sha2-256 wrong authorized_principals 530s 07:16:44.605365377 O: certified user keys: rsa-sha2-256 correct authorized_principals 530s 07:16:44.727578371 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 530s 07:16:44.817675731 O: certified user keys: rsa-sha2-256 authorized_principals command=false 530s 07:16:44.941133410 O: certified user keys: rsa-sha2-256 authorized_principals command=true 531s 07:16:45.068302777 O: certified user keys: rsa-sha2-256 wrong principals key option 531s 07:16:45.161992084 O: certified user keys: rsa-sha2-256 correct principals key option 531s 07:16:45.288672954 O: certified user keys: rsa-sha2-512 missing authorized_principals 531s 07:16:45.381224604 O: certified user keys: rsa-sha2-512 empty authorized_principals 531s 07:16:45.475449600 O: certified user keys: rsa-sha2-512 wrong authorized_principals 531s 07:16:45.571825162 O: certified user keys: rsa-sha2-512 correct authorized_principals 531s 07:16:45.706424828 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 531s 07:16:45.803215393 O: certified user keys: rsa-sha2-512 authorized_principals command=false 531s 07:16:45.927108049 O: certified user keys: rsa-sha2-512 authorized_principals command=true 532s 07:16:46.053223117 O: certified user keys: rsa-sha2-512 wrong principals key option 532s 07:16:46.149181146 O: certified user keys: rsa-sha2-512 correct principals key option 532s 07:16:46.283971526 O: certified user keys: ed25519 authorized_keys connect 532s 07:16:46.410526654 O: certified user keys: ed25519 authorized_keys revoked key 532s 07:16:46.510181529 O: certified user keys: ed25519 authorized_keys revoked via KRL 532s 07:16:46.611778706 O: certified user keys: ed25519 authorized_keys empty KRL 532s 07:16:46.742691510 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 532s 07:16:46.860039682 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 533s 07:16:46.962515670 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 533s 07:16:47.072365853 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 533s 07:16:47.200849799 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 533s 07:16:47.319331781 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 533s 07:16:47.428200542 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 533s 07:16:47.537970112 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 533s 07:16:47.668515785 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 533s 07:16:47.789025603 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 533s 07:16:47.884008335 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 534s 07:16:47.979287301 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 534s 07:16:48.101774694 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 534s 07:16:48.217324280 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 534s 07:16:48.319057004 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 534s 07:16:48.422618113 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 534s 07:16:48.550113454 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 534s 07:16:48.687261400 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 534s 07:16:48.790097647 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 534s 07:16:48.896160070 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 535s 07:16:49.030456558 O: certified user keys: rsa authorized_keys connect 535s 07:16:49.169892525 O: certified user keys: rsa authorized_keys revoked key 535s 07:16:49.266676786 O: certified user keys: rsa authorized_keys revoked via KRL 535s 07:16:49.375653823 O: certified user keys: rsa authorized_keys empty KRL 535s 07:16:49.511797135 O: certified user keys: rsa-sha2-256 authorized_keys connect 535s 07:16:49.642066947 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 535s 07:16:49.729872813 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 535s 07:16:49.822271403 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 535s 07:16:49.947229835 O: certified user keys: rsa-sha2-512 authorized_keys connect 536s 07:16:50.078162236 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 536s 07:16:50.180868779 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 536s 07:16:50.279933228 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 536s 07:16:50.415970081 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 536s 07:16:50.508293186 O: certified user keys: authorized_keys CA does not authenticate 536s 07:16:50.510880761 O: certified user keys: ensure CA key does not authenticate user 536s 07:16:50.603892021 O: certified user keys: ed25519 TrustedUserCAKeys connect 536s 07:16:50.734666821 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 536s 07:16:50.831786153 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 536s 07:16:50.924843411 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 537s 07:16:51.060389129 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 537s 07:16:51.190470149 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 537s 07:16:51.293295120 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 537s 07:16:51.409056161 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 537s 07:16:51.538526574 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 537s 07:16:51.671548013 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 537s 07:16:51.770852680 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 537s 07:16:51.885546505 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 538s 07:16:52.022493766 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 538s 07:16:52.160821738 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 538s 07:16:52.266198818 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 538s 07:16:52.377282087 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 538s 07:16:52.509981749 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 538s 07:16:52.633323489 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 538s 07:16:52.741160401 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 538s 07:16:52.855213841 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 539s 07:16:52.988118956 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 539s 07:16:53.128328019 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 539s 07:16:53.247145170 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 539s 07:16:53.363086845 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 539s 07:16:53.499703353 O: certified user keys: rsa TrustedUserCAKeys connect 539s 07:16:53.644788013 O: certified user keys: rsa TrustedUserCAKeys revoked key 539s 07:16:53.747413936 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 539s 07:16:53.845872221 O: certified user keys: rsa TrustedUserCAKeys empty KRL 540s 07:16:53.992182954 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 540s 07:16:54.128942864 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 540s 07:16:54.248783062 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 540s 07:16:54.374377598 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 540s 07:16:54.525690332 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 540s 07:16:54.666519186 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 540s 07:16:54.777555877 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 540s 07:16:54.874024159 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 541s 07:16:55.001176468 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 541s 07:16:55.092437682 O: certified user keys: TrustedUserCAKeys CA does not authenticate 541s 07:16:55.094479316 O: certified user keys: ensure CA key does not authenticate user 541s 07:16:55.179730355 O: certified user keys: correct principal auth authorized_keys expect success rsa 541s 07:16:55.306907640 O: certified user keys: correct principal auth authorized_keys expect success ed25519 541s 07:16:55.471602907 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 541s 07:16:55.636538850 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 541s 07:16:55.780621980 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 541s 07:16:55.869548300 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 542s 07:16:55.958408031 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 542s 07:16:56.055523653 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 542s 07:16:56.151630221 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 542s 07:16:56.250581332 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 542s 07:16:56.346738694 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 542s 07:16:56.451691020 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 542s 07:16:56.569481601 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 542s 07:16:56.673968085 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 542s 07:16:56.782966287 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 542s 07:16:56.901092407 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 543s 07:16:57.021617640 O: certified user keys: cert expired auth authorized_keys expect failure rsa 543s 07:16:57.133672354 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 543s 07:16:57.241044463 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 543s 07:16:57.347309213 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 543s 07:16:57.455602963 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 543s 07:16:57.592708578 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 543s 07:16:57.733416790 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 543s 07:16:57.873762829 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 544s 07:16:58.023846412 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 544s 07:16:58.125681630 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 544s 07:16:58.224035954 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 544s 07:16:58.325262039 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 544s 07:16:58.428522377 O: certified user keys: force-command auth authorized_keys expect failure rsa 544s 07:16:58.559255194 O: certified user keys: force-command auth authorized_keys expect failure ed25519 544s 07:16:58.684301895 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 544s 07:16:58.818557061 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 544s 07:16:58.951775840 O: certified user keys: empty principals auth authorized_keys expect success rsa 545s 07:16:59.079645245 O: certified user keys: empty principals auth authorized_keys expect success ed25519 545s 07:16:59.208159181 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 545s 07:16:59.308666532 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 545s 07:16:59.411453428 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 545s 07:16:59.546504550 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 545s 07:16:59.684732897 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 545s 07:16:59.784796650 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 545s 07:16:59.894944546 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 546s 07:17:00.032790547 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 546s 07:17:00.170103282 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 546s 07:17:00.267991852 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 546s 07:17:00.366023027 O: certified user keys: force-command match true auth authorized_keys expect success rsa 546s 07:17:00.493458860 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 546s 07:17:00.625856440 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 546s 07:17:00.755108577 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 546s 07:17:00.884252234 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 547s 07:17:00.978818261 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 547s 07:17:01.080307095 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 547s 07:17:01.182257680 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 547s 07:17:01.292494342 O: certified user keys: user ed25519 connect wrong cert 547s 07:17:01.405968678 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 547s 07:17:01.494973785 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 547s 07:17:01.583714550 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 547s 07:17:01.663059188 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 547s 07:17:01.743819490 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 547s 07:17:01.825656008 O: certified user keys: user rsa connect wrong cert 547s 07:17:01.922613074 O: certified user keys: user rsa-sha2-256 connect wrong cert 548s 07:17:02.025930275 O: certified user keys: user rsa-sha2-512 connect wrong cert 548s 07:17:02.117758169 O: ok certified user keys 548s 07:17:02.118798045 E: run test host-expand.sh ... 548s 07:17:02.375274760 O: ok expand %h and %n 548s 07:17:02.376131534 E: run test keys-command.sh ... 548s 07:17:02.530668075 O: SKIPPED: /var/run/keycommand_openssh-tests.61827 not executable (/var/run mounted noexec?) 548s 07:17:02.537759848 E: run test forward-control.sh ... 550s 07:17:04.058709855 O: check_lfwd done (expecting Y): default configuration 551s 07:17:05.415235390 O: check_rfwd done (expecting Y): default configuration 552s 07:17:06.770076139 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 554s 07:17:08.123571441 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 554s 07:17:08.265528981 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 555s 07:17:09.628862733 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 556s 07:17:09.986610080 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 557s 07:17:11.336317345 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 558s 07:17:12.484082631 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 558s 07:17:12.877820470 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 560s 07:17:14.226983979 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 560s 07:17:14.590464598 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 561s 07:17:15.738035206 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 561s 07:17:15.868129379 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 562s 07:17:16.006237607 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 562s 07:17:16.139530470 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 563s 07:17:17.482650954 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 564s 07:17:18.843933707 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 565s 07:17:19.196432067 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 565s 07:17:19.342625892 O: check_rfwd done (expecting N): AllowTcpForwarding=local 565s 07:17:19.478921518 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 565s 07:17:19.607973797 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 566s 07:17:20.945817996 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 567s 07:17:21.091134032 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 568s 07:17:22.235026874 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 568s 07:17:22.376409638 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 569s 07:17:23.719691503 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 569s 07:17:23.845728019 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 571s 07:17:24.977042874 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 571s 07:17:25.107565423 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 571s 07:17:25.245569914 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 571s 07:17:25.383853515 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 571s 07:17:25.726172271 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 571s 07:17:25.851936590 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 573s 07:17:26.991297966 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 573s 07:17:27.338399627 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 574s 07:17:28.475897058 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 576s 07:17:30.098127895 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 576s 07:17:30.230275464 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 577s 07:17:31.574859042 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 578s 07:17:32.713443730 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 580s 07:17:34.018563119 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 581s 07:17:35.150741733 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 582s 07:17:36.462453415 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 583s 07:17:37.597625825 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 583s 07:17:37.721607135 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 583s 07:17:37.851971263 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 584s 07:17:37.975076670 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 584s 07:17:38.106806998 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 585s 07:17:39.413509142 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 586s 07:17:40.549278806 O: check_lfwd done (expecting N): AllowTcpForwarding=no 586s 07:17:40.683814970 O: check_rfwd done (expecting N): AllowTcpForwarding=no 587s 07:17:41.823578849 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 587s 07:17:41.957905556 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 589s 07:17:43.095136381 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 589s 07:17:43.233947967 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 589s 07:17:43.373261769 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 589s 07:17:43.505234754 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 590s 07:17:44.653548303 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 590s 07:17:44.783987952 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 590s 07:17:44.919795344 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 591s 07:17:45.050551862 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 592s 07:17:46.197228052 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 592s 07:17:46.339539866 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 592s 07:17:46.476665775 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 592s 07:17:46.611314914 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 593s 07:17:46.949191928 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 593s 07:17:47.081942841 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 593s 07:17:47.415994266 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 593s 07:17:47.760692558 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 594s 07:17:48.097530928 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 594s 07:17:48.231373379 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 595s 07:17:49.563162865 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 595s 07:17:49.698377075 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 596s 07:17:50.835020730 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 597s 07:17:50.966296606 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 598s 07:17:52.101852185 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 599s 07:17:53.420484956 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 599s 07:17:53.580481278 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 599s 07:17:53.732690781 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 600s 07:17:54.883596021 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 601s 07:17:55.022150254 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 601s 07:17:55.038981569 O: ok sshd control of local and remote forwarding 601s 07:17:55.039869851 E: run test integrity.sh ... 601s 07:17:55.217838861 O: test integrity: hmac-sha1 @2900 601s 07:17:55.355077975 O: test integrity: hmac-sha1 @2901 601s 07:17:55.489190973 O: test integrity: hmac-sha1 @2902 601s 07:17:55.615704602 O: test integrity: hmac-sha1 @2903 601s 07:17:55.744733203 O: test integrity: hmac-sha1 @2904 601s 07:17:55.881401949 O: test integrity: hmac-sha1 @2905 602s 07:17:56.012440962 O: test integrity: hmac-sha1 @2906 602s 07:17:56.138848550 O: test integrity: hmac-sha1 @2907 602s 07:17:56.265815764 O: test integrity: hmac-sha1 @2908 602s 07:17:56.397151665 O: test integrity: hmac-sha1 @2909 602s 07:17:56.518125020 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 602s 07:17:56.532238423 O: test integrity: hmac-sha1-96 @2900 602s 07:17:56.660282897 O: test integrity: hmac-sha1-96 @2901 602s 07:17:56.788100607 O: test integrity: hmac-sha1-96 @2902 602s 07:17:56.915940089 O: test integrity: hmac-sha1-96 @2903 603s 07:17:57.042945450 O: test integrity: hmac-sha1-96 @2904 603s 07:17:57.171285943 O: test integrity: hmac-sha1-96 @2905 603s 07:17:57.296663268 O: test integrity: hmac-sha1-96 @2906 603s 07:17:57.424457282 O: test integrity: hmac-sha1-96 @2907 603s 07:17:57.552442851 O: test integrity: hmac-sha1-96 @2908 603s 07:17:57.680645776 O: test integrity: hmac-sha1-96 @2909 603s 07:17:57.799163326 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 603s 07:17:57.813241136 O: test integrity: hmac-sha2-256 @2900 603s 07:17:57.944022908 O: test integrity: hmac-sha2-256 @2901 604s 07:17:58.072256810 O: test integrity: hmac-sha2-256 @2902 604s 07:17:58.198444080 O: test integrity: hmac-sha2-256 @2903 604s 07:17:58.325741921 O: test integrity: hmac-sha2-256 @2904 604s 07:17:58.454761697 O: test integrity: hmac-sha2-256 @2905 604s 07:17:58.583918073 O: test integrity: hmac-sha2-256 @2906 604s 07:17:58.712944995 O: test integrity: hmac-sha2-256 @2907 604s 07:17:58.845296549 O: test integrity: hmac-sha2-256 @2908 605s 07:17:58.978647296 O: test integrity: hmac-sha2-256 @2909 605s 07:17:59.094983147 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 605s 07:17:59.108706240 O: test integrity: hmac-sha2-512 @2900 605s 07:17:59.185702973 O: test integrity: hmac-sha2-512 @2901 605s 07:17:59.261523727 O: test integrity: hmac-sha2-512 @2902 605s 07:17:59.338719940 O: test integrity: hmac-sha2-512 @2903 605s 07:17:59.414450148 O: test integrity: hmac-sha2-512 @2904 605s 07:17:59.491761796 O: test integrity: hmac-sha2-512 @2905 605s 07:17:59.570502947 O: test integrity: hmac-sha2-512 @2906 605s 07:17:59.650046797 O: test integrity: hmac-sha2-512 @2907 605s 07:17:59.725845438 O: test integrity: hmac-sha2-512 @2908 605s 07:17:59.802640146 O: test integrity: hmac-sha2-512 @2909 605s 07:17:59.870373559 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 605s 07:17:59.884727977 O: test integrity: hmac-md5 @2900 606s 07:18:00.012079719 O: test integrity: hmac-md5 @2901 606s 07:18:00.142013992 O: test integrity: hmac-md5 @2902 606s 07:18:00.270062814 O: test integrity: hmac-md5 @2903 606s 07:18:00.393182091 O: test integrity: hmac-md5 @2904 606s 07:18:00.523107817 O: test integrity: hmac-md5 @2905 606s 07:18:00.648690163 O: test integrity: hmac-md5 @2906 606s 07:18:00.770997976 O: test integrity: hmac-md5 @2907 606s 07:18:00.897211444 O: test integrity: hmac-md5 @2908 607s 07:18:01.024896230 O: test integrity: hmac-md5 @2909 607s 07:18:01.137620359 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 607s 07:18:01.152332251 O: test integrity: hmac-md5-96 @2900 607s 07:18:01.275887481 O: test integrity: hmac-md5-96 @2901 607s 07:18:01.397867759 O: test integrity: hmac-md5-96 @2902 607s 07:18:01.517326126 O: test integrity: hmac-md5-96 @2903 607s 07:18:01.637810424 O: test integrity: hmac-md5-96 @2904 607s 07:18:01.760858997 O: test integrity: hmac-md5-96 @2905 607s 07:18:01.884179378 O: test integrity: hmac-md5-96 @2906 608s 07:18:02.023433844 O: test integrity: hmac-md5-96 @2907 608s 07:18:02.164056024 O: test integrity: hmac-md5-96 @2908 608s 07:18:02.307254056 O: test integrity: hmac-md5-96 @2909 608s 07:18:02.438404601 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 608s 07:18:02.453532924 O: test integrity: umac-64@openssh.com @2900 608s 07:18:02.592616234 O: test integrity: umac-64@openssh.com @2901 608s 07:18:02.727223178 O: test integrity: umac-64@openssh.com @2902 608s 07:18:02.855943675 O: test integrity: umac-64@openssh.com @2903 609s 07:18:02.987034463 O: test integrity: umac-64@openssh.com @2904 609s 07:18:03.121038637 O: test integrity: umac-64@openssh.com @2905 609s 07:18:03.254329124 O: test integrity: umac-64@openssh.com @2906 609s 07:18:03.384833011 O: test integrity: umac-64@openssh.com @2907 609s 07:18:03.548105267 O: test integrity: umac-64@openssh.com @2908 609s 07:18:03.675549494 O: test integrity: umac-64@openssh.com @2909 609s 07:18:03.801331251 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 609s 07:18:03.818577079 O: test integrity: umac-128@openssh.com @2900 609s 07:18:03.959945834 O: test integrity: umac-128@openssh.com @2901 610s 07:18:04.093969114 O: test integrity: umac-128@openssh.com @2902 610s 07:18:04.229224692 O: test integrity: umac-128@openssh.com @2903 610s 07:18:04.362519841 O: test integrity: umac-128@openssh.com @2904 610s 07:18:04.498152342 O: test integrity: umac-128@openssh.com @2905 610s 07:18:04.634005793 O: test integrity: umac-128@openssh.com @2906 610s 07:18:04.768268186 O: test integrity: umac-128@openssh.com @2907 610s 07:18:04.899402890 O: test integrity: umac-128@openssh.com @2908 611s 07:18:05.027859978 O: test integrity: umac-128@openssh.com @2909 611s 07:18:05.148048358 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 611s 07:18:05.161955766 O: test integrity: hmac-sha1-etm@openssh.com @2900 611s 07:18:05.289214823 O: test integrity: hmac-sha1-etm@openssh.com @2901 611s 07:18:05.417813206 O: test integrity: hmac-sha1-etm@openssh.com @2902 611s 07:18:05.544460512 O: test integrity: hmac-sha1-etm@openssh.com @2903 611s 07:18:05.670105558 O: test integrity: hmac-sha1-etm@openssh.com @2904 611s 07:18:05.796320915 O: test integrity: hmac-sha1-etm@openssh.com @2905 611s 07:18:05.925214381 O: test integrity: hmac-sha1-etm@openssh.com @2906 612s 07:18:06.060583765 O: test integrity: hmac-sha1-etm@openssh.com @2907 612s 07:18:06.204138932 O: test integrity: hmac-sha1-etm@openssh.com @2908 612s 07:18:06.331879799 O: test integrity: hmac-sha1-etm@openssh.com @2909 612s 07:18:06.455250338 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 612s 07:18:06.470743592 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 612s 07:18:06.602449940 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 612s 07:18:06.730641774 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 612s 07:18:06.856242328 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 613s 07:18:06.988764643 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 613s 07:18:07.119253071 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 613s 07:18:07.245654794 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 613s 07:18:07.370485132 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 613s 07:18:07.492712914 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 613s 07:18:07.620437857 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 613s 07:18:07.740665353 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 613s 07:18:07.755606435 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 613s 07:18:07.890439752 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 614s 07:18:08.023810720 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 614s 07:18:08.158886974 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 614s 07:18:08.292786280 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 614s 07:18:08.423625965 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 614s 07:18:08.550702111 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 614s 07:18:08.680179710 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 614s 07:18:08.822916780 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 615s 07:18:08.970950387 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 615s 07:18:09.106751047 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 615s 07:18:09.123746124 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 615s 07:18:09.207630786 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 615s 07:18:09.287389100 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 615s 07:18:09.369452027 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 615s 07:18:09.451512289 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 615s 07:18:09.534525209 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 615s 07:18:09.616459245 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 615s 07:18:09.697413000 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 615s 07:18:09.777719698 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 615s 07:18:09.857131228 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 615s 07:18:09.926760684 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 615s 07:18:09.941485393 O: test integrity: hmac-md5-etm@openssh.com @2900 616s 07:18:10.071037457 O: test integrity: hmac-md5-etm@openssh.com @2901 616s 07:18:10.199959002 O: test integrity: hmac-md5-etm@openssh.com @2902 616s 07:18:10.328640482 O: test integrity: hmac-md5-etm@openssh.com @2903 616s 07:18:10.458852120 O: test integrity: hmac-md5-etm@openssh.com @2904 616s 07:18:10.599215772 O: test integrity: hmac-md5-etm@openssh.com @2905 616s 07:18:10.740751316 O: test integrity: hmac-md5-etm@openssh.com @2906 616s 07:18:10.864626031 O: test integrity: hmac-md5-etm@openssh.com @2907 617s 07:18:10.988510726 O: test integrity: hmac-md5-etm@openssh.com @2908 617s 07:18:11.118797376 O: test integrity: hmac-md5-etm@openssh.com @2909 617s 07:18:11.236890973 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 617s 07:18:11.251428288 O: test integrity: hmac-md5-96-etm@openssh.com @2900 617s 07:18:11.382279288 O: test integrity: hmac-md5-96-etm@openssh.com @2901 617s 07:18:11.510329770 O: test integrity: hmac-md5-96-etm@openssh.com @2902 617s 07:18:11.638689311 O: test integrity: hmac-md5-96-etm@openssh.com @2903 617s 07:18:11.768264086 O: test integrity: hmac-md5-96-etm@openssh.com @2904 617s 07:18:11.898909058 O: test integrity: hmac-md5-96-etm@openssh.com @2905 618s 07:18:12.029827160 O: test integrity: hmac-md5-96-etm@openssh.com @2906 618s 07:18:12.159908616 O: test integrity: hmac-md5-96-etm@openssh.com @2907 618s 07:18:12.288814435 O: test integrity: hmac-md5-96-etm@openssh.com @2908 618s 07:18:12.418770197 O: test integrity: hmac-md5-96-etm@openssh.com @2909 618s 07:18:12.535973378 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 618s 07:18:12.550167423 O: test integrity: umac-64-etm@openssh.com @2900 618s 07:18:12.680696397 O: test integrity: umac-64-etm@openssh.com @2901 618s 07:18:12.814484787 O: test integrity: umac-64-etm@openssh.com @2902 618s 07:18:12.942873854 O: test integrity: umac-64-etm@openssh.com @2903 619s 07:18:13.073049612 O: test integrity: umac-64-etm@openssh.com @2904 619s 07:18:13.210389869 O: test integrity: umac-64-etm@openssh.com @2905 619s 07:18:13.365683758 O: test integrity: umac-64-etm@openssh.com @2906 619s 07:18:13.499137658 O: test integrity: umac-64-etm@openssh.com @2907 619s 07:18:13.630066386 O: test integrity: umac-64-etm@openssh.com @2908 619s 07:18:13.760645989 O: test integrity: umac-64-etm@openssh.com @2909 619s 07:18:13.881625739 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 619s 07:18:13.896988564 O: test integrity: umac-128-etm@openssh.com @2900 620s 07:18:14.029008730 O: test integrity: umac-128-etm@openssh.com @2901 620s 07:18:14.158585206 O: test integrity: umac-128-etm@openssh.com @2902 620s 07:18:14.286113207 O: test integrity: umac-128-etm@openssh.com @2903 620s 07:18:14.417726245 O: test integrity: umac-128-etm@openssh.com @2904 620s 07:18:14.545180144 O: test integrity: umac-128-etm@openssh.com @2905 620s 07:18:14.670880125 O: test integrity: umac-128-etm@openssh.com @2906 620s 07:18:14.800621661 O: test integrity: umac-128-etm@openssh.com @2907 620s 07:18:14.931947623 O: test integrity: umac-128-etm@openssh.com @2908 621s 07:18:15.069325850 O: test integrity: umac-128-etm@openssh.com @2909 621s 07:18:15.207514980 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 621s 07:18:15.223245687 O: test integrity: aes128-gcm@openssh.com @2900 621s 07:18:15.300068741 O: test integrity: aes128-gcm@openssh.com @2901 621s 07:18:15.383776506 O: test integrity: aes128-gcm@openssh.com @2902 621s 07:18:15.459888085 O: test integrity: aes128-gcm@openssh.com @2903 621s 07:18:15.536034730 O: test integrity: aes128-gcm@openssh.com @2904 621s 07:18:15.611856797 O: test integrity: aes128-gcm@openssh.com @2905 621s 07:18:15.685766755 O: test integrity: aes128-gcm@openssh.com @2906 621s 07:18:15.763119043 O: test integrity: aes128-gcm@openssh.com @2907 621s 07:18:15.840173826 O: test integrity: aes128-gcm@openssh.com @2908 621s 07:18:15.917290167 O: test integrity: aes128-gcm@openssh.com @2909 622s 07:18:15.984027517 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 622s 07:18:15.998467777 O: test integrity: aes256-gcm@openssh.com @2900 622s 07:18:16.071806125 O: test integrity: aes256-gcm@openssh.com @2901 622s 07:18:16.146121330 O: test integrity: aes256-gcm@openssh.com @2902 622s 07:18:16.222525455 O: test integrity: aes256-gcm@openssh.com @2903 622s 07:18:16.296312318 O: test integrity: aes256-gcm@openssh.com @2904 622s 07:18:16.372500085 O: test integrity: aes256-gcm@openssh.com @2905 622s 07:18:16.448395296 O: test integrity: aes256-gcm@openssh.com @2906 622s 07:18:16.521826131 O: test integrity: aes256-gcm@openssh.com @2907 622s 07:18:16.600496830 O: test integrity: aes256-gcm@openssh.com @2908 622s 07:18:16.677481037 O: test integrity: aes256-gcm@openssh.com @2909 622s 07:18:16.743301864 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 622s 07:18:16.757700140 O: test integrity: chacha20-poly1305@openssh.com @2900 622s 07:18:16.846479309 O: test integrity: chacha20-poly1305@openssh.com @2901 622s 07:18:16.930192459 O: test integrity: chacha20-poly1305@openssh.com @2902 623s 07:18:17.012935042 O: test integrity: chacha20-poly1305@openssh.com @2903 623s 07:18:17.099486707 O: test integrity: chacha20-poly1305@openssh.com @2904 623s 07:18:17.184561978 O: test integrity: chacha20-poly1305@openssh.com @2905 623s 07:18:17.260798312 O: test integrity: chacha20-poly1305@openssh.com @2906 623s 07:18:17.347631742 O: test integrity: chacha20-poly1305@openssh.com @2907 623s 07:18:17.426088609 O: test integrity: chacha20-poly1305@openssh.com @2908 623s 07:18:17.517142127 O: test integrity: chacha20-poly1305@openssh.com @2909 623s 07:18:17.588199309 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 623s 07:18:17.590583639 O: ok integrity 623s 07:18:17.591706149 E: run test krl.sh ... 623s 07:18:17.756530525 O: key revocation lists: generating test keys 624s 07:18:18.330166518 O: key revocation lists: generating KRLs 624s 07:18:18.409964881 O: key revocation lists: checking revocations for revoked keys 624s 07:18:18.826068161 O: key revocation lists: checking revocations for unrevoked keys 625s 07:18:19.241682148 O: key revocation lists: checking revocations for revoked certs 625s 07:18:19.856027388 O: key revocation lists: checking revocations for unrevoked certs 626s 07:18:20.458274268 O: key revocation lists: testing KRL update 627s 07:18:21.101499473 O: key revocation lists: checking revocations for revoked keys 627s 07:18:21.531319869 O: key revocation lists: checking revocations for unrevoked keys 627s 07:18:21.953262492 O: key revocation lists: checking revocations for revoked certs 628s 07:18:22.622496716 O: key revocation lists: checking revocations for unrevoked certs 629s 07:18:23.227471134 O: ok key revocation lists 629s 07:18:23.228498164 E: run test multipubkey.sh ... 630s 07:18:24.394778340 O: ok multiple pubkey 630s 07:18:24.395195323 E: run test limit-keytype.sh ... 631s 07:18:25.094778859 O: allow rsa,ed25519 631s 07:18:25.404169551 O: allow ed25519 631s 07:18:25.659462643 O: allow cert only 631s 07:18:25.938767408 O: match w/ no match 632s 07:18:26.207478041 O: match w/ matching 632s 07:18:26.519506242 O: ok restrict pubkey type 632s 07:18:26.520754347 E: run test hostkey-agent.sh ... 632s 07:18:26.805694665 O: key type ssh-ed25519 632s 07:18:26.910622632 O: key type sk-ssh-ed25519@openssh.com 633s 07:18:27.014635898 O: key type ecdsa-sha2-nistp256 633s 07:18:27.112115644 O: key type ecdsa-sha2-nistp384 633s 07:18:27.211371903 O: key type ecdsa-sha2-nistp521 633s 07:18:27.311818953 O: key type sk-ecdsa-sha2-nistp256@openssh.com 633s 07:18:27.417244111 O: key type ssh-rsa 633s 07:18:27.524660764 O: cert type ssh-ed25519-cert-v01@openssh.com 633s 07:18:27.651058982 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 633s 07:18:27.779711280 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 633s 07:18:27.903534930 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 634s 07:18:28.030598652 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 634s 07:18:28.158070088 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 634s 07:18:28.285513962 O: cert type ssh-rsa-cert-v01@openssh.com 634s 07:18:28.413255928 O: cert type rsa-sha2-256-cert-v01@openssh.com 634s 07:18:28.542935587 O: cert type rsa-sha2-512-cert-v01@openssh.com 634s 07:18:28.677626682 O: ok hostkey agent 634s 07:18:28.678424268 E: run test hostkey-rotate.sh ... 634s 07:18:28.929381699 O: learn hostkey with StrictHostKeyChecking=no 635s 07:18:29.307649573 O: learn additional hostkeys 635s 07:18:29.461784109 O: learn additional hostkeys, type=ssh-ed25519 635s 07:18:29.588417006 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 635s 07:18:29.720989205 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 635s 07:18:29.843800105 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 636s 07:18:29.971233443 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 636s 07:18:30.098088441 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 636s 07:18:30.240488241 O: learn additional hostkeys, type=ssh-rsa 636s 07:18:30.362110465 O: learn changed non-primary hostkey type=ssh-rsa 636s 07:18:30.669752204 O: learn new primary hostkey 636s 07:18:30.796980874 O: rotate primary hostkey 636s 07:18:30.921902276 O: check rotate primary hostkey 637s 07:18:31.036793478 O: ok hostkey rotate 637s 07:18:31.037138221 E: run test principals-command.sh ... 637s 07:18:31.256013609 O: SKIPPED: /var/run/principals_command_openssh-tests.85491 not executable (/var/run mounted noexec?) 637s 07:18:31.263036638 E: run test cert-file.sh ... 637s 07:18:31.437490113 O: identity cert with no plain public file 637s 07:18:31.560385799 O: CertificateFile with no plain public file 637s 07:18:31.668961450 O: plain keys 637s 07:18:31.768185373 O: untrusted cert 637s 07:18:31.864218148 O: good cert, bad key 638s 07:18:31.968890953 O: single trusted 638s 07:18:32.080798430 O: multiple trusted 638s 07:18:32.566075199 O: ok ssh with certificates 638s 07:18:32.566468917 E: run test cfginclude.sh ... 638s 07:18:32.696409452 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.704204082 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.711909528 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.718907825 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.726184979 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.733173628 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.740795805 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.747510445 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.755052819 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.771983876 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.779188580 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.786983569 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.798416655 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.805981481 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.813661640 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.821171570 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.828214433 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.835114241 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.842869664 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.850200762 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.857219267 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.874211913 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.882078149 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.889861105 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.902563070 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.911116828 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 638s 07:18:32.915948971 O: ok config include 638s 07:18:32.916900380 E: run test servcfginclude.sh ... 639s 07:18:33.250733394 O: ok server config include 639s 07:18:33.251085200 E: run test allow-deny-users.sh ... 640s 07:18:34.264085158 O: ok AllowUsers/DenyUsers 640s 07:18:34.265108976 E: run test authinfo.sh ... 640s 07:18:34.388579352 O: ExposeAuthInfo=no 640s 07:18:34.502786507 O: ExposeAuthInfo=yes 640s 07:18:34.617237289 O: ok authinfo 640s 07:18:34.619239006 E: run test sshsig.sh ... 640s 07:18:34.760932747 O: sshsig: make certificates 640s 07:18:34.789186156 O: sshsig: check signature for ssh-ed25519 641s 07:18:35.086245321 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 641s 07:18:35.439051454 O: sshsig: check signature for ecdsa-sha2-nistp256 641s 07:18:35.739225139 O: sshsig: check signature for ecdsa-sha2-nistp384 642s 07:18:36.074069845 O: sshsig: check signature for ecdsa-sha2-nistp521 642s 07:18:36.408712670 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 642s 07:18:36.764145862 O: sshsig: check signature for ssh-rsa 643s 07:18:37.076395055 O: sshsig: check signature for ssh-ed25519-cert.pub 643s 07:18:37.670823466 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 644s 07:18:38.264192273 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 644s 07:18:38.836588987 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 645s 07:18:39.467431869 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 646s 07:18:40.080305751 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 646s 07:18:40.642049935 O: sshsig: check signature for ssh-rsa-cert.pub 647s 07:18:41.189311939 O: sshsig: match principals 647s 07:18:41.207074931 O: sshsig: nomatch principals 647s 07:18:41.220973379 O: ok sshsig 647s 07:18:41.221990261 E: run test knownhosts.sh ... 648s 07:18:42.145301843 E: run test knownhosts-command.sh ... 648s 07:18:42.145918205 O: ok known hosts 648s 07:18:42.279433637 O: simple connection 648s 07:18:42.403323113 O: no keys 648s 07:18:42.460959892 O: bad exit status 648s 07:18:42.506482632 O: keytype ssh-ed25519 648s 07:18:42.625204736 O: keytype sk-ssh-ed25519@openssh.com 648s 07:18:42.747181473 O: keytype ecdsa-sha2-nistp256 648s 07:18:42.860802650 O: keytype ecdsa-sha2-nistp384 649s 07:18:42.973640175 O: keytype ecdsa-sha2-nistp521 649s 07:18:43.088663990 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 649s 07:18:43.204484943 O: keytype ssh-rsa 649s 07:18:43.318270664 O: ok known hosts command 649s 07:18:43.319022205 E: run test agent-restrict.sh ... 649s 07:18:43.449095455 O: generate keys 649s 07:18:43.508544177 O: prepare client config 649s 07:18:43.522522367 O: prepare known_hosts 649s 07:18:43.527693826 O: prepare server configs 649s 07:18:43.545469660 O: authentication w/o agent 650s 07:18:44.602301153 O: start agent 654s 07:18:48.607013396 O: authentication with agent (no restrict) 655s 07:18:49.720277165 O: unrestricted keylist 656s 07:18:50.291232117 O: authentication with agent (basic restrict) 656s 07:18:50.842840802 O: authentication with agent incorrect key (basic restrict) 657s 07:18:51.104729476 O: keylist (basic restrict) 657s 07:18:51.696677502 O: username 658s 07:18:52.263203171 O: username wildcard 658s 07:18:52.827007029 O: username incorrect 658s 07:18:52.897392462 O: agent restriction honours certificate principal 658s 07:18:52.932436182 O: multihop without agent 659s 07:18:53.538051183 O: multihop agent unrestricted 660s 07:18:54.153937645 O: multihop restricted 660s 07:18:54.792171836 O: multihop username 661s 07:18:55.387945149 O: multihop wildcard username 662s 07:18:55.990748262 O: multihop wrong username 662s 07:18:56.391751127 O: multihop cycle no agent 663s 07:18:57.257929627 O: multihop cycle agent unrestricted 664s 07:18:58.143915427 O: multihop cycle restricted deny 664s 07:18:58.451391384 O: multihop cycle restricted allow 665s 07:18:59.355441335 O: ok agent restrictions 665s 07:18:59.356826934 E: run test hostbased.sh ... 665s 07:18:59.479012305 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 665s 07:18:59.478930145 E: run test channel-timeout.sh ... 665s 07:18:59.607457993 O: no timeout 670s 07:19:04.719557992 O: command timeout 671s 07:19:05.569149523 O: command long timeout 671s 07:19:05.702727990 O: command wildcard timeout 672s 07:19:06.567752955 O: command irrelevant timeout 677s 07:19:11.689253812 O: multiplexed command timeout 688s 07:19:22.592510231 O: irrelevant multiplexed command timeout 703s 07:19:37.734394936 O: global command timeout 725s 07:19:59.595861227 O: sftp no timeout 730s 07:20:04.734607583 O: sftp timeout 731s 07:20:05.567688962 E: Connection closed 731s 07:20:05.569875756 O: sftp irrelevant timeout 736s 07:20:10.698834055 O: ok channel timeout 736s 07:20:10.699594810 E: run test connection-timeout.sh ... 736s 07:20:10.832684548 O: no timeout 741s 07:20:15.929186542 O: timeout 750s 07:20:24.010748335 O: session inhibits timeout 758s 07:20:32.131532315 O: timeout after session 766s 07:20:40.140195401 O: timeout with listeners 774s 07:20:48.551373904 O: ok unused connection timeout 774s 07:20:48.552983153 E: run test match-subsystem.sh ... 777s 07:20:49.790750696 O: ok sshd_config match subsystem 777s 07:20:49.790973974 E: run test agent-pkcs11-restrict.sh ... 777s 07:20:49.922516447 O: SKIPPED: No PKCS#11 library found 777s 07:20:49.922649562 E: run test agent-pkcs11-cert.sh ... 777s 07:20:50.055795024 O: SKIPPED: No PKCS#11 library found 777s 07:20:50.055894124 E: run test penalty.sh ... 777s 07:20:50.225727557 O: test connect 777s 07:20:50.441992270 O: penalty for authentication failure 783s 07:20:57.323142631 O: penalty for no authentication 785s 07:20:59.367868665 O: ok penalties 785s 07:20:59.368118133 E: run test penalty-expire.sh ... 785s 07:20:59.536452234 O: test connect 785s 07:20:59.748332963 O: penalty expiry 799s 07:21:13.163881549 O: ok penalties 799s 07:21:13.164935899 O: set -e ; if test -z "" ; then \ 799s 07:21:13.165947669 O: V="" ; \ 799s 07:21:13.166947626 O: test "x" = "x" || \ 799s 07:21:13.168035241 O: V=/tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 799s 07:21:13.169099441 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 799s 07:21:13.170159373 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 799s 07:21:13.171281565 O: -d /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 799s 07:21:13.172317801 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 799s 07:21:13.173385518 O: -d /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 799s 07:21:13.174446853 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 799s 07:21:13.175522606 O: -d /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 799s 07:21:13.176555496 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 799s 07:21:13.177575281 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 799s 07:21:13.178595046 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 799s 07:21:13.179650620 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 799s 07:21:13.180689853 O: -d /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 799s 07:21:13.181702964 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 799s 07:21:13.182718863 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 799s 07:21:13.183753666 O: if test "x" = "xyes" ; then \ 799s 07:21:13.184780134 O: $V /tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 799s 07:21:13.185760674 O: fi \ 799s 07:21:13.186752345 O: fi 804s 07:21:18.391071630 O: test_sshbuf: ...................................................................................................... 103 tests ok 837s 07:21:51.282304831 O: test_sshkey: .................................................................................... 84 tests ok 837s 07:21:51.290726637 O: test_sshsig: ....... 7 tests ok 837s 07:21:51.448656787 O: test_authopt: .................................................................................................................................................. 146 tests ok 845s 07:21:59.655262120 O: test_bitmap: .. 2 tests ok 845s 07:21:59.658473950 O: test_conversion: . 1 tests ok 848s 07:22:02.932158020 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 848s 07:22:02.963521842 O: test_hostkeys: .................. 18 tests ok 848s 07:22:02.966948201 O: test_match: ...... 6 tests ok 848s 07:22:02.969801022 O: test_misc: ........................................... 43 tests ok 848s 07:22:02.971257621 E: run test putty-transfer.sh ... 849s 07:22:03.443054160 O: plink version 0.82 major 0 minor 82 849s 07:22:03.452654208 O: putty transfer data: compression 0 850s 07:22:04.264355460 O: putty transfer data: compression 1 851s 07:22:05.103546211 O: ok putty transfer data 851s 07:22:05.104852371 E: run test putty-ciphers.sh ... 851s 07:22:05.816621137 O: plink version 0.82 major 0 minor 82 852s 07:22:06.114910227 O: putty ciphers: cipher default mac default 852s 07:22:06.221147920 O: putty ciphers: cipher default mac hmac-sha1 852s 07:22:06.324818029 O: putty ciphers: cipher default mac hmac-sha1-96 852s 07:22:06.451938146 O: putty ciphers: cipher default mac hmac-sha2-256 852s 07:22:06.566192856 O: putty ciphers: cipher default mac hmac-sha2-512 852s 07:22:06.678584359 O: putty ciphers: cipher default mac hmac-md5 852s 07:22:06.803654303 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 853s 07:22:06.919004239 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 853s 07:22:07.037211023 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 853s 07:22:07.150737183 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 853s 07:22:07.257538843 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 853s 07:22:07.371077678 O: putty ciphers: cipher 3des-cbc mac default 853s 07:22:07.475864211 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 853s 07:22:07.584519318 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 853s 07:22:07.694753116 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 853s 07:22:07.812672086 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 853s 07:22:07.929835449 O: putty ciphers: cipher 3des-cbc mac hmac-md5 854s 07:22:08.042214884 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 854s 07:22:08.154087026 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 854s 07:22:08.292119362 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 854s 07:22:08.415829513 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 854s 07:22:08.539079042 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 854s 07:22:08.685718974 O: putty ciphers: cipher aes128-cbc mac default 854s 07:22:08.789206145 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 854s 07:22:08.915401815 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 855s 07:22:09.031375836 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 855s 07:22:09.143242663 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 855s 07:22:09.253884686 O: putty ciphers: cipher aes128-cbc mac hmac-md5 855s 07:22:09.366978743 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 855s 07:22:09.478808430 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 855s 07:22:09.590629859 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 855s 07:22:09.696427829 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 855s 07:22:09.814839424 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 855s 07:22:09.940991435 O: putty ciphers: cipher aes192-cbc mac default 856s 07:22:10.076136207 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 856s 07:22:10.201173009 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 856s 07:22:10.303853709 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 856s 07:22:10.412746420 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 856s 07:22:10.518158890 O: putty ciphers: cipher aes192-cbc mac hmac-md5 856s 07:22:10.626400354 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 856s 07:22:10.737313451 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 856s 07:22:10.847094504 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 856s 07:22:10.951734484 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 857s 07:22:11.056356980 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 857s 07:22:11.167895291 O: putty ciphers: cipher aes256-cbc mac default 857s 07:22:11.272138307 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 857s 07:22:11.379893878 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 857s 07:22:11.486389277 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 857s 07:22:11.601810821 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 857s 07:22:11.710188662 O: putty ciphers: cipher aes256-cbc mac hmac-md5 857s 07:22:11.821419655 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 857s 07:22:11.933921151 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 858s 07:22:12.047938434 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 858s 07:22:12.153445498 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 858s 07:22:12.258179452 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 858s 07:22:12.369327622 O: putty ciphers: cipher aes128-ctr mac default 858s 07:22:12.473110646 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 858s 07:22:12.577126254 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 858s 07:22:12.679067392 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 858s 07:22:12.789197315 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 858s 07:22:12.892678600 O: putty ciphers: cipher aes128-ctr mac hmac-md5 859s 07:22:13.000325720 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 859s 07:22:13.111570622 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 859s 07:22:13.223219819 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 859s 07:22:13.325858378 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 859s 07:22:13.427855711 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 859s 07:22:13.535886882 O: putty ciphers: cipher aes192-ctr mac default 859s 07:22:13.631847410 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 859s 07:22:13.735573110 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 859s 07:22:13.835264078 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 859s 07:22:13.944749645 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 860s 07:22:14.047707764 O: putty ciphers: cipher aes192-ctr mac hmac-md5 860s 07:22:14.155801612 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 860s 07:22:14.265064106 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 860s 07:22:14.373945380 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 860s 07:22:14.475540048 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 860s 07:22:14.578968839 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 860s 07:22:14.687974000 O: putty ciphers: cipher aes256-ctr mac default 860s 07:22:14.790181712 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 860s 07:22:14.893068424 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 861s 07:22:14.997440788 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 861s 07:22:15.105246201 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 861s 07:22:15.210549615 O: putty ciphers: cipher aes256-ctr mac hmac-md5 861s 07:22:15.317561297 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 861s 07:22:15.425686652 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 861s 07:22:15.532953765 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 861s 07:22:15.633481828 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 861s 07:22:15.737914315 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 861s 07:22:15.843262204 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 861s 07:22:15.947022855 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 862s 07:22:16.053048079 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 862s 07:22:16.149221906 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 862s 07:22:16.258825888 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 862s 07:22:16.363993618 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 862s 07:22:16.473040694 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 862s 07:22:16.582595741 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 862s 07:22:16.695943748 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 862s 07:22:16.801445519 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 863s 07:22:16.907174313 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 863s 07:22:17.024727929 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 863s 07:22:17.149692175 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 863s 07:22:17.274301691 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 863s 07:22:17.377419205 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 863s 07:22:17.486975683 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 863s 07:22:17.589764728 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 863s 07:22:17.699709438 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 863s 07:22:17.808863064 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 863s 07:22:17.918553637 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 864s 07:22:18.013572501 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 864s 07:22:18.119715692 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 864s 07:22:18.227009216 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 864s 07:22:18.333694374 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 864s 07:22:18.441557798 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 864s 07:22:18.545112966 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 864s 07:22:18.661065611 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 864s 07:22:18.767590954 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 864s 07:22:18.876562088 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 865s 07:22:18.986899867 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 865s 07:22:19.095250350 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 865s 07:22:19.196639490 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 865s 07:22:19.299550292 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 865s 07:22:19.413986582 O: ok putty ciphers 865s 07:22:19.414405103 E: run test putty-kex.sh ... 866s 07:22:19.991361419 O: plink version 0.82 major 0 minor 82 866s 07:22:20.161828828 O: putty KEX: kex diffie-hellman-group1-sha1 866s 07:22:20.245179965 O: putty KEX: kex diffie-hellman-group14-sha1 866s 07:22:20.340550333 O: putty KEX: kex diffie-hellman-group14-sha256 866s 07:22:20.435743162 O: putty KEX: kex diffie-hellman-group16-sha512 866s 07:22:20.624595264 O: putty KEX: kex diffie-hellman-group18-sha512 867s 07:22:21.516025164 O: putty KEX: kex diffie-hellman-group-exchange-sha1 867s 07:22:21.612069600 O: putty KEX: kex diffie-hellman-group-exchange-sha256 867s 07:22:21.817820238 O: putty KEX: kex ecdh-sha2-nistp256 867s 07:22:21.899306171 O: putty KEX: kex ecdh-sha2-nistp384 868s 07:22:21.981026735 O: putty KEX: kex ecdh-sha2-nistp521 868s 07:22:22.067636103 O: putty KEX: kex curve25519-sha256 868s 07:22:22.148227332 O: putty KEX: kex curve25519-sha256@libssh.org 868s 07:22:22.231416924 O: putty KEX: kex sntrup761x25519-sha512 868s 07:22:22.328711240 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 868s 07:22:22.426756602 O: ok putty KEX 868s 07:22:22.427671683 E: run test conch-ciphers.sh ... 868s 07:22:22.590303977 O: conch ciphers: cipher aes256-ctr 869s 07:22:23.603282418 O: conch ciphers: cipher aes256-cbc 870s 07:22:24.418100566 O: conch ciphers: cipher aes192-ctr 871s 07:22:25.082961693 O: conch ciphers: cipher aes192-cbc 871s 07:22:25.752478282 O: conch ciphers: cipher aes128-ctr 872s 07:22:26.456660323 O: conch ciphers: cipher aes128-cbc 873s 07:22:27.175774550 O: conch ciphers: cipher cast128-cbc 873s 07:22:27.849732370 O: conch ciphers: cipher blowfish 874s 07:22:28.545605597 O: conch ciphers: cipher 3des-cbc 875s 07:22:29.258823304 O: ok conch ciphers 875s 07:22:29.259105183 E: run test dropbear-ciphers.sh ... 875s 07:22:29.407248783 O: Create dropbear key type ed25519 875s 07:22:29.418884899 O: Create dropbear key type rsa 875s 07:22:29.729721619 O: Create dropbear key type ecdsa 875s 07:22:29.774106673 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 875s 07:22:29.970473088 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 876s 07:22:30.158517187 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 876s 07:22:30.353571356 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 876s 07:22:30.546185138 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 876s 07:22:30.742520600 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 876s 07:22:30.935147406 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 877s 07:22:31.125233045 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 877s 07:22:31.318300049 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 877s 07:22:31.510258101 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 877s 07:22:31.700082699 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 877s 07:22:31.889817888 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 878s 07:22:32.080057579 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 878s 07:22:32.278366648 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 878s 07:22:32.469726641 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 878s 07:22:32.661437975 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 878s 07:22:32.858302020 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 879s 07:22:33.056726258 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 879s 07:22:33.276877948 O: ok dropbear ciphers 879s 07:22:33.277939749 E: run test dropbear-kex.sh ... 879s 07:22:33.455418978 O: diffie-hellman-group14-sha1 879s 07:22:33.456545012 O: dropbear kex: kex curve25519-sha256 879s 07:22:33.641954463 O: dropbear kex: kex curve25519-sha256@libssh.org 879s 07:22:33.827326835 O: dropbear kex: kex diffie-hellman-group14-sha256 880s 07:22:34.029777764 O: dropbear kex: kex diffie-hellman-group14-sha1 880s 07:22:34.223050968 O: ok dropbear kex 880s 07:22:34.224181762 O: make: Leaving directory '/tmp/autopkgtest.yFj6c5/autopkgtest_tmp/user/regress' 880s 07:22:34.225819235 I: Finished with exitcode 0 880s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 880s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 880s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 880s info: Looking for files to backup/remove ... 880s info: Removing files ... 880s info: Removing crontab ... 880s info: Removing user `openssh-tests' ... 881s autopkgtest [07:22:35]: test regress: -----------------------] 882s regress PASS 882s autopkgtest [07:22:36]: test regress: - - - - - - - - - - results - - - - - - - - - - 882s autopkgtest [07:22:36]: test ssh-gssapi: preparing testbed 964s autopkgtest [07:23:58]: testbed dpkg architecture: amd64 964s autopkgtest [07:23:58]: testbed apt version: 2.9.18 964s autopkgtest [07:23:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 965s autopkgtest [07:23:59]: testbed release detected to be: plucky 965s autopkgtest [07:23:59]: updating testbed package index (apt update) 966s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 966s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 966s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 966s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 966s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [820 kB] 967s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 967s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [145 kB] 967s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 967s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [269 kB] 967s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [182 kB] 967s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 967s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 967s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [365 kB] 967s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [891 kB] 967s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [24.6 kB] 967s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [4116 B] 967s Fetched 2841 kB in 1s (2182 kB/s) 968s Reading package lists... 968s Reading package lists... 968s Building dependency tree... 968s Reading state information... 968s Calculating upgrade... 969s The following packages will be upgraded: 969s netcat-openbsd python3-more-itertools 969s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 969s Need to get 103 kB of archives. 969s After this operation, 13.3 kB of additional disk space will be used. 969s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 netcat-openbsd amd64 1.228-1 [44.9 kB] 969s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-more-itertools all 10.6.0-1 [57.7 kB] 970s Fetched 103 kB in 1s (118 kB/s) 970s (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 ... 89449 files and directories currently installed.) 970s Preparing to unpack .../netcat-openbsd_1.228-1_amd64.deb ... 970s Unpacking netcat-openbsd (1.228-1) over (1.226-1.1) ... 970s Preparing to unpack .../python3-more-itertools_10.6.0-1_all.deb ... 970s Unpacking python3-more-itertools (10.6.0-1) over (10.5.0-1) ... 970s Setting up python3-more-itertools (10.6.0-1) ... 970s Setting up netcat-openbsd (1.228-1) ... 970s Processing triggers for man-db (2.13.0-1) ... 971s 971s Running kernel seems to be up-to-date. 971s 971s No services need to be restarted. 971s 971s No containers need to be restarted. 971s 971s No user sessions are running outdated binaries. 971s 971s No VM guests are running outdated hypervisor (qemu) binaries on this host. 972s Reading package lists... 972s Building dependency tree... 972s Reading state information... 972s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 972s autopkgtest [07:24:06]: upgrading testbed (apt dist-upgrade and autopurge) 973s Reading package lists... 973s Building dependency tree... 973s Reading state information... 973s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 973s Starting 2 pkgProblemResolver with broken count: 0 973s Done 973s Entering ResolveByKeep 974s 974s The following packages will be upgraded: 974s libedit2 974s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 974s Need to get 110 kB of archives. 974s After this operation, 0 B of additional disk space will be used. 974s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libedit2 amd64 3.1-20250104-1 [110 kB] 975s Fetched 110 kB in 1s (135 kB/s) 975s (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 ... 89449 files and directories currently installed.) 975s Preparing to unpack .../libedit2_3.1-20250104-1_amd64.deb ... 975s Unpacking libedit2:amd64 (3.1-20250104-1) over (3.1-20240808-1) ... 975s Setting up libedit2:amd64 (3.1-20250104-1) ... 975s Processing triggers for man-db (2.13.0-1) ... 975s Processing triggers for libc-bin (2.40-4ubuntu1) ... 976s 976s Running kernel seems to be up-to-date. 976s 976s No services need to be restarted. 976s 976s No containers need to be restarted. 976s 976s No user sessions are running outdated binaries. 976s 976s No VM guests are running outdated hypervisor (qemu) binaries on this host. 977s Reading package lists... 977s Building dependency tree... 977s Reading state information... 977s Starting pkgProblemResolver with broken count: 0 977s Starting 2 pkgProblemResolver with broken count: 0 977s Done 977s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 980s Reading package lists... 980s Building dependency tree... 980s Reading state information... 980s Starting pkgProblemResolver with broken count: 0 980s Starting 2 pkgProblemResolver with broken count: 0 980s Done 981s The following NEW packages will be installed: 981s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 981s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 981s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 981s openssh-server-gssapi 981s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 981s Need to get 778 kB of archives. 981s After this operation, 2746 kB of additional disk space will be used. 981s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 krb5-config all 2.7 [22.0 kB] 981s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 libgssrpc4t64 amd64 1.21.3-3 [57.5 kB] 981s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 libkadm5clnt-mit12 amd64 1.21.3-3 [40.0 kB] 981s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 libkdb5-10t64 amd64 1.21.3-3 [40.6 kB] 981s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libkadm5srv-mit12 amd64 1.21.3-3 [52.8 kB] 981s Get:6 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-user amd64 1.21.3-3 [109 kB] 981s Get:7 http://ftpmaster.internal/ubuntu plucky/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB] 981s Get:8 http://ftpmaster.internal/ubuntu plucky/main amd64 libverto1t64 amd64 0.3.1-1.2ubuntu3 [10.5 kB] 981s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 libverto-libevent1t64 amd64 0.3.1-1.2ubuntu3 [6424 B] 981s Get:10 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-kdc amd64 1.21.3-3 [189 kB] 981s Get:11 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-admin-server amd64 1.21.3-3 [95.7 kB] 981s Get:12 http://ftpmaster.internal/ubuntu plucky/main amd64 openssh-client-gssapi all 1:9.9p1-3ubuntu2 [5028 B] 981s Get:13 http://ftpmaster.internal/ubuntu plucky/main amd64 openssh-server-gssapi all 1:9.9p1-3ubuntu2 [5036 B] 981s Preconfiguring packages ... 981s Fetched 778 kB in 1s (1347 kB/s) 981s Selecting previously unselected package krb5-config. 982s (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 ... 89449 files and directories currently installed.) 982s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 982s Unpacking krb5-config (2.7) ... 982s Selecting previously unselected package libgssrpc4t64:amd64. 982s Preparing to unpack .../01-libgssrpc4t64_1.21.3-3_amd64.deb ... 982s Unpacking libgssrpc4t64:amd64 (1.21.3-3) ... 982s Selecting previously unselected package libkadm5clnt-mit12:amd64. 982s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_amd64.deb ... 982s Unpacking libkadm5clnt-mit12:amd64 (1.21.3-3) ... 982s Selecting previously unselected package libkdb5-10t64:amd64. 982s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_amd64.deb ... 982s Unpacking libkdb5-10t64:amd64 (1.21.3-3) ... 982s Selecting previously unselected package libkadm5srv-mit12:amd64. 982s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_amd64.deb ... 982s Unpacking libkadm5srv-mit12:amd64 (1.21.3-3) ... 982s Selecting previously unselected package krb5-user. 982s Preparing to unpack .../05-krb5-user_1.21.3-3_amd64.deb ... 982s Unpacking krb5-user (1.21.3-3) ... 982s Selecting previously unselected package libevent-2.1-7t64:amd64. 982s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ... 982s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 982s Selecting previously unselected package libverto1t64:amd64. 982s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_amd64.deb ... 982s Unpacking libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 982s Selecting previously unselected package libverto-libevent1t64:amd64. 982s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_amd64.deb ... 982s Unpacking libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 982s Selecting previously unselected package krb5-kdc. 982s Preparing to unpack .../09-krb5-kdc_1.21.3-3_amd64.deb ... 982s Unpacking krb5-kdc (1.21.3-3) ... 982s Selecting previously unselected package krb5-admin-server. 982s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_amd64.deb ... 982s Unpacking krb5-admin-server (1.21.3-3) ... 982s Selecting previously unselected package openssh-client-gssapi. 982s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 982s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 982s Selecting previously unselected package openssh-server-gssapi. 982s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu2_all.deb ... 982s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 982s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 982s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu2) ... 982s Setting up libgssrpc4t64:amd64 (1.21.3-3) ... 982s Setting up krb5-config (2.7) ... 982s Setting up libkadm5clnt-mit12:amd64 (1.21.3-3) ... 982s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu2) ... 982s Setting up libkdb5-10t64:amd64 (1.21.3-3) ... 982s Setting up libkadm5srv-mit12:amd64 (1.21.3-3) ... 982s Setting up krb5-user (1.21.3-3) ... 982s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 982s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 982s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 982s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 982s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 982s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 982s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 982s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 982s Setting up libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 982s Setting up libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 982s Setting up krb5-kdc (1.21.3-3) ... 982s invoke-rc.d: policy-rc.d denied execution of start. 982s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 983s /usr/sbin/policy-rc.d returned 101, not running 'start krb5-kdc.service' 983s Setting up krb5-admin-server (1.21.3-3) ... 983s invoke-rc.d: policy-rc.d denied execution of start. 983s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 983s /usr/sbin/policy-rc.d returned 101, not running 'start krb5-admin-server.service' 983s Processing triggers for libc-bin (2.40-4ubuntu1) ... 983s Processing triggers for man-db (2.13.0-1) ... 984s 984s Running kernel seems to be up-to-date. 984s 984s No services need to be restarted. 984s 984s No containers need to be restarted. 984s 984s No user sessions are running outdated binaries. 984s 984s No VM guests are running outdated hypervisor (qemu) binaries on this host. 989s autopkgtest [07:24:23]: test ssh-gssapi: [----------------------- 989s Generating public/private ed25519 key pair. 989s Your identification has been saved in /root/.ssh/id_ed25519 989s Your public key has been saved in /root/.ssh/id_ed25519.pub 989s The key fingerprint is: 989s SHA256:3q2hTy0LLRB3IitXSz5hrl33UBrTbyZl1v9DOnTovtg root@autopkgtest 989s The key's randomart image is: 989s +--[ED25519 256]--+ 989s | | 989s | . .| 989s | o B . o o =| 989s | @ = =.=.| 989s | . + S . +o.o=| 989s | o = = +oo++.| 989s | . = * o+...| 989s | = =+ . .| 989s | ..+. E. | 989s +----[SHA256]-----+ 989s ## Setting up test environment 989s ## Creating Kerberos realm EXAMPLE.FAKE 989s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 989s master key name 'K/M@EXAMPLE.FAKE' 989s ## Creating principals 989s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 989s Principal "testuser2508@EXAMPLE.FAKE" created. 989s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 989s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 989s ## Extracting service principal host/sshd-gssapi.example.fake 989s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 989s 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. 989s 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. 989s ## Adjusting /etc/krb5.conf 989s ## TESTS 989s 989s ## TEST test_gssapi_login 989s ## Configuring sshd for gssapi-with-mic authentication 989s ## Restarting ssh 989s ## Obtaining TGT 989s Password for testuser2508@EXAMPLE.FAKE: 989s Ticket cache: FILE:/tmp/krb5cc_0 989s Default principal: testuser2508@EXAMPLE.FAKE 989s 989s Valid starting Expires Service principal 989s 01/20/25 07:24:23 01/20/25 17:24:23 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 989s renew until 01/21/25 07:24:23 989s 989s ## ssh'ing into localhost using gssapi-with-mic auth 989s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 990s Mon Jan 20 07:24:24 UTC 2025 990s 990s ## checking that we got a service ticket for ssh (host/) 990s 01/20/25 07:24:23 01/20/25 17:24:23 host/sshd-gssapi.example.fake@ 990s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 990s 990s ## Checking ssh logs to confirm gssapi-with-mic auth was used 990s Jan 20 07:24:23 sshd-gssapi.example.fake sshd-session[2611]: Accepted gssapi-with-mic for testuser2508 from 127.0.0.1 port 55040 ssh2: testuser2508@EXAMPLE.FAKE 990s ## PASS test_gssapi_login 990s 990s ## TEST test_gssapi_keyex_login 990s ## Configuring sshd for gssapi-keyex authentication 990s ## Restarting ssh 990s ## Obtaining TGT 990s Password for testuser2508@EXAMPLE.FAKE: 990s Ticket cache: FILE:/tmp/krb5cc_0 990s Default principal: testuser2508@EXAMPLE.FAKE 990s 990s Valid starting Expires Service principal 990s 01/20/25 07:24:24 01/20/25 17:24:24 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 990s renew until 01/21/25 07:24:24 990s 990s ## ssh'ing into localhost using gssapi-keyex auth 990s Mon Jan 20 07:24:24 UTC 2025 990s 990s ## checking that we got a service ticket for ssh (host/) 990s 01/20/25 07:24:24 01/20/25 17:24:24 host/sshd-gssapi.example.fake@ 990s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 990s 990s ## Checking ssh logs to confirm gssapi-keyex auth was used 990s Jan 20 07:24:24 sshd-gssapi.example.fake sshd-session[2691]: Accepted gssapi-keyex for testuser2508 from 127.0.0.1 port 55056 ssh2: testuser2508@EXAMPLE.FAKE 990s ## PASS test_gssapi_keyex_login 990s 990s ## TEST test_gssapi_keyex_pubkey_fallback 990s ## Configuring sshd for gssapi-keyex authentication 990s ## Restarting ssh 990s ## Obtaining TGT 990s Password for testuser2508@EXAMPLE.FAKE: 990s Ticket cache: FILE:/tmp/krb5cc_0 990s Default principal: testuser2508@EXAMPLE.FAKE 990s 990s Valid starting Expires Service principal 990s 01/20/25 07:24:24 01/20/25 17:24:24 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 990s renew until 01/21/25 07:24:24 990s 990s ## ssh'ing into localhost using gssapi-keyex auth 991s Mon Jan 20 07:24:24 UTC 2025 991s 991s ## checking that we got a service ticket for ssh (host/) 991s 01/20/25 07:24:24 01/20/25 17:24:24 host/sshd-gssapi.example.fake@ 991s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 991s 991s ## Checking ssh logs to confirm publickey auth was used 991s Jan 20 07:24:24 sshd-gssapi.example.fake sshd-session[2757]: Accepted publickey for testuser2508-2 from 127.0.0.1 port 55062 ssh2: ED25519 SHA256:3q2hTy0LLRB3IitXSz5hrl33UBrTbyZl1v9DOnTovtg 991s ## PASS test_gssapi_keyex_pubkey_fallback 991s 991s ## ALL TESTS PASSED 991s ## Cleaning up 991s autopkgtest [07:24:25]: test ssh-gssapi: -----------------------] 991s ssh-gssapi PASS 991s autopkgtest [07:24:25]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 992s autopkgtest [07:24:26]: test socket-activation: preparing testbed 1485s autopkgtest [07:32:39]: testbed dpkg architecture: amd64 1485s autopkgtest [07:32:39]: testbed apt version: 2.9.18 1486s autopkgtest [07:32:40]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1486s autopkgtest [07:32:40]: testbed release detected to be: plucky 1486s autopkgtest [07:32:40]: updating testbed package index (apt update) 1487s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1487s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1487s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1487s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1487s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 1487s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [145 kB] 1487s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [820 kB] 1487s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 1487s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [269 kB] 1488s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [182 kB] 1488s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 1488s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 1488s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [891 kB] 1488s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [365 kB] 1488s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [4116 B] 1488s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [24.6 kB] 1488s Fetched 2841 kB in 1s (2845 kB/s) 1488s Reading package lists... 1489s Reading package lists... 1489s Building dependency tree... 1489s Reading state information... 1489s Calculating upgrade... 1490s The following packages will be upgraded: 1490s netcat-openbsd python3-more-itertools 1490s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1490s Need to get 103 kB of archives. 1490s After this operation, 13.3 kB of additional disk space will be used. 1490s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 netcat-openbsd amd64 1.228-1 [44.9 kB] 1490s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-more-itertools all 10.6.0-1 [57.7 kB] 1490s Fetched 103 kB in 0s (299 kB/s) 1490s (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 ... 89449 files and directories currently installed.) 1490s Preparing to unpack .../netcat-openbsd_1.228-1_amd64.deb ... 1490s Unpacking netcat-openbsd (1.228-1) over (1.226-1.1) ... 1491s Preparing to unpack .../python3-more-itertools_10.6.0-1_all.deb ... 1491s Unpacking python3-more-itertools (10.6.0-1) over (10.5.0-1) ... 1491s Setting up python3-more-itertools (10.6.0-1) ... 1491s Setting up netcat-openbsd (1.228-1) ... 1491s Processing triggers for man-db (2.13.0-1) ... 1492s 1492s Running kernel seems to be up-to-date. 1492s 1492s No services need to be restarted. 1492s 1492s No containers need to be restarted. 1492s 1492s No user sessions are running outdated binaries. 1492s 1492s No VM guests are running outdated hypervisor (qemu) binaries on this host. 1492s Reading package lists... 1493s Building dependency tree... 1493s Reading state information... 1493s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 1493s autopkgtest [07:32:47]: upgrading testbed (apt dist-upgrade and autopurge) 1493s Reading package lists... 1493s Building dependency tree... 1493s Reading state information... 1494s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 1494s Starting 2 pkgProblemResolver with broken count: 0 1494s Done 1494s Entering ResolveByKeep 1494s 1495s The following packages will be upgraded: 1495s libedit2 1495s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1495s Need to get 110 kB of archives. 1495s After this operation, 0 B of additional disk space will be used. 1495s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libedit2 amd64 3.1-20250104-1 [110 kB] 1495s Fetched 110 kB in 0s (315 kB/s) 1495s (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 ... 89449 files and directories currently installed.) 1495s Preparing to unpack .../libedit2_3.1-20250104-1_amd64.deb ... 1495s Unpacking libedit2:amd64 (3.1-20250104-1) over (3.1-20240808-1) ... 1495s Setting up libedit2:amd64 (3.1-20250104-1) ... 1495s Processing triggers for man-db (2.13.0-1) ... 1495s Processing triggers for libc-bin (2.40-4ubuntu1) ... 1496s 1496s Running kernel seems to be up-to-date. 1496s 1496s No services need to be restarted. 1496s 1496s No containers need to be restarted. 1496s 1496s No user sessions are running outdated binaries. 1496s 1496s No VM guests are running outdated hypervisor (qemu) binaries on this host. 1497s Reading package lists... 1497s Building dependency tree... 1497s Reading state information... 1497s Starting pkgProblemResolver with broken count: 0 1497s Starting 2 pkgProblemResolver with broken count: 0 1497s Done 1497s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1500s Reading package lists... 1500s Building dependency tree... 1500s Reading state information... 1500s Starting pkgProblemResolver with broken count: 0 1500s Starting 2 pkgProblemResolver with broken count: 0 1500s Done 1501s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1503s autopkgtest [07:32:57]: test socket-activation: [----------------------- 1503s Generating public/private ed25519 key pair. 1503s Your identification has been saved in /home/testuser2036/.ssh/id_ed25519 1503s Your public key has been saved in /home/testuser2036/.ssh/id_ed25519.pub 1503s The key fingerprint is: 1503s SHA256:BkMxbkeXDLaYEVIE86sPyc3FIrH8UcF/6Aa6CbUokC4 testuser2036@autopkgtest 1503s The key's randomart image is: 1503s +--[ED25519 256]--+ 1503s | ++O+=o.. | 1503s | * B.+o | 1503s | . . O +. . | 1503s |o . +.B. o . | 1503s |.. +o+oSo . | 1503s |E...oBo= o | 1503s |. .=.+o . | 1503s | oo | 1503s | . | 1503s +----[SHA256]-----+ 1503s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1503s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1504s Disabling 'ssh.service', but its triggering units are still active: 1504s ssh.socket 1504s Stopping 'ssh.service', but its triggering units are still active: 1504s ssh.socket 1504s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1505s Mon Jan 20 07:32:59 UTC 2025 1505s autopkgtest [07:32:59]: test socket-activation: -----------------------] 1505s autopkgtest [07:32:59]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1505s socket-activation PASS 1506s autopkgtest [07:33:00]: test xinetd: preparing testbed 1506s Reading package lists... 1506s Building dependency tree... 1506s Reading state information... 1506s Starting pkgProblemResolver with broken count: 0 1506s Starting 2 pkgProblemResolver with broken count: 0 1506s Done 1507s The following NEW packages will be installed: 1507s xinetd 1507s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1507s Need to get 115 kB of archives. 1507s After this operation, 312 kB of additional disk space will be used. 1507s Get:1 http://ftpmaster.internal/ubuntu plucky/universe amd64 xinetd amd64 1:2.3.15.4-4 [115 kB] 1507s Fetched 115 kB in 0s (329 kB/s) 1507s Selecting previously unselected package xinetd. 1507s (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 ... 89449 files and directories currently installed.) 1507s Preparing to unpack .../xinetd_1%3a2.3.15.4-4_amd64.deb ... 1507s Unpacking xinetd (1:2.3.15.4-4) ... 1507s Setting up xinetd (1:2.3.15.4-4) ... 1508s invoke-rc.d: policy-rc.d denied execution of start. 1508s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1508s /usr/sbin/policy-rc.d returned 101, not running 'start xinetd.service' 1508s Processing triggers for man-db (2.13.0-1) ... 1509s 1509s Running kernel seems to be up-to-date. 1509s 1509s No services need to be restarted. 1509s 1509s No containers need to be restarted. 1509s 1509s No user sessions are running outdated binaries. 1509s 1509s No VM guests are running outdated hypervisor (qemu) binaries on this host. 1511s autopkgtest [07:33:05]: test xinetd: [----------------------- 1511s Generating public/private ed25519 key pair. 1511s Your identification has been saved in /home/testuser2659/.ssh/id_ed25519 1511s Your public key has been saved in /home/testuser2659/.ssh/id_ed25519.pub 1511s The key fingerprint is: 1511s SHA256:UB9DhT+yckQpNAjP7lgxPyJqGKCbsIQCPImtmrY+pEs testuser2659@autopkgtest 1511s The key's randomart image is: 1511s +--[ED25519 256]--+ 1511s | .. o+.++. | 1511s |o.. o...o+o | 1511s |++. * o.. | 1511s |=.. . = o o | 1511s |B. . + S. o . | 1511s |=O . = ...o | 1511s |XEo . . o | 1511s |+o. | 1511s |o+. | 1511s +----[SHA256]-----+ 1511s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1511s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1512s Disabling 'ssh.service', but its triggering units are still active: 1512s ssh.socket 1512s Stopping 'ssh.service', but its triggering units are still active: 1512s ssh.socket 1512s xinetd.service is not active, cannot reload. 1512s ## Something failed 1512s 1512s ## ssh server log 1512s Jan 20 07:32:28 autopkgtest systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... 1512s Jan 20 07:32:28 autopkgtest sshd[882]: Server listening on 0.0.0.0 port 22. 1512s Jan 20 07:32:28 autopkgtest sshd[882]: Server listening on :: port 22. 1512s Jan 20 07:32:28 autopkgtest systemd[1]: Started ssh.service - OpenBSD Secure Shell server. 1512s Jan 20 07:32:32 autopkgtest sshd-session[951]: Accepted publickey for ubuntu from 10.136.6.18 port 47852 ssh2: RSA SHA256:6DXgQITpIEY2ww0kj/cCYqLG9/Vc7T/fK1SLaZfrmF4 1512s Jan 20 07:32:32 autopkgtest sshd-session[951]: pam_unix(sshd:session): session opened for user ubuntu(uid=1000) by ubuntu(uid=0) 1512s Jan 20 07:32:33 autopkgtest sshd-session[951]: pam_unix(sshd:session): session closed for user ubuntu 1512s Jan 20 07:32:34 autopkgtest sshd-session[1079]: Accepted publickey for ubuntu from 10.136.6.18 port 47860 ssh2: RSA SHA256:6DXgQITpIEY2ww0kj/cCYqLG9/Vc7T/fK1SLaZfrmF4 1512s Jan 20 07:32:34 autopkgtest sshd-session[1079]: pam_unix(sshd:session): session opened for user ubuntu(uid=1000) by ubuntu(uid=0) 1512s Jan 20 07:32:37 autopkgtest sshd-session[1199]: Accepted publickey for ubuntu from 10.136.6.18 port 47862 ssh2: RSA SHA256:6DXgQITpIEY2ww0kj/cCYqLG9/Vc7T/fK1SLaZfrmF4 1512s Jan 20 07:32:37 autopkgtest sshd-session[1199]: pam_unix(sshd:session): session opened for user ubuntu(uid=1000) by ubuntu(uid=0) 1512s Jan 20 07:32:58 autopkgtest systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... 1512s Jan 20 07:32:58 autopkgtest sshd[882]: Received signal 15; terminating. 1512s Jan 20 07:32:58 autopkgtest systemd[1]: ssh.service: Deactivated successfully. 1512s Jan 20 07:32:58 autopkgtest systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. 1512s Jan 20 07:32:58 autopkgtest systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... 1512s Jan 20 07:32:58 autopkgtest sshd[2249]: Server listening on 0.0.0.0 port 22. 1512s Jan 20 07:32:58 autopkgtest sshd[2249]: Server listening on :: port 22. 1512s Jan 20 07:32:58 autopkgtest systemd[1]: Started ssh.service - OpenBSD Secure Shell server. 1512s Jan 20 07:32:58 autopkgtest sshd-session[2252]: Accepted publickey for testuser2036 from ::1 port 37346 ssh2: ED25519 SHA256:BkMxbkeXDLaYEVIE86sPyc3FIrH8UcF/6Aa6CbUokC4 1512s Jan 20 07:32:58 autopkgtest sshd-session[2252]: pam_unix(sshd:session): session opened for user testuser2036(uid=1001) by testuser2036(uid=0) 1512s Jan 20 07:32:59 autopkgtest sshd-session[2252]: pam_unix(sshd:session): session closed for user testuser2036 1512s Jan 20 07:33:06 autopkgtest systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... 1512s Jan 20 07:33:06 autopkgtest sshd[2249]: Received signal 15; terminating. 1512s Jan 20 07:33:06 autopkgtest systemd[1]: ssh.service: Deactivated successfully. 1512s Jan 20 07:33:06 autopkgtest systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. 1512s autopkgtest [07:33:06]: test xinetd: -----------------------] 1513s xinetd FAIL non-zero exit status 1 1513s autopkgtest [07:33:07]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1513s autopkgtest [07:33:07]: test systemd-socket-activation: preparing testbed 2149s autopkgtest [07:43:43]: testbed dpkg architecture: amd64 2149s autopkgtest [07:43:43]: testbed apt version: 2.9.18 2149s autopkgtest [07:43:43]: @@@@@@@@@@@@@@@@@@@@ test bed setup 2150s autopkgtest [07:43:44]: testbed release detected to be: plucky 2150s autopkgtest [07:43:44]: updating testbed package index (apt update) 2151s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 2151s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 2151s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 2151s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 2151s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 2151s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [820 kB] 2151s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [145 kB] 2151s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 2151s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [182 kB] 2151s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [269 kB] 2151s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 2151s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 2151s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [891 kB] 2151s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [365 kB] 2151s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [24.6 kB] 2151s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [4116 B] 2152s Fetched 2841 kB in 1s (2818 kB/s) 2153s Reading package lists... 2153s Reading package lists... 2154s Building dependency tree... 2154s Reading state information... 2154s Calculating upgrade... 2154s The following packages will be upgraded: 2154s netcat-openbsd python3-more-itertools 2154s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2154s Need to get 103 kB of archives. 2154s After this operation, 13.3 kB of additional disk space will be used. 2154s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 netcat-openbsd amd64 1.228-1 [44.9 kB] 2155s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-more-itertools all 10.6.0-1 [57.7 kB] 2155s Fetched 103 kB in 0s (288 kB/s) 2155s (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 ... 89449 files and directories currently installed.) 2155s Preparing to unpack .../netcat-openbsd_1.228-1_amd64.deb ... 2155s Unpacking netcat-openbsd (1.228-1) over (1.226-1.1) ... 2155s Preparing to unpack .../python3-more-itertools_10.6.0-1_all.deb ... 2155s Unpacking python3-more-itertools (10.6.0-1) over (10.5.0-1) ... 2155s Setting up python3-more-itertools (10.6.0-1) ... 2155s Setting up netcat-openbsd (1.228-1) ... 2155s Processing triggers for man-db (2.13.0-1) ... 2157s 2157s Running kernel seems to be up-to-date. 2157s 2157s No services need to be restarted. 2157s 2157s No containers need to be restarted. 2157s 2157s No user sessions are running outdated binaries. 2157s 2157s No VM guests are running outdated hypervisor (qemu) binaries on this host. 2158s Reading package lists... 2159s Building dependency tree... 2159s Reading state information... 2159s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 2159s autopkgtest [07:43:53]: upgrading testbed (apt dist-upgrade and autopurge) 2159s Reading package lists... 2159s Building dependency tree... 2159s Reading state information... 2160s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 2160s Starting 2 pkgProblemResolver with broken count: 0 2160s Done 2161s Entering ResolveByKeep 2161s 2161s The following packages will be upgraded: 2161s libedit2 2161s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2161s Need to get 110 kB of archives. 2161s After this operation, 0 B of additional disk space will be used. 2161s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libedit2 amd64 3.1-20250104-1 [110 kB] 2162s Fetched 110 kB in 0s (322 kB/s) 2162s (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 ... 89449 files and directories currently installed.) 2162s Preparing to unpack .../libedit2_3.1-20250104-1_amd64.deb ... 2162s Unpacking libedit2:amd64 (3.1-20250104-1) over (3.1-20240808-1) ... 2162s Setting up libedit2:amd64 (3.1-20250104-1) ... 2162s Processing triggers for man-db (2.13.0-1) ... 2162s Processing triggers for libc-bin (2.40-4ubuntu1) ... 2163s 2163s Running kernel seems to be up-to-date. 2163s 2163s No services need to be restarted. 2163s 2163s No containers need to be restarted. 2163s 2163s No user sessions are running outdated binaries. 2163s 2163s No VM guests are running outdated hypervisor (qemu) binaries on this host. 2164s Reading package lists... 2165s Building dependency tree... 2165s Reading state information... 2165s Starting pkgProblemResolver with broken count: 0 2165s Starting 2 pkgProblemResolver with broken count: 0 2165s Done 2165s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2168s Reading package lists... 2169s Building dependency tree... 2169s Reading state information... 2169s Starting pkgProblemResolver with broken count: 0 2169s Starting 2 pkgProblemResolver with broken count: 0 2169s Done 2169s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2172s autopkgtest [07:44:06]: test systemd-socket-activation: [----------------------- 2173s Stopping ssh.service... 2173s Checking that ssh.socket is active and listening... 2173s Checking that ssh.service is inactive/dead... 2173s Checking that a connection attempt activates ssh.service... 2173s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 2173s Checking that sshd can be re-executed... 2173s Checking sshd can run in debug mode... 2173s debug1: PAM: establishing credentials 2173s debug1: permanently_set_uid: 0/0 2173s debug3: Copy environment: XDG_SESSION_ID=7 2173s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 2173s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 2173s debug3: Copy environment: XDG_SESSION_TYPE=tty 2173s debug3: Copy environment: XDG_SESSION_CLASS=user 2173s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2173s debug3: Copy environment: TERM=linux 2173s debug3: Copy environment: http_proxy=http://squid.internal:3128 2173s debug3: Copy environment: https_proxy=http://squid.internal:3128 2173s 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 2173s debug3: Copy environment: LANG=C.UTF-8 2173s Environment: 2173s LANG=C.UTF-8 2173s USER=root 2173s LOGNAME=root 2173s HOME=/root 2173s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2173s SHELL=/bin/bash 2173s XDG_SESSION_ID=7 2173s XDG_RUNTIME_DIR=/run/user/0 2173s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 2173s XDG_SESSION_TYPE=tty 2173s XDG_SESSION_CLASS=user 2173s TERM=linux 2173s http_proxy=http://squid.internal:3128 2173s https_proxy=http://squid.internal:3128 2173s 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 2173s SSH_CLIENT=::1 60914 22 2173s SSH_CONNECTION=::1 60914 ::1 22 2173s Done. 2174s autopkgtest [07:44:08]: test systemd-socket-activation: -----------------------] 2174s systemd-socket-activation PASS 2174s autopkgtest [07:44:08]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 2175s autopkgtest [07:44:09]: test sshd-socket-generator: preparing testbed 2175s Reading package lists... 2175s Building dependency tree... 2175s Reading state information... 2176s Starting pkgProblemResolver with broken count: 0 2176s Starting 2 pkgProblemResolver with broken count: 0 2176s Done 2176s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2177s autopkgtest [07:44:11]: test sshd-socket-generator: [----------------------- 2177s test_default...PASS 2177s test_custom_port...PASS 2177s test_default_and_custom_port...PASS 2177s test_mutiple_custom_ports...PASS 2177s test_custom_listenaddress...PASS 2177s test_custom_listenaddress_and_port...PASS 2177s test_custom_ipv6_listenaddress...PASS 2177s test_custom_family_ipv4...PASS 2177s test_custom_family_ipv6...PASS 2177s test_custom_port_and_family_ipv4...PASS 2177s test_custom_port_and_family_ipv6...PASS 2177s test_match_on_port...PASS 2178s autopkgtest [07:44:12]: test sshd-socket-generator: -----------------------] 2178s sshd-socket-generator PASS 2178s autopkgtest [07:44:12]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 2179s autopkgtest [07:44:13]: @@@@@@@@@@@@@@@@@@@@ summary 2179s regress PASS 2179s ssh-gssapi PASS 2179s socket-activation PASS 2179s xinetd FAIL non-zero exit status 1 2179s systemd-socket-activation PASS 2179s sshd-socket-generator PASS 2197s nova [W] Skipping flock for amd64 2197s Creating nova instance adt-plucky-amd64-openssh-20250120-040318-juju-7f2275-prod-proposed-migration-environment-15-3bfec6a5-d6d1-446f-8262-58af0569baf2 from image adt/ubuntu-plucky-amd64-server-20250120.img (UUID 6dcf8cbe-dbc3-4abc-a32e-fe359949151c)... 2197s nova [W] Timed out waiting for 293c8894-cf9b-4c94-9259-f8245715b06b to get deleted. 2197s nova [W] Skipping flock for amd64 2197s Creating nova instance adt-plucky-amd64-openssh-20250120-040318-juju-7f2275-prod-proposed-migration-environment-15-3bfec6a5-d6d1-446f-8262-58af0569baf2 from image adt/ubuntu-plucky-amd64-server-20250120.img (UUID 6dcf8cbe-dbc3-4abc-a32e-fe359949151c)... 2197s nova [W] Timed out waiting for bb91742f-943a-40ae-b17b-a55869bd1449 to get deleted. 2197s nova [W] Skipping flock for amd64 2197s Creating nova instance adt-plucky-amd64-openssh-20250120-040318-juju-7f2275-prod-proposed-migration-environment-15-3bfec6a5-d6d1-446f-8262-58af0569baf2 from image adt/ubuntu-plucky-amd64-server-20250120.img (UUID 6dcf8cbe-dbc3-4abc-a32e-fe359949151c)... 2197s nova [W] Skipping flock for amd64 2197s Creating nova instance adt-plucky-amd64-openssh-20250120-040318-juju-7f2275-prod-proposed-migration-environment-15-3bfec6a5-d6d1-446f-8262-58af0569baf2 from image adt/ubuntu-plucky-amd64-server-20250120.img (UUID 6dcf8cbe-dbc3-4abc-a32e-fe359949151c)... 2197s nova [W] Timed out waiting for e75e0bf7-56c0-4dc2-987a-c4436f5a9879 to get deleted.