0s autopkgtest [01:18:41]: starting date and time: 2025-05-02 01:18:41+0000 0s autopkgtest [01:18:41]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [01:18:41]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.j2dn9mbf/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ucf --apt-upgrade openssh --timeout-short=1200 --timeout-copy=80000 --timeout-build=80000 --env=ADT_TEST_TRIGGERS=ucf/3.0051 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-riscv64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-riscv64-8.secgroup --name adt-questing-riscv64-openssh-20250502-011840-juju-7f2275-prod-proposed-migration-environment-15-96cc86ba-10f6-4702-971e-efd799a4752e --image adt/ubuntu-questing-riscv64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-riscv64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 192s autopkgtest [01:21:53]: testbed dpkg architecture: riscv64 193s autopkgtest [01:21:54]: testbed apt version: 3.0.0 194s autopkgtest [01:21:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 195s autopkgtest [01:21:56]: testbed release detected to be: None 199s autopkgtest [01:22:00]: updating testbed package index (apt update) 201s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 202s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 202s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 202s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 202s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2281 kB] 203s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [57.4 kB] 203s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [190 kB] 203s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [169 kB] 203s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [833 kB] 204s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [15.3 kB] 207s Fetched 3655 kB in 6s (601 kB/s) 221s Reading package lists... 224s autopkgtest [01:22:25]: upgrading testbed (apt dist-upgrade and autopurge) 225s Reading package lists... 227s Building dependency tree... 227s Reading state information... 228s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 229s Starting 2 pkgProblemResolver with broken count: 0 229s Done 231s Entering ResolveByKeep 231s 232s Calculating upgrade... 233s The following packages will be upgraded: 233s ucf 233s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 233s Need to get 43.6 kB of archives. 233s After this operation, 1024 B of additional disk space will be used. 233s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 ucf all 3.0051 [43.6 kB] 237s Preconfiguring packages ... 238s Fetched 43.6 kB in 0s (156 kB/s) 239s (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 ... 114197 files and directories currently installed.) 239s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 239s Unpacking ucf (3.0051) over (3.0050) ... 240s Setting up ucf (3.0051) ... 240s Processing triggers for man-db (2.13.0-1) ... 249s Reading package lists... 251s Building dependency tree... 251s Reading state information... 252s Starting pkgProblemResolver with broken count: 0 252s Starting 2 pkgProblemResolver with broken count: 0 252s Done 254s Solving dependencies... 254s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 267s autopkgtest [01:23:08]: testbed running kernel: Linux 6.14.0-13-generic #13.2-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 05:26:54 UTC 2025 268s autopkgtest [01:23:09]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 305s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (dsc) [3480 B] 305s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (tar) [1965 kB] 305s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (asc) [833 B] 305s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3 (diff) [212 kB] 307s gpgv: Signature made Tue Feb 18 14:21:37 2025 UTC 307s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 307s gpgv: Can't check signature: No public key 307s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.dsc: no acceptable signature found 315s autopkgtest [01:23:56]: testing package openssh version 1:9.9p1-3ubuntu3 317s autopkgtest [01:23:58]: build not needed 322s autopkgtest [01:24:03]: test regress: preparing testbed 325s Reading package lists... 327s Building dependency tree... 327s Reading state information... 328s Starting pkgProblemResolver with broken count: 0 328s Starting 2 pkgProblemResolver with broken count: 0 328s Done 330s The following NEW packages will be installed: 330s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 330s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 330s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 330s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 330s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 330s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 330s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 330s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 330s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 330s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 330s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 330s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 330s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 330s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 330s python3-packaging python3-pyasn1 python3-pyasn1-modules 330s python3-service-identity python3-twisted python3-zope.interface wdiff 331s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 331s Need to get 8682 kB of archives. 331s After this operation, 40.3 MB of additional disk space will be used. 331s Get:1 http://ftpmaster.internal/ubuntu questing/universe riscv64 libtommath1 riscv64 1.3.0-1 [67.8 kB] 331s Get:2 http://ftpmaster.internal/ubuntu questing/universe riscv64 libtomcrypt1 riscv64 1.18.2+dfsg-7build1 [483 kB] 331s Get:3 http://ftpmaster.internal/ubuntu questing/universe riscv64 dropbear-bin riscv64 2024.86-2 [143 kB] 331s Get:4 http://ftpmaster.internal/ubuntu questing/universe riscv64 dropbear all 2024.86-2 [8664 B] 331s Get:5 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-dirlist-perl all 0.05-3 [7286 B] 331s Get:6 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-which-perl all 1.27-2 [12.5 kB] 331s Get:7 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-homedir-perl all 1.006-2 [37.0 kB] 331s Get:8 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-touch-perl all 0.12-2 [7498 B] 331s Get:9 http://ftpmaster.internal/ubuntu questing/main riscv64 libio-pty-perl riscv64 1:1.20-1build3 [31.6 kB] 331s Get:10 http://ftpmaster.internal/ubuntu questing/main riscv64 libipc-run-perl all 20231003.0-2 [91.5 kB] 331s Get:11 http://ftpmaster.internal/ubuntu questing/main riscv64 libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 331s Get:12 http://ftpmaster.internal/ubuntu questing/main riscv64 libclass-xsaccessor-perl riscv64 1.19-4build6 [34.5 kB] 331s Get:13 http://ftpmaster.internal/ubuntu questing/main riscv64 libb-hooks-op-check-perl riscv64 0.22-3build2 [9556 B] 331s Get:14 http://ftpmaster.internal/ubuntu questing/main riscv64 libdynaloader-functions-perl all 0.004-1 [11.4 kB] 331s Get:15 http://ftpmaster.internal/ubuntu questing/main riscv64 libdevel-callchecker-perl riscv64 0.009-1build1 [14.1 kB] 331s Get:16 http://ftpmaster.internal/ubuntu questing/main riscv64 libparams-classify-perl riscv64 0.015-2build6 [20.1 kB] 331s Get:17 http://ftpmaster.internal/ubuntu questing/main riscv64 libmodule-runtime-perl all 0.016-2 [16.4 kB] 331s Get:18 http://ftpmaster.internal/ubuntu questing/main riscv64 libimport-into-perl all 1.002005-2 [10.7 kB] 331s Get:19 http://ftpmaster.internal/ubuntu questing/main riscv64 librole-tiny-perl all 2.002004-1 [16.3 kB] 331s Get:20 http://ftpmaster.internal/ubuntu questing/main riscv64 libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 331s Get:21 http://ftpmaster.internal/ubuntu questing/main riscv64 libmoo-perl all 2.005005-1 [47.4 kB] 331s Get:22 http://ftpmaster.internal/ubuntu questing/main riscv64 libencode-locale-perl all 1.05-3 [11.6 kB] 331s Get:23 http://ftpmaster.internal/ubuntu questing/main riscv64 libtimedate-perl all 2.3300-2 [34.0 kB] 331s Get:24 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-date-perl all 6.06-1 [10.2 kB] 331s Get:25 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-listing-perl all 6.16-1 [11.3 kB] 331s Get:26 http://ftpmaster.internal/ubuntu questing/main riscv64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 331s Get:27 http://ftpmaster.internal/ubuntu questing/main riscv64 liburi-perl all 5.30-1 [94.4 kB] 331s Get:28 http://ftpmaster.internal/ubuntu questing/main riscv64 libhtml-parser-perl riscv64 3.83-1build1 [86.6 kB] 331s Get:29 http://ftpmaster.internal/ubuntu questing/main riscv64 libhtml-tree-perl all 5.07-3 [200 kB] 331s Get:30 http://ftpmaster.internal/ubuntu questing/main riscv64 libclone-perl riscv64 0.47-1 [10.5 kB] 331s Get:31 http://ftpmaster.internal/ubuntu questing/main riscv64 libio-html-perl all 1.004-3 [15.9 kB] 331s Get:32 http://ftpmaster.internal/ubuntu questing/main riscv64 liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 331s Get:33 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 331s Get:34 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-cookies-perl all 6.11-1 [18.2 kB] 331s Get:35 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-negotiate-perl all 6.01-2 [12.4 kB] 331s Get:36 http://ftpmaster.internal/ubuntu questing/main riscv64 perl-openssl-defaults riscv64 7build3 [6632 B] 331s Get:37 http://ftpmaster.internal/ubuntu questing/main riscv64 libnet-ssleay-perl riscv64 1.94-3 [317 kB] 331s Get:38 http://ftpmaster.internal/ubuntu questing/main riscv64 libio-socket-ssl-perl all 2.089-1 [200 kB] 331s Get:39 http://ftpmaster.internal/ubuntu questing/main riscv64 libnet-http-perl all 6.23-1 [22.3 kB] 331s Get:40 http://ftpmaster.internal/ubuntu questing/main riscv64 liblwp-protocol-https-perl all 6.14-1 [9040 B] 331s Get:41 http://ftpmaster.internal/ubuntu questing/main riscv64 libtry-tiny-perl all 0.32-1 [21.2 kB] 331s Get:42 http://ftpmaster.internal/ubuntu questing/main riscv64 libwww-robotrules-perl all 6.02-1 [12.6 kB] 331s Get:43 http://ftpmaster.internal/ubuntu questing/main riscv64 libwww-perl all 6.78-1 [139 kB] 331s Get:44 http://ftpmaster.internal/ubuntu questing/main riscv64 patchutils riscv64 0.4.2-1build3 [77.3 kB] 331s Get:45 http://ftpmaster.internal/ubuntu questing/main riscv64 wdiff riscv64 1.2.2-8 [30.5 kB] 331s Get:46 http://ftpmaster.internal/ubuntu questing/main riscv64 devscripts all 2.25.5 [1059 kB] 332s Get:47 http://ftpmaster.internal/ubuntu questing/universe riscv64 libhavege2 riscv64 1.9.19-11ubuntu1 [26.2 kB] 332s Get:48 http://ftpmaster.internal/ubuntu questing/universe riscv64 putty-tools riscv64 0.83-2 [746 kB] 332s Get:49 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-hamcrest all 2.1.0-1 [28.1 kB] 332s Get:50 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-pyasn1 all 0.6.1-1 [56.4 kB] 332s Get:51 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 332s Get:52 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-service-identity all 24.2.0-1 [11.0 kB] 332s Get:53 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-automat all 24.8.1-1 [35.5 kB] 332s Get:54 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-constantly all 23.10.4-2 [13.9 kB] 332s Get:55 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-hyperlink all 21.0.0-6 [68.0 kB] 332s Get:56 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-packaging all 24.2-1 [51.5 kB] 332s Get:57 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-incremental all 24.7.2-3 [18.6 kB] 332s Get:58 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-zope.interface riscv64 7.2-1build1 [142 kB] 332s Get:59 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-twisted all 24.11.0-1 [2066 kB] 332s Get:60 http://ftpmaster.internal/ubuntu questing/universe riscv64 openssh-tests riscv64 1:9.9p1-3ubuntu3 [1689 kB] 332s Get:61 http://ftpmaster.internal/ubuntu questing/universe riscv64 haveged riscv64 1.9.19-11ubuntu1 [32.4 kB] 338s Fetched 8682 kB in 2s (5088 kB/s) 339s Selecting previously unselected package libtommath1:riscv64. 340s (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 ... 114197 files and directories currently installed.) 340s Preparing to unpack .../00-libtommath1_1.3.0-1_riscv64.deb ... 340s Unpacking libtommath1:riscv64 (1.3.0-1) ... 340s Selecting previously unselected package libtomcrypt1:riscv64. 340s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_riscv64.deb ... 340s Unpacking libtomcrypt1:riscv64 (1.18.2+dfsg-7build1) ... 340s Selecting previously unselected package dropbear-bin. 340s Preparing to unpack .../02-dropbear-bin_2024.86-2_riscv64.deb ... 340s Unpacking dropbear-bin (2024.86-2) ... 341s Selecting previously unselected package dropbear. 341s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 341s Unpacking dropbear (2024.86-2) ... 341s Selecting previously unselected package libfile-dirlist-perl. 341s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 341s Unpacking libfile-dirlist-perl (0.05-3) ... 342s Selecting previously unselected package libfile-which-perl. 342s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 342s Unpacking libfile-which-perl (1.27-2) ... 342s Selecting previously unselected package libfile-homedir-perl. 342s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 342s Unpacking libfile-homedir-perl (1.006-2) ... 343s Selecting previously unselected package libfile-touch-perl. 343s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 343s Unpacking libfile-touch-perl (0.12-2) ... 343s Selecting previously unselected package libio-pty-perl. 343s Preparing to unpack .../08-libio-pty-perl_1%3a1.20-1build3_riscv64.deb ... 343s Unpacking libio-pty-perl (1:1.20-1build3) ... 344s Selecting previously unselected package libipc-run-perl. 344s Preparing to unpack .../09-libipc-run-perl_20231003.0-2_all.deb ... 344s Unpacking libipc-run-perl (20231003.0-2) ... 344s Selecting previously unselected package libclass-method-modifiers-perl. 344s Preparing to unpack .../10-libclass-method-modifiers-perl_2.15-1_all.deb ... 344s Unpacking libclass-method-modifiers-perl (2.15-1) ... 345s Selecting previously unselected package libclass-xsaccessor-perl. 345s Preparing to unpack .../11-libclass-xsaccessor-perl_1.19-4build6_riscv64.deb ... 345s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 346s Selecting previously unselected package libb-hooks-op-check-perl:riscv64. 346s Preparing to unpack .../12-libb-hooks-op-check-perl_0.22-3build2_riscv64.deb ... 346s Unpacking libb-hooks-op-check-perl:riscv64 (0.22-3build2) ... 346s Selecting previously unselected package libdynaloader-functions-perl. 346s Preparing to unpack .../13-libdynaloader-functions-perl_0.004-1_all.deb ... 346s Unpacking libdynaloader-functions-perl (0.004-1) ... 347s Selecting previously unselected package libdevel-callchecker-perl:riscv64. 347s Preparing to unpack .../14-libdevel-callchecker-perl_0.009-1build1_riscv64.deb ... 347s Unpacking libdevel-callchecker-perl:riscv64 (0.009-1build1) ... 347s Selecting previously unselected package libparams-classify-perl:riscv64. 347s Preparing to unpack .../15-libparams-classify-perl_0.015-2build6_riscv64.deb ... 347s Unpacking libparams-classify-perl:riscv64 (0.015-2build6) ... 348s Selecting previously unselected package libmodule-runtime-perl. 348s Preparing to unpack .../16-libmodule-runtime-perl_0.016-2_all.deb ... 348s Unpacking libmodule-runtime-perl (0.016-2) ... 348s Selecting previously unselected package libimport-into-perl. 348s Preparing to unpack .../17-libimport-into-perl_1.002005-2_all.deb ... 348s Unpacking libimport-into-perl (1.002005-2) ... 348s Selecting previously unselected package librole-tiny-perl. 349s Preparing to unpack .../18-librole-tiny-perl_2.002004-1_all.deb ... 349s Unpacking librole-tiny-perl (2.002004-1) ... 349s Selecting previously unselected package libsub-quote-perl. 349s Preparing to unpack .../19-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 349s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 349s Selecting previously unselected package libmoo-perl. 350s Preparing to unpack .../20-libmoo-perl_2.005005-1_all.deb ... 350s Unpacking libmoo-perl (2.005005-1) ... 350s Selecting previously unselected package libencode-locale-perl. 350s Preparing to unpack .../21-libencode-locale-perl_1.05-3_all.deb ... 350s Unpacking libencode-locale-perl (1.05-3) ... 350s Selecting previously unselected package libtimedate-perl. 351s Preparing to unpack .../22-libtimedate-perl_2.3300-2_all.deb ... 351s Unpacking libtimedate-perl (2.3300-2) ... 351s Selecting previously unselected package libhttp-date-perl. 351s Preparing to unpack .../23-libhttp-date-perl_6.06-1_all.deb ... 351s Unpacking libhttp-date-perl (6.06-1) ... 351s Selecting previously unselected package libfile-listing-perl. 352s Preparing to unpack .../24-libfile-listing-perl_6.16-1_all.deb ... 352s Unpacking libfile-listing-perl (6.16-1) ... 352s Selecting previously unselected package libhtml-tagset-perl. 352s Preparing to unpack .../25-libhtml-tagset-perl_3.24-1_all.deb ... 352s Unpacking libhtml-tagset-perl (3.24-1) ... 352s Selecting previously unselected package liburi-perl. 352s Preparing to unpack .../26-liburi-perl_5.30-1_all.deb ... 352s Unpacking liburi-perl (5.30-1) ... 353s Selecting previously unselected package libhtml-parser-perl:riscv64. 353s Preparing to unpack .../27-libhtml-parser-perl_3.83-1build1_riscv64.deb ... 353s Unpacking libhtml-parser-perl:riscv64 (3.83-1build1) ... 353s Selecting previously unselected package libhtml-tree-perl. 354s Preparing to unpack .../28-libhtml-tree-perl_5.07-3_all.deb ... 354s Unpacking libhtml-tree-perl (5.07-3) ... 354s Selecting previously unselected package libclone-perl:riscv64. 354s Preparing to unpack .../29-libclone-perl_0.47-1_riscv64.deb ... 354s Unpacking libclone-perl:riscv64 (0.47-1) ... 355s Selecting previously unselected package libio-html-perl. 355s Preparing to unpack .../30-libio-html-perl_1.004-3_all.deb ... 355s Unpacking libio-html-perl (1.004-3) ... 355s Selecting previously unselected package liblwp-mediatypes-perl. 355s Preparing to unpack .../31-liblwp-mediatypes-perl_6.04-2_all.deb ... 355s Unpacking liblwp-mediatypes-perl (6.04-2) ... 355s Selecting previously unselected package libhttp-message-perl. 356s Preparing to unpack .../32-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 356s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 356s Selecting previously unselected package libhttp-cookies-perl. 356s Preparing to unpack .../33-libhttp-cookies-perl_6.11-1_all.deb ... 356s Unpacking libhttp-cookies-perl (6.11-1) ... 356s Selecting previously unselected package libhttp-negotiate-perl. 357s Preparing to unpack .../34-libhttp-negotiate-perl_6.01-2_all.deb ... 357s Unpacking libhttp-negotiate-perl (6.01-2) ... 357s Selecting previously unselected package perl-openssl-defaults:riscv64. 357s Preparing to unpack .../35-perl-openssl-defaults_7build3_riscv64.deb ... 357s Unpacking perl-openssl-defaults:riscv64 (7build3) ... 357s Selecting previously unselected package libnet-ssleay-perl:riscv64. 358s Preparing to unpack .../36-libnet-ssleay-perl_1.94-3_riscv64.deb ... 358s Unpacking libnet-ssleay-perl:riscv64 (1.94-3) ... 358s Selecting previously unselected package libio-socket-ssl-perl. 358s Preparing to unpack .../37-libio-socket-ssl-perl_2.089-1_all.deb ... 358s Unpacking libio-socket-ssl-perl (2.089-1) ... 359s Selecting previously unselected package libnet-http-perl. 359s Preparing to unpack .../38-libnet-http-perl_6.23-1_all.deb ... 359s Unpacking libnet-http-perl (6.23-1) ... 359s Selecting previously unselected package liblwp-protocol-https-perl. 359s Preparing to unpack .../39-liblwp-protocol-https-perl_6.14-1_all.deb ... 359s Unpacking liblwp-protocol-https-perl (6.14-1) ... 360s Selecting previously unselected package libtry-tiny-perl. 360s Preparing to unpack .../40-libtry-tiny-perl_0.32-1_all.deb ... 360s Unpacking libtry-tiny-perl (0.32-1) ... 360s Selecting previously unselected package libwww-robotrules-perl. 360s Preparing to unpack .../41-libwww-robotrules-perl_6.02-1_all.deb ... 360s Unpacking libwww-robotrules-perl (6.02-1) ... 361s Selecting previously unselected package libwww-perl. 361s Preparing to unpack .../42-libwww-perl_6.78-1_all.deb ... 361s Unpacking libwww-perl (6.78-1) ... 361s Selecting previously unselected package patchutils. 361s Preparing to unpack .../43-patchutils_0.4.2-1build3_riscv64.deb ... 361s Unpacking patchutils (0.4.2-1build3) ... 362s Selecting previously unselected package wdiff. 362s Preparing to unpack .../44-wdiff_1.2.2-8_riscv64.deb ... 362s Unpacking wdiff (1.2.2-8) ... 362s Selecting previously unselected package devscripts. 362s Preparing to unpack .../45-devscripts_2.25.5_all.deb ... 363s Unpacking devscripts (2.25.5) ... 364s Selecting previously unselected package libhavege2:riscv64. 364s Preparing to unpack .../46-libhavege2_1.9.19-11ubuntu1_riscv64.deb ... 364s Unpacking libhavege2:riscv64 (1.9.19-11ubuntu1) ... 365s Selecting previously unselected package putty-tools. 365s Preparing to unpack .../47-putty-tools_0.83-2_riscv64.deb ... 365s Unpacking putty-tools (0.83-2) ... 366s Selecting previously unselected package python3-hamcrest. 366s Preparing to unpack .../48-python3-hamcrest_2.1.0-1_all.deb ... 366s Unpacking python3-hamcrest (2.1.0-1) ... 366s Selecting previously unselected package python3-pyasn1. 366s Preparing to unpack .../49-python3-pyasn1_0.6.1-1_all.deb ... 366s Unpacking python3-pyasn1 (0.6.1-1) ... 367s Selecting previously unselected package python3-pyasn1-modules. 367s Preparing to unpack .../50-python3-pyasn1-modules_0.4.1-2_all.deb ... 367s Unpacking python3-pyasn1-modules (0.4.1-2) ... 367s Selecting previously unselected package python3-service-identity. 368s Preparing to unpack .../51-python3-service-identity_24.2.0-1_all.deb ... 368s Unpacking python3-service-identity (24.2.0-1) ... 368s Selecting previously unselected package python3-automat. 368s Preparing to unpack .../52-python3-automat_24.8.1-1_all.deb ... 368s Unpacking python3-automat (24.8.1-1) ... 368s Selecting previously unselected package python3-constantly. 369s Preparing to unpack .../53-python3-constantly_23.10.4-2_all.deb ... 369s Unpacking python3-constantly (23.10.4-2) ... 369s Selecting previously unselected package python3-hyperlink. 369s Preparing to unpack .../54-python3-hyperlink_21.0.0-6_all.deb ... 369s Unpacking python3-hyperlink (21.0.0-6) ... 370s Selecting previously unselected package python3-packaging. 370s Preparing to unpack .../55-python3-packaging_24.2-1_all.deb ... 370s Unpacking python3-packaging (24.2-1) ... 370s Selecting previously unselected package python3-incremental. 370s Preparing to unpack .../56-python3-incremental_24.7.2-3_all.deb ... 370s Unpacking python3-incremental (24.7.2-3) ... 371s Selecting previously unselected package python3-zope.interface. 371s Preparing to unpack .../57-python3-zope.interface_7.2-1build1_riscv64.deb ... 371s Unpacking python3-zope.interface (7.2-1build1) ... 371s Selecting previously unselected package python3-twisted. 371s Preparing to unpack .../58-python3-twisted_24.11.0-1_all.deb ... 371s Unpacking python3-twisted (24.11.0-1) ... 374s Selecting previously unselected package openssh-tests. 374s Preparing to unpack .../59-openssh-tests_1%3a9.9p1-3ubuntu3_riscv64.deb ... 374s Unpacking openssh-tests (1:9.9p1-3ubuntu3) ... 376s Selecting previously unselected package haveged. 376s Preparing to unpack .../60-haveged_1.9.19-11ubuntu1_riscv64.deb ... 376s Unpacking haveged (1.9.19-11ubuntu1) ... 377s Setting up wdiff (1.2.2-8) ... 377s Setting up libfile-which-perl (1.27-2) ... 377s Setting up libdynaloader-functions-perl (0.004-1) ... 377s Setting up libclass-method-modifiers-perl (2.15-1) ... 377s Setting up libio-pty-perl (1:1.20-1build3) ... 377s Setting up python3-zope.interface (7.2-1build1) ... 382s Setting up libclone-perl:riscv64 (0.47-1) ... 382s Setting up libtommath1:riscv64 (1.3.0-1) ... 382s Setting up libhtml-tagset-perl (3.24-1) ... 382s Setting up python3-automat (24.8.1-1) ... 385s Setting up liblwp-mediatypes-perl (6.04-2) ... 385s Setting up libtry-tiny-perl (0.32-1) ... 385s Setting up perl-openssl-defaults:riscv64 (7build3) ... 385s Setting up libencode-locale-perl (1.05-3) ... 385s Setting up python3-hamcrest (2.1.0-1) ... 387s Setting up putty-tools (0.83-2) ... 387s Setting up libhavege2:riscv64 (1.9.19-11ubuntu1) ... 387s Setting up patchutils (0.4.2-1build3) ... 387s Setting up python3-packaging (24.2-1) ... 389s Setting up python3-incremental (24.7.2-3) ... 391s Setting up python3-hyperlink (21.0.0-6) ... 394s Setting up libio-html-perl (1.004-3) ... 394s Setting up libb-hooks-op-check-perl:riscv64 (0.22-3build2) ... 394s Setting up libipc-run-perl (20231003.0-2) ... 394s Setting up libtimedate-perl (2.3300-2) ... 394s Setting up librole-tiny-perl (2.002004-1) ... 394s Setting up python3-pyasn1 (0.6.1-1) ... 396s Setting up python3-constantly (23.10.4-2) ... 398s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 398s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 398s Setting up libfile-dirlist-perl (0.05-3) ... 398s Setting up libfile-homedir-perl (1.006-2) ... 398s Setting up liburi-perl (5.30-1) ... 398s Setting up libfile-touch-perl (0.12-2) ... 398s Setting up libnet-ssleay-perl:riscv64 (1.94-3) ... 398s Setting up libtomcrypt1:riscv64 (1.18.2+dfsg-7build1) ... 398s Setting up libhttp-date-perl (6.06-1) ... 398s Setting up haveged (1.9.19-11ubuntu1) ... 402s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 407s Setting up dropbear-bin (2024.86-2) ... 407s Setting up libfile-listing-perl (6.16-1) ... 407s Setting up libnet-http-perl (6.23-1) ... 407s Setting up libdevel-callchecker-perl:riscv64 (0.009-1build1) ... 407s Setting up dropbear (2024.86-2) ... 407s Converting existing OpenSSH RSA host key to Dropbear format. 407s Key is a ssh-rsa key 407s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 408s 3072 SHA256:6NH/E/7jk+P1O/bKFLbbuVRpUD4XgCVH7UWwD46OQZQ /etc/dropbear/dropbear_rsa_host_key (RSA) 408s +---[RSA 3072]----+ 408s | .o+=+=.| 408s | .E.o ooo| 408s | . .+oo| 408s | o . o.++| 408s | o S . . +oo| 408s | . . . +...o.| 408s | . o...oo.| 408s | .oo**o| 408s | .=OOO| 408s +----[SHA256]-----+ 408s Converting existing OpenSSH ECDSA host key to Dropbear format. 408s Key is a ecdsa-sha2-nistp256 key 408s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 408s 256 SHA256:0Ey7d0sq/2lnQ1lGgHr38JEfoKjlINTpP9dq1v0GeH0 /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 408s +---[ECDSA 256]---+ 408s | . o ... | 408s | . * . .. .| 408s | . o + ... ...| 408s | . + +... o+o| 408s | . S ..+o O+| 408s | . = =.o= E| 408s | . + +o...| 408s | o +.o+..| 408s | +ooo oo| 408s +----[SHA256]-----+ 408s Converting existing OpenSSH ED25519 host key to Dropbear format. 408s Key is a ssh-ed25519 key 408s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 409s 256 SHA256:3U9wstxhrBZ5j8XKk4KnlNf7AFHbLSbK8kvkKMB41sc /etc/dropbear/dropbear_ed25519_host_key (ED25519) 409s +--[ED25519 256]--+ 409s | . | 409s | + +.| 409s | B X =| 409s | o . . o * ^ O | 409s | . = . E O @ X .| 409s | o . . B = = o | 409s | . . = + | 409s | . . . o | 409s | . .| 409s +----[SHA256]-----+ 416s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 421s Setting up python3-pyasn1-modules (0.4.1-2) ... 426s Setting up python3-service-identity (24.2.0-1) ... 428s Setting up libwww-robotrules-perl (6.02-1) ... 428s Setting up libhtml-parser-perl:riscv64 (3.83-1build1) ... 428s Setting up libio-socket-ssl-perl (2.089-1) ... 428s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 428s Setting up libhttp-negotiate-perl (6.01-2) ... 428s Setting up libhttp-cookies-perl (6.11-1) ... 428s Setting up libhtml-tree-perl (5.07-3) ... 428s Setting up libparams-classify-perl:riscv64 (0.015-2build6) ... 428s Setting up libmodule-runtime-perl (0.016-2) ... 428s Setting up python3-twisted (24.11.0-1) ... 466s Setting up libimport-into-perl (1.002005-2) ... 466s Setting up libmoo-perl (2.005005-1) ... 466s Setting up openssh-tests (1:9.9p1-3ubuntu3) ... 466s Setting up liblwp-protocol-https-perl (6.14-1) ... 466s Setting up libwww-perl (6.78-1) ... 466s Setting up devscripts (2.25.5) ... 468s Processing triggers for install-info (7.1.1-1) ... 471s Processing triggers for libc-bin (2.41-6ubuntu1) ... 471s Processing triggers for man-db (2.13.0-1) ... 486s autopkgtest [01:26:47]: test regress: [----------------------- 488s info: Adding user `openssh-tests' ... 488s info: Selecting UID/GID from range 1000 to 59999 ... 488s info: Adding new group `openssh-tests' (1001) ... 489s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 489s info: Creating home directory `/home/openssh-tests' ... 489s info: Copying files from `/etc/skel' ... 489s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 489s info: Adding user `openssh-tests' to group `users' ... 494s I: annotate-output 2.25.5 494s I: prefix='%H:%M:%S.%N ' 494s 01:26:55.270802100 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user 495s 01:26:55.753511700 O: make: Entering directory '/tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress' 495s 01:26:55.803315900 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/valgrind-out 495s 01:26:55.871600300 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 495s 01:26:55.876921200 E: + + /usr/bin/sshgrep -Q key 495s 01:26:55.912691700 O: ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 495s 01:26:55.954209000 O: tr '\n' '\r' /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 495s 01:26:55.974664200 E: -q ^ssh-rsa 495s 01:26:55.993538600 O: ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 495s 01:26:56.032903800 O: awk '{print $0 "\r"}' /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 495s 01:26:56.072358400 O: ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 495s 01:26:56.126495900 O: fi 495s 01:26:56.150610700 E: + + ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2.prv 495s 01:26:56.189936800 E: diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv 495s 01:26:56.382039600 E: + tr \n \r 495s 01:26:56.425014200 E: + ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 496s 01:26:56.466174800 E: + diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv 496s 01:26:56.657312200 E: + awk {print $0 "\r"} /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2.prv 496s 01:26:56.759274200 E: + ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 496s 01:26:56.802092300 E: + diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv 496s 01:26:56.925550300 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 496s 01:26:56.991627300 E: + /usr/bin/ssh -Q key 496s 01:26:57.003030800 O: cat /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t2.out ; \ 496s 01:26:57.060804200 O: chmod 600 /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t2.out ; \ 496s 01:26:57.087961200 E: + grep -q ^ssh-rsa 496s 01:26:57.101342000 O: ssh-keygen -yf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 496s 01:26:57.141678300 O: fi 496s 01:26:57.251383400 E: + cat /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.prv 496s 01:26:57.316130200 E: + chmod 600 /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t2.out 496s 01:26:57.357752600 E: + ssh-keygen -yf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t2.out 496s 01:26:57.397864400 E: + diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub 497s 01:26:57.566364400 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 497s 01:26:57.632620700 E: + /usr/bin/ssh -Q+ key 497s 01:26:57.646719800 O: ssh-keygen -ef /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t3.out ; \ 497s 01:26:57.705056700 O: ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 497s 01:26:57.721531400 E: grep -q ^ssh-rsa 497s 01:26:57.749601900 O: fi 497s 01:26:57.835107400 E: + ssh-keygen -ef /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub 497s 01:26:57.980938600 E: + ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t3.out 497s 01:26:58.022197100 E: + diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub 497s 01:26:58.122703900 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 497s 01:26:58.184339500 E: + + /usr/bin/ssh -Q key 497s 01:26:58.202011400 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 497s 01:26:58.245875100 O: awk '{print $2}' | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t4.ok ; \ 497s 01:26:58.276750900 E: grep -q ^ssh-rsa 497s 01:26:58.285437700 O: fi 497s 01:26:58.420718500 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub+ 498s 01:26:58.471054300 E: + diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t4.ok 498s 01:26:58.511277200 E: awk {print $2} 498s 01:26:58.575083200 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 498s 01:26:58.651425400 O: ssh-keygen -Bf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 498s 01:26:58.652321800 E: + + /usr/bin/ssh -Q key 498s 01:26:58.707524100 O: awk '{print $2}' | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t5.ok ; \ 498s 01:26:58.747344300 O: fi 498s 01:26:58.751455100 E: grep -q ^ssh-rsa 498s 01:26:58.880090800 E: + + ssh-keygenawk {print $2} 498s 01:26:58.926081100 E: -Bf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub 498s 01:26:58.966464400 E: + diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t5.ok 498s 01:26:59.027322700 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 498s 01:26:59.097501300 E: + /usr/bin/ssh -Q+ key 498s 01:26:59.106448700 O: ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t6.out1 ; \ 498s 01:26:59.158847500 O: ssh-keygen -if /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t6.out2 ; \ 498s 01:26:59.186734300 E: grep -q ^ssh-dss 498s 01:26:59.199170500 O: chmod 600 /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t6.out1 ; \ 498s 01:26:59.239833100 O: ssh-keygen -yf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t6.out2 ; \ 498s 01:26:59.281149700 O: fi 498s 01:26:59.351097300 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 498s 01:26:59.374589700 E: + /usr/bin/ssh -Q key 498s 01:26:59.418103900 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t7.out ; \ 498s 01:26:59.458682100 O: fi 499s 01:26:59.468246600 E: + grep -q ^ssh-dss 499s 01:26:59.559550500 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 499s 01:26:59.621776300 E: + + /usr/bin/ssh -Q key 499s 01:26:59.639001300 O: ssh-keygen -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 499s 01:26:59.686466600 O: ssh-keygen -Bf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 499s 01:26:59.725569200 O: fi 499s 01:26:59.719895400 E: grep -q ^ssh-dss 499s 01:27:00.166441300 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 499s 01:27:00.276005300 E: + ssh -Q key 499s 01:27:00.280279600 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t8.out ; \ 499s 01:27:00.324406200 E: + grep -q ^ssh-dss 499s 01:27:00.367165600 O: fi 500s 01:27:00.517233000 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 500s 01:27:00.559224700 O: ssh-keygen -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 500s 01:27:00.582177400 E: + + ssh -Q key 500s 01:27:00.617744700 O: ssh-keygen -Bf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 500s 01:27:00.666164300 E: grep -q ^ssh-dss 500s 01:27:00.691992800 O: fi 500s 01:27:00.765014400 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 500s 01:27:00.836116600 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t9.out 500s 01:27:01.116260100 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 500s 01:27:01.159508800 O: ssh-keygen -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t9.out > /dev/null 501s 01:27:01.483241500 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 501s 01:27:01.545362500 O: ssh-keygen -Bf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t9.out > /dev/null 501s 01:27:01.852072200 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t10.out 501s 01:27:02.012326800 O: ssh-keygen -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t10.out > /dev/null 501s 01:27:02.166671600 O: ssh-keygen -Bf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t10.out > /dev/null 501s 01:27:02.329701500 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 501s 01:27:02.404582700 E: + + /usr/bin/ssh -Q key 501s 01:27:02.407067200 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 501s 01:27:02.456545300 E: grep -q ^ssh-dss 502s 01:27:02.477978000 O: awk '{print $2}' | diff - /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t11.ok ; \ 502s 01:27:02.518435300 O: fi 502s 01:27:02.571212500 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t12.out 502s 01:27:02.714408900 O: ssh-keygen -lf /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 502s 01:27:02.925423000 E: run test connect.sh ... 518s 01:27:19.145819800 O: ok simple connect 518s 01:27:19.168969100 E: run test proxy-connect.sh ... 524s 01:27:24.604469400 O: plain username comp=no 527s 01:27:28.393924600 O: plain username comp=yes 531s 01:27:31.967075900 O: username with style 535s 01:27:35.591324100 O: ok proxy connect 535s 01:27:35.623897700 E: run test sshfp-connect.sh ... 540s 01:27:41.064747200 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 540s 01:27:41.108412000 E: run test connect-privsep.sh ... 593s 01:28:34.265172100 O: ok proxy connect with privsep 593s 01:28:34.315888100 E: run test connect-uri.sh ... 599s 01:28:40.331988600 O: uri connect: no trailing slash 602s 01:28:43.120595300 O: uri connect: trailing slash 605s 01:28:45.908345400 O: uri connect: with path name 606s 01:28:47.236725100 O: ok uri connect 606s 01:28:47.273427100 E: run test proto-version.sh ... 612s 01:28:52.647597100 O: ok sshd version with different protocol combinations 612s 01:28:52.671444900 E: run test proto-mismatch.sh ... 617s 01:28:57.797936100 O: ok protocol version mismatch 617s 01:28:57.820423400 E: run test exit-status.sh ... 621s 01:29:02.424698600 O: test remote exit status: status 0 634s 01:29:15.427975800 O: test remote exit status: status 1 647s 01:29:27.653523100 O: test remote exit status: status 4 659s 01:29:39.919535300 O: test remote exit status: status 5 672s 01:29:52.941121300 O: test remote exit status: status 44 684s 01:30:05.184348000 O: ok remote exit status 684s 01:30:05.197704500 E: run test exit-status-signal.sh ... 693s 01:30:14.154983000 O: ok exit status on signal 693s 01:30:14.201505600 E: run test envpass.sh ... 698s 01:30:19.101304700 O: test environment passing: pass env, don't accept 702s 01:30:22.802603100 O: test environment passing: setenv, don't accept 705s 01:30:26.429183000 O: test environment passing: don't pass env, accept 709s 01:30:30.116922400 O: test environment passing: pass single env, accept single env 713s 01:30:33.768314800 O: test environment passing: pass multiple env, accept multiple env 717s 01:30:38.155043900 O: test environment passing: setenv, accept 721s 01:30:41.728684000 O: test environment passing: setenv, first match wins 724s 01:30:45.407898700 O: test environment passing: server setenv wins 728s 01:30:49.110147400 O: test environment passing: server setenv wins 732s 01:30:52.820425500 O: ok environment passing 732s 01:30:52.853491900 E: run test transfer.sh ... 769s 01:31:30.222099600 O: ok transfer data 769s 01:31:30.262453900 E: run test banner.sh ... 774s 01:31:34.980272300 O: test banner: missing banner file 778s 01:31:38.678416100 O: test banner: size 0 782s 01:31:42.924234300 O: test banner: size 10 786s 01:31:47.152072800 O: test banner: size 100 790s 01:31:51.236512600 O: test banner: size 1000 794s 01:31:55.253391900 O: test banner: size 10000 798s 01:31:59.373137500 O: test banner: size 100000 802s 01:32:03.122394500 O: test banner: suppress banner (-q) 806s 01:32:07.371000600 O: ok banner 806s 01:32:07.400636900 E: run test rekey.sh ... 812s 01:32:13.002542700 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 815s 01:32:16.362750900 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 819s 01:32:19.858900700 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 823s 01:32:24.136218300 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 827s 01:32:28.034294800 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 832s 01:32:33.033010800 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 837s 01:32:38.451139000 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 843s 01:32:44.173976400 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 847s 01:32:47.918365200 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 850s 01:32:51.278840100 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 854s 01:32:54.641203400 O: client rekey KexAlgorithms=curve25519-sha256 857s 01:32:58.042937500 O: client rekey KexAlgorithms=sntrup761x25519-sha512 865s 01:33:05.668877600 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 872s 01:33:12.579017100 O: client rekey KexAlgorithms=mlkem768x25519-sha256 875s 01:33:16.019398100 O: client rekey Ciphers=3des-cbc 878s 01:33:19.394454600 O: client rekey Ciphers=aes128-cbc 882s 01:33:22.708694100 O: client rekey Ciphers=aes192-cbc 886s 01:33:26.863261300 O: client rekey Ciphers=aes256-cbc 889s 01:33:30.321807500 O: client rekey Ciphers=aes128-ctr 893s 01:33:33.633355100 O: client rekey Ciphers=aes192-ctr 896s 01:33:37.039577500 O: client rekey Ciphers=aes256-ctr 900s 01:33:41.110792500 O: client rekey Ciphers=aes128-gcm@openssh.com 953s 01:34:34.346442200 O: client rekey Ciphers=aes256-gcm@openssh.com 1009s 01:35:29.444181400 O: client rekey Ciphers=chacha20-poly1305@openssh.com 1066s 01:36:27.239144900 O: client rekey MACs=hmac-sha1 1070s 01:36:30.585240000 O: client rekey MACs=hmac-sha1-96 1073s 01:36:33.952519700 O: client rekey MACs=hmac-sha2-256 1077s 01:36:38.054791600 O: client rekey MACs=hmac-sha2-512 1081s 01:36:41.679016600 O: client rekey MACs=hmac-md5 1084s 01:36:45.041601500 O: client rekey MACs=hmac-md5-96 1088s 01:36:48.454197200 O: client rekey MACs=umac-64@openssh.com 1091s 01:36:51.802594400 O: client rekey MACs=umac-128@openssh.com 1095s 01:36:55.910696900 O: client rekey MACs=hmac-sha1-etm@openssh.com 1098s 01:36:59.305163800 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 1102s 01:37:02.940583000 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 1105s 01:37:06.309347900 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 1110s 01:37:10.468824100 O: client rekey MACs=hmac-md5-etm@openssh.com 1113s 01:37:13.970676200 O: client rekey MACs=hmac-md5-96-etm@openssh.com 1116s 01:37:17.394504800 O: client rekey MACs=umac-64-etm@openssh.com 1120s 01:37:20.846039600 O: client rekey MACs=umac-128-etm@openssh.com 1123s 01:37:24.240901900 O: client rekeylimit 16 1127s 01:37:28.056246600 O: client rekeylimit 1k 1130s 01:37:31.022065700 O: client rekeylimit 128k 1133s 01:37:34.054270200 O: client rekeylimit 256k 1136s 01:37:37.376902000 O: client rekeylimit default 5 1155s 01:37:55.465939900 O: client rekeylimit default 10 1178s 01:38:18.970634900 O: client rekeylimit default 5 no data 1196s 01:38:36.895903800 O: client rekeylimit default 10 no data 1219s 01:38:59.703346800 O: server rekeylimit 16 1222s 01:39:02.753413400 O: server rekeylimit 1k 1225s 01:39:05.832706900 O: server rekeylimit 128k 1228s 01:39:08.984562500 O: server rekeylimit 256k 1232s 01:39:13.146165700 O: server rekeylimit default 5 no data 1250s 01:39:31.053921700 O: server rekeylimit default 10 no data 1273s 01:39:53.936086100 O: rekeylimit parsing: bytes 1276s 01:39:56.549237000 O: rekeylimit parsing: time 1278s 01:39:59.307979300 O: ok rekey 1278s 01:39:59.340645300 E: run test dhgex.sh ... 1284s 01:40:05.021747700 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 1287s 01:40:08.050721500 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 1290s 01:40:11.292720000 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 1293s 01:40:14.234302400 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 1296s 01:40:17.080826200 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 1300s 01:40:20.661108500 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 1302s 01:40:23.452701400 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 1305s 01:40:26.268213700 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 1308s 01:40:29.337823400 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 1312s 01:40:32.463878400 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 1315s 01:40:36.358440000 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 1319s 01:40:39.579401800 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 1322s 01:40:42.944879000 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 1325s 01:40:46.092723800 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 1328s 01:40:49.281260300 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 1332s 01:40:52.635339800 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 1335s 01:40:56.289576100 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 1339s 01:40:59.560166800 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 1342s 01:41:02.792040700 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 1345s 01:41:06.297820400 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 1349s 01:41:09.700637300 O: ok dhgex 1349s 01:41:09.729295300 E: run test stderr-data.sh ... 1354s 01:41:14.933890300 O: test stderr data transfer: () 1368s 01:41:29.012181900 O: test stderr data transfer: (-n) 1382s 01:41:43.011535300 O: ok stderr data transfer 1382s 01:41:43.050256500 E: run test stderr-after-eof.sh ... 1394s 01:41:54.785190400 O: ok stderr data after eof 1394s 01:41:54.819929300 E: run test broken-pipe.sh ... 1399s 01:42:00.424456900 O: ok broken pipe test 1399s 01:42:00.455201700 E: run test try-ciphers.sh ... 1405s 01:42:05.881445500 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 1409s 01:42:10.403919000 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 1413s 01:42:14.450333900 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 1417s 01:42:18.440272600 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 1422s 01:42:22.644836700 O: test try ciphers: cipher 3des-cbc mac hmac-md5 1426s 01:42:26.583630500 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 1430s 01:42:31.277060000 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 1434s 01:42:35.316512100 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 1439s 01:42:39.525990200 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1443s 01:42:43.554860000 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1447s 01:42:47.655100200 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1451s 01:42:52.193768700 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1456s 01:42:56.462957700 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1460s 01:43:00.487304900 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 1464s 01:43:04.505797600 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 1468s 01:43:08.541880000 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 1472s 01:43:12.811143300 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 1476s 01:43:17.415564400 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 1481s 01:43:21.517855400 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 1485s 01:43:25.541306100 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 1489s 01:43:29.501646100 O: test try ciphers: cipher aes128-cbc mac hmac-md5 1493s 01:43:33.519181300 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 1497s 01:43:37.696641900 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 1501s 01:43:42.108521700 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 1505s 01:43:46.122068600 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1509s 01:43:50.052944000 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1513s 01:43:54.132145800 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1517s 01:43:58.147071900 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1522s 01:44:02.894795100 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1526s 01:44:06.986938800 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 1530s 01:44:10.994423300 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 1534s 01:44:15.082382300 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 1539s 01:44:19.490618000 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 1543s 01:44:23.976489000 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 1547s 01:44:28.247278700 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 1551s 01:44:32.317018000 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 1555s 01:44:36.305480600 O: test try ciphers: cipher aes192-cbc mac hmac-md5 1559s 01:44:40.425942800 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 1564s 01:44:44.506259100 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 1568s 01:44:49.242932300 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 1572s 01:44:53.433411700 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1577s 01:44:57.493028500 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1580s 01:45:01.441923300 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1585s 01:45:05.491290600 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1589s 01:45:10.220361800 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1593s 01:45:14.340735600 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 1597s 01:45:18.384458100 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 1601s 01:45:22.342525700 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 1606s 01:45:26.639144000 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 1610s 01:45:30.986265700 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 1614s 01:45:35.341862600 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 1618s 01:45:39.329237600 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 1622s 01:45:43.344663700 O: test try ciphers: cipher aes256-cbc mac hmac-md5 1626s 01:45:47.365873400 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 1630s 01:45:51.393866100 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 1635s 01:45:56.202490500 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 1639s 01:46:00.268249100 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1643s 01:46:04.291341900 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1647s 01:46:08.291403300 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1651s 01:46:12.364883400 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1656s 01:46:16.710953100 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1660s 01:46:20.895169100 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 1664s 01:46:24.887215700 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 1668s 01:46:28.840238500 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 1672s 01:46:33.235190100 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 1676s 01:46:37.230758000 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 1681s 01:46:42.003512800 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 1685s 01:46:46.063602300 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 1689s 01:46:50.151004700 O: test try ciphers: cipher aes128-ctr mac hmac-md5 1693s 01:46:54.293405200 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 1697s 01:46:58.352882700 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 1702s 01:47:03.144169200 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 1706s 01:47:07.306343400 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1710s 01:47:11.318927000 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1714s 01:47:15.282754900 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1718s 01:47:19.251852800 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1722s 01:47:23.388549300 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1727s 01:47:27.932326200 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 1731s 01:47:31.900607300 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 1735s 01:47:35.931288100 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 1739s 01:47:40.247150300 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 1743s 01:47:44.226441800 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 1748s 01:47:48.995934700 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 1752s 01:47:52.996908600 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 1756s 01:47:57.166829400 O: test try ciphers: cipher aes192-ctr mac hmac-md5 1760s 01:48:01.194499700 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 1764s 01:48:05.332473600 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 1769s 01:48:10.020686500 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 1773s 01:48:14.160266600 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1777s 01:48:18.203431300 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1781s 01:48:22.184094900 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1785s 01:48:26.329319600 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1790s 01:48:30.916668200 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1794s 01:48:35.190669500 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 1798s 01:48:39.266306500 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 1802s 01:48:43.335169900 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 1807s 01:48:47.789624500 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 1811s 01:48:52.014734900 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 1816s 01:48:56.736062100 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 1820s 01:49:00.813641900 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 1824s 01:49:04.798479100 O: test try ciphers: cipher aes256-ctr mac hmac-md5 1828s 01:49:08.809632200 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 1832s 01:49:12.860113300 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 1836s 01:49:17.316289800 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 1841s 01:49:21.657347600 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1845s 01:49:25.667290300 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1849s 01:49:29.671027700 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1853s 01:49:33.730317400 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1857s 01:49:37.638891000 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1861s 01:49:42.360931800 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 1865s 01:49:46.400357700 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 1869s 01:49:50.365304300 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 1874s 01:49:54.656527300 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1878s 01:49:58.994628800 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1883s 01:50:03.881059500 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1887s 01:50:07.886969600 O: ok try ciphers 1887s 01:50:07.931473600 E: run test yes-head.sh ... 1895s 01:50:16.270629400 O: ok yes pipe head 1895s 01:50:16.294582900 E: run test login-timeout.sh ... 1921s 01:50:41.525752300 O: ok connect after login grace timeout 1921s 01:50:41.554248200 E: run test agent.sh ... 2033s 01:52:33.952397200 O: ok simple agent test 2033s 01:52:33.973163300 E: run test agent-getpeereid.sh ... 2039s 01:52:39.572176900 O: ok disallow agent attach from other uid 2039s 01:52:39.599007200 E: run test agent-timeout.sh ... 2065s 01:53:06.012648600 O: ok agent timeout test 2065s 01:53:06.036491800 E: run test agent-ptrace.sh ... 2070s 01:53:11.309485100 O: skipped (gdb not found) 2070s 01:53:11.341338400 E: run test agent-subprocess.sh ... 2086s 01:53:26.534118800 O: ok agent subprocess 2086s 01:53:26.560259400 E: run test keyscan.sh ... 2122s 01:54:02.704978900 O: ok keyscan 2122s 01:54:02.738792000 E: run test keygen-change.sh ... 2146s 01:54:27.023222400 O: ok change passphrase for key 2146s 01:54:27.083870200 E: run test keygen-comment.sh ... 2202s 01:55:22.527256700 O: ok Comment extraction from private key 2202s 01:55:22.593826100 E: run test keygen-convert.sh ... 2233s 01:55:53.669775500 O: ok convert keys 2233s 01:55:53.735518200 E: run test keygen-knownhosts.sh ... 2242s 01:56:03.140655500 O: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts updated. 2242s 01:56:03.182186000 O: Original contents retained as /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts.old 2243s 01:56:03.784097200 O: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts updated. 2243s 01:56:03.824370700 O: Original contents retained as /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts.old 2243s 01:56:04.090211400 O: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts updated. 2243s 01:56:04.150061200 O: Original contents retained as /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts.old 2244s 01:56:04.568859600 O: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts updated. 2244s 01:56:04.616332000 O: Original contents retained as /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hosts.old 2244s 01:56:05.404872600 O: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hashed updated. 2244s 01:56:05.445322300 O: Original contents retained as /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/kh.hashed.old 2245s 01:56:05.570971200 O: ok ssh-keygen known_hosts 2245s 01:56:05.592917900 E: run test keygen-moduli.sh ... 2271s 01:56:32.176013900 O: ok keygen moduli 2271s 01:56:32.205396100 E: run test keygen-sshfp.sh ... 2277s 01:56:38.071569800 O: ok keygen-sshfp 2277s 01:56:38.095502100 E: run test key-options.sh ... 2282s 01:56:42.836502300 O: key option command="echo bar" 2286s 01:56:47.115129800 O: key option no-pty,command="echo bar" 2290s 01:56:50.923504100 O: key option pty default 2294s 01:56:54.715543900 O: key option pty no-pty 2297s 01:56:58.271514100 O: key option pty restrict 2301s 01:57:01.830683300 O: key option pty restrict,pty 2305s 01:57:05.579084600 O: key option environment 2314s 01:57:14.857620200 O: key option from="127.0.0.1" 2323s 01:57:24.281906000 O: key option from="127.0.0.0/8" 2329s 01:57:30.130421400 O: key option expiry-time default 2333s 01:57:34.436095900 O: key option expiry-time invalid 2337s 01:57:37.582008200 O: key option expiry-time expired 2340s 01:57:40.598215000 O: key option expiry-time valid 2344s 01:57:44.719427500 O: ok key options 2344s 01:57:44.745234200 E: run test scp.sh ... 2350s 01:57:49.457589300 O: scp: scp mode: simple copy local file to local file 2350s 01:57:49.770390100 O: scp: scp mode: simple copy local file to remote file 2350s 01:57:50.165169500 O: scp: scp mode: simple copy remote file to local file 2350s 01:57:50.551914200 O: scp: scp mode: copy local file to remote file in place 2350s 01:57:50.983102200 O: scp: scp mode: copy remote file to local file in place 2350s 01:57:51.412176700 O: scp: scp mode: copy local file to remote file clobber 2351s 01:57:51.769318000 O: -rw-r--r-- 1 openssh-tests openssh-tests 797736 May 2 01:57 /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/copy 2351s 01:57:51.812469200 O: -rw-rw-r-- 1 openssh-tests openssh-tests 797736 May 2 01:57 /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/data 2351s 01:57:51.889524000 O: scp: scp mode: copy remote file to local file clobber 2351s 01:57:52.315528900 O: scp: scp mode: simple copy local file to remote dir 2352s 01:57:52.745364600 O: scp: scp mode: simple copy local file to local dir 2352s 01:57:53.308268000 O: scp: scp mode: simple copy remote file to local dir 2353s 01:57:53.880760600 O: scp: scp mode: recursive local dir to remote dir 2354s 01:57:54.818442500 O: scp: scp mode: recursive local dir to local dir 2355s 01:57:55.514532400 O: scp: scp mode: recursive remote dir to local dir 2356s 01:57:56.529480600 O: scp: scp mode: unmatched glob file local->remote 2356s 01:57:56.929050300 O: scp: scp mode: unmatched glob file remote->local 2356s 01:57:57.224267200 O: scp: scp mode: unmatched glob dir recursive local->remote 2357s 01:57:57.847077300 O: scp: scp mode: unmatched glob dir recursive remote->local 2357s 01:57:58.262162300 O: scp: scp mode: shell metacharacters 2358s 01:57:58.538565400 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 2359s 01:58:00.088219900 O: scp: scp mode: disallow bad server #0 2360s 01:58:00.901516900 O: scp: scp mode: disallow bad server #1 2361s 01:58:01.677960600 O: scp: scp mode: disallow bad server #2 2362s 01:58:02.466175100 O: scp: scp mode: disallow bad server #3 2362s 01:58:03.279295200 O: scp: scp mode: disallow bad server #4 2363s 01:58:04.092972500 O: scp: scp mode: disallow bad server #5 2364s 01:58:04.870125900 O: scp: scp mode: disallow bad server #6 2365s 01:58:05.674780800 O: scp: scp mode: disallow bad server #7 2366s 01:58:06.474359800 O: scp: scp mode: detect non-directory target 2366s 01:58:06.705997500 E: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/copy2: Not a directory 2366s 01:58:06.776559500 O: scp: sftp mode: simple copy local file to local file 2366s 01:58:07.089470100 O: scp: sftp mode: simple copy local file to remote file 2367s 01:58:07.520842600 O: scp: sftp mode: simple copy remote file to local file 2367s 01:58:07.931023900 O: scp: sftp mode: copy local file to remote file in place 2367s 01:58:08.392484200 O: scp: sftp mode: copy remote file to local file in place 2368s 01:58:08.840865800 O: scp: sftp mode: copy local file to remote file clobber 2368s 01:58:09.232946400 O: -rw-r--r-- 1 openssh-tests openssh-tests 797736 May 2 01:58 /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/copy 2368s 01:58:09.277738100 O: -rw-rw-r-- 1 openssh-tests openssh-tests 797736 May 2 01:57 /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/data 2368s 01:58:09.354935100 O: scp: sftp mode: copy remote file to local file clobber 2369s 01:58:09.798895000 O: scp: sftp mode: simple copy local file to remote dir 2369s 01:58:10.264220400 O: scp: sftp mode: simple copy local file to local dir 2371s 01:58:10.620702200 O: scp: sftp mode: simple copy remote file to local dir 2371s 01:58:11.075452600 O: scp: sftp mode: recursive local dir to remote dir 2371s 01:58:12.108855400 O: scp: sftp mode: recursive local dir to local dir 2372s 01:58:13.151032700 O: scp: sftp mode: recursive remote dir to local dir 2373s 01:58:14.382017700 O: scp: sftp mode: unmatched glob file local->remote 2374s 01:58:14.820609200 O: scp: sftp mode: unmatched glob file remote->local 2374s 01:58:15.160358900 O: scp: sftp mode: unmatched glob dir recursive local->remote 2375s 01:58:15.845966400 O: scp: sftp mode: unmatched glob dir recursive remote->local 2375s 01:58:16.366041200 O: scp: sftp mode: shell metacharacters 2376s 01:58:16.667535600 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 2377s 01:58:18.265588100 O: scp: sftp mode: disallow bad server #0 2379s 01:58:19.528390200 O: scp: sftp mode: disallow bad server #1 2380s 01:58:20.816749600 O: scp: sftp mode: disallow bad server #2 2381s 01:58:22.075472600 O: scp: sftp mode: disallow bad server #3 2382s 01:58:23.336172900 O: scp: sftp mode: disallow bad server #4 2384s 01:58:24.595377200 O: scp: sftp mode: disallow bad server #5 2385s 01:58:25.861503900 O: scp: sftp mode: disallow bad server #6 2386s 01:58:27.142082300 O: scp: sftp mode: disallow bad server #7 2387s 01:58:28.402373400 O: scp: sftp mode: detect non-directory target 2388s 01:58:28.559561600 E: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/copy2: Not a directory 2388s 01:58:28.778520200 O: ok scp 2388s 01:58:28.864351700 E: run test scp3.sh ... 2393s 01:58:34.080510800 O: scp3: scp mode: simple copy remote file to remote file 2398s 01:58:38.992967800 O: scp3: scp mode: simple copy remote file to remote dir 2403s 01:58:43.626253500 O: scp3: scp mode: recursive remote dir to remote dir 2409s 01:58:50.376938100 O: scp3: scp mode: detect non-directory target 2418s 01:58:58.754241900 O: scp3: sftp mode: simple copy remote file to remote file 2418s 01:58:59.239208100 O: scp3: sftp mode: simple copy remote file to remote dir 2419s 01:58:59.837471500 O: scp3: sftp mode: recursive remote dir to remote dir 2420s 01:59:01.290341800 O: scp3: sftp mode: detect non-directory target 2421s 01:59:01.516485600 E: scp: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/copy2: destination is not a directory 2421s 01:59:01.564874700 E: scp: /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/copy2: destination is not a directory 2421s 01:59:01.785493400 O: ok scp3 2421s 01:59:01.807245000 E: run test scp-uri.sh ... 2428s 01:59:07.266094300 O: scp-uri: scp mode: simple copy local file to remote file 2428s 01:59:07.640295800 O: scp-uri: scp mode: simple copy remote file to local file 2428s 01:59:08.004777300 O: scp-uri: scp mode: simple copy local file to remote dir 2428s 01:59:08.408225800 O: scp-uri: scp mode: simple copy remote file to local dir 2428s 01:59:08.811770200 O: scp-uri: scp mode: recursive local dir to remote dir 2428s 01:59:09.261631000 O: scp-uri: scp mode: recursive remote dir to local dir 2429s 01:59:09.714314600 O: scp-uri: sftp mode: simple copy local file to remote file 2429s 01:59:10.110571800 O: scp-uri: sftp mode: simple copy remote file to local file 2430s 01:59:10.491742000 O: scp-uri: sftp mode: simple copy local file to remote dir 2430s 01:59:10.924021200 O: scp-uri: sftp mode: simple copy remote file to local dir 2430s 01:59:11.387948500 O: scp-uri: sftp mode: recursive local dir to remote dir 2431s 01:59:11.872817700 O: scp-uri: sftp mode: recursive remote dir to local dir 2432s 01:59:12.469127200 O: ok scp-uri 2432s 01:59:12.492801500 E: run test sftp.sh ... 2436s 01:59:17.123215300 O: test basic sftp put/get: buffer_size 5 num_requests 1 2499s 02:00:19.882759700 O: test basic sftp put/get: buffer_size 5 num_requests 2 2533s 02:00:54.003363300 O: test basic sftp put/get: buffer_size 5 num_requests 10 2547s 02:01:08.359254400 O: test basic sftp put/get: buffer_size 1000 num_requests 1 2548s 02:01:09.169449300 O: test basic sftp put/get: buffer_size 1000 num_requests 2 2549s 02:01:09.755053100 O: test basic sftp put/get: buffer_size 1000 num_requests 10 2549s 02:01:10.262121900 O: test basic sftp put/get: buffer_size 32000 num_requests 1 2550s 02:01:10.763259300 O: test basic sftp put/get: buffer_size 32000 num_requests 2 2550s 02:01:11.215039600 O: test basic sftp put/get: buffer_size 32000 num_requests 10 2551s 02:01:11.661589100 O: test basic sftp put/get: buffer_size 64000 num_requests 1 2551s 02:01:12.144827000 O: test basic sftp put/get: buffer_size 64000 num_requests 2 2552s 02:01:12.597663200 O: test basic sftp put/get: buffer_size 64000 num_requests 10 2552s 02:01:13.122254400 O: ok basic sftp put/get 2552s 02:01:13.323660800 E: run test sftp-chroot.sh ... 2559s 02:01:19.911391400 O: test sftp in chroot: get 2563s 02:01:23.464727900 O: test sftp in chroot: match 2567s 02:01:28.408085300 O: ok sftp in chroot 2568s 02:01:28.677337700 E: run test sftp-cmds.sh ... 2573s 02:01:33.991294900 O: sftp commands: lls 2573s 02:01:34.274536300 O: sftp commands: lls w/path 2574s 02:01:34.532102600 O: sftp commands: ls 2574s 02:01:34.720953100 O: sftp commands: shell 2574s 02:01:34.916218800 O: sftp commands: pwd 2574s 02:01:35.034717500 O: sftp commands: lpwd 2574s 02:01:35.152929600 O: sftp commands: quit 2574s 02:01:35.269329300 O: sftp commands: help 2574s 02:01:35.417186900 O: sftp commands: get 2575s 02:01:35.763803900 O: sftp commands: get quoted 2575s 02:01:36.150849400 O: sftp commands: get filename with quotes 2576s 02:01:36.580444900 O: sftp commands: get filename with spaces 2576s 02:01:36.966199000 O: sftp commands: get filename with glob metacharacters 2576s 02:01:37.308420400 O: sftp commands: get to directory 2577s 02:01:37.649056000 O: sftp commands: glob get to directory 2578s 02:01:39.268643600 O: sftp commands: get to local dir 2579s 02:01:39.612986300 O: sftp commands: glob get to local dir 2580s 02:01:41.031213700 O: sftp commands: put 2580s 02:01:41.383597000 O: sftp commands: put filename with quotes 2581s 02:01:41.737500800 O: sftp commands: put filename with spaces 2581s 02:01:42.121804600 O: sftp commands: put to directory 2582s 02:01:42.479506100 O: sftp commands: glob put to directory 2582s 02:01:42.933211700 O: sftp commands: put to local dir 2582s 02:01:43.287908600 O: sftp commands: glob put to local dir 2584s 02:01:44.500178100 O: sftp commands: rename 2584s 02:01:44.700100500 O: sftp commands: rename directory 2584s 02:01:44.818349200 O: sftp commands: ln 2584s 02:01:44.971343800 O: sftp commands: ln -s 2584s 02:01:45.119306500 O: sftp commands: cp 2584s 02:01:45.407640000 O: sftp commands: mkdir 2585s 02:01:45.526857100 O: sftp commands: chdir 2585s 02:01:45.645647800 O: sftp commands: rmdir 2585s 02:01:45.763231200 O: sftp commands: lmkdir 2585s 02:01:45.904399600 O: sftp commands: lchdir 2585s 02:01:46.134617500 O: ok sftp commands 2585s 02:01:46.163360700 E: run test sftp-badcmds.sh ... 2590s 02:01:50.902048200 O: sftp invalid commands: get nonexistent 2590s 02:01:51.053237500 O: sftp invalid commands: glob get to nonexistent directory 2590s 02:01:51.321950900 O: sftp invalid commands: put nonexistent 2591s 02:01:51.475992000 O: sftp invalid commands: glob put to nonexistent directory 2591s 02:01:51.639490800 O: sftp invalid commands: rename nonexistent 2591s 02:01:51.872399100 O: sftp invalid commands: rename target exists (directory) 2591s 02:01:52.181957000 O: sftp invalid commands: glob put files to local file 2591s 02:01:52.389378300 O: ok sftp invalid commands 2591s 02:01:52.412499500 E: run test sftp-batch.sh ... 2597s 02:01:57.731015800 O: sftp batchfile: good commands 2597s 02:01:58.054215600 O: sftp batchfile: bad commands 2597s 02:01:58.403252400 O: sftp batchfile: comments and blanks 2598s 02:01:58.576250500 O: sftp batchfile: junk command 2598s 02:01:58.727409400 O: ok sftp batchfile 2598s 02:01:58.759141100 E: run test sftp-glob.sh ... 2603s 02:02:03.552401200 O: sftp glob: file glob 2603s 02:02:03.853706600 O: sftp glob: dir glob 2603s 02:02:04.118147700 O: sftp glob: quoted glob 2603s 02:02:04.434068400 O: sftp glob: escaped glob 2604s 02:02:04.748787300 O: sftp glob: escaped quote 2604s 02:02:04.996319600 O: sftp glob: quoted quote 2604s 02:02:05.320762400 O: sftp glob: single-quoted quote 2605s 02:02:05.598907900 O: sftp glob: escaped space 2605s 02:02:05.809966800 O: sftp glob: quoted space 2605s 02:02:06.062698900 O: sftp glob: escaped slash 2605s 02:02:06.336837800 O: sftp glob: quoted slash 2606s 02:02:06.546620600 O: sftp glob: escaped slash at EOL 2606s 02:02:06.800631500 O: sftp glob: quoted slash at EOL 2606s 02:02:07.049150900 O: sftp glob: escaped slash+quote 2606s 02:02:07.300115500 O: sftp glob: quoted slash+quote 2607s 02:02:07.588472900 O: ok sftp glob 2607s 02:02:07.606967200 E: run test sftp-perm.sh ... 2612s 02:02:12.859325700 O: sftp permissions: read-only upload 2613s 02:02:13.525868600 O: sftp permissions: read-only setstat 2613s 02:02:14.137942400 O: sftp permissions: read-only rm 2614s 02:02:14.690086900 O: sftp permissions: read-only mkdir 2614s 02:02:15.196204800 O: sftp permissions: read-only rmdir 2615s 02:02:15.748915100 O: sftp permissions: read-only posix-rename 2615s 02:02:16.330170700 O: sftp permissions: read-only oldrename 2616s 02:02:16.874166500 O: sftp permissions: read-only symlink 2616s 02:02:17.422772300 O: sftp permissions: read-only hardlink 2617s 02:02:17.971884700 O: sftp permissions: explicit open 2618s 02:02:19.248667700 O: sftp permissions: explicit read 2620s 02:02:20.537736000 O: sftp permissions: explicit write 2621s 02:02:21.904028700 O: sftp permissions: explicit lstat 2622s 02:02:23.247771200 O: sftp permissions: explicit opendir 2623s 02:02:24.164066400 O: sftp permissions: explicit readdir 2624s 02:02:25.081360600 O: sftp permissions: explicit setstat 2625s 02:02:26.271353700 O: sftp permissions: explicit remove 2627s 02:02:27.608307500 O: sftp permissions: explicit mkdir 2628s 02:02:28.541851600 O: sftp permissions: explicit rmdir 2629s 02:02:29.663112200 O: sftp permissions: explicit rename 2630s 02:02:30.764717500 O: sftp permissions: explicit symlink 2631s 02:02:31.824334600 O: sftp permissions: explicit hardlink 2632s 02:02:32.917187400 O: sftp permissions: explicit statvfs 2633s 02:02:33.697341600 O: ok sftp permissions 2633s 02:02:33.720224600 E: run test sftp-uri.sh ... 2639s 02:02:40.055537700 O: sftp-uri: non-interactive fetch to local file 2642s 02:02:43.290935800 O: sftp-uri: non-interactive fetch to local dir 2646s 02:02:47.085588100 O: sftp-uri: put to remote directory (trailing slash) 2650s 02:02:50.493419600 O: sftp-uri: put to remote directory (no slash) 2654s 02:02:54.296088400 O: ok sftp-uri 2654s 02:02:54.320488000 E: run test reconfigure.sh ... 2685s 02:03:26.177884400 O: ok simple connect after reconfigure 2685s 02:03:26.205736600 E: run test dynamic-forward.sh ... 2691s 02:03:32.286796500 O: test -D forwarding 2709s 02:03:49.497289300 O: test -R forwarding 2724s 02:04:05.048296200 O: PermitRemoteOpen=any 2741s 02:04:21.485174900 O: PermitRemoteOpen=none 2745s 02:04:25.597281400 O: PermitRemoteOpen=explicit 2762s 02:04:42.547594000 O: PermitRemoteOpen=disallowed 2767s 02:04:48.272250300 O: ok dynamic forwarding 2767s 02:04:48.307496200 E: run test forwarding.sh ... 2826s 02:05:47.420273200 O: ok local and remote forwarding 2826s 02:05:47.449745400 E: run test multiplex.sh ... 2837s 02:05:57.475175000 O: test connection multiplexing: setenv 2837s 02:05:57.962546100 O: test connection multiplexing: envpass 2837s 02:05:58.425894600 O: test connection multiplexing: transfer 2839s 02:05:59.918246000 O: test connection multiplexing: transfer -Oproxy 2842s 02:06:03.082316500 O: test connection multiplexing: forward 2846s 02:06:06.837946200 O: test connection multiplexing: status 0 () 2852s 02:06:13.378635900 O: test connection multiplexing: status 0 (-Oproxy) 2858s 02:06:19.364113200 O: test connection multiplexing: status 1 () 2864s 02:06:25.340982300 O: test connection multiplexing: status 1 (-Oproxy) 2870s 02:06:31.319782700 O: test connection multiplexing: status 4 () 2876s 02:06:37.288371600 O: test connection multiplexing: status 4 (-Oproxy) 2882s 02:06:43.316627500 O: test connection multiplexing: status 5 () 2888s 02:06:49.288404300 O: test connection multiplexing: status 5 (-Oproxy) 2894s 02:06:55.283134700 O: test connection multiplexing: status 44 () 2900s 02:07:01.249237700 O: test connection multiplexing: status 44 (-Oproxy) 2906s 02:07:07.200445700 O: test connection multiplexing: cmd check 2907s 02:07:07.503253900 O: test connection multiplexing: cmd forward local (TCP) 2912s 02:07:12.505141000 O: test connection multiplexing: cmd forward remote (TCP) 2916s 02:07:17.141942700 O: test connection multiplexing: cmd forward local (UNIX) 2919s 02:07:19.622078800 O: test connection multiplexing: cmd forward remote (UNIX) 2921s 02:07:21.784515800 O: test connection multiplexing: cmd exit 2921s 02:07:22.173194800 O: test connection multiplexing: cmd stop 2936s 02:07:37.356385800 O: ok connection multiplexing 2936s 02:07:37.380867200 E: run test reexec.sh ... 2941s 02:07:42.011337300 O: test config passing 2946s 02:07:47.192879600 O: test reexec fallback 2946s 02:07:47.232456700 E: ln: failed to create hard link '/tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 2952s 02:07:53.264592300 O: ok reexec tests 2952s 02:07:53.296021700 E: run test brokenkeys.sh ... 2962s 02:08:02.877668700 O: ok broken keys 2962s 02:08:02.899485800 E: run test sshcfgparse.sh ... 2967s 02:08:07.847794300 O: reparse minimal config 2968s 02:08:08.866292000 O: ssh -W opts 2970s 02:08:10.766058300 O: user first match 2971s 02:08:12.044609900 O: pubkeyacceptedalgorithms 2973s 02:08:14.092730400 O: agentforwarding 2975s 02:08:15.603280500 O: command line override 2975s 02:08:16.441946100 O: ok ssh config parse 2976s 02:08:16.473368200 E: run test cfgparse.sh ... 2981s 02:08:21.684042600 O: reparse minimal config 2982s 02:08:22.957701700 O: reparse regress config 2984s 02:08:24.817926300 O: listenaddress order 2986s 02:08:26.524380500 O: ok sshd config parse 2986s 02:08:26.556587300 E: run test cfgmatch.sh ... 3037s 02:09:17.746100100 O: ok sshd_config match 3037s 02:09:17.779075800 E: run test cfgmatchlisten.sh ... 3129s 02:10:49.921761100 O: ok sshd_config matchlisten 3129s 02:10:49.951025700 E: run test percent.sh ... 3134s 02:10:54.675970600 O: percent expansions matchexec percent 3172s 02:11:33.128982900 O: percent expansions localcommand percent 3218s 02:12:19.056469100 O: percent expansions remotecommand percent 3223s 02:12:24.379551100 O: percent expansions controlpath percent 3228s 02:12:29.288635700 O: percent expansions identityagent percent 3233s 02:12:34.334873400 O: percent expansions forwardagent percent 3239s 02:12:39.830621700 O: percent expansions localforward percent 3244s 02:12:44.969177100 O: percent expansions remoteforward percent 3249s 02:12:50.031295400 O: percent expansions revokedhostkeys percent 3254s 02:12:55.414726600 O: percent expansions userknownhostsfile percent 3294s 02:13:34.874874000 O: percent expansions controlpath dollar 3294s 02:13:35.355174200 O: percent expansions identityagent dollar 3295s 02:13:35.817375800 O: percent expansions forwardagent dollar 3295s 02:13:36.273828600 O: percent expansions localforward dollar 3296s 02:13:36.730382500 O: percent expansions remoteforward dollar 3296s 02:13:37.187090300 O: percent expansions userknownhostsfile dollar 3301s 02:13:41.763098800 O: percent expansions controlpath tilde 3302s 02:13:42.674279200 O: percent expansions identityagent tilde 3303s 02:13:43.553482800 O: percent expansions forwardagent tilde 3303s 02:13:44.417685700 O: ok percent expansions 3303s 02:13:44.443583100 E: run test addrmatch.sh ... 3308s 02:13:49.336047500 O: test first entry for user 192.168.0.1 somehost 3309s 02:13:49.753115800 O: test negative match for user 192.168.30.1 somehost 3309s 02:13:50.183256000 O: test no match for user 19.0.0.1 somehost 3310s 02:13:50.609353000 O: test list middle for user 10.255.255.254 somehost 3310s 02:13:51.040395100 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 3311s 02:13:51.462286600 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 3311s 02:13:51.872165700 O: test localaddress for user 19.0.0.1 somehost 3311s 02:13:52.401950000 O: test localport for user 19.0.0.1 somehost 3312s 02:13:52.770280300 O: test bare IP6 address for user ::1 somehost.example.com 3312s 02:13:53.178994200 O: test deny IPv6 for user ::2 somehost.example.com 3313s 02:13:53.595856000 O: test IP6 negated for user ::3 somehost 3313s 02:13:54.018312200 O: test IP6 no match for user ::4 somehost 3313s 02:13:54.437721200 O: test IP6 network for user 2000::1 somehost 3314s 02:13:54.853524300 O: test IP6 network for user 2001::1 somehost 3314s 02:13:55.275875600 O: test IP6 localaddress for user ::5 somehost 3315s 02:13:55.682995700 O: test IP6 localport for user ::5 somehost 3315s 02:13:56.108605000 O: test invalid Match address 10.0.1.0/8 3316s 02:13:56.855296800 O: test invalid Match localaddress 10.0.1.0/8 3316s 02:13:57.324689300 O: test invalid Match address 10.0.0.1/24 3317s 02:13:57.729566000 O: test invalid Match localaddress 10.0.0.1/24 3317s 02:13:58.100543000 O: test invalid Match address 2000:aa:bb:01::/56 3318s 02:13:58.476309700 O: test invalid Match localaddress 2000:aa:bb:01::/56 3318s 02:13:58.960615900 O: ok address match 3318s 02:13:58.986146000 E: run test localcommand.sh ... 3323s 02:14:03.793140600 O: test localcommand: proto localcommand 3327s 02:14:07.598128300 O: ok localcommand 3327s 02:14:07.624876600 E: run test forcecommand.sh ... 3348s 02:14:29.279176100 E: Connection closed. 3348s 02:14:29.338040000 E: Connection closed 3356s 02:14:36.643269400 E: Connection closed. 3356s 02:14:36.691429700 E: Connection closed 3360s 02:14:41.193925500 O: ok forced command 3360s 02:14:41.234764800 E: run test portnum.sh ... 3365s 02:14:45.984476600 O: port number parsing: invalid port 0 3365s 02:14:46.315169200 O: port number parsing: invalid port 65536 3366s 02:14:46.617941800 O: port number parsing: invalid port 131073 3366s 02:14:46.916953400 O: port number parsing: invalid port 2000blah 3366s 02:14:47.217140100 O: port number parsing: invalid port blah2000 3367s 02:14:47.517319000 O: port number parsing: valid port 1 3370s 02:14:51.173845700 O: port number parsing: valid port 22 3374s 02:14:54.789386300 O: port number parsing: valid port 2222 3377s 02:14:58.320006700 O: port number parsing: valid port 22222 3382s 02:15:02.533836400 O: port number parsing: valid port 65535 3385s 02:15:06.249819100 O: ok port number parsing 3385s 02:15:06.273593000 E: run test keytype.sh ... 3390s 02:15:11.132897100 O: keygen ed25519, 512 bits 3391s 02:15:11.465075300 O: keygen ed25519-sk, n/a bits 3391s 02:15:11.898927500 O: keygen ecdsa, 256 bits 3391s 02:15:12.248138000 O: keygen ecdsa, 384 bits 3392s 02:15:12.598447100 O: keygen ecdsa, 521 bits 3392s 02:15:12.945006700 O: keygen ecdsa-sk, n/a bits 3392s 02:15:13.432978800 O: keygen rsa, 2048 bits 3393s 02:15:14.449038100 O: keygen rsa, 3072 bits 3397s 02:15:17.865437800 O: userkey ed25519-512, hostkey ed25519-512 3400s 02:15:21.084442300 O: userkey ed25519-512, hostkey ed25519-512 3403s 02:15:24.292430100 O: userkey ed25519-512, hostkey ed25519-512 3408s 02:15:28.550436100 O: userkey ed25519-sk, hostkey ed25519-sk 3411s 02:15:32.040451300 O: userkey ed25519-sk, hostkey ed25519-sk 3415s 02:15:35.568620100 O: userkey ed25519-sk, hostkey ed25519-sk 3418s 02:15:39.261004800 O: userkey ecdsa-256, hostkey ecdsa-256 3422s 02:15:42.806880000 O: userkey ecdsa-256, hostkey ecdsa-256 3425s 02:15:46.235077100 O: userkey ecdsa-256, hostkey ecdsa-256 3430s 02:15:50.610331600 O: userkey ecdsa-384, hostkey ecdsa-384 3433s 02:15:54.254850100 O: userkey ecdsa-384, hostkey ecdsa-384 3437s 02:15:57.734107800 O: userkey ecdsa-384, hostkey ecdsa-384 3440s 02:16:01.384837600 O: userkey ecdsa-521, hostkey ecdsa-521 3444s 02:16:04.828664000 O: userkey ecdsa-521, hostkey ecdsa-521 3447s 02:16:08.319424400 O: userkey ecdsa-521, hostkey ecdsa-521 3451s 02:16:11.970490500 O: userkey ecdsa-sk, hostkey ecdsa-sk 3456s 02:16:16.475009100 O: userkey ecdsa-sk, hostkey ecdsa-sk 3459s 02:16:20.267603800 O: userkey ecdsa-sk, hostkey ecdsa-sk 3463s 02:16:24.199242800 O: userkey rsa-2048, hostkey rsa-2048 3467s 02:16:27.480456000 O: userkey rsa-2048, hostkey rsa-2048 3470s 02:16:30.844145000 O: userkey rsa-2048, hostkey rsa-2048 3473s 02:16:34.376257900 O: userkey rsa-3072, hostkey rsa-3072 3478s 02:16:38.440733000 O: userkey rsa-3072, hostkey rsa-3072 3481s 02:16:41.800214100 O: userkey rsa-3072, hostkey rsa-3072 3484s 02:16:45.190132200 O: ok login with different key types 3484s 02:16:45.223245500 E: run test kextype.sh ... 3490s 02:16:50.666044600 O: kex diffie-hellman-group1-sha1 3500s 02:17:01.254963000 O: kex diffie-hellman-group14-sha1 3511s 02:17:11.835008700 O: kex diffie-hellman-group14-sha256 3521s 02:17:22.048667400 O: kex diffie-hellman-group16-sha512 3532s 02:17:33.222763400 O: kex diffie-hellman-group18-sha512 3545s 02:17:45.313169000 O: kex diffie-hellman-group-exchange-sha1 3558s 02:17:58.734859100 O: kex diffie-hellman-group-exchange-sha256 3571s 02:18:12.039461800 O: kex ecdh-sha2-nistp256 3581s 02:18:21.959495200 O: kex ecdh-sha2-nistp384 3592s 02:18:32.634080700 O: kex ecdh-sha2-nistp521 3602s 02:18:43.176218300 O: kex curve25519-sha256 3612s 02:18:53.372833800 O: kex curve25519-sha256@libssh.org 3623s 02:19:03.958779000 O: kex sntrup761x25519-sha512 3637s 02:19:18.308508200 O: kex sntrup761x25519-sha512@openssh.com 3652s 02:19:33.412837000 O: kex mlkem768x25519-sha256 3663s 02:19:44.183847800 O: ok login with different key exchange algorithms 3663s 02:19:44.218165800 E: run test cert-hostkey.sh ... 3671s 02:19:51.821403400 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/host_ca_key.pub 3671s 02:19:51.870523900 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/host_ca_key2.pub 3671s 02:19:51.910748400 O: certified host keys: sign host ed25519 cert 3671s 02:19:52.132923100 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 3672s 02:19:52.480704800 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 3672s 02:19:52.592806600 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 3672s 02:19:52.962387700 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 3672s 02:19:53.292929900 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 3672s 02:19:53.406560600 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 3673s 02:19:53.714262800 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 3673s 02:19:54.095408200 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 3673s 02:19:54.206638000 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 3674s 02:19:54.506419300 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 3674s 02:19:54.889447800 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 3674s 02:19:54.999489300 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 3674s 02:19:55.374669800 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 3675s 02:19:55.755383800 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 3675s 02:19:55.866807400 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 3676s 02:19:56.310809900 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 3676s 02:19:56.693092200 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 3676s 02:19:56.804368300 O: certified host keys: sign host rsa cert 3679s 02:20:00.110846200 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 3680s 02:20:00.541212800 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 3680s 02:20:00.632049300 O: certified host keys: sign host rsa-sha2-256 cert 3683s 02:20:03.552592800 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 3683s 02:20:03.947851500 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 3683s 02:20:04.058491400 O: certified host keys: sign host rsa-sha2-512 cert 3685s 02:20:06.293420500 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 3686s 02:20:07.052502000 O: Revoking from /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 3686s 02:20:07.230745900 O: certified host keys: host ed25519 cert connect 3686s 02:20:07.338505900 O: certified host keys: ed25519 basic connect expect success yes 3690s 02:20:11.452427000 O: certified host keys: ed25519 empty KRL expect success yes 3694s 02:20:15.320320100 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 3697s 02:20:17.986179700 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 3700s 02:20:20.645879300 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 3702s 02:20:23.318534400 O: certified host keys: ed25519 empty plaintext revocation expect success yes 3706s 02:20:27.232125300 O: certified host keys: ed25519 plain key plaintext revocation expect success no 3709s 02:20:29.952892500 O: certified host keys: ed25519 cert plaintext revocation expect success no 3712s 02:20:33.203517600 O: certified host keys: ed25519 CA plaintext revocation expect success no 3715s 02:20:36.030093000 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 3715s 02:20:36.109199600 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 3719s 02:20:40.163828700 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 3723s 02:20:44.047284900 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 3726s 02:20:46.812833200 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 3729s 02:20:49.593853400 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 3731s 02:20:52.451552800 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 3735s 02:20:56.375609700 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 3739s 02:20:59.800149200 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 3742s 02:21:02.704698200 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 3745s 02:21:05.472737100 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 3745s 02:21:05.549823700 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 3749s 02:21:09.537469700 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 3752s 02:21:13.405439500 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 3755s 02:21:16.256092000 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 3758s 02:21:19.041431800 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 3761s 02:21:22.250107400 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 3765s 02:21:26.440103700 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 3768s 02:21:29.217408300 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 3771s 02:21:32.124182300 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 3774s 02:21:34.876130100 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 3774s 02:21:34.968286400 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 3778s 02:21:38.876459700 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 3782s 02:21:42.841203300 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 3785s 02:21:45.804043200 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 3788s 02:21:49.012835900 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 3791s 02:21:51.791949600 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 3795s 02:21:55.745144400 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 3798s 02:21:58.540336700 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 3800s 02:22:01.378035200 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 3803s 02:22:04.150531900 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 3803s 02:22:04.229538500 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 3807s 02:22:08.297529700 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 3812s 02:22:12.833771300 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 3815s 02:22:15.766043800 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 3818s 02:22:18.549520600 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 3820s 02:22:21.295931200 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 3824s 02:22:25.199999400 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 3828s 02:22:28.014557700 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 3830s 02:22:30.998145500 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 3833s 02:22:33.781752800 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 3833s 02:22:33.859165300 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 3838s 02:22:38.473314000 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 3842s 02:22:42.707866000 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 3845s 02:22:45.660443200 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 3848s 02:22:48.545055000 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 3850s 02:22:51.430421700 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 3855s 02:22:55.568926500 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 3858s 02:22:58.487267700 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 3861s 02:23:01.990092100 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 3864s 02:23:05.117339600 O: certified host keys: host rsa cert connect 3864s 02:23:05.207938700 O: certified host keys: rsa basic connect expect success yes 3868s 02:23:09.197421400 O: certified host keys: rsa empty KRL expect success yes 3872s 02:23:13.092598600 O: certified host keys: rsa KRL w/ plain key revoked expect success no 3875s 02:23:15.868173800 O: certified host keys: rsa KRL w/ cert revoked expect success no 3878s 02:23:18.641479600 O: certified host keys: rsa KRL w/ CA revoked expect success no 3881s 02:23:21.466496400 O: certified host keys: rsa empty plaintext revocation expect success yes 3884s 02:23:25.313264600 O: certified host keys: rsa plain key plaintext revocation expect success no 3888s 02:23:28.752371400 O: certified host keys: rsa cert plaintext revocation expect success no 3891s 02:23:31.786107700 O: certified host keys: rsa CA plaintext revocation expect success no 3894s 02:23:34.550961500 O: certified host keys: host rsa-sha2-256 cert connect 3894s 02:23:34.631976400 O: certified host keys: rsa-sha2-256 basic connect expect success yes 3898s 02:23:38.497916500 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 3901s 02:23:42.274934900 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 3904s 02:23:44.992817400 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 3907s 02:23:47.769271000 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 3910s 02:23:50.475040900 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 3914s 02:23:54.944823200 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 3917s 02:23:57.777218700 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 3920s 02:24:00.681135600 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 3922s 02:24:03.412486500 O: certified host keys: host rsa-sha2-512 cert connect 3923s 02:24:03.494789300 O: certified host keys: rsa-sha2-512 basic connect expect success yes 3926s 02:24:07.361909700 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 3930s 02:24:11.214241300 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 3933s 02:24:13.909181900 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 3936s 02:24:16.633612000 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 3939s 02:24:19.937029400 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 3943s 02:24:23.829218200 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 3946s 02:24:26.553839600 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 3949s 02:24:29.584385700 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 3952s 02:24:32.702195400 O: certified host keys: host ed25519 revoked cert 3954s 02:24:35.450214300 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 3957s 02:24:38.390245900 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 3960s 02:24:41.175636100 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 3963s 02:24:43.965120800 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 3966s 02:24:47.347568100 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 3969s 02:24:50.366530400 O: certified host keys: host rsa revoked cert 3972s 02:24:53.221168800 O: certified host keys: host rsa-sha2-256 revoked cert 3975s 02:24:56.015020700 O: certified host keys: host rsa-sha2-512 revoked cert 3978s 02:24:58.929704300 O: certified host keys: host ed25519 revoked cert 3981s 02:25:01.632475400 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 3984s 02:25:04.518868600 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 3986s 02:25:07.313458200 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 3989s 02:25:10.115511400 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 3993s 02:25:13.527863800 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 3996s 02:25:16.530292200 O: certified host keys: host rsa revoked cert 3998s 02:25:19.313874700 O: certified host keys: host rsa-sha2-256 revoked cert 4001s 02:25:22.058897000 O: certified host keys: host rsa-sha2-512 revoked cert 4217s 02:28:57.474208000 O: certified host keys: host ed25519 cert downgrade to raw key 4224s 02:29:05.341697700 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 4233s 02:29:13.539325100 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 4241s 02:29:22.283192400 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 4249s 02:29:30.404443900 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 4258s 02:29:38.640187200 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 4267s 02:29:47.905053100 O: certified host keys: host rsa cert downgrade to raw key 4278s 02:29:58.546713400 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 4290s 02:30:10.610375200 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 4301s 02:30:21.688727200 O: certified host keys: host ed25519 connect wrong cert 4304s 02:30:24.714366700 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 4307s 02:30:28.150461700 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 4310s 02:30:31.373065100 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 4314s 02:30:34.548103500 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 4317s 02:30:38.274124000 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 4321s 02:30:42.018499600 O: certified host keys: host rsa connect wrong cert 4326s 02:30:46.636557700 O: certified host keys: host rsa-sha2-256 connect wrong cert 4332s 02:30:52.885906900 O: certified host keys: host rsa-sha2-512 connect wrong cert 4339s 02:31:00.241753800 O: ok certified host keys 4339s 02:31:00.272221500 E: run test cert-userkey.sh ... 4349s 02:31:09.480801200 O: certified user keys: sign user ed25519 cert 4349s 02:31:09.870193500 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 4350s 02:31:10.836129000 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 4350s 02:31:11.326926400 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 4351s 02:31:11.777174500 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 4351s 02:31:12.219834600 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 4352s 02:31:12.797674300 O: certified user keys: sign user rsa cert 4353s 02:31:14.332160500 O: certified user keys: sign user rsa-sha2-256 cert 4355s 02:31:15.880444600 O: certified user keys: sign user rsa-sha2-512 cert 4358s 02:31:19.035641900 O: certified user keys: ed25519 missing authorized_principals 4361s 02:31:22.156704100 O: certified user keys: ed25519 empty authorized_principals 4364s 02:31:25.038061600 O: certified user keys: ed25519 wrong authorized_principals 4367s 02:31:28.008482200 O: certified user keys: ed25519 correct authorized_principals 4371s 02:31:31.781214300 O: certified user keys: ed25519 authorized_principals bad key opt 4374s 02:31:34.660967400 O: certified user keys: ed25519 authorized_principals command=false 4378s 02:31:38.929610900 O: certified user keys: ed25519 authorized_principals command=true 4382s 02:31:42.923602300 O: certified user keys: ed25519 wrong principals key option 4385s 02:31:45.877234100 O: certified user keys: ed25519 correct principals key option 4389s 02:31:49.635081200 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 4392s 02:31:52.581303100 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 4395s 02:31:55.498992100 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 4398s 02:31:58.502522700 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 4401s 02:32:02.283343000 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 4405s 02:32:05.826247000 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 4409s 02:32:09.810897900 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 4413s 02:32:13.782240800 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 4416s 02:32:16.812098700 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 4420s 02:32:20.759606000 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 4423s 02:32:23.793145400 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 4426s 02:32:26.690466100 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 4429s 02:32:30.089308600 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 4433s 02:32:34.006608000 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 4436s 02:32:37.030335100 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 4440s 02:32:40.759645200 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 4444s 02:32:44.595188400 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 4447s 02:32:47.638275100 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 4451s 02:32:51.584330900 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 4454s 02:32:55.157855500 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 4457s 02:32:58.228543100 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 4460s 02:33:01.205744500 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 4464s 02:33:05.020896000 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 4467s 02:33:07.998385100 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 4471s 02:33:11.758015600 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 4475s 02:33:15.588172300 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 4478s 02:33:19.170688800 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 4482s 02:33:23.435897900 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 4486s 02:33:26.541981500 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 4489s 02:33:29.544490800 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 4492s 02:33:32.550248300 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 4495s 02:33:36.376403700 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 4498s 02:33:39.389952300 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 4502s 02:33:43.025714100 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 4507s 02:33:47.596123300 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 4510s 02:33:50.586120700 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 4514s 02:33:54.580200300 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 4517s 02:33:57.613221500 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 4520s 02:34:00.603953800 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 4523s 02:34:03.627928200 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 4527s 02:34:07.538107200 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 4530s 02:34:11.049546700 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 4534s 02:34:15.082522500 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 4538s 02:34:19.085297300 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 4541s 02:34:22.066218700 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 4545s 02:34:26.196815600 O: certified user keys: rsa missing authorized_principals 4548s 02:34:29.142010700 O: certified user keys: rsa empty authorized_principals 4551s 02:34:32.100076900 O: certified user keys: rsa wrong authorized_principals 4555s 02:34:35.602055300 O: certified user keys: rsa correct authorized_principals 4558s 02:34:39.410482800 O: certified user keys: rsa authorized_principals bad key opt 4561s 02:34:42.392398900 O: certified user keys: rsa authorized_principals command=false 4565s 02:34:46.104520900 O: certified user keys: rsa authorized_principals command=true 4569s 02:34:49.972271900 O: certified user keys: rsa wrong principals key option 4572s 02:34:52.946452000 O: certified user keys: rsa correct principals key option 4576s 02:34:56.810801700 O: certified user keys: rsa-sha2-256 missing authorized_principals 4579s 02:35:00.397385100 O: certified user keys: rsa-sha2-256 empty authorized_principals 4582s 02:35:03.401927100 O: certified user keys: rsa-sha2-256 wrong authorized_principals 4585s 02:35:06.390350400 O: certified user keys: rsa-sha2-256 correct authorized_principals 4589s 02:35:10.175580500 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 4592s 02:35:13.117989900 O: certified user keys: rsa-sha2-256 authorized_principals command=false 4596s 02:35:16.865763500 O: certified user keys: rsa-sha2-256 authorized_principals command=true 4600s 02:35:20.662459100 O: certified user keys: rsa-sha2-256 wrong principals key option 4603s 02:35:23.592493800 O: certified user keys: rsa-sha2-256 correct principals key option 4607s 02:35:28.159238200 O: certified user keys: rsa-sha2-512 missing authorized_principals 4610s 02:35:31.118069300 O: certified user keys: rsa-sha2-512 empty authorized_principals 4613s 02:35:34.030261400 O: certified user keys: rsa-sha2-512 wrong authorized_principals 4616s 02:35:36.892206800 O: certified user keys: rsa-sha2-512 correct authorized_principals 4620s 02:35:40.544555200 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 4623s 02:35:43.539507900 O: certified user keys: rsa-sha2-512 authorized_principals command=false 4626s 02:35:47.222175900 O: certified user keys: rsa-sha2-512 authorized_principals command=true 4631s 02:35:51.811405100 O: certified user keys: rsa-sha2-512 wrong principals key option 4634s 02:35:54.793695800 O: certified user keys: rsa-sha2-512 correct principals key option 4638s 02:35:58.655395700 O: certified user keys: ed25519 authorized_keys connect 4641s 02:36:02.361041100 O: certified user keys: ed25519 authorized_keys revoked key 4644s 02:36:05.343226500 O: certified user keys: ed25519 authorized_keys revoked via KRL 4648s 02:36:08.482078900 O: certified user keys: ed25519 authorized_keys empty KRL 4651s 02:36:12.258537300 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 4656s 02:36:16.873575000 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 4659s 02:36:19.961808900 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 4662s 02:36:23.059203100 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 4666s 02:36:27.043442700 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 4670s 02:36:30.824320700 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 4673s 02:36:33.948577700 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 4677s 02:36:37.101274500 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 4681s 02:36:41.630286600 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 4685s 02:36:45.527058700 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 4688s 02:36:48.600864800 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 4691s 02:36:51.734869800 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 4695s 02:36:55.587382000 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 4698s 02:36:59.445643500 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 4702s 02:37:02.470580000 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 4705s 02:37:06.259378300 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 4709s 02:37:10.229685600 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 4713s 02:37:14.245410500 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 4716s 02:37:17.242029700 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 4719s 02:37:20.303483100 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 4723s 02:37:24.438384500 O: certified user keys: rsa authorized_keys connect 4728s 02:37:28.856421500 O: certified user keys: rsa authorized_keys revoked key 4731s 02:37:31.959204500 O: certified user keys: rsa authorized_keys revoked via KRL 4734s 02:37:35.046808600 O: certified user keys: rsa authorized_keys empty KRL 4738s 02:37:38.919390700 O: certified user keys: rsa-sha2-256 authorized_keys connect 4742s 02:37:42.714432600 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 4745s 02:37:45.770019600 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 4748s 02:37:48.867821800 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 4752s 02:37:53.339265000 O: certified user keys: rsa-sha2-512 authorized_keys connect 4756s 02:37:57.288667300 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 4759s 02:38:00.269121000 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 4762s 02:38:03.281040000 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 4766s 02:38:07.171242600 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 4769s 02:38:10.210799900 O: certified user keys: authorized_keys CA does not authenticate 4769s 02:38:10.290566700 O: certified user keys: ensure CA key does not authenticate user 4772s 02:38:13.185542500 O: certified user keys: ed25519 TrustedUserCAKeys connect 4777s 02:38:17.468203400 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 4780s 02:38:20.706008900 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 4783s 02:38:23.856623400 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 4787s 02:38:27.675000500 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 4791s 02:38:31.544456800 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 4794s 02:38:34.648967200 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 4797s 02:38:37.648952100 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 4801s 02:38:41.809925300 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 4805s 02:38:46.106631400 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 4808s 02:38:49.200540000 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 4811s 02:38:52.352591600 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 4815s 02:38:56.273869000 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 4819s 02:39:00.194794600 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 4822s 02:39:03.276085900 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 4825s 02:39:06.384259100 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 4830s 02:39:11.028275100 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 4834s 02:39:14.974362200 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 4837s 02:39:18.068728200 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 4840s 02:39:21.207113500 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 4844s 02:39:25.156921200 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 4848s 02:39:29.166184900 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 4852s 02:39:32.900161800 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 4855s 02:39:36.146164900 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 4859s 02:39:40.384214600 O: certified user keys: rsa TrustedUserCAKeys connect 4863s 02:39:44.160007200 O: certified user keys: rsa TrustedUserCAKeys revoked key 4866s 02:39:47.105312700 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 4869s 02:39:50.274115300 O: certified user keys: rsa TrustedUserCAKeys empty KRL 4873s 02:39:54.075097300 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 4878s 02:39:58.537424100 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 4882s 02:40:01.647068200 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 4884s 02:40:04.728797100 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 4888s 02:40:08.563944200 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 4891s 02:40:12.454891700 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 4895s 02:40:15.468264600 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 4898s 02:40:18.505012400 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 4902s 02:40:23.061199100 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 4905s 02:40:26.049544400 O: certified user keys: TrustedUserCAKeys CA does not authenticate 4905s 02:40:26.132159700 O: certified user keys: ensure CA key does not authenticate user 4908s 02:40:29.135102200 O: certified user keys: correct principal auth authorized_keys expect success rsa 4912s 02:40:33.122196300 O: certified user keys: correct principal auth authorized_keys expect success ed25519 4916s 02:40:37.143450400 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 4920s 02:40:41.126620400 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 4925s 02:40:45.966367000 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 4928s 02:40:49.206477200 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 4931s 02:40:52.340922200 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 4934s 02:40:55.427912800 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 4938s 02:40:58.618103300 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 4941s 02:41:01.725055200 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 4944s 02:41:04.922082000 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 4947s 02:41:08.118589900 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 4951s 02:41:11.982383900 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 4954s 02:41:15.244073700 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 4957s 02:41:18.410637200 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 4961s 02:41:21.586762600 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 4964s 02:41:24.815831700 O: certified user keys: cert expired auth authorized_keys expect failure rsa 4967s 02:41:27.967543000 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 4970s 02:41:31.192050000 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 4973s 02:41:34.386196300 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 4977s 02:41:38.236365900 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 4981s 02:41:42.345370500 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 4985s 02:41:46.365572300 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 4989s 02:41:50.293829300 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 4993s 02:41:54.330791800 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 4997s 02:41:57.516135600 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 5000s 02:42:01.198937800 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 5004s 02:42:04.606071900 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 5007s 02:42:07.812295300 O: certified user keys: force-command auth authorized_keys expect failure rsa 5011s 02:42:11.855573600 O: certified user keys: force-command auth authorized_keys expect failure ed25519 5015s 02:42:15.689986800 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 5019s 02:42:19.681955300 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 5023s 02:42:23.918976300 O: certified user keys: empty principals auth authorized_keys expect success rsa 5027s 02:42:28.446446500 O: certified user keys: empty principals auth authorized_keys expect success ed25519 5031s 02:42:32.348378000 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 5035s 02:42:35.535274000 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 5038s 02:42:38.737667600 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 5042s 02:42:42.739285000 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 5046s 02:42:46.619471400 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 5050s 02:42:50.452761400 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 5053s 02:42:53.771248300 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 5057s 02:42:57.791632400 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 5061s 02:43:01.687482400 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 5064s 02:43:04.816986700 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 5067s 02:43:08.090246600 O: certified user keys: force-command match true auth authorized_keys expect success rsa 5071s 02:43:12.339410300 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 5076s 02:43:16.717979500 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 5080s 02:43:20.733731200 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 5084s 02:43:24.568951800 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 5087s 02:43:27.708271300 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 5090s 02:43:30.954136000 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 5093s 02:43:34.071282300 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 5097s 02:43:38.060730700 O: certified user keys: user ed25519 connect wrong cert 5100s 02:43:41.382193000 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 5104s 02:43:44.463251800 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 5107s 02:43:47.586801600 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 5110s 02:43:50.665913900 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 5113s 02:43:53.835164900 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 5116s 02:43:57.005719100 O: certified user keys: user rsa connect wrong cert 5119s 02:44:00.085083100 O: certified user keys: user rsa-sha2-256 connect wrong cert 5123s 02:44:03.506897900 O: certified user keys: user rsa-sha2-512 connect wrong cert 5126s 02:44:06.891012900 O: ok certified user keys 5126s 02:44:06.914875800 E: run test host-expand.sh ... 5134s 02:44:15.233834400 O: ok expand %h and %n 5134s 02:44:15.265175800 E: run test keys-command.sh ... 5140s 02:44:20.716495800 O: SKIPPED: /var/run/keycommand_openssh-tests.44415 not executable (/var/run mounted noexec?) 5140s 02:44:20.981119100 E: run test forward-control.sh ... 5154s 02:44:35.294790500 O: check_lfwd done (expecting Y): default configuration 5163s 02:44:43.639769000 O: check_rfwd done (expecting Y): default configuration 5171s 02:44:52.286240800 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 5178s 02:44:59.393992800 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 5184s 02:45:04.704137000 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 5193s 02:45:13.729458500 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 5201s 02:45:21.602104600 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 5209s 02:45:29.884427500 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 5214s 02:45:35.184499300 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 5223s 02:45:44.219293300 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 5231s 02:45:51.967928700 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 5239s 02:46:00.211569700 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 5245s 02:46:06.237281800 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 5249s 02:46:10.341395900 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 5255s 02:46:15.628106800 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 5259s 02:46:19.661587300 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 5267s 02:46:28.185788500 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 5275s 02:46:36.427911200 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 5283s 02:46:44.402228600 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 5287s 02:46:48.434324900 O: check_rfwd done (expecting N): AllowTcpForwarding=local 5293s 02:46:54.434080500 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 5298s 02:46:58.521705100 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 5304s 02:47:05.293257300 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 5308s 02:47:09.359938800 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 5314s 02:47:15.267261000 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 5318s 02:47:19.386800100 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 5325s 02:47:26.220545500 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 5329s 02:47:30.290306500 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 5335s 02:47:36.248737300 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 5340s 02:47:40.458692300 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 5345s 02:47:45.816596800 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 5349s 02:47:49.870251700 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 5357s 02:47:58.188604400 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 5362s 02:48:02.513264300 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 5367s 02:48:07.907581300 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 5375s 02:48:16.246338400 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 5381s 02:48:21.573475100 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 5390s 02:48:30.799484500 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 5395s 02:48:36.035561400 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 5403s 02:48:44.262586700 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 5409s 02:48:50.129299600 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 5417s 02:48:57.463293600 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 5422s 02:49:02.840524300 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 5430s 02:49:11.025585500 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 5436s 02:49:17.068643600 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 5440s 02:49:21.158389900 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 5446s 02:49:26.510720200 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 5450s 02:49:30.622109900 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 5455s 02:49:35.913841600 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 5464s 02:49:44.982622300 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 5469s 02:49:50.306657300 O: check_lfwd done (expecting N): AllowTcpForwarding=no 5473s 02:49:54.353933800 O: check_rfwd done (expecting N): AllowTcpForwarding=no 5479s 02:49:59.958723900 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 5483s 02:50:04.347606400 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 5489s 02:50:09.639549900 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 5493s 02:50:13.671022500 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 5498s 02:50:19.001591400 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 5503s 02:50:23.634816900 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 5508s 02:50:29.083928900 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 5512s 02:50:33.111576100 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 5518s 02:50:38.493106600 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 5522s 02:50:42.461712700 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 5527s 02:50:48.442364800 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 5532s 02:50:52.565125500 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 5537s 02:50:57.860536600 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 5541s 02:51:01.935866000 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 5549s 02:51:10.415078500 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 5554s 02:51:14.566912700 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 5560s 02:51:21.350478500 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 5569s 02:51:29.560138500 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 5577s 02:51:38.128749500 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 5581s 02:51:42.200325000 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 5589s 02:51:50.025269300 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 5593s 02:51:54.036848300 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 5599s 02:52:00.370065700 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 5604s 02:52:04.627936500 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 5609s 02:52:09.843748300 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 5618s 02:52:18.489870300 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 5623s 02:52:24.255163900 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 5627s 02:52:28.269397300 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 5633s 02:52:33.575196800 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 5637s 02:52:37.696097000 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 5637s 02:52:38.279449300 O: ok sshd control of local and remote forwarding 5637s 02:52:38.305339400 E: run test integrity.sh ... 5643s 02:52:44.440526500 O: test integrity: hmac-sha1 @2900 5648s 02:52:48.657270700 O: test integrity: hmac-sha1 @2901 5652s 02:52:52.730564200 O: test integrity: hmac-sha1 @2902 5656s 02:52:56.817040400 O: test integrity: hmac-sha1 @2903 5660s 02:53:00.882078900 O: test integrity: hmac-sha1 @2904 5665s 02:53:05.640743000 O: test integrity: hmac-sha1 @2905 5669s 02:53:09.701502300 O: test integrity: hmac-sha1 @2906 5673s 02:53:13.778941900 O: test integrity: hmac-sha1 @2907 5677s 02:53:17.888569000 O: test integrity: hmac-sha1 @2908 5681s 02:53:21.912485800 O: test integrity: hmac-sha1 @2909 5685s 02:53:26.284718800 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5686s 02:53:26.813028000 O: test integrity: hmac-sha1-96 @2900 5690s 02:53:30.889274900 O: test integrity: hmac-sha1-96 @2901 5694s 02:53:34.926219000 O: test integrity: hmac-sha1-96 @2902 5698s 02:53:38.986865500 O: test integrity: hmac-sha1-96 @2903 5702s 02:53:42.982792400 O: test integrity: hmac-sha1-96 @2904 5707s 02:53:47.720488100 O: test integrity: hmac-sha1-96 @2905 5711s 02:53:51.794676700 O: test integrity: hmac-sha1-96 @2906 5715s 02:53:55.832873600 O: test integrity: hmac-sha1-96 @2907 5719s 02:53:59.894090000 O: test integrity: hmac-sha1-96 @2908 5723s 02:54:03.979249000 O: test integrity: hmac-sha1-96 @2909 5727s 02:54:08.383309000 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5728s 02:54:08.939294600 O: test integrity: hmac-sha2-256 @2900 5732s 02:54:13.042573900 O: test integrity: hmac-sha2-256 @2901 5736s 02:54:17.100469200 O: test integrity: hmac-sha2-256 @2902 5740s 02:54:21.132962200 O: test integrity: hmac-sha2-256 @2903 5744s 02:54:25.214282900 O: test integrity: hmac-sha2-256 @2904 5749s 02:54:29.934142700 O: test integrity: hmac-sha2-256 @2905 5753s 02:54:34.124795800 O: test integrity: hmac-sha2-256 @2906 5757s 02:54:38.176600900 O: test integrity: hmac-sha2-256 @2907 5761s 02:54:42.211379600 O: test integrity: hmac-sha2-256 @2908 5765s 02:54:46.297805700 O: test integrity: hmac-sha2-256 @2909 5770s 02:54:50.576424000 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5770s 02:54:51.171282100 O: test integrity: hmac-sha2-512 @2900 5774s 02:54:54.717266600 O: test integrity: hmac-sha2-512 @2901 5777s 02:54:58.054684900 O: test integrity: hmac-sha2-512 @2902 5780s 02:55:01.422687600 O: test integrity: hmac-sha2-512 @2903 5784s 02:55:04.835384700 O: test integrity: hmac-sha2-512 @2904 5787s 02:55:08.272135400 O: test integrity: hmac-sha2-512 @2905 5791s 02:55:11.584533900 O: test integrity: hmac-sha2-512 @2906 5795s 02:55:15.576254700 O: test integrity: hmac-sha2-512 @2907 5798s 02:55:18.886493800 O: test integrity: hmac-sha2-512 @2908 5801s 02:55:22.243916800 O: test integrity: hmac-sha2-512 @2909 5804s 02:55:25.265560000 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5805s 02:55:25.781927500 O: test integrity: hmac-md5 @2900 5809s 02:55:29.980081700 O: test integrity: hmac-md5 @2901 5813s 02:55:34.073441700 O: test integrity: hmac-md5 @2902 5818s 02:55:38.959245800 O: test integrity: hmac-md5 @2903 5822s 02:55:43.062647500 O: test integrity: hmac-md5 @2904 5826s 02:55:47.118549700 O: test integrity: hmac-md5 @2905 5830s 02:55:51.236799300 O: test integrity: hmac-md5 @2906 5834s 02:55:55.402979600 O: test integrity: hmac-md5 @2907 5839s 02:56:00.160212900 O: test integrity: hmac-md5 @2908 5843s 02:56:04.346058700 O: test integrity: hmac-md5 @2909 5847s 02:56:08.112054900 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5848s 02:56:08.624373200 O: test integrity: hmac-md5-96 @2900 5852s 02:56:12.637318200 O: test integrity: hmac-md5-96 @2901 5856s 02:56:16.731239500 O: test integrity: hmac-md5-96 @2902 5860s 02:56:21.187923600 O: test integrity: hmac-md5-96 @2903 5865s 02:56:25.481998500 O: test integrity: hmac-md5-96 @2904 5869s 02:56:29.565060800 O: test integrity: hmac-md5-96 @2905 5873s 02:56:33.572897300 O: test integrity: hmac-md5-96 @2906 5877s 02:56:37.650551600 O: test integrity: hmac-md5-96 @2907 5881s 02:56:41.659125500 O: test integrity: hmac-md5-96 @2908 5885s 02:56:46.323525900 O: test integrity: hmac-md5-96 @2909 5889s 02:56:50.088995700 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5890s 02:56:50.600183800 O: test integrity: umac-64@openssh.com @2900 5894s 02:56:54.655950200 O: test integrity: umac-64@openssh.com @2901 5898s 02:56:58.714834300 O: test integrity: umac-64@openssh.com @2902 5902s 02:57:02.773465200 O: test integrity: umac-64@openssh.com @2903 5907s 02:57:07.562701200 O: test integrity: umac-64@openssh.com @2904 5911s 02:57:11.600907800 O: test integrity: umac-64@openssh.com @2905 5915s 02:57:15.572635500 O: test integrity: umac-64@openssh.com @2906 5919s 02:57:19.624632000 O: test integrity: umac-64@openssh.com @2907 5923s 02:57:23.678141800 O: test integrity: umac-64@openssh.com @2908 5927s 02:57:28.418670000 O: test integrity: umac-64@openssh.com @2909 5931s 02:57:32.101466300 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5932s 02:57:32.620893000 O: test integrity: umac-128@openssh.com @2900 5936s 02:57:36.643792300 O: test integrity: umac-128@openssh.com @2901 5940s 02:57:40.673954500 O: test integrity: umac-128@openssh.com @2902 5944s 02:57:44.719649800 O: test integrity: umac-128@openssh.com @2903 5949s 02:57:49.508179100 O: test integrity: umac-128@openssh.com @2904 5953s 02:57:53.582320600 O: test integrity: umac-128@openssh.com @2905 5957s 02:57:57.571145900 O: test integrity: umac-128@openssh.com @2906 5961s 02:58:01.565995900 O: test integrity: umac-128@openssh.com @2907 5965s 02:58:05.656290800 O: test integrity: umac-128@openssh.com @2908 5969s 02:58:10.374828500 O: test integrity: umac-128@openssh.com @2909 5973s 02:58:14.144809800 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 5974s 02:58:14.656285100 O: test integrity: hmac-sha1-etm@openssh.com @2900 5978s 02:58:18.662277300 O: test integrity: hmac-sha1-etm@openssh.com @2901 5982s 02:58:22.710308500 O: test integrity: hmac-sha1-etm@openssh.com @2902 5986s 02:58:26.848009200 O: test integrity: hmac-sha1-etm@openssh.com @2903 5990s 02:58:31.303113600 O: test integrity: hmac-sha1-etm@openssh.com @2904 5995s 02:58:35.668027300 O: test integrity: hmac-sha1-etm@openssh.com @2905 5999s 02:58:39.771920800 O: test integrity: hmac-sha1-etm@openssh.com @2906 6003s 02:58:43.857309900 O: test integrity: hmac-sha1-etm@openssh.com @2907 6007s 02:58:48.006647500 O: test integrity: hmac-sha1-etm@openssh.com @2908 6012s 02:58:52.459374000 O: test integrity: hmac-sha1-etm@openssh.com @2909 6016s 02:58:56.496985900 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6016s 02:58:57.010764800 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 6020s 02:59:01.091050400 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 6024s 02:59:05.005183800 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 6028s 02:59:09.030412300 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 6032s 02:59:13.023061800 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 6037s 02:59:17.834277800 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 6041s 02:59:21.891447500 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 6045s 02:59:25.853876700 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 6049s 02:59:29.851592100 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 6053s 02:59:34.020537400 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 6057s 02:59:38.441354600 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6058s 02:59:39.058007400 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 6062s 02:59:43.230474800 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 6066s 02:59:47.252922500 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 6070s 02:59:51.359499400 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 6075s 02:59:55.493907900 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 6079s 03:00:00.269360400 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 6084s 03:00:04.592236300 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 6088s 03:00:08.678328600 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 6092s 03:00:12.728407600 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 6096s 03:00:16.848232900 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 6101s 03:00:21.118442100 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6101s 03:00:21.708352900 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 6104s 03:00:25.205650800 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 6108s 03:00:28.574562000 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 6111s 03:00:31.935264000 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 6114s 03:00:35.247126900 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 6118s 03:00:38.668178200 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 6121s 03:00:42.329583200 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 6125s 03:00:46.012951600 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 6128s 03:00:49.421188800 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 6132s 03:00:52.788054600 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 6135s 03:00:55.769385500 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6135s 03:00:56.282807700 O: test integrity: hmac-md5-etm@openssh.com @2900 6140s 03:01:00.497611800 O: test integrity: hmac-md5-etm@openssh.com @2901 6144s 03:01:04.535824700 O: test integrity: hmac-md5-etm@openssh.com @2902 6148s 03:01:09.318391000 O: test integrity: hmac-md5-etm@openssh.com @2903 6153s 03:01:13.464406200 O: test integrity: hmac-md5-etm@openssh.com @2904 6157s 03:01:17.509670300 O: test integrity: hmac-md5-etm@openssh.com @2905 6161s 03:01:21.624684900 O: test integrity: hmac-md5-etm@openssh.com @2906 6165s 03:01:25.653177100 O: test integrity: hmac-md5-etm@openssh.com @2907 6169s 03:01:30.392694300 O: test integrity: hmac-md5-etm@openssh.com @2908 6174s 03:01:34.477462500 O: test integrity: hmac-md5-etm@openssh.com @2909 6177s 03:01:38.227939900 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6178s 03:01:38.747614000 O: test integrity: hmac-md5-96-etm@openssh.com @2900 6182s 03:01:42.788211700 O: test integrity: hmac-md5-96-etm@openssh.com @2901 6186s 03:01:46.940164000 O: test integrity: hmac-md5-96-etm@openssh.com @2902 6191s 03:01:51.568210500 O: test integrity: hmac-md5-96-etm@openssh.com @2903 6195s 03:01:55.756129000 O: test integrity: hmac-md5-96-etm@openssh.com @2904 6199s 03:01:59.904072900 O: test integrity: hmac-md5-96-etm@openssh.com @2905 6203s 03:02:04.006999100 O: test integrity: hmac-md5-96-etm@openssh.com @2906 6207s 03:02:08.153571900 O: test integrity: hmac-md5-96-etm@openssh.com @2907 6212s 03:02:12.599061100 O: test integrity: hmac-md5-96-etm@openssh.com @2908 6216s 03:02:16.989070900 O: test integrity: hmac-md5-96-etm@openssh.com @2909 6220s 03:02:20.762060400 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6220s 03:02:21.275925500 O: test integrity: umac-64-etm@openssh.com @2900 6224s 03:02:25.270669200 O: test integrity: umac-64-etm@openssh.com @2901 6228s 03:02:29.383935900 O: test integrity: umac-64-etm@openssh.com @2902 6232s 03:02:33.388233400 O: test integrity: umac-64-etm@openssh.com @2903 6237s 03:02:38.169624900 O: test integrity: umac-64-etm@openssh.com @2904 6241s 03:02:42.252695900 O: test integrity: umac-64-etm@openssh.com @2905 6245s 03:02:46.263476600 O: test integrity: umac-64-etm@openssh.com @2906 6249s 03:02:50.284259400 O: test integrity: umac-64-etm@openssh.com @2907 6253s 03:02:54.404558400 O: test integrity: umac-64-etm@openssh.com @2908 6258s 03:02:59.181581500 O: test integrity: umac-64-etm@openssh.com @2909 6262s 03:03:02.947414300 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6263s 03:03:03.507996600 O: test integrity: umac-128-etm@openssh.com @2900 6267s 03:03:07.586175500 O: test integrity: umac-128-etm@openssh.com @2901 6271s 03:03:11.602080600 O: test integrity: umac-128-etm@openssh.com @2902 6275s 03:03:15.723434400 O: test integrity: umac-128-etm@openssh.com @2903 6279s 03:03:20.402117300 O: test integrity: umac-128-etm@openssh.com @2904 6285s 03:03:24.537097100 O: test integrity: umac-128-etm@openssh.com @2905 6288s 03:03:28.633014800 O: test integrity: umac-128-etm@openssh.com @2906 6292s 03:03:32.724006500 O: test integrity: umac-128-etm@openssh.com @2907 6296s 03:03:36.821462700 O: test integrity: umac-128-etm@openssh.com @2908 6301s 03:03:41.545443200 O: test integrity: umac-128-etm@openssh.com @2909 6304s 03:03:45.401469200 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6305s 03:03:45.920540300 O: test integrity: aes128-gcm@openssh.com @2900 6308s 03:03:49.195026600 O: test integrity: aes128-gcm@openssh.com @2901 6312s 03:03:52.469899000 O: test integrity: aes128-gcm@openssh.com @2902 6315s 03:03:55.763273800 O: test integrity: aes128-gcm@openssh.com @2903 6318s 03:03:59.112383300 O: test integrity: aes128-gcm@openssh.com @2904 6321s 03:04:02.380298400 O: test integrity: aes128-gcm@openssh.com @2905 6325s 03:04:06.204826800 O: test integrity: aes128-gcm@openssh.com @2906 6329s 03:04:09.529847300 O: test integrity: aes128-gcm@openssh.com @2907 6332s 03:04:12.802462300 O: test integrity: aes128-gcm@openssh.com @2908 6335s 03:04:16.064560000 O: test integrity: aes128-gcm@openssh.com @2909 6338s 03:04:18.974590700 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6339s 03:04:19.491554200 O: test integrity: aes256-gcm@openssh.com @2900 6342s 03:04:22.855609900 O: test integrity: aes256-gcm@openssh.com @2901 6345s 03:04:26.103549500 O: test integrity: aes256-gcm@openssh.com @2902 6349s 03:04:29.955084600 O: test integrity: aes256-gcm@openssh.com @2903 6352s 03:04:33.234362800 O: test integrity: aes256-gcm@openssh.com @2904 6356s 03:04:36.530581500 O: test integrity: aes256-gcm@openssh.com @2905 6359s 03:04:39.788588300 O: test integrity: aes256-gcm@openssh.com @2906 6362s 03:04:43.007970400 O: test integrity: aes256-gcm@openssh.com @2907 6365s 03:04:46.308134200 O: test integrity: aes256-gcm@openssh.com @2908 6369s 03:04:49.610768400 O: test integrity: aes256-gcm@openssh.com @2909 6372s 03:04:52.988521500 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6373s 03:04:53.556101800 O: test integrity: chacha20-poly1305@openssh.com @2900 6376s 03:04:56.982662300 O: test integrity: chacha20-poly1305@openssh.com @2901 6379s 03:05:00.291016100 O: test integrity: chacha20-poly1305@openssh.com @2902 6383s 03:05:03.575386200 O: test integrity: chacha20-poly1305@openssh.com @2903 6386s 03:05:06.838848700 O: test integrity: chacha20-poly1305@openssh.com @2904 6389s 03:05:10.214982000 O: test integrity: chacha20-poly1305@openssh.com @2905 6393s 03:05:13.465651700 O: test integrity: chacha20-poly1305@openssh.com @2906 6396s 03:05:17.118993000 O: test integrity: chacha20-poly1305@openssh.com @2907 6400s 03:05:20.577423700 O: test integrity: chacha20-poly1305@openssh.com @2908 6403s 03:05:23.831221200 O: test integrity: chacha20-poly1305@openssh.com @2909 6406s 03:05:26.804286400 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 6406s 03:05:26.883653300 O: ok integrity 6406s 03:05:26.911168400 E: run test krl.sh ... 6411s 03:05:32.054394900 O: key revocation lists: generating test keys 6424s 03:05:44.779466400 O: key revocation lists: generating KRLs 6426s 03:05:47.306268300 O: key revocation lists: checking revocations for revoked keys 6445s 03:06:06.252166000 O: key revocation lists: checking revocations for unrevoked keys 6463s 03:06:24.028904800 O: key revocation lists: checking revocations for revoked certs 6488s 03:06:48.777510200 O: key revocation lists: checking revocations for unrevoked certs 6511s 03:07:12.367282100 O: key revocation lists: testing KRL update 6533s 03:07:33.790547700 O: key revocation lists: checking revocations for revoked keys 6552s 03:07:52.753512800 O: key revocation lists: checking revocations for unrevoked keys 6570s 03:08:10.707031000 O: key revocation lists: checking revocations for revoked certs 6594s 03:08:35.356194200 O: key revocation lists: checking revocations for unrevoked certs 6618s 03:08:58.595582400 O: ok key revocation lists 6618s 03:08:58.636131700 E: run test multipubkey.sh ... 6652s 03:09:33.032523300 O: ok multiple pubkey 6652s 03:09:33.066573000 E: run test limit-keytype.sh ... 6674s 03:09:55.189692200 O: allow rsa,ed25519 6685s 03:10:05.505762700 O: allow ed25519 6694s 03:10:14.567004700 O: allow cert only 6704s 03:10:24.480630500 O: match w/ no match 6713s 03:10:33.725277500 O: match w/ matching 6724s 03:10:44.613147600 O: ok restrict pubkey type 6724s 03:10:44.651367600 E: run test hostkey-agent.sh ... 6735s 03:10:55.751902000 O: key type ssh-ed25519 6738s 03:10:59.283527800 O: key type sk-ssh-ed25519@openssh.com 6742s 03:11:02.677274100 O: key type ecdsa-sha2-nistp256 6746s 03:11:06.946302400 O: key type ecdsa-sha2-nistp384 6750s 03:11:10.509669400 O: key type ecdsa-sha2-nistp521 6753s 03:11:13.993326300 O: key type sk-ecdsa-sha2-nistp256@openssh.com 6757s 03:11:17.557810500 O: key type ssh-rsa 6760s 03:11:21.191000300 O: cert type ssh-ed25519-cert-v01@openssh.com 6764s 03:11:25.042692200 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 6769s 03:11:29.625161800 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 6773s 03:11:33.656453200 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 6777s 03:11:37.518248100 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 6780s 03:11:41.374131500 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 6784s 03:11:45.414783100 O: cert type ssh-rsa-cert-v01@openssh.com 6788s 03:11:49.317218400 O: cert type rsa-sha2-256-cert-v01@openssh.com 6793s 03:11:53.507009100 O: cert type rsa-sha2-512-cert-v01@openssh.com 6797s 03:11:57.820331300 O: ok hostkey agent 6797s 03:11:57.851831100 E: run test hostkey-rotate.sh ... 6804s 03:12:05.193022800 O: learn hostkey with StrictHostKeyChecking=no 6808s 03:12:09.078065700 O: learn additional hostkeys 6813s 03:12:14.185545900 O: learn additional hostkeys, type=ssh-ed25519 6818s 03:12:18.732788200 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 6822s 03:12:22.729700300 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 6826s 03:12:26.637698800 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 6830s 03:12:30.473978000 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 6834s 03:12:34.504457300 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 6838s 03:12:39.072372200 O: learn additional hostkeys, type=ssh-rsa 6842s 03:12:43.200672200 O: learn changed non-primary hostkey type=ssh-rsa 6850s 03:12:51.137525600 O: learn new primary hostkey 6854s 03:12:55.293670900 O: rotate primary hostkey 6859s 03:12:59.532962200 O: check rotate primary hostkey 6862s 03:13:03.415373000 O: ok hostkey rotate 6862s 03:13:03.439504800 E: run test principals-command.sh ... 6872s 03:13:13.045676000 O: SKIPPED: /var/run/principals_command_openssh-tests.60824 not executable (/var/run mounted noexec?) 6872s 03:13:13.306484700 E: run test cert-file.sh ... 6879s 03:13:19.844222400 O: identity cert with no plain public file 6883s 03:13:23.722866800 O: CertificateFile with no plain public file 6887s 03:13:28.179098800 O: plain keys 6890s 03:13:31.127562400 O: untrusted cert 6893s 03:13:34.038136600 O: good cert, bad key 6896s 03:13:37.034147800 O: single trusted 6900s 03:13:40.776314100 O: multiple trusted 6915s 03:13:55.841418900 O: ok ssh with certificates 6915s 03:13:55.875774500 E: run test cfginclude.sh ... 6920s 03:14:00.754744000 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6920s 03:14:01.008297900 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6920s 03:14:01.278001700 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6921s 03:14:01.537987100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6921s 03:14:01.793263100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6921s 03:14:02.063340100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6921s 03:14:02.323625900 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6922s 03:14:02.579871100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6922s 03:14:02.835369100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6923s 03:14:03.471031800 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6923s 03:14:03.827000500 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6923s 03:14:04.095629100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6924s 03:14:04.526625100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6924s 03:14:04.785176100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6924s 03:14:05.042016700 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6924s 03:14:05.299492500 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6925s 03:14:05.557552000 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6925s 03:14:05.811169500 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6925s 03:14:06.062533500 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6925s 03:14:06.309065000 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6926s 03:14:06.562299100 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6926s 03:14:07.202089700 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6927s 03:14:07.480366300 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6927s 03:14:07.754984800 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6928s 03:14:08.712300800 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6928s 03:14:09.073005600 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 6928s 03:14:09.275072300 O: ok config include 6928s 03:14:09.300799300 E: run test servcfginclude.sh ... 6941s 03:14:21.918546400 O: ok server config include 6941s 03:14:21.944364600 E: run test allow-deny-users.sh ... 6972s 03:14:52.844696800 O: ok AllowUsers/DenyUsers 6972s 03:14:52.873870300 E: run test authinfo.sh ... 6977s 03:14:57.528760200 O: ExposeAuthInfo=no 6980s 03:15:01.225895200 O: ExposeAuthInfo=yes 6984s 03:15:04.849377000 O: ok authinfo 6984s 03:15:04.875612600 E: run test sshsig.sh ... 6989s 03:15:10.012001700 O: sshsig: make certificates 6991s 03:15:11.590773000 O: sshsig: check signature for ssh-ed25519 7001s 03:15:21.997712200 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 7013s 03:15:33.510559700 O: sshsig: check signature for ecdsa-sha2-nistp256 7024s 03:15:44.786006000 O: sshsig: check signature for ecdsa-sha2-nistp384 7036s 03:15:56.709827900 O: sshsig: check signature for ecdsa-sha2-nistp521 7047s 03:16:08.105328800 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 7060s 03:16:20.722892900 O: sshsig: check signature for ssh-rsa 7071s 03:16:31.861447200 O: sshsig: check signature for ssh-ed25519-cert.pub 7088s 03:16:49.428070200 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 7107s 03:17:08.232258400 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 7127s 03:17:27.812918600 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 7147s 03:17:47.654265200 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 7167s 03:18:07.669849100 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 7188s 03:18:28.459472700 O: sshsig: check signature for ssh-rsa-cert.pub 7206s 03:18:47.099299900 O: sshsig: match principals 7207s 03:18:47.777007700 O: sshsig: nomatch principals 7207s 03:18:48.332006700 O: ok sshsig 7207s 03:18:48.355405700 E: run test knownhosts.sh ... 7238s 03:19:18.576740200 O: ok known hosts 7238s 03:19:18.609889300 E: run test knownhosts-command.sh ... 7243s 03:19:24.007558900 O: simple connection 7247s 03:19:27.834122600 O: no keys 7249s 03:19:30.456082000 O: bad exit status 7251s 03:19:31.945754300 O: keytype ssh-ed25519 7255s 03:19:35.633579300 O: keytype sk-ssh-ed25519@openssh.com 7259s 03:19:39.994285900 O: keytype ecdsa-sha2-nistp256 7263s 03:19:43.950290100 O: keytype ecdsa-sha2-nistp384 7267s 03:19:47.753245100 O: keytype ecdsa-sha2-nistp521 7270s 03:19:51.456477100 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 7274s 03:19:55.376559300 O: keytype ssh-rsa 7278s 03:19:59.190710900 O: ok known hosts command 7278s 03:19:59.224099600 E: run test agent-restrict.sh ... 7284s 03:20:04.500863100 O: generate keys 7286s 03:20:06.807475200 O: prepare client config 7286s 03:20:07.379051100 O: prepare known_hosts 7287s 03:20:07.558991400 O: prepare server configs 7287s 03:20:08.220148100 O: authentication w/o agent 7323s 03:20:43.556162800 O: start agent 7327s 03:20:47.737623700 O: authentication with agent (no restrict) 7361s 03:21:22.383274000 O: unrestricted keylist 7379s 03:21:40.399263200 O: authentication with agent (basic restrict) 7397s 03:21:58.286721900 O: authentication with agent incorrect key (basic restrict) 7410s 03:22:11.016632500 O: keylist (basic restrict) 7428s 03:22:28.780230000 O: username 7446s 03:22:47.125099000 O: username wildcard 7465s 03:23:05.532952900 O: username incorrect 7467s 03:23:08.260648200 O: agent restriction honours certificate principal 7469s 03:23:09.577931200 O: multihop without agent 7488s 03:23:29.041220500 O: multihop agent unrestricted 7508s 03:23:49.012008700 O: multihop restricted 7528s 03:24:09.408330700 O: multihop username 7548s 03:24:28.677568000 O: multihop wildcard username 7567s 03:24:47.834413500 O: multihop wrong username 7582s 03:25:02.458571100 O: multihop cycle no agent 7609s 03:25:29.623141800 O: multihop cycle agent unrestricted 7636s 03:25:57.210616300 O: multihop cycle restricted deny 7648s 03:26:08.824254600 O: multihop cycle restricted allow 7676s 03:26:36.939911300 O: ok agent restrictions 7676s 03:26:36.977666000 E: run test hostbased.sh ... 7681s 03:26:41.613596800 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 7681s 03:26:41.650781500 E: run test channel-timeout.sh ... 7685s 03:26:46.320339600 O: no timeout 7695s 03:26:55.670268500 O: command timeout 7699s 03:26:59.631941900 O: command long timeout 7702s 03:27:03.219345900 O: command wildcard timeout 7707s 03:27:07.618307200 O: command irrelevant timeout 7715s 03:27:16.414010600 O: multiplexed command timeout 7730s 03:27:31.401212400 O: irrelevant multiplexed command timeout 7750s 03:27:50.869770900 O: global command timeout 7776s 03:28:17.341945100 O: sftp no timeout 7786s 03:28:26.495634300 O: sftp timeout 7790s 03:28:30.624724400 E: Connection closed 7790s 03:28:30.631852900 O: sftp irrelevant timeout 7799s 03:28:40.422245800 O: ok channel timeout 7800s 03:28:40.467465900 E: run test connection-timeout.sh ... 7804s 03:28:45.199403300 O: no timeout 7813s 03:28:53.788220200 O: timeout 7824s 03:29:04.991027300 O: session inhibits timeout 7836s 03:29:16.758161500 O: timeout after session 7844s 03:29:24.993656700 O: timeout with listeners 7856s 03:29:37.337011900 O: ok unused connection timeout 7856s 03:29:37.378797800 E: run test match-subsystem.sh ... 7894s 03:30:14.960620700 O: ok sshd_config match subsystem 7894s 03:30:14.995066900 E: run test agent-pkcs11-restrict.sh ... 7899s 03:30:20.181821900 O: SKIPPED: No PKCS#11 library found 7899s 03:30:20.212552600 E: run test agent-pkcs11-cert.sh ... 7904s 03:30:24.800053800 O: SKIPPED: No PKCS#11 library found 7904s 03:30:24.819359800 E: run test penalty.sh ... 7910s 03:30:30.862250200 O: test connect 7913s 03:30:34.315010100 O: penalty for authentication failure 7933s 03:30:54.260386500 O: penalty for no authentication 7938s 03:30:58.462340700 O: ok penalties 7938s 03:30:58.492604100 E: run test penalty-expire.sh ... 7944s 03:31:04.614710300 O: test connect 7947s 03:31:08.123310300 O: penalty expiry 7967s 03:31:27.619270400 O: ok penalties 7967s 03:31:27.667143800 O: set -e ; if test -z "" ; then \ 7967s 03:31:27.708219400 O: V="" ; \ 7967s 03:31:27.748328100 O: test "x" = "x" || \ 7967s 03:31:27.787359800 O: V=/tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 7967s 03:31:27.826265700 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 7967s 03:31:27.867064700 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 7967s 03:31:27.908632100 O: -d /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 7967s 03:31:27.948596600 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 7967s 03:31:27.988077600 O: -d /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 7967s 03:31:28.028816100 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 7967s 03:31:28.067990900 O: -d /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 7967s 03:31:28.107062600 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 7967s 03:31:28.146261800 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 7967s 03:31:28.186691500 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 7967s 03:31:28.225815300 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 7967s 03:31:28.265237000 O: -d /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 7967s 03:31:28.304472000 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 7967s 03:31:28.344116300 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 7967s 03:31:28.383622400 O: if test "x" = "xyes" ; then \ 7967s 03:31:28.423115200 O: $V /tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 7968s 03:31:28.465683100 O: fi \ 7968s 03:31:28.506540900 O: fi 8147s 03:34:28.046086400 O: test_sshbuf: ...................................................................................................... 103 tests ok 8675s 03:43:15.825209000 O: test_sshkey: .................................................................................... 84 tests ok 8675s 03:43:16.104685700 O: test_sshsig: ....... 7 tests ok 8677s 03:43:18.248400000 O: test_authopt: .................................................................................................................................................. 146 tests ok 8884s 03:46:44.657946300 O: test_bitmap: .. 2 tests ok 8884s 03:46:44.764484700 O: test_conversion: . 1 tests ok 8938s 03:47:39.072533800 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 8939s 03:47:39.688538000 O: test_hostkeys: .................. 18 tests ok 8939s 03:47:39.793847800 O: test_match: ...... 6 tests ok 8939s 03:47:39.906266800 O: test_misc: ........................................... 43 tests ok 8939s 03:47:39.963176700 E: run test putty-transfer.sh ... 8947s 03:47:48.317016600 O: plink version 0.83 major 0 minor 83 8948s 03:47:48.703591700 O: putty transfer data: compression 0 8966s 03:48:07.134510700 O: putty transfer data: compression 1 8985s 03:48:25.579279900 O: ok putty transfer data 8985s 03:48:25.604739100 E: run test putty-ciphers.sh ... 8993s 03:48:34.122879900 O: plink version 0.83 major 0 minor 83 8998s 03:48:38.878909500 O: putty ciphers: cipher default mac default 9000s 03:48:41.293135500 O: putty ciphers: cipher default mac hmac-sha1 9003s 03:48:43.748276400 O: putty ciphers: cipher default mac hmac-sha1-96 9005s 03:48:46.121401500 O: putty ciphers: cipher default mac hmac-sha2-256 9007s 03:48:48.420441200 O: putty ciphers: cipher default mac hmac-sha2-512 9011s 03:48:51.450492300 O: putty ciphers: cipher default mac hmac-md5 9013s 03:48:53.912435200 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 9015s 03:48:56.308502300 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 9018s 03:48:58.702098600 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 9020s 03:49:01.088848200 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 9022s 03:49:03.420538400 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 9025s 03:49:05.842430900 O: putty ciphers: cipher 3des-cbc mac default 9027s 03:49:08.171469600 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 9030s 03:49:10.964402400 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 9033s 03:49:13.512039300 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 9035s 03:49:15.885432200 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 9037s 03:49:18.299576400 O: putty ciphers: cipher 3des-cbc mac hmac-md5 9040s 03:49:20.590076300 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 9042s 03:49:22.888482500 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 9044s 03:49:25.199478800 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 9047s 03:49:27.626412400 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 9049s 03:49:30.034357000 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 9052s 03:49:33.021194100 O: putty ciphers: cipher aes128-cbc mac default 9055s 03:49:35.509098800 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 9057s 03:49:37.885256400 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 9059s 03:49:40.288933000 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 9062s 03:49:42.615081500 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 9064s 03:49:44.912960200 O: putty ciphers: cipher aes128-cbc mac hmac-md5 9066s 03:49:47.321273000 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 9069s 03:49:49.686908700 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 9071s 03:49:52.061060000 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 9074s 03:49:54.970114800 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 9077s 03:49:57.472452700 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 9079s 03:49:59.853035100 O: putty ciphers: cipher aes192-cbc mac default 9081s 03:50:02.406561900 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 9084s 03:50:04.789884200 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 9086s 03:50:07.113140000 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 9089s 03:50:09.495103500 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 9091s 03:50:11.862853000 O: putty ciphers: cipher aes192-cbc mac hmac-md5 9094s 03:50:14.787285800 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 9096s 03:50:17.305974500 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 9099s 03:50:19.691825500 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 9101s 03:50:22.147441000 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 9104s 03:50:24.467159300 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 9106s 03:50:26.885313100 O: putty ciphers: cipher aes256-cbc mac default 9108s 03:50:29.300122800 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 9111s 03:50:31.664118500 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 9113s 03:50:33.982380300 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 9116s 03:50:36.851561100 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 9118s 03:50:39.344297400 O: putty ciphers: cipher aes256-cbc mac hmac-md5 9121s 03:50:41.646253900 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 9123s 03:50:43.992315800 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 9125s 03:50:46.287169600 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 9128s 03:50:48.649901200 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 9130s 03:50:51.031571900 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 9132s 03:50:53.324804900 O: putty ciphers: cipher aes128-ctr mac default 9135s 03:50:55.617261400 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 9138s 03:50:58.605039000 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 9140s 03:51:00.982033400 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 9142s 03:51:03.348759700 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 9145s 03:51:05.672647300 O: putty ciphers: cipher aes128-ctr mac hmac-md5 9147s 03:51:08.014218000 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 9149s 03:51:10.342099600 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 9152s 03:51:12.792658100 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 9154s 03:51:15.132695300 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 9157s 03:51:18.085786200 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 9160s 03:51:20.605937500 O: putty ciphers: cipher aes192-ctr mac default 9162s 03:51:22.943640500 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 9164s 03:51:25.274195600 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 9167s 03:51:27.614304300 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 9169s 03:51:29.928385700 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 9171s 03:51:32.331478000 O: putty ciphers: cipher aes192-ctr mac hmac-md5 9174s 03:51:34.652744000 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 9176s 03:51:37.007451200 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 9179s 03:51:40.059418100 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 9182s 03:51:42.522685300 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 9184s 03:51:44.874412200 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 9186s 03:51:47.230006700 O: putty ciphers: cipher aes256-ctr mac default 9189s 03:51:49.553162700 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 9191s 03:51:51.914679500 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 9193s 03:51:54.299325600 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 9196s 03:51:56.650297400 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 9198s 03:51:58.947038400 O: putty ciphers: cipher aes256-ctr mac hmac-md5 9201s 03:52:01.868072000 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 9203s 03:52:04.270080600 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 9206s 03:52:06.602714700 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 9208s 03:52:09.029516800 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 9210s 03:52:11.388036500 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 9213s 03:52:13.743046100 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 9215s 03:52:16.176203900 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 9218s 03:52:18.506334700 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 9220s 03:52:20.836800600 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 9223s 03:52:23.884218600 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 9225s 03:52:26.297809600 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 9228s 03:52:28.679522200 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 9230s 03:52:31.004807800 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 9233s 03:52:33.359516900 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 9235s 03:52:35.670036100 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 9237s 03:52:38.081957700 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 9239s 03:52:40.413951000 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 9242s 03:52:43.307073800 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 9245s 03:52:45.812000500 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 9247s 03:52:48.160251700 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 9250s 03:52:50.539020000 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 9252s 03:52:52.940282000 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 9254s 03:52:55.282716000 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 9257s 03:52:57.609833000 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 9259s 03:53:00.032415500 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 9261s 03:53:02.374470700 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 9264s 03:53:05.269821200 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 9267s 03:53:07.740483600 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 9269s 03:53:10.084643200 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 9271s 03:53:12.450541500 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 9274s 03:53:14.788190700 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 9277s 03:53:17.099338200 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 9279s 03:53:19.527599100 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 9281s 03:53:21.885671100 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 9283s 03:53:24.237319700 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 9286s 03:53:27.202465800 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 9289s 03:53:29.627574000 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 9291s 03:53:31.969313900 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 9293s 03:53:34.362058500 O: ok putty ciphers 9293s 03:53:34.389759800 E: run test putty-kex.sh ... 9301s 03:53:41.667425300 O: plink version 0.83 major 0 minor 83 9303s 03:53:44.318611500 O: putty KEX: kex diffie-hellman-group1-sha1 9306s 03:53:47.095232600 O: putty KEX: kex diffie-hellman-group14-sha1 9308s 03:53:49.238363600 O: putty KEX: kex diffie-hellman-group14-sha256 9310s 03:53:51.291247200 O: putty KEX: kex diffie-hellman-group16-sha512 9313s 03:53:53.930613500 O: putty KEX: kex diffie-hellman-group18-sha512 9320s 03:54:00.686779900 O: putty KEX: kex diffie-hellman-group-exchange-sha1 9322s 03:54:02.881331200 O: putty KEX: kex diffie-hellman-group-exchange-sha256 9325s 03:54:05.661881200 O: putty KEX: kex ecdh-sha2-nistp256 9327s 03:54:07.607148400 O: putty KEX: kex ecdh-sha2-nistp384 9329s 03:54:10.222567800 O: putty KEX: kex ecdh-sha2-nistp521 9331s 03:54:12.336446800 O: putty KEX: kex curve25519-sha256 9333s 03:54:14.290370100 O: putty KEX: kex curve25519-sha256@libssh.org 9335s 03:54:16.252479900 O: putty KEX: kex sntrup761x25519-sha512 9337s 03:54:18.325001000 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 9339s 03:54:20.346099600 O: putty KEX: kex mlkem768x25519-sha256 9342s 03:54:22.401764800 O: ok putty KEX 9342s 03:54:22.425568300 E: run test conch-ciphers.sh ... 9348s 03:54:28.962307600 O: conch ciphers: cipher aes256-ctr 9358s 03:54:39.154612600 O: conch ciphers: cipher aes256-cbc 9367s 03:54:48.217974700 O: conch ciphers: cipher aes192-ctr 9376s 03:54:57.222749300 O: conch ciphers: cipher aes192-cbc 9385s 03:55:06.111073300 O: conch ciphers: cipher aes128-ctr 9394s 03:55:15.062128300 O: conch ciphers: cipher aes128-cbc 9405s 03:55:25.475603900 O: conch ciphers: cipher cast128-cbc 9414s 03:55:34.577902000 O: conch ciphers: cipher blowfish 9423s 03:55:43.487095500 O: conch ciphers: cipher 3des-cbc 9432s 03:55:52.895108000 O: ok conch ciphers 9432s 03:55:52.922014300 E: run test dropbear-ciphers.sh ... 9437s 03:55:58.234090900 O: Create dropbear key type ed25519 9438s 03:55:58.714080500 O: Create dropbear key type rsa 9439s 03:56:00.285936700 O: Create dropbear key type ecdsa 9441s 03:56:01.738771100 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 9444s 03:56:04.817401100 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 9447s 03:56:07.636413900 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 9449s 03:56:10.440196800 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 9452s 03:56:13.372379300 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 9455s 03:56:16.120790900 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 9459s 03:56:19.667887000 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 9462s 03:56:22.686665100 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 9465s 03:56:25.572434500 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 9467s 03:56:28.456734700 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 9470s 03:56:31.438310500 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 9473s 03:56:34.296426800 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 9477s 03:56:37.836274400 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 9480s 03:56:40.864544000 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 9483s 03:56:43.772325700 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 9486s 03:56:46.602512600 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 9489s 03:56:49.505809200 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 9491s 03:56:52.442144400 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 9495s 03:56:55.849036200 O: ok dropbear ciphers 9495s 03:56:55.895609400 E: run test dropbear-kex.sh ... 9501s 03:57:02.433891000 O: diffie-hellman-group14-sha1 9502s 03:57:02.474296900 O: dropbear kex: kex curve25519-sha256 9504s 03:57:05.400550700 O: dropbear kex: kex curve25519-sha256@libssh.org 9508s 03:57:08.344707600 O: dropbear kex: kex diffie-hellman-group14-sha256 9510s 03:57:11.314768900 O: dropbear kex: kex diffie-hellman-group14-sha1 9514s 03:57:14.843207300 O: ok dropbear kex 9514s 03:57:14.884238500 O: make: Leaving directory '/tmp/autopkgtest.sV2a1v/autopkgtest_tmp/user/regress' 9514s 03:57:14.947023800 I: Finished with exitcode 0 9514s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 9520s info: Looking for files to backup/remove ... 9520s info: Removing files ... 9520s info: Removing crontab ... 9520s info: Removing user `openssh-tests' ... 9522s autopkgtest [03:57:23]: test regress: -----------------------] 9525s regress PASS 9525s autopkgtest [03:57:26]: test regress: - - - - - - - - - - results - - - - - - - - - - 9527s autopkgtest [03:57:28]: test ssh-gssapi: preparing testbed 9746s autopkgtest [04:01:07]: testbed dpkg architecture: riscv64 9747s autopkgtest [04:01:08]: testbed apt version: 3.0.0 9748s autopkgtest [04:01:09]: @@@@@@@@@@@@@@@@@@@@ test bed setup 9749s autopkgtest [04:01:10]: testbed release detected to be: questing 9753s autopkgtest [04:01:14]: updating testbed package index (apt update) 9755s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 9756s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 9756s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 9756s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 9756s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2281 kB] 9757s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [190 kB] 9757s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [57.4 kB] 9757s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [169 kB] 9757s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [833 kB] 9758s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [15.3 kB] 9761s Fetched 3655 kB in 6s (647 kB/s) 9774s Reading package lists... 9778s autopkgtest [04:01:39]: upgrading testbed (apt dist-upgrade and autopurge) 9779s Reading package lists... 9781s Building dependency tree... 9781s Reading state information... 9782s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 9782s Starting 2 pkgProblemResolver with broken count: 0 9782s Done 9785s Entering ResolveByKeep 9785s 9786s Calculating upgrade... 9787s The following packages will be upgraded: 9787s ucf 9787s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 9787s Need to get 43.6 kB of archives. 9787s After this operation, 1024 B of additional disk space will be used. 9787s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 ucf all 3.0051 [43.6 kB] 9791s Preconfiguring packages ... 9792s Fetched 43.6 kB in 0s (142 kB/s) 9793s (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 ... 114197 files and directories currently installed.) 9793s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 9793s Unpacking ucf (3.0051) over (3.0050) ... 9794s Setting up ucf (3.0051) ... 9794s Processing triggers for man-db (2.13.0-1) ... 9803s Reading package lists... 9805s Building dependency tree... 9805s Reading state information... 9806s Starting pkgProblemResolver with broken count: 0 9806s Starting 2 pkgProblemResolver with broken count: 0 9806s Done 9808s Solving dependencies... 9808s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 9824s Reading package lists... 9826s Building dependency tree... 9826s Reading state information... 9827s Starting pkgProblemResolver with broken count: 0 9827s Starting 2 pkgProblemResolver with broken count: 0 9827s Done 9829s The following NEW packages will be installed: 9829s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 9829s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 9829s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 9829s openssh-server-gssapi 9829s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 9829s Need to get 824 kB of archives. 9829s After this operation, 2588 kB of additional disk space will be used. 9829s Get:1 http://ftpmaster.internal/ubuntu questing/main riscv64 krb5-config all 2.7 [22.0 kB] 9829s Get:2 http://ftpmaster.internal/ubuntu questing/main riscv64 libgssrpc4t64 riscv64 1.21.3-4ubuntu2 [62.4 kB] 9829s Get:3 http://ftpmaster.internal/ubuntu questing/main riscv64 libkadm5clnt-mit12 riscv64 1.21.3-4ubuntu2 [41.4 kB] 9829s Get:4 http://ftpmaster.internal/ubuntu questing/main riscv64 libkdb5-10t64 riscv64 1.21.3-4ubuntu2 [42.9 kB] 9829s Get:5 http://ftpmaster.internal/ubuntu questing/main riscv64 libkadm5srv-mit12 riscv64 1.21.3-4ubuntu2 [55.2 kB] 9829s Get:6 http://ftpmaster.internal/ubuntu questing/universe riscv64 krb5-user riscv64 1.21.3-4ubuntu2 [116 kB] 9829s Get:7 http://ftpmaster.internal/ubuntu questing/main riscv64 libevent-2.1-7t64 riscv64 2.1.12-stable-10 [151 kB] 9829s Get:8 http://ftpmaster.internal/ubuntu questing/main riscv64 libverto1t64 riscv64 0.3.1-1.2ubuntu3 [11.3 kB] 9829s Get:9 http://ftpmaster.internal/ubuntu questing/main riscv64 libverto-libevent1t64 riscv64 0.3.1-1.2ubuntu3 [6188 B] 9830s Get:10 http://ftpmaster.internal/ubuntu questing/universe riscv64 krb5-kdc riscv64 1.21.3-4ubuntu2 [206 kB] 9830s Get:11 http://ftpmaster.internal/ubuntu questing/universe riscv64 krb5-admin-server riscv64 1.21.3-4ubuntu2 [98.8 kB] 9830s Get:12 http://ftpmaster.internal/ubuntu questing/universe riscv64 openssh-client-gssapi all 1:9.9p1-3ubuntu3 [5022 B] 9830s Get:13 http://ftpmaster.internal/ubuntu questing/universe riscv64 openssh-server-gssapi all 1:9.9p1-3ubuntu3 [5026 B] 9833s Preconfiguring packages ... 9839s Fetched 824 kB in 1s (1008 kB/s) 9839s Selecting previously unselected package krb5-config. 9840s (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 ... 114197 files and directories currently installed.) 9840s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 9840s Unpacking krb5-config (2.7) ... 9840s Selecting previously unselected package libgssrpc4t64:riscv64. 9840s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_riscv64.deb ... 9840s Unpacking libgssrpc4t64:riscv64 (1.21.3-4ubuntu2) ... 9841s Selecting previously unselected package libkadm5clnt-mit12:riscv64. 9841s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_riscv64.deb ... 9841s Unpacking libkadm5clnt-mit12:riscv64 (1.21.3-4ubuntu2) ... 9841s Selecting previously unselected package libkdb5-10t64:riscv64. 9841s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_riscv64.deb ... 9841s Unpacking libkdb5-10t64:riscv64 (1.21.3-4ubuntu2) ... 9842s Selecting previously unselected package libkadm5srv-mit12:riscv64. 9842s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_riscv64.deb ... 9842s Unpacking libkadm5srv-mit12:riscv64 (1.21.3-4ubuntu2) ... 9842s Selecting previously unselected package krb5-user. 9842s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_riscv64.deb ... 9842s Unpacking krb5-user (1.21.3-4ubuntu2) ... 9843s Selecting previously unselected package libevent-2.1-7t64:riscv64. 9843s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_riscv64.deb ... 9843s Unpacking libevent-2.1-7t64:riscv64 (2.1.12-stable-10) ... 9843s Selecting previously unselected package libverto1t64:riscv64. 9843s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_riscv64.deb ... 9843s Unpacking libverto1t64:riscv64 (0.3.1-1.2ubuntu3) ... 9844s Selecting previously unselected package libverto-libevent1t64:riscv64. 9844s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_riscv64.deb ... 9844s Unpacking libverto-libevent1t64:riscv64 (0.3.1-1.2ubuntu3) ... 9844s Selecting previously unselected package krb5-kdc. 9844s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_riscv64.deb ... 9844s Unpacking krb5-kdc (1.21.3-4ubuntu2) ... 9845s Selecting previously unselected package krb5-admin-server. 9845s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_riscv64.deb ... 9845s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ... 9845s Selecting previously unselected package openssh-client-gssapi. 9845s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 9845s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 9846s Selecting previously unselected package openssh-server-gssapi. 9846s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3_all.deb ... 9846s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 9846s Setting up libevent-2.1-7t64:riscv64 (2.1.12-stable-10) ... 9846s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3) ... 9846s Setting up libgssrpc4t64:riscv64 (1.21.3-4ubuntu2) ... 9846s Setting up krb5-config (2.7) ... 9852s Setting up libkadm5clnt-mit12:riscv64 (1.21.3-4ubuntu2) ... 9852s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3) ... 9852s Setting up libkdb5-10t64:riscv64 (1.21.3-4ubuntu2) ... 9852s Setting up libkadm5srv-mit12:riscv64 (1.21.3-4ubuntu2) ... 9852s Setting up krb5-user (1.21.3-4ubuntu2) ... 9852s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 9852s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 9852s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 9852s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 9852s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 9852s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 9852s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 9852s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 9852s Setting up libverto1t64:riscv64 (0.3.1-1.2ubuntu3) ... 9852s Setting up libverto-libevent1t64:riscv64 (0.3.1-1.2ubuntu3) ... 9852s Setting up krb5-kdc (1.21.3-4ubuntu2) ... 9862s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 9867s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 9868s Setting up krb5-admin-server (1.21.3-4ubuntu2) ... 9877s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 9881s Processing triggers for libc-bin (2.41-6ubuntu1) ... 9882s Processing triggers for man-db (2.13.0-1) ... 9906s autopkgtest [04:03:47]: test ssh-gssapi: [----------------------- 9912s Generating public/private ed25519 key pair. 9912s Your identification has been saved in /root/.ssh/id_ed25519 9912s Your public key has been saved in /root/.ssh/id_ed25519.pub 9912s The key fingerprint is: 9912s SHA256:S9XmPClr71bOlQ/ulIUv33/U92K6RRy/X4Rpho8IkYw root@autopkgtest 9912s The key's randomart image is: 9912s +--[ED25519 256]--+ 9912s | | 9912s | o . . | 9912s | E + . o . | 9912s | o + + * | 9912s | S . * O *| 9912s | . o + Bo**| 9912s | . + .=*=*| 9912s | . ..o*oB| 9912s | o==..B| 9912s +----[SHA256]-----+ 9913s ## Setting up test environment 9913s ## Creating Kerberos realm EXAMPLE.FAKE 9913s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 9913s master key name 'K/M@EXAMPLE.FAKE' 9914s ## Creating principals 9915s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 9915s Principal "testuser2161@EXAMPLE.FAKE" created. 9915s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 9915s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 9915s ## Extracting service principal host/sshd-gssapi.example.fake 9915s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 9915s 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. 9915s 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. 9915s ## Adjusting /etc/krb5.conf 9915s ## TESTS 9915s 9915s ## TEST test_gssapi_login 9915s ## Configuring sshd for gssapi-with-mic authentication 9915s ## Restarting ssh 9916s ## Obtaining TGT 9916s Password for testuser2161@EXAMPLE.FAKE: 9917s Ticket cache: FILE:/tmp/krb5cc_0 9917s Default principal: testuser2161@EXAMPLE.FAKE 9917s 9917s Valid starting Expires Service principal 9917s 05/02/25 04:03:56 05/02/25 14:03:56 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 9917s renew until 05/03/25 04:03:56 9917s 9917s ## ssh'ing into localhost using gssapi-with-mic auth 9918s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 9922s Fri May 2 04:04:02 UTC 2025 9922s 9922s ## checking that we got a service ticket for ssh (host/) 9922s 05/02/25 04:03:59 05/02/25 14:03:56 host/sshd-gssapi.example.fake@ 9922s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 9922s 9922s ## Checking ssh logs to confirm gssapi-with-mic auth was used 9923s May 02 04:03:59 sshd-gssapi.example.fake sshd-session[2264]: Accepted gssapi-with-mic for testuser2161 from 127.0.0.1 port 39292 ssh2: testuser2161@EXAMPLE.FAKE 9923s ## PASS test_gssapi_login 9923s 9923s ## TEST test_gssapi_keyex_login 9923s ## Configuring sshd for gssapi-keyex authentication 9923s ## Restarting ssh 9924s ## Obtaining TGT 9925s Password for testuser2161@EXAMPLE.FAKE: 9925s Ticket cache: FILE:/tmp/krb5cc_0 9925s Default principal: testuser2161@EXAMPLE.FAKE 9925s 9925s Valid starting Expires Service principal 9925s 05/02/25 04:04:05 05/02/25 14:04:05 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 9925s renew until 05/03/25 04:04:05 9925s 9925s ## ssh'ing into localhost using gssapi-keyex auth 9927s Fri May 2 04:04:07 UTC 2025 9927s 9927s ## checking that we got a service ticket for ssh (host/) 9927s 05/02/25 04:04:05 05/02/25 14:04:05 host/sshd-gssapi.example.fake@ 9927s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 9927s 9927s ## Checking ssh logs to confirm gssapi-keyex auth was used 9927s May 02 04:04:06 sshd-gssapi.example.fake sshd-session[2319]: Accepted gssapi-keyex for testuser2161 from 127.0.0.1 port 43226 ssh2: testuser2161@EXAMPLE.FAKE 9927s ## PASS test_gssapi_keyex_login 9927s 9927s ## TEST test_gssapi_keyex_pubkey_fallback 9927s ## Configuring sshd for gssapi-keyex authentication 9927s ## Restarting ssh 9928s ## Obtaining TGT 9929s Password for testuser2161@EXAMPLE.FAKE: 9929s Ticket cache: FILE:/tmp/krb5cc_0 9929s Default principal: testuser2161@EXAMPLE.FAKE 9929s 9929s Valid starting Expires Service principal 9929s 05/02/25 04:04:09 05/02/25 14:04:09 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 9929s renew until 05/03/25 04:04:08 9929s 9929s ## ssh'ing into localhost using gssapi-keyex auth 9933s Fri May 2 04:04:13 UTC 2025 9933s 9933s ## checking that we got a service ticket for ssh (host/) 9933s 05/02/25 04:04:09 05/02/25 14:04:09 host/sshd-gssapi.example.fake@ 9933s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 9933s 9933s ## Checking ssh logs to confirm publickey auth was used 9933s May 02 04:04:10 sshd-gssapi.example.fake sshd-session[2360]: Accepted publickey for testuser2161-2 from 127.0.0.1 port 43230 ssh2: ED25519 SHA256:S9XmPClr71bOlQ/ulIUv33/U92K6RRy/X4Rpho8IkYw 9933s ## PASS test_gssapi_keyex_pubkey_fallback 9933s 9933s ## ALL TESTS PASSED 9933s ## Cleaning up 9935s autopkgtest [04:04:16]: test ssh-gssapi: -----------------------] 9937s ssh-gssapi PASS 9937s autopkgtest [04:04:18]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 9939s autopkgtest [04:04:20]: test socket-activation: preparing testbed 10142s autopkgtest [04:07:43]: testbed dpkg architecture: riscv64 10143s autopkgtest [04:07:44]: testbed apt version: 3.0.0 10145s autopkgtest [04:07:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 10146s autopkgtest [04:07:47]: testbed release detected to be: questing 10149s autopkgtest [04:07:50]: updating testbed package index (apt update) 10152s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 10152s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 10152s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 10152s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 10152s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2281 kB] 10153s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [57.4 kB] 10154s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [190 kB] 10154s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [169 kB] 10154s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [833 kB] 10154s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [15.3 kB] 10158s Fetched 3655 kB in 5s (676 kB/s) 10171s Reading package lists... 10175s autopkgtest [04:08:15]: upgrading testbed (apt dist-upgrade and autopurge) 10176s Reading package lists... 10177s Building dependency tree... 10177s Reading state information... 10179s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 10180s Starting 2 pkgProblemResolver with broken count: 0 10180s Done 10182s Entering ResolveByKeep 10183s 10184s Calculating upgrade... 10185s The following packages will be upgraded: 10185s ucf 10185s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10185s Need to get 43.6 kB of archives. 10185s After this operation, 1024 B of additional disk space will be used. 10185s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 ucf all 3.0051 [43.6 kB] 10189s Preconfiguring packages ... 10190s Fetched 43.6 kB in 0s (145 kB/s) 10191s (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 ... 114197 files and directories currently installed.) 10191s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 10191s Unpacking ucf (3.0051) over (3.0050) ... 10192s Setting up ucf (3.0051) ... 10192s Processing triggers for man-db (2.13.0-1) ... 10201s Reading package lists... 10203s Building dependency tree... 10203s Reading state information... 10204s Starting pkgProblemResolver with broken count: 0 10204s Starting 2 pkgProblemResolver with broken count: 0 10204s Done 10206s Solving dependencies... 10206s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10222s Reading package lists... 10224s Building dependency tree... 10224s Reading state information... 10225s Starting pkgProblemResolver with broken count: 0 10225s Starting 2 pkgProblemResolver with broken count: 0 10225s Done 10227s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10242s autopkgtest [04:09:23]: test socket-activation: [----------------------- 10247s Generating public/private ed25519 key pair. 10247s Your identification has been saved in /home/testuser1748/.ssh/id_ed25519 10247s Your public key has been saved in /home/testuser1748/.ssh/id_ed25519.pub 10247s The key fingerprint is: 10247s SHA256:rx5FEOJJqwmIeFLFzfxs/iDe7V/fc4qzsPPMpb8Q4vQ testuser1748@autopkgtest 10247s The key's randomart image is: 10247s +--[ED25519 256]--+ 10247s | .o.+o o. | 10247s |oo .o++ . | 10247s |= o +o . | 10247s | o . o +. | 10247s | o oS + . | 10247s | . o= o . | 10247s | . o.++ E o | 10247s | . .o+=.* oo| 10247s | .o.o+O++o=| 10247s +----[SHA256]-----+ 10247s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 10247s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 10258s Disabling 'ssh.service', but its triggering units are still active: 10258s ssh.socket 10259s Stopping 'ssh.service', but its triggering units are still active: 10259s ssh.socket 10265s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 10269s Fri May 2 04:09:49 UTC 2025 10270s autopkgtest [04:09:51]: test socket-activation: -----------------------] 10272s socket-activation PASS 10272s autopkgtest [04:09:53]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 10274s autopkgtest [04:09:55]: test xinetd: preparing testbed 10277s Reading package lists... 10279s Building dependency tree... 10279s Reading state information... 10280s Starting pkgProblemResolver with broken count: 0 10281s Starting 2 pkgProblemResolver with broken count: 0 10281s Done 10283s The following NEW packages will be installed: 10283s xinetd 10283s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 10283s Need to get 118 kB of archives. 10283s After this operation, 296 kB of additional disk space will be used. 10283s Get:1 http://ftpmaster.internal/ubuntu questing/universe riscv64 xinetd riscv64 1:2.3.15.4-5 [118 kB] 10287s Fetched 118 kB in 0s (288 kB/s) 10288s Selecting previously unselected package xinetd. 10288s (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 ... 114197 files and directories currently installed.) 10288s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_riscv64.deb ... 10288s Unpacking xinetd (1:2.3.15.4-5) ... 10289s Setting up xinetd (1:2.3.15.4-5) ... 10296s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 10302s Processing triggers for man-db (2.13.0-1) ... 10313s autopkgtest [04:10:34]: test xinetd: [----------------------- 10317s Generating public/private ed25519 key pair. 10317s Your identification has been saved in /home/testuser2310/.ssh/id_ed25519 10317s Your public key has been saved in /home/testuser2310/.ssh/id_ed25519.pub 10317s The key fingerprint is: 10317s SHA256:jN3ip+3iJVBU2fJ/aKux0+eNwtVcz9oswhS87EUchoY testuser2310@autopkgtest 10317s The key's randomart image is: 10317s +--[ED25519 256]--+ 10317s | ...+ . | 10317s | . E + o | 10317s | . = o . | 10317s | = . + o .| 10317s | o S .. = =o| 10317s | o . + * *| 10317s | o o=.= * | 10317s | .* Bo=.=| 10317s | .ooo o=.+o| 10317s +----[SHA256]-----+ 10321s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 10321s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 10333s Disabling 'ssh.service', but its triggering units are still active: 10333s ssh.socket 10334s Stopping 'ssh.service', but its triggering units are still active: 10334s ssh.socket 10337s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 10341s Fri May 2 04:11:02 UTC 2025 10342s autopkgtest [04:11:03]: test xinetd: -----------------------] 10344s autopkgtest [04:11:05]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 10344s xinetd PASS 10347s autopkgtest [04:11:07]: test systemd-socket-activation: preparing testbed 10556s autopkgtest [04:14:37]: testbed dpkg architecture: riscv64 10557s autopkgtest [04:14:38]: testbed apt version: 3.0.0 10559s autopkgtest [04:14:40]: @@@@@@@@@@@@@@@@@@@@ test bed setup 10560s autopkgtest [04:14:41]: testbed release detected to be: questing 10563s autopkgtest [04:14:44]: updating testbed package index (apt update) 10566s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 10566s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 10566s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 10566s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 10566s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2281 kB] 10567s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [190 kB] 10568s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [57.4 kB] 10568s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [169 kB] 10568s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [833 kB] 10568s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [15.3 kB] 10572s Fetched 3655 kB in 6s (605 kB/s) 10585s Reading package lists... 10588s autopkgtest [04:15:09]: upgrading testbed (apt dist-upgrade and autopurge) 10590s Reading package lists... 10591s Building dependency tree... 10591s Reading state information... 10593s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 10593s Starting 2 pkgProblemResolver with broken count: 0 10593s Done 10595s Entering ResolveByKeep 10596s 10596s Calculating upgrade... 10597s The following packages will be upgraded: 10597s ucf 10597s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10597s Need to get 43.6 kB of archives. 10597s After this operation, 1024 B of additional disk space will be used. 10597s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 ucf all 3.0051 [43.6 kB] 10601s Preconfiguring packages ... 10602s Fetched 43.6 kB in 0s (151 kB/s) 10604s (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 ... 114197 files and directories currently installed.) 10604s Preparing to unpack .../archives/ucf_3.0051_all.deb ... 10604s Unpacking ucf (3.0051) over (3.0050) ... 10604s Setting up ucf (3.0051) ... 10604s Processing triggers for man-db (2.13.0-1) ... 10614s Reading package lists... 10615s Building dependency tree... 10615s Reading state information... 10616s Starting pkgProblemResolver with broken count: 0 10617s Starting 2 pkgProblemResolver with broken count: 0 10617s Done 10618s Solving dependencies... 10619s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10635s Reading package lists... 10637s Building dependency tree... 10637s Reading state information... 10638s Starting pkgProblemResolver with broken count: 0 10638s Starting 2 pkgProblemResolver with broken count: 0 10638s Done 10640s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10656s autopkgtest [04:16:17]: test systemd-socket-activation: [----------------------- 10659s Stopping ssh.service... 10659s Checking that ssh.socket is active and listening... 10659s Checking that ssh.service is inactive/dead... 10660s Checking that a connection attempt activates ssh.service... 10662s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 10667s Checking that sshd can be re-executed... 10668s Checking sshd can run in debug mode... 10672s debug1: PAM: establishing credentials 10672s debug1: permanently_set_uid: 0/0 10672s debug3: Copy environment: XDG_SESSION_ID=8 10672s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 10672s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 10672s debug3: Copy environment: XDG_SESSION_TYPE=tty 10672s debug3: Copy environment: XDG_SESSION_CLASS=user 10672s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 10672s debug3: Copy environment: http_proxy=http://egress.ps7.internal:3128/ 10672s debug3: Copy environment: https_proxy=http://egress.ps7.internal:3128/ 10672s debug3: Copy environment: TERM=linux 10672s debug3: Copy environment: LANG=C.UTF-8 10672s Environment: 10672s LANG=C.UTF-8 10672s USER=root 10672s LOGNAME=root 10672s HOME=/root 10672s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 10672s SHELL=/bin/bash 10672s XDG_SESSION_ID=8 10672s XDG_RUNTIME_DIR=/run/user/0 10672s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 10672s XDG_SESSION_TYPE=tty 10672s XDG_SESSION_CLASS=user 10672s http_proxy=http://egress.ps7.internal:3128/ 10672s https_proxy=http://egress.ps7.internal:3128/ 10672s TERM=linux 10672s SSH_CLIENT=::1 45098 22 10672s SSH_CONNECTION=::1 45098 ::1 22 10672s Done. 10673s autopkgtest [04:16:34]: test systemd-socket-activation: -----------------------] 10675s autopkgtest [04:16:36]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 10675s systemd-socket-activation PASS 10677s autopkgtest [04:16:38]: test sshd-socket-generator: preparing testbed 10681s Reading package lists... 10683s Building dependency tree... 10683s Reading state information... 10685s Starting pkgProblemResolver with broken count: 0 10685s Starting 2 pkgProblemResolver with broken count: 0 10685s Done 10687s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10692s autopkgtest [04:16:53]: test sshd-socket-generator: [----------------------- 10694s test_default...PASS 10694s test_custom_port...PASS 10694s test_default_and_custom_port...PASS 10695s test_mutiple_custom_ports...PASS 10695s test_custom_listenaddress...PASS 10695s test_custom_listenaddress_and_port...PASS 10695s test_custom_ipv6_listenaddress...PASS 10695s test_custom_family_ipv4...PASS 10696s test_custom_family_ipv6...PASS 10696s test_custom_port_and_family_ipv4...PASS 10696s test_custom_port_and_family_ipv6...PASS 10696s test_match_on_port...PASS 10697s autopkgtest [04:16:58]: test sshd-socket-generator: -----------------------] 10699s autopkgtest [04:17:00]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 10699s sshd-socket-generator PASS 10701s autopkgtest [04:17:02]: @@@@@@@@@@@@@@@@@@@@ summary 10701s regress PASS 10701s ssh-gssapi PASS 10701s socket-activation PASS 10701s xinetd PASS 10701s systemd-socket-activation PASS 10701s sshd-socket-generator PASS 10706s nova [W] Using flock in prodstack7-riscv64 10706s Creating nova instance adt-questing-riscv64-openssh-20250502-011840-juju-7f2275-prod-proposed-migration-environment-15-96cc86ba-10f6-4702-971e-efd799a4752e from image adt/ubuntu-questing-riscv64-server-20250501.img (UUID 2359bd49-b5b7-4912-915a-f8dc98a06fb2)... 10706s nova [W] Timed out waiting for ebf1d590-b2a0-4e78-9a85-8b5019cbdb44 to get deleted. 10706s nova [W] Using flock in prodstack7-riscv64 10706s Creating nova instance adt-questing-riscv64-openssh-20250502-011840-juju-7f2275-prod-proposed-migration-environment-15-96cc86ba-10f6-4702-971e-efd799a4752e from image adt/ubuntu-questing-riscv64-server-20250501.img (UUID 2359bd49-b5b7-4912-915a-f8dc98a06fb2)... 10706s nova [W] Timed out waiting for 7baea000-0d00-4525-b554-61570ac0260c to get deleted. 10706s nova [W] Using flock in prodstack7-riscv64 10706s Creating nova instance adt-questing-riscv64-openssh-20250502-011840-juju-7f2275-prod-proposed-migration-environment-15-96cc86ba-10f6-4702-971e-efd799a4752e from image adt/ubuntu-questing-riscv64-server-20250501.img (UUID 2359bd49-b5b7-4912-915a-f8dc98a06fb2)... 10706s nova [W] Timed out waiting for 439e1849-caf6-4fed-b935-3ec1807d02d6 to get deleted. 10706s nova [W] Using flock in prodstack7-riscv64 10706s Creating nova instance adt-questing-riscv64-openssh-20250502-011840-juju-7f2275-prod-proposed-migration-environment-15-96cc86ba-10f6-4702-971e-efd799a4752e from image adt/ubuntu-questing-riscv64-server-20250501.img (UUID 2359bd49-b5b7-4912-915a-f8dc98a06fb2)... 10706s nova [W] Timed out waiting for 148e5817-e200-4497-8c94-1c6f1f214cb6 to get deleted.