0s autopkgtest [03:01:14]: starting date and time: 2025-05-08 03:01:14+0000 0s autopkgtest [03:01:14]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [03:01:14]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ti0gffkt/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:dropbear --apt-upgrade openssh --timeout-short=1200 --timeout-copy=80000 --timeout-build=80000 --env=ADT_TEST_TRIGGERS=dropbear/2025.88-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-riscv64-17.secgroup --name adt-questing-riscv64-openssh-20250508-030111-juju-7f2275-prod-proposed-migration-environment-2-e8bca6d1-5ded-4f5c-be32-9f990a6fb41b --image adt/ubuntu-questing-riscv64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-riscv64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 225s autopkgtest [03:04:59]: testbed dpkg architecture: riscv64 226s autopkgtest [03:05:00]: testbed apt version: 3.0.0 229s autopkgtest [03:05:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 230s autopkgtest [03:05:04]: testbed release detected to be: None 236s autopkgtest [03:05:10]: updating testbed package index (apt update) 238s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 239s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 239s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 239s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 239s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [13.2 kB] 240s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [54.9 kB] 240s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [486 kB] 242s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [104 kB] 242s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [442 kB] 242s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [3196 B] 245s Fetched 1215 kB in 5s (229 kB/s) 263s Reading package lists... 268s autopkgtest [03:05:42]: upgrading testbed (apt dist-upgrade and autopurge) 270s Reading package lists... 271s Building dependency tree... 271s Reading state information... 273s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 274s Starting 2 pkgProblemResolver with broken count: 0 274s Done 279s Entering ResolveByKeep 280s 281s Calculating upgrade... 282s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 284s Reading package lists... 286s Building dependency tree... 286s Reading state information... 289s Starting pkgProblemResolver with broken count: 0 290s Starting 2 pkgProblemResolver with broken count: 0 290s Done 292s Solving dependencies... 293s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 310s autopkgtest [03:06:24]: testbed running kernel: Linux 6.14.0-13-generic #13.2-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 05:26:54 UTC 2025 312s autopkgtest [03:06:26]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 359s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (dsc) [3488 B] 359s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (tar) [1965 kB] 359s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (asc) [833 B] 359s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (diff) [212 kB] 362s gpgv: Signature made Fri Apr 11 11:58:21 2025 UTC 362s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 362s gpgv: Can't check signature: No public key 362s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.1.dsc: no acceptable signature found 371s autopkgtest [03:07:25]: testing package openssh version 1:9.9p1-3ubuntu3.1 373s autopkgtest [03:07:27]: build not needed 380s autopkgtest [03:07:34]: test regress: preparing testbed 384s Reading package lists... 387s Building dependency tree... 387s Reading state information... 389s Starting pkgProblemResolver with broken count: 0 390s Starting 2 pkgProblemResolver with broken count: 0 390s Done 393s The following NEW packages will be installed: 393s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 393s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 393s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 393s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 393s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 393s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 393s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 393s libio-html-perl libio-socket-ssl-perl liblwp-mediatypes-perl 393s liblwp-protocol-https-perl libmodule-runtime-perl libmoo-perl 393s libnet-http-perl libnet-ssleay-perl libparams-classify-perl 393s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 393s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 393s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 393s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 393s python3-packaging python3-pyasn1 python3-pyasn1-modules 393s python3-service-identity python3-twisted python3-zope.interface wdiff 394s 0 upgraded, 59 newly installed, 0 to remove and 0 not upgraded. 394s Need to get 8605 kB of archives. 394s After this operation, 40.1 MB of additional disk space will be used. 394s Get:1 http://ftpmaster.internal/ubuntu questing/universe riscv64 libtommath1 riscv64 1.3.0-1 [67.8 kB] 394s Get:2 http://ftpmaster.internal/ubuntu questing/universe riscv64 libtomcrypt1 riscv64 1.18.2+dfsg-7build1 [483 kB] 394s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 dropbear-bin riscv64 2025.88-1 [181 kB] 394s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 dropbear all 2025.88-1 [8670 B] 394s Get:5 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-dirlist-perl all 0.05-3 [7286 B] 394s Get:6 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-which-perl all 1.27-2 [12.5 kB] 394s Get:7 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-homedir-perl all 1.006-2 [37.0 kB] 394s Get:8 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-touch-perl all 0.12-2 [7498 B] 394s Get:9 http://ftpmaster.internal/ubuntu questing/main riscv64 libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 395s Get:10 http://ftpmaster.internal/ubuntu questing/main riscv64 libclass-xsaccessor-perl riscv64 1.19-4build6 [34.5 kB] 395s Get:11 http://ftpmaster.internal/ubuntu questing/main riscv64 libb-hooks-op-check-perl riscv64 0.22-3build2 [9556 B] 395s Get:12 http://ftpmaster.internal/ubuntu questing/main riscv64 libdynaloader-functions-perl all 0.004-2 [11.5 kB] 395s Get:13 http://ftpmaster.internal/ubuntu questing/main riscv64 libdevel-callchecker-perl riscv64 0.009-2 [14.1 kB] 395s Get:14 http://ftpmaster.internal/ubuntu questing/main riscv64 libparams-classify-perl riscv64 0.015-2build6 [20.1 kB] 395s Get:15 http://ftpmaster.internal/ubuntu questing/main riscv64 libmodule-runtime-perl all 0.018-1 [15.2 kB] 395s Get:16 http://ftpmaster.internal/ubuntu questing/main riscv64 libimport-into-perl all 1.002005-2 [10.7 kB] 395s Get:17 http://ftpmaster.internal/ubuntu questing/main riscv64 librole-tiny-perl all 2.002004-1 [16.3 kB] 395s Get:18 http://ftpmaster.internal/ubuntu questing/main riscv64 libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 395s Get:19 http://ftpmaster.internal/ubuntu questing/main riscv64 libmoo-perl all 2.005005-1 [47.4 kB] 395s Get:20 http://ftpmaster.internal/ubuntu questing/main riscv64 libencode-locale-perl all 1.05-3 [11.6 kB] 395s Get:21 http://ftpmaster.internal/ubuntu questing/main riscv64 libtimedate-perl all 2.3300-2 [34.0 kB] 395s Get:22 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-date-perl all 6.06-1 [10.2 kB] 395s Get:23 http://ftpmaster.internal/ubuntu questing/main riscv64 libfile-listing-perl all 6.16-1 [11.3 kB] 395s Get:24 http://ftpmaster.internal/ubuntu questing/main riscv64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 395s Get:25 http://ftpmaster.internal/ubuntu questing/main riscv64 liburi-perl all 5.30-1 [94.4 kB] 395s Get:26 http://ftpmaster.internal/ubuntu questing/main riscv64 libhtml-parser-perl riscv64 3.83-1build1 [86.6 kB] 395s Get:27 http://ftpmaster.internal/ubuntu questing/main riscv64 libhtml-tree-perl all 5.07-3 [200 kB] 395s Get:28 http://ftpmaster.internal/ubuntu questing/main riscv64 libclone-perl riscv64 0.47-1 [10.5 kB] 395s Get:29 http://ftpmaster.internal/ubuntu questing/main riscv64 libio-html-perl all 1.004-3 [15.9 kB] 395s Get:30 http://ftpmaster.internal/ubuntu questing/main riscv64 liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 395s Get:31 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 395s Get:32 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-cookies-perl all 6.11-1 [18.2 kB] 395s Get:33 http://ftpmaster.internal/ubuntu questing/main riscv64 libhttp-negotiate-perl all 6.01-2 [12.4 kB] 395s Get:34 http://ftpmaster.internal/ubuntu questing/main riscv64 perl-openssl-defaults riscv64 7build3 [6632 B] 395s Get:35 http://ftpmaster.internal/ubuntu questing/main riscv64 libnet-ssleay-perl riscv64 1.94-3 [317 kB] 395s Get:36 http://ftpmaster.internal/ubuntu questing/main riscv64 libio-socket-ssl-perl all 2.089-1 [200 kB] 395s Get:37 http://ftpmaster.internal/ubuntu questing/main riscv64 libnet-http-perl all 6.23-1 [22.3 kB] 395s Get:38 http://ftpmaster.internal/ubuntu questing/main riscv64 liblwp-protocol-https-perl all 6.14-1 [9040 B] 395s Get:39 http://ftpmaster.internal/ubuntu questing/main riscv64 libtry-tiny-perl all 0.32-1 [21.2 kB] 395s Get:40 http://ftpmaster.internal/ubuntu questing/main riscv64 libwww-robotrules-perl all 6.02-1 [12.6 kB] 395s Get:41 http://ftpmaster.internal/ubuntu questing/main riscv64 libwww-perl all 6.78-1 [139 kB] 395s Get:42 http://ftpmaster.internal/ubuntu questing/main riscv64 patchutils riscv64 0.4.2-1build3 [77.3 kB] 395s Get:43 http://ftpmaster.internal/ubuntu questing/main riscv64 wdiff riscv64 1.2.2-9 [30.5 kB] 395s Get:44 http://ftpmaster.internal/ubuntu questing/main riscv64 devscripts all 2.25.11 [1066 kB] 395s Get:45 http://ftpmaster.internal/ubuntu questing/universe riscv64 libhavege2 riscv64 1.9.19-11ubuntu1 [26.2 kB] 395s Get:46 http://ftpmaster.internal/ubuntu questing/universe riscv64 putty-tools riscv64 0.83-2 [746 kB] 395s Get:47 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-hamcrest all 2.1.0-1 [28.1 kB] 395s Get:48 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-pyasn1 all 0.6.1-1 [56.4 kB] 395s Get:49 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 395s Get:50 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-service-identity all 24.2.0-1 [11.0 kB] 395s Get:51 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-automat all 25.4.16-1 [35.7 kB] 395s Get:52 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-constantly all 23.10.4-2 [13.9 kB] 395s Get:53 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-hyperlink all 21.0.0-6 [68.0 kB] 395s Get:54 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-packaging all 25.0-1 [52.8 kB] 395s Get:55 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-incremental all 24.7.2-3 [18.6 kB] 396s Get:56 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-zope.interface riscv64 7.2-1build1 [142 kB] 396s Get:57 http://ftpmaster.internal/ubuntu questing/main riscv64 python3-twisted all 24.11.0-1 [2066 kB] 396s Get:58 http://ftpmaster.internal/ubuntu questing/universe riscv64 openssh-tests riscv64 1:9.9p1-3ubuntu3.1 [1690 kB] 396s Get:59 http://ftpmaster.internal/ubuntu questing/universe riscv64 haveged riscv64 1.9.19-11ubuntu1 [32.4 kB] 403s Fetched 8605 kB in 3s (3240 kB/s) 404s Selecting previously unselected package libtommath1:riscv64. 405s (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 ... 114088 files and directories currently installed.) 405s Preparing to unpack .../00-libtommath1_1.3.0-1_riscv64.deb ... 405s Unpacking libtommath1:riscv64 (1.3.0-1) ... 406s Selecting previously unselected package libtomcrypt1:riscv64. 406s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_riscv64.deb ... 406s Unpacking libtomcrypt1:riscv64 (1.18.2+dfsg-7build1) ... 407s Selecting previously unselected package dropbear-bin. 407s Preparing to unpack .../02-dropbear-bin_2025.88-1_riscv64.deb ... 407s Unpacking dropbear-bin (2025.88-1) ... 407s Selecting previously unselected package dropbear. 408s Preparing to unpack .../03-dropbear_2025.88-1_all.deb ... 408s Unpacking dropbear (2025.88-1) ... 408s Selecting previously unselected package libfile-dirlist-perl. 408s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 408s Unpacking libfile-dirlist-perl (0.05-3) ... 409s Selecting previously unselected package libfile-which-perl. 409s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 409s Unpacking libfile-which-perl (1.27-2) ... 409s Selecting previously unselected package libfile-homedir-perl. 410s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 410s Unpacking libfile-homedir-perl (1.006-2) ... 410s Selecting previously unselected package libfile-touch-perl. 410s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 410s Unpacking libfile-touch-perl (0.12-2) ... 411s Selecting previously unselected package libclass-method-modifiers-perl. 411s Preparing to unpack .../08-libclass-method-modifiers-perl_2.15-1_all.deb ... 411s Unpacking libclass-method-modifiers-perl (2.15-1) ... 411s Selecting previously unselected package libclass-xsaccessor-perl. 412s Preparing to unpack .../09-libclass-xsaccessor-perl_1.19-4build6_riscv64.deb ... 412s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 412s Selecting previously unselected package libb-hooks-op-check-perl:riscv64. 412s Preparing to unpack .../10-libb-hooks-op-check-perl_0.22-3build2_riscv64.deb ... 412s Unpacking libb-hooks-op-check-perl:riscv64 (0.22-3build2) ... 413s Selecting previously unselected package libdynaloader-functions-perl. 413s Preparing to unpack .../11-libdynaloader-functions-perl_0.004-2_all.deb ... 413s Unpacking libdynaloader-functions-perl (0.004-2) ... 413s Selecting previously unselected package libdevel-callchecker-perl:riscv64. 413s Preparing to unpack .../12-libdevel-callchecker-perl_0.009-2_riscv64.deb ... 413s Unpacking libdevel-callchecker-perl:riscv64 (0.009-2) ... 414s Selecting previously unselected package libparams-classify-perl:riscv64. 414s Preparing to unpack .../13-libparams-classify-perl_0.015-2build6_riscv64.deb ... 414s Unpacking libparams-classify-perl:riscv64 (0.015-2build6) ... 415s Selecting previously unselected package libmodule-runtime-perl. 415s Preparing to unpack .../14-libmodule-runtime-perl_0.018-1_all.deb ... 415s Unpacking libmodule-runtime-perl (0.018-1) ... 416s Selecting previously unselected package libimport-into-perl. 416s Preparing to unpack .../15-libimport-into-perl_1.002005-2_all.deb ... 416s Unpacking libimport-into-perl (1.002005-2) ... 416s Selecting previously unselected package librole-tiny-perl. 416s Preparing to unpack .../16-librole-tiny-perl_2.002004-1_all.deb ... 416s Unpacking librole-tiny-perl (2.002004-1) ... 417s Selecting previously unselected package libsub-quote-perl. 417s Preparing to unpack .../17-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 417s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 418s Selecting previously unselected package libmoo-perl. 418s Preparing to unpack .../18-libmoo-perl_2.005005-1_all.deb ... 418s Unpacking libmoo-perl (2.005005-1) ... 418s Selecting previously unselected package libencode-locale-perl. 418s Preparing to unpack .../19-libencode-locale-perl_1.05-3_all.deb ... 418s Unpacking libencode-locale-perl (1.05-3) ... 419s Selecting previously unselected package libtimedate-perl. 419s Preparing to unpack .../20-libtimedate-perl_2.3300-2_all.deb ... 419s Unpacking libtimedate-perl (2.3300-2) ... 420s Selecting previously unselected package libhttp-date-perl. 420s Preparing to unpack .../21-libhttp-date-perl_6.06-1_all.deb ... 420s Unpacking libhttp-date-perl (6.06-1) ... 420s Selecting previously unselected package libfile-listing-perl. 420s Preparing to unpack .../22-libfile-listing-perl_6.16-1_all.deb ... 420s Unpacking libfile-listing-perl (6.16-1) ... 421s Selecting previously unselected package libhtml-tagset-perl. 421s Preparing to unpack .../23-libhtml-tagset-perl_3.24-1_all.deb ... 421s Unpacking libhtml-tagset-perl (3.24-1) ... 421s Selecting previously unselected package liburi-perl. 422s Preparing to unpack .../24-liburi-perl_5.30-1_all.deb ... 422s Unpacking liburi-perl (5.30-1) ... 422s Selecting previously unselected package libhtml-parser-perl:riscv64. 423s Preparing to unpack .../25-libhtml-parser-perl_3.83-1build1_riscv64.deb ... 423s Unpacking libhtml-parser-perl:riscv64 (3.83-1build1) ... 423s Selecting previously unselected package libhtml-tree-perl. 423s Preparing to unpack .../26-libhtml-tree-perl_5.07-3_all.deb ... 423s Unpacking libhtml-tree-perl (5.07-3) ... 424s Selecting previously unselected package libclone-perl:riscv64. 424s Preparing to unpack .../27-libclone-perl_0.47-1_riscv64.deb ... 424s Unpacking libclone-perl:riscv64 (0.47-1) ... 424s Selecting previously unselected package libio-html-perl. 425s Preparing to unpack .../28-libio-html-perl_1.004-3_all.deb ... 425s Unpacking libio-html-perl (1.004-3) ... 425s Selecting previously unselected package liblwp-mediatypes-perl. 425s Preparing to unpack .../29-liblwp-mediatypes-perl_6.04-2_all.deb ... 425s Unpacking liblwp-mediatypes-perl (6.04-2) ... 426s Selecting previously unselected package libhttp-message-perl. 426s Preparing to unpack .../30-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 426s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 426s Selecting previously unselected package libhttp-cookies-perl. 427s Preparing to unpack .../31-libhttp-cookies-perl_6.11-1_all.deb ... 427s Unpacking libhttp-cookies-perl (6.11-1) ... 427s Selecting previously unselected package libhttp-negotiate-perl. 427s Preparing to unpack .../32-libhttp-negotiate-perl_6.01-2_all.deb ... 427s Unpacking libhttp-negotiate-perl (6.01-2) ... 428s Selecting previously unselected package perl-openssl-defaults:riscv64. 428s Preparing to unpack .../33-perl-openssl-defaults_7build3_riscv64.deb ... 428s Unpacking perl-openssl-defaults:riscv64 (7build3) ... 428s Selecting previously unselected package libnet-ssleay-perl:riscv64. 428s Preparing to unpack .../34-libnet-ssleay-perl_1.94-3_riscv64.deb ... 428s Unpacking libnet-ssleay-perl:riscv64 (1.94-3) ... 429s Selecting previously unselected package libio-socket-ssl-perl. 429s Preparing to unpack .../35-libio-socket-ssl-perl_2.089-1_all.deb ... 429s Unpacking libio-socket-ssl-perl (2.089-1) ... 430s Selecting previously unselected package libnet-http-perl. 430s Preparing to unpack .../36-libnet-http-perl_6.23-1_all.deb ... 430s Unpacking libnet-http-perl (6.23-1) ... 431s Selecting previously unselected package liblwp-protocol-https-perl. 431s Preparing to unpack .../37-liblwp-protocol-https-perl_6.14-1_all.deb ... 431s Unpacking liblwp-protocol-https-perl (6.14-1) ... 431s Selecting previously unselected package libtry-tiny-perl. 431s Preparing to unpack .../38-libtry-tiny-perl_0.32-1_all.deb ... 431s Unpacking libtry-tiny-perl (0.32-1) ... 432s Selecting previously unselected package libwww-robotrules-perl. 432s Preparing to unpack .../39-libwww-robotrules-perl_6.02-1_all.deb ... 432s Unpacking libwww-robotrules-perl (6.02-1) ... 432s Selecting previously unselected package libwww-perl. 433s Preparing to unpack .../40-libwww-perl_6.78-1_all.deb ... 433s Unpacking libwww-perl (6.78-1) ... 433s Selecting previously unselected package patchutils. 433s Preparing to unpack .../41-patchutils_0.4.2-1build3_riscv64.deb ... 433s Unpacking patchutils (0.4.2-1build3) ... 434s Selecting previously unselected package wdiff. 434s Preparing to unpack .../42-wdiff_1.2.2-9_riscv64.deb ... 434s Unpacking wdiff (1.2.2-9) ... 435s Selecting previously unselected package devscripts. 435s Preparing to unpack .../43-devscripts_2.25.11_all.deb ... 436s Unpacking devscripts (2.25.11) ... 438s Selecting previously unselected package libhavege2:riscv64. 438s Preparing to unpack .../44-libhavege2_1.9.19-11ubuntu1_riscv64.deb ... 438s Unpacking libhavege2:riscv64 (1.9.19-11ubuntu1) ... 438s Selecting previously unselected package putty-tools. 439s Preparing to unpack .../45-putty-tools_0.83-2_riscv64.deb ... 439s Unpacking putty-tools (0.83-2) ... 440s Selecting previously unselected package python3-hamcrest. 440s Preparing to unpack .../46-python3-hamcrest_2.1.0-1_all.deb ... 440s Unpacking python3-hamcrest (2.1.0-1) ... 440s Selecting previously unselected package python3-pyasn1. 441s Preparing to unpack .../47-python3-pyasn1_0.6.1-1_all.deb ... 441s Unpacking python3-pyasn1 (0.6.1-1) ... 441s Selecting previously unselected package python3-pyasn1-modules. 441s Preparing to unpack .../48-python3-pyasn1-modules_0.4.1-2_all.deb ... 441s Unpacking python3-pyasn1-modules (0.4.1-2) ... 442s Selecting previously unselected package python3-service-identity. 442s Preparing to unpack .../49-python3-service-identity_24.2.0-1_all.deb ... 442s Unpacking python3-service-identity (24.2.0-1) ... 443s Selecting previously unselected package python3-automat. 443s Preparing to unpack .../50-python3-automat_25.4.16-1_all.deb ... 443s Unpacking python3-automat (25.4.16-1) ... 444s Selecting previously unselected package python3-constantly. 444s Preparing to unpack .../51-python3-constantly_23.10.4-2_all.deb ... 444s Unpacking python3-constantly (23.10.4-2) ... 444s Selecting previously unselected package python3-hyperlink. 445s Preparing to unpack .../52-python3-hyperlink_21.0.0-6_all.deb ... 445s Unpacking python3-hyperlink (21.0.0-6) ... 445s Selecting previously unselected package python3-packaging. 445s Preparing to unpack .../53-python3-packaging_25.0-1_all.deb ... 445s Unpacking python3-packaging (25.0-1) ... 446s Selecting previously unselected package python3-incremental. 446s Preparing to unpack .../54-python3-incremental_24.7.2-3_all.deb ... 446s Unpacking python3-incremental (24.7.2-3) ... 447s Selecting previously unselected package python3-zope.interface. 447s Preparing to unpack .../55-python3-zope.interface_7.2-1build1_riscv64.deb ... 447s Unpacking python3-zope.interface (7.2-1build1) ... 447s Selecting previously unselected package python3-twisted. 448s Preparing to unpack .../56-python3-twisted_24.11.0-1_all.deb ... 448s Unpacking python3-twisted (24.11.0-1) ... 451s Selecting previously unselected package openssh-tests. 452s Preparing to unpack .../57-openssh-tests_1%3a9.9p1-3ubuntu3.1_riscv64.deb ... 452s Unpacking openssh-tests (1:9.9p1-3ubuntu3.1) ... 454s Selecting previously unselected package haveged. 454s Preparing to unpack .../58-haveged_1.9.19-11ubuntu1_riscv64.deb ... 454s Unpacking haveged (1.9.19-11ubuntu1) ... 455s Setting up wdiff (1.2.2-9) ... 455s Setting up libfile-which-perl (1.27-2) ... 455s Setting up libdynaloader-functions-perl (0.004-2) ... 455s Setting up libclass-method-modifiers-perl (2.15-1) ... 455s Setting up python3-zope.interface (7.2-1build1) ... 462s Setting up libclone-perl:riscv64 (0.47-1) ... 462s Setting up libtommath1:riscv64 (1.3.0-1) ... 462s Setting up libhtml-tagset-perl (3.24-1) ... 462s Setting up python3-automat (25.4.16-1) ... 465s Setting up liblwp-mediatypes-perl (6.04-2) ... 465s Setting up libtry-tiny-perl (0.32-1) ... 465s Setting up perl-openssl-defaults:riscv64 (7build3) ... 465s Setting up libencode-locale-perl (1.05-3) ... 465s Setting up python3-hamcrest (2.1.0-1) ... 468s Setting up putty-tools (0.83-2) ... 468s Setting up libhavege2:riscv64 (1.9.19-11ubuntu1) ... 468s Setting up patchutils (0.4.2-1build3) ... 468s Setting up python3-packaging (25.0-1) ... 471s Setting up python3-incremental (24.7.2-3) ... 474s Setting up python3-hyperlink (21.0.0-6) ... 477s Setting up libio-html-perl (1.004-3) ... 477s Setting up libb-hooks-op-check-perl:riscv64 (0.22-3build2) ... 477s Setting up libtimedate-perl (2.3300-2) ... 477s Setting up librole-tiny-perl (2.002004-1) ... 477s Setting up python3-pyasn1 (0.6.1-1) ... 481s Setting up python3-constantly (23.10.4-2) ... 485s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 485s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 485s Setting up libfile-dirlist-perl (0.05-3) ... 485s Setting up libfile-homedir-perl (1.006-2) ... 485s Setting up liburi-perl (5.30-1) ... 485s Setting up libfile-touch-perl (0.12-2) ... 485s Setting up libnet-ssleay-perl:riscv64 (1.94-3) ... 485s Setting up libtomcrypt1:riscv64 (1.18.2+dfsg-7build1) ... 485s Setting up libhttp-date-perl (6.06-1) ... 485s Setting up haveged (1.9.19-11ubuntu1) ... 490s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 497s Setting up dropbear-bin (2025.88-1) ... 497s Setting up libfile-listing-perl (6.16-1) ... 497s Setting up libnet-http-perl (6.23-1) ... 497s Setting up libdevel-callchecker-perl:riscv64 (0.009-2) ... 497s Setting up dropbear (2025.88-1) ... 497s Converting existing OpenSSH RSA host key to Dropbear format. 497s Key is a ssh-rsa key 497s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 498s 3072 SHA256:yJBsiWvl+HbB8SzpQzQlmgWb74LtCjmcpyj7MMLfaz0 /etc/dropbear/dropbear_rsa_host_key (RSA) 498s +---[RSA 3072]----+ 498s | ..o . | 498s | o O o | 498s | . % + | 498s | * * B | 498s | + . X S | 498s |oo.+ + o | 498s |B+..= * | 498s |+=+o.+ E | 498s |++oooo. . | 498s +----[SHA256]-----+ 498s Converting existing OpenSSH ECDSA host key to Dropbear format. 498s Key is a ecdsa-sha2-nistp256 key 498s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 498s 256 SHA256:o+FND3ei0ILd1zWpJ28CQJg7NUm1ck/5gAMvtQFZJjk /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 498s +---[ECDSA 256]---+ 498s | +*B* | 498s | o.E* = . . | 498s | o+oB + + | 498s | ooo = = = . | 498s | . =.S = * o | 498s | . B B + + | 498s | o o . . o | 498s | o | 498s | | 498s +----[SHA256]-----+ 499s Converting existing OpenSSH ED25519 host key to Dropbear format. 499s Key is a ssh-ed25519 key 499s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 499s 256 SHA256:H4LZgeE3RvTzei6a3mH4Z6RJbbkHjUSDzloPJhPnhns /etc/dropbear/dropbear_ed25519_host_key (ED25519) 499s +--[ED25519 256]--+ 499s | ..o . | 499s | . +..o o | 499s | o =Bo. . | 499s | *+oOo. | 499s | o SO.=.+ | 499s | o+EoO . | 499s | .o==.o | 499s | =+++ . | 499s | .+.o+.. | 499s +----[SHA256]-----+ 509s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 515s Setting up python3-pyasn1-modules (0.4.1-2) ... 523s Setting up python3-service-identity (24.2.0-1) ... 527s Setting up libwww-robotrules-perl (6.02-1) ... 527s Setting up libhtml-parser-perl:riscv64 (3.83-1build1) ... 527s Setting up libio-socket-ssl-perl (2.089-1) ... 527s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 527s Setting up libhttp-negotiate-perl (6.01-2) ... 527s Setting up libhttp-cookies-perl (6.11-1) ... 527s Setting up libhtml-tree-perl (5.07-3) ... 527s Setting up libparams-classify-perl:riscv64 (0.015-2build6) ... 527s Setting up libmodule-runtime-perl (0.018-1) ... 527s Setting up python3-twisted (24.11.0-1) ... 582s Setting up libimport-into-perl (1.002005-2) ... 582s Setting up libmoo-perl (2.005005-1) ... 582s Setting up openssh-tests (1:9.9p1-3ubuntu3.1) ... 582s Setting up liblwp-protocol-https-perl (6.14-1) ... 582s Setting up libwww-perl (6.78-1) ... 582s Setting up devscripts (2.25.11) ... 585s Processing triggers for install-info (7.1.1-1) ... 588s Processing triggers for libc-bin (2.41-6ubuntu1) ... 588s Processing triggers for man-db (2.13.1-1) ... 600s autopkgtest [03:11:14]: test regress: [----------------------- 604s info: Adding user `openssh-tests' ... 604s info: Selecting UID/GID from range 1000 to 59999 ... 604s info: Adding new group `openssh-tests' (1001) ... 604s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 605s info: Creating home directory `/home/openssh-tests' ... 605s info: Copying files from `/etc/skel' ... 605s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 606s info: Adding user `openssh-tests' to group `users' ... 612s I: annotate-output 2.25.11 612s I: prefix='%H:%M:%S.%N ' 612s 03:11:26.453749471 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user 613s 03:11:27.085325820 O: make: Entering directory '/tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress' 613s 03:11:27.149164991 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/valgrind-out 613s 03:11:27.225565522 E: + /usr/bin/ssh -Q key 613s 03:11:27.273517524 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 613s 03:11:27.336923307 E: + grep -q ^ssh-rsa 613s 03:11:27.360644865 O: ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 613s 03:11:27.413250841 O: tr '\n' '\r' /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 613s 03:11:27.484336716 O: ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 613s 03:11:27.566577589 E: + + diff -ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2.prv 613s 03:11:27.634732444 O: awk '{print $0 "\r"}' /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 613s 03:11:27.662117502 E: 613s 03:11:27.706725094 O: ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 613s 03:11:27.758396995 O: fi 613s 03:11:27.845161246 E: + tr \n \r 613s 03:11:27.918929349 E: + ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 614s 03:11:27.971889015 E: + diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv 614s 03:11:28.137622088 E: + awk {print $0 "\r"} /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2.prv 614s 03:11:28.238550501 E: + ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 614s 03:11:28.289721839 E: + diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv 614s 03:11:28.468677574 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 614s 03:11:28.553979703 E: + /usr/bin/ssh -Q key 614s 03:11:28.575600528 O: cat /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t2.out ; \ 614s 03:11:28.635387336 O: chmod 600 /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t2.out ; \ 614s 03:11:28.689402498 O: ssh-keygen -yf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 614s 03:11:28.688254228 E: + grep -q ^ssh-rsa 614s 03:11:28.740744731 O: fi 614s 03:11:28.821340585 E: + cat /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.prv 614s 03:11:28.874224177 E: + chmod 600 /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t2.out 614s 03:11:28.926520185 E: + ssh-keygen -yf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t2.out 615s 03:11:28.978745194 E: + diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub 615s 03:11:29.169393893 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 615s 03:11:29.259462636 E: + /usr/bin/ssh -Q key 615s 03:11:29.264543103 O: ssh-keygen -ef /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t3.out ; \ 615s 03:11:29.331652846 O: ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 615s 03:11:29.385133046 O: fi 615s 03:11:29.385597734 E: + grep -q ^ssh-rsa 615s 03:11:29.517560080 E: + ssh-keygen -ef /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub 615s 03:11:29.714497626 E: + ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t3.out+ 615s 03:11:29.765877181 E: diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub 615s 03:11:29.887588495 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 616s 03:11:29.978391918 E: + + /usr/bin/ssh -Q key 616s 03:11:30.007848249 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 616s 03:11:30.050824644 E: grep -q ^ssh-rsa 616s 03:11:30.092470972 O: awk '{print $2}' | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t4.ok ; \ 616s 03:11:30.144102344 O: fi 616s 03:11:30.258380504 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub 616s 03:11:30.318819549 E: + awk {print $2} 616s 03:11:30.368973458 E: + diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t4.ok 616s 03:11:30.450942549 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 616s 03:11:30.538528396 E: + /usr/bin/ssh -Q key 616s 03:11:30.546415093 O: ssh-keygen -Bf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 616s 03:11:30.611107928 O: awk '{print $2}' | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t5.ok ; \ 616s 03:11:30.661539131 O: fi 616s 03:11:30.663030192 E: + grep -q ^ssh-rsa 616s 03:11:30.846005185 E: + ssh-keygen -Bf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub 616s 03:11:30.903007518 E: + awk {print $2} 616s 03:11:30.953444821 E: + diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t5.ok 617s 03:11:31.043082932 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 617s 03:11:31.118530324 E: + + /usr/bin/ssh -Q key 617s 03:11:31.147415894 O: ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t6.out1 ; \ 617s 03:11:31.206546799 O: ssh-keygen -if /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t6.out2 ; \ 617s 03:11:31.234474992 E: grep -q ^ssh-dss 617s 03:11:31.263109768 O: chmod 600 /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t6.out1 ; \ 617s 03:11:31.315579041 O: ssh-keygen -yf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t6.out2 ; \ 617s 03:11:31.368419905 O: fi 617s 03:11:31.482821213 E: + /usr/bin/ssh -Q key 617s 03:11:31.505610936 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 617s 03:11:31.565091432 E: + grep -q ^ssh-dss 617s 03:11:31.600440138 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t7.out ; \ 617s 03:11:31.652736716 O: fi 617s 03:11:31.776623483 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 617s 03:11:31.774901127 E: + /usr/bin/ssh -Q key 617s 03:11:31.876446459 O: ssh-keygen -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 617s 03:11:31.882899896 E: + grep -q ^ssh-dss 618s 03:11:31.970159390 O: ssh-keygen -Bf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 618s 03:11:32.011990536 E: + ssh -Q key 618s 03:11:32.060897020 O: fi 618s 03:11:32.115541863 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 618s 03:11:32.150347198 E: + grep -q ^ssh-dss 618s 03:11:32.166003709 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t8.out ; \ 618s 03:11:32.220151363 O: fi 618s 03:11:32.280429466 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 618s 03:11:32.427927101 O: ssh-keygen -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 618s 03:11:32.443992602 E: + + grep -q ^ssh-dss 618s 03:11:32.514000262 E: ssh -Q key 618s 03:11:32.528342506 O: ssh-keygen -Bf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 618s 03:11:32.580128119 O: fi 618s 03:11:32.677388502 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 618s 03:11:32.762370291 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t9.out 619s 03:11:33.112387741 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 619s 03:11:33.194942426 O: ssh-keygen -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t9.out > /dev/null 619s 03:11:33.515672596 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 619s 03:11:33.608369733 O: ssh-keygen -Bf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t9.out > /dev/null 619s 03:11:33.905363283 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t10.out 620s 03:11:34.081950906 O: ssh-keygen -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t10.out > /dev/null 620s 03:11:34.274587486 O: ssh-keygen -Bf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t10.out > /dev/null 620s 03:11:34.489478133 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 620s 03:11:34.546227873 E: + + /usr/bin/ssh -Q key 620s 03:11:34.595934081 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 620s 03:11:34.646862259 O: awk '{print $2}' | diff - /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t11.ok ; \ 620s 03:11:34.656774422 E: grep -q ^ssh-dss 620s 03:11:34.700657769 O: fi 620s 03:11:34.794561117 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t12.out 621s 03:11:34.961457415 O: ssh-keygen -lf /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 621s 03:11:35.212430487 E: run test connect.sh ... 644s 03:11:58.037534366 O: ok simple connect 644s 03:11:58.073985535 E: run test proxy-connect.sh ... 650s 03:12:04.693325327 O: plain username comp=no 656s 03:12:10.318835286 O: plain username comp=yes 661s 03:12:14.981788674 O: username with style 665s 03:12:19.683550038 O: ok proxy connect 665s 03:12:19.688868998 E: run test sshfp-connect.sh ... 672s 03:12:26.122539366 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 672s 03:12:26.152449898 E: run test connect-privsep.sh ... 739s 03:13:33.445384623 O: ok proxy connect with privsep 739s 03:13:33.488180922 E: run test connect-uri.sh ... 747s 03:13:41.142729599 O: uri connect: no trailing slash 750s 03:13:44.566255097 O: uri connect: trailing slash 753s 03:13:47.946412985 O: uri connect: with path name 755s 03:13:49.100981303 O: ok uri connect 755s 03:13:49.137682538 E: run test proto-version.sh ... 762s 03:13:56.361227702 O: ok sshd version with different protocol combinations 762s 03:13:56.387597441 E: run test proto-mismatch.sh ... 769s 03:14:03.279179612 O: ok protocol version mismatch 769s 03:14:03.306583791 E: run test exit-status.sh ... 775s 03:14:09.168694780 O: test remote exit status: status 0 789s 03:14:23.787630933 O: test remote exit status: status 1 804s 03:14:38.454276854 O: test remote exit status: status 4 818s 03:14:52.796237091 O: test remote exit status: status 5 833s 03:15:07.231562663 O: test remote exit status: status 44 848s 03:15:22.695712273 E: run test exit-status-signal.sh ... 848s 03:15:22.713853567 O: ok remote exit status 860s 03:15:34.080422259 O: ok exit status on signal 860s 03:15:34.112614785 E: run test envpass.sh ... 867s 03:15:40.949843360 O: test environment passing: pass env, don't accept 872s 03:15:45.965873510 O: test environment passing: setenv, don't accept 876s 03:15:50.543402955 O: test environment passing: don't pass env, accept 880s 03:15:54.925220546 O: test environment passing: pass single env, accept single env 885s 03:15:59.496486269 O: test environment passing: pass multiple env, accept multiple env 890s 03:16:04.070751179 O: test environment passing: setenv, accept 895s 03:16:08.932715029 O: test environment passing: setenv, first match wins 899s 03:16:13.921085891 O: test environment passing: server setenv wins 904s 03:16:18.449083554 O: test environment passing: server setenv wins 908s 03:16:22.965501369 O: ok environment passing 909s 03:16:22.994309094 E: run test transfer.sh ... 956s 03:17:10.230962755 O: ok transfer data 956s 03:17:10.271534849 E: run test banner.sh ... 962s 03:17:16.240041787 O: test banner: missing banner file 967s 03:17:21.033626627 O: test banner: size 0 971s 03:17:25.820780247 O: test banner: size 10 976s 03:17:30.752464771 O: test banner: size 100 982s 03:17:36.914373972 O: test banner: size 1000 987s 03:17:41.915847542 O: test banner: size 10000 992s 03:17:46.850183341 O: test banner: size 100000 997s 03:17:51.453967540 O: test banner: suppress banner (-q) 1001s 03:17:55.867846768 O: ok banner 1001s 03:17:55.904726205 E: run test rekey.sh ... 1009s 03:18:03.642919186 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 1014s 03:18:08.257275533 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 1018s 03:18:12.641981361 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 1023s 03:18:17.321053857 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 1029s 03:18:22.983463619 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 1035s 03:18:29.520077285 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 1042s 03:18:36.262478800 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 1049s 03:18:42.981378248 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 1053s 03:18:47.024410624 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 1058s 03:18:52.059406103 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 1062s 03:18:56.289250248 O: client rekey KexAlgorithms=curve25519-sha256 1066s 03:19:00.534094372 O: client rekey KexAlgorithms=sntrup761x25519-sha512 1075s 03:19:09.043961204 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 1084s 03:19:18.324184196 O: client rekey KexAlgorithms=mlkem768x25519-sha256 1089s 03:19:22.969667669 O: client rekey Ciphers=3des-cbc 1093s 03:19:27.209820207 O: client rekey Ciphers=aes128-cbc 1097s 03:19:31.457351953 O: client rekey Ciphers=aes192-cbc 1102s 03:19:36.021142451 O: client rekey Ciphers=aes256-cbc 1107s 03:19:41.369347431 O: client rekey Ciphers=aes128-ctr 1111s 03:19:45.684616081 O: client rekey Ciphers=aes192-ctr 1115s 03:19:49.811057481 O: client rekey Ciphers=aes256-ctr 1120s 03:19:54.175872955 O: client rekey Ciphers=aes128-gcm@openssh.com 1188s 03:21:02.502912489 O: client rekey Ciphers=aes256-gcm@openssh.com 1257s 03:22:11.788849865 O: client rekey Ciphers=chacha20-poly1305@openssh.com 1334s 03:23:28.216542338 O: client rekey MACs=hmac-sha1 1338s 03:23:32.675945518 O: client rekey MACs=hmac-sha1-96 1343s 03:23:37.205609356 O: client rekey MACs=hmac-sha2-256 1348s 03:23:41.943687936 O: client rekey MACs=hmac-sha2-512 1352s 03:23:46.569236285 O: client rekey MACs=hmac-md5 1357s 03:23:51.287803011 O: client rekey MACs=hmac-md5-96 1362s 03:23:56.714112989 O: client rekey MACs=umac-64@openssh.com 1367s 03:24:01.212207282 O: client rekey MACs=umac-128@openssh.com 1372s 03:24:06.613897781 O: client rekey MACs=hmac-sha1-etm@openssh.com 1376s 03:24:10.915945622 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 1381s 03:24:15.514483695 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 1386s 03:24:20.202822636 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 1390s 03:24:24.634736548 O: client rekey MACs=hmac-md5-etm@openssh.com 1396s 03:24:30.117754752 O: client rekey MACs=hmac-md5-96-etm@openssh.com 1400s 03:24:34.856112827 O: client rekey MACs=umac-64-etm@openssh.com 1405s 03:24:39.358649082 O: client rekey MACs=umac-128-etm@openssh.com 1409s 03:24:43.694010312 O: client rekeylimit 16 1414s 03:24:48.413701149 O: client rekeylimit 1k 1418s 03:24:52.461280563 O: client rekeylimit 128k 1423s 03:24:57.036174503 O: client rekeylimit 256k 1427s 03:25:01.768127901 O: client rekeylimit default 5 1447s 03:25:20.999015057 O: client rekeylimit default 10 1471s 03:25:45.444525907 O: client rekeylimit default 5 no data 1490s 03:26:04.486918416 O: client rekeylimit default 10 no data 1514s 03:26:28.416349547 O: server rekeylimit 16 1518s 03:26:32.228934075 O: server rekeylimit 1k 1522s 03:26:36.117733652 O: server rekeylimit 128k 1526s 03:26:40.151560277 O: server rekeylimit 256k 1531s 03:26:45.525588071 O: server rekeylimit default 5 no data 1550s 03:27:04.218441468 O: server rekeylimit default 10 no data 1574s 03:27:27.986792460 O: rekeylimit parsing: bytes 1577s 03:27:31.331498631 O: rekeylimit parsing: time 1580s 03:27:34.802497035 O: ok rekey 1580s 03:27:34.839625435 E: run test dhgex.sh ... 1588s 03:27:42.103851673 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 1592s 03:27:46.021936509 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 1596s 03:27:50.064587363 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 1600s 03:27:54.032809555 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 1603s 03:27:57.784651217 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 1608s 03:28:02.628873176 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 1612s 03:28:06.564462249 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 1616s 03:28:10.226430414 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 1620s 03:28:14.190466624 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 1624s 03:28:18.355505472 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 1628s 03:28:22.543413932 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 1632s 03:28:26.833522515 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 1637s 03:28:31.215161391 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 1641s 03:28:35.365145560 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 1646s 03:28:40.149913208 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 1650s 03:28:44.340315037 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 1655s 03:28:49.321535896 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 1659s 03:28:53.583973604 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 1663s 03:28:57.497416274 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 1667s 03:29:01.927906704 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 1672s 03:29:06.479946576 O: ok dhgex 1672s 03:29:06.508567247 E: run test stderr-data.sh ... 1679s 03:29:13.392685685 O: test stderr data transfer: () 1695s 03:29:29.690044983 O: test stderr data transfer: (-n) 1711s 03:29:45.791589220 O: ok stderr data transfer 1711s 03:29:45.825512111 E: run test stderr-after-eof.sh ... 1726s 03:30:00.221984866 O: ok stderr data after eof 1726s 03:30:00.260934058 E: run test broken-pipe.sh ... 1733s 03:30:07.560373823 O: ok broken pipe test 1733s 03:30:07.589993853 E: run test try-ciphers.sh ... 1740s 03:30:14.238217895 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 1746s 03:30:20.428782032 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 1751s 03:30:25.450701670 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 1756s 03:30:30.426116453 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 1761s 03:30:35.599917582 O: test try ciphers: cipher 3des-cbc mac hmac-md5 1766s 03:30:40.634383834 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 1772s 03:30:46.407657152 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 1777s 03:30:51.433764352 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 1782s 03:30:56.756865604 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1787s 03:31:01.665303397 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1792s 03:31:06.810393107 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1797s 03:31:11.782426055 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1804s 03:31:18.093522733 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1809s 03:31:23.093558338 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 1814s 03:31:28.196323930 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 1819s 03:31:33.042872539 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 1824s 03:31:38.446313785 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 1830s 03:31:44.111838905 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 1835s 03:31:49.401575891 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 1840s 03:31:54.359998427 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 1845s 03:31:59.250358156 O: test try ciphers: cipher aes128-cbc mac hmac-md5 1850s 03:32:04.225197895 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 1855s 03:32:09.117658723 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 1860s 03:32:14.933563969 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 1865s 03:32:19.948210938 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1870s 03:32:24.924234346 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1875s 03:32:29.874758946 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1881s 03:32:35.070233715 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1886s 03:32:40.946060457 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1892s 03:32:46.206360529 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 1897s 03:32:51.416640993 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 1902s 03:32:56.333454433 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 1907s 03:33:01.701846237 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 1912s 03:33:06.625678372 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 1918s 03:33:12.302856158 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 1923s 03:33:17.453870653 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 1928s 03:33:22.433946459 O: test try ciphers: cipher aes192-cbc mac hmac-md5 1933s 03:33:27.423565510 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 1939s 03:33:33.004436564 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 1944s 03:33:38.807532875 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 1950s 03:33:44.016598514 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1955s 03:33:49.034693278 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1960s 03:33:54.125466002 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1965s 03:33:59.337525918 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1970s 03:34:04.809372473 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1976s 03:34:10.609069944 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 1981s 03:34:15.561317650 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 1986s 03:34:20.637915906 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 1992s 03:34:26.011924119 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 1996s 03:34:30.835921783 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 2002s 03:34:36.877021323 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 2007s 03:34:41.886996775 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 2012s 03:34:46.864463110 O: test try ciphers: cipher aes256-cbc mac hmac-md5 2017s 03:34:51.752370392 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 2022s 03:34:56.681446628 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 2028s 03:35:02.257281618 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 2033s 03:35:07.688272279 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 2038s 03:35:12.621818840 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 2043s 03:35:17.475623525 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 2048s 03:35:22.669946768 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 2053s 03:35:27.525334993 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 2059s 03:35:33.275685490 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 2064s 03:35:38.307407578 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 2069s 03:35:43.250038163 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 2074s 03:35:48.478651055 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 2079s 03:35:53.456945907 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 2084s 03:35:58.849629399 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 2090s 03:36:04.257560183 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 2095s 03:36:09.348345735 O: test try ciphers: cipher aes128-ctr mac hmac-md5 2100s 03:36:14.382826680 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 2105s 03:36:19.424504511 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 2110s 03:36:24.390782803 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 2116s 03:36:30.494070572 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 2121s 03:36:35.605042433 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 2126s 03:36:40.515325565 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 2131s 03:36:45.512073126 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 2136s 03:36:50.521006170 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 2142s 03:36:55.954044557 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 2147s 03:37:01.456335008 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 2152s 03:37:06.394344815 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 2157s 03:37:11.524230941 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 2162s 03:37:16.412183841 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 2167s 03:37:21.339136914 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 2173s 03:37:27.646846822 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 2178s 03:37:32.698209175 O: test try ciphers: cipher aes192-ctr mac hmac-md5 2183s 03:37:37.720661455 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 2188s 03:37:42.568929453 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 2193s 03:37:47.677184770 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 2199s 03:37:53.718161861 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 2204s 03:37:58.882749212 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 2210s 03:38:03.991552576 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 2215s 03:38:09.018993775 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 2220s 03:38:14.004234218 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 2224s 03:38:18.854978621 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 2230s 03:38:24.692908770 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 2235s 03:38:29.619592771 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 2241s 03:38:35.186995847 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 2246s 03:38:40.498652236 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 2252s 03:38:46.201153255 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 2258s 03:38:52.435870376 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 2263s 03:38:57.775561071 O: test try ciphers: cipher aes256-ctr mac hmac-md5 2268s 03:39:02.814114882 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 2273s 03:39:07.786641148 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 2278s 03:39:12.858230475 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 2284s 03:39:18.668738678 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 2289s 03:39:23.756553694 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 2294s 03:39:28.731842578 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 2299s 03:39:33.892159575 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 2305s 03:39:39.318010345 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 2310s 03:39:44.320724703 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 2316s 03:39:50.301448786 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 2321s 03:39:55.219149320 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 2326s 03:40:00.610831842 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 2332s 03:40:06.074027080 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 2337s 03:40:11.416017142 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 2343s 03:40:17.177834701 O: ok try ciphers 2343s 03:40:17.193763659 E: run test yes-head.sh ... 2353s 03:40:27.496393602 O: ok yes pipe head 2353s 03:40:27.525513999 E: run test login-timeout.sh ... 2381s 03:40:55.329992322 O: ok connect after login grace timeout 2381s 03:40:55.364992566 E: run test agent.sh ... 2520s 03:43:14.561730525 O: ok simple agent test 2520s 03:43:14.600635620 E: run test agent-getpeereid.sh ... 2527s 03:43:21.943935283 O: ok disallow agent attach from other uid 2527s 03:43:21.963695785 E: run test agent-timeout.sh ... 2556s 03:43:50.356643669 O: ok agent timeout test 2556s 03:43:50.381619255 E: run test agent-ptrace.sh ... 2563s 03:43:57.380663733 O: skipped (gdb not found) 2563s 03:43:57.430402423 E: run test agent-subprocess.sh ... 2580s 03:44:14.406618557 O: ok agent subprocess 2580s 03:44:14.436187037 E: run test keyscan.sh ... 2626s 03:44:59.984946999 E: run test keygen-change.sh ... 2626s 03:44:59.995028244 O: ok keyscan 2654s 03:45:28.713589782 O: ok change passphrase for key 2654s 03:45:28.762682974 E: run test keygen-comment.sh ... 2709s 03:46:23.428168990 O: ok Comment extraction from private key 2709s 03:46:23.472683495 E: run test keygen-convert.sh ... 2741s 03:46:55.128628802 O: ok convert keys 2741s 03:46:55.173820856 E: run test keygen-knownhosts.sh ... 2754s 03:47:08.067932134 O: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts updated. 2754s 03:47:08.137915394 O: Original contents retained as /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts.old 2754s 03:47:08.871148170 O: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts updated. 2754s 03:47:08.925047339 O: Original contents retained as /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts.old 2755s 03:47:09.193448790 O: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts updated. 2755s 03:47:09.247034461 O: Original contents retained as /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts.old 2755s 03:47:09.772810074 O: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts updated. 2755s 03:47:09.823097846 O: Original contents retained as /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hosts.old 2756s 03:47:10.818988330 O: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hashed updated. 2756s 03:47:10.869201905 O: Original contents retained as /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/kh.hashed.old 2757s 03:47:11.025112625 O: ok ssh-keygen known_hosts 2757s 03:47:11.072355603 E: run test keygen-moduli.sh ... 2790s 03:47:44.640001597 E: run test keygen-sshfp.sh ... 2790s 03:47:44.637587596 O: ok keygen moduli 2798s 03:47:52.638060191 E: run test key-options.sh ... 2798s 03:47:52.658841808 O: ok keygen-sshfp 2804s 03:47:58.583347009 O: key option command="echo bar" 2809s 03:48:03.379906154 O: key option no-pty,command="echo bar" 2814s 03:48:08.720689887 O: key option pty default 2819s 03:48:13.664348683 O: key option pty no-pty 2824s 03:48:18.178613710 O: key option pty restrict 2828s 03:48:22.605607179 O: key option pty restrict,pty 2833s 03:48:27.234007677 O: key option environment 2845s 03:48:39.368850746 O: key option from="127.0.0.1" 2857s 03:48:51.382706991 O: key option from="127.0.0.0/8" 2864s 03:48:58.499052312 O: key option expiry-time default 2869s 03:49:03.042069841 O: key option expiry-time invalid 2873s 03:49:07.083722649 O: key option expiry-time expired 2877s 03:49:11.103580120 O: key option expiry-time valid 2882s 03:49:16.405289453 O: ok key options 2882s 03:49:16.441035967 E: run test scp.sh ... 2888s 03:49:22.760797005 O: scp: scp mode: simple copy local file to local file 2889s 03:49:23.143949382 O: scp: scp mode: simple copy local file to remote file 2889s 03:49:23.705449563 O: scp: scp mode: simple copy remote file to local file 2890s 03:49:24.176714546 O: scp: scp mode: copy local file to remote file in place 2890s 03:49:24.709389643 O: scp: scp mode: copy remote file to local file in place 2891s 03:49:25.423525263 O: scp: scp mode: copy local file to remote file clobber 2891s 03:49:25.896023969 O: -rw-r--r-- 1 openssh-tests openssh-tests 797736 May 8 03:49 /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/copy 2891s 03:49:25.955755573 O: -rw-rw-r-- 1 openssh-tests openssh-tests 797736 May 8 03:49 /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/data 2892s 03:49:26.091470836 O: scp: scp mode: copy remote file to local file clobber 2892s 03:49:26.617912540 O: scp: scp mode: simple copy local file to remote dir 2893s 03:49:27.300199384 O: scp: scp mode: simple copy local file to local dir 2893s 03:49:27.808826340 O: scp: scp mode: simple copy remote file to local dir 2894s 03:49:28.350058216 O: scp: scp mode: recursive local dir to remote dir 2895s 03:49:29.516355783 O: scp: scp mode: recursive local dir to local dir 2896s 03:49:30.396296174 O: scp: scp mode: recursive remote dir to local dir 2897s 03:49:31.681475223 O: scp: scp mode: unmatched glob file local->remote 2898s 03:49:32.217322031 O: scp: scp mode: unmatched glob file remote->local 2898s 03:49:32.619922471 O: scp: scp mode: unmatched glob dir recursive local->remote 2900s 03:49:33.954580566 O: scp: scp mode: unmatched glob dir recursive remote->local 2900s 03:49:34.551629630 O: scp: scp mode: shell metacharacters 2901s 03:49:34.992981130 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 2903s 03:49:36.980362510 O: scp: scp mode: disallow bad server #0 2904s 03:49:38.075768065 O: scp: scp mode: disallow bad server #1 2905s 03:49:39.097606974 O: scp: scp mode: disallow bad server #2 2906s 03:49:40.169131297 O: scp: scp mode: disallow bad server #3 2907s 03:49:41.245307265 O: scp: scp mode: disallow bad server #4 2908s 03:49:42.348119763 O: scp: scp mode: disallow bad server #5 2909s 03:49:43.343027376 O: scp: scp mode: disallow bad server #6 2910s 03:49:44.329860082 O: scp: scp mode: disallow bad server #7 2911s 03:49:45.343613716 O: scp: scp mode: detect non-directory target 2911s 03:49:45.529780641 E: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/copy2: Not a directory 2911s 03:49:45.617625584 O: scp: sftp mode: simple copy local file to local file 2912s 03:49:46.020678555 O: scp: sftp mode: simple copy local file to remote file 2912s 03:49:46.566572095 O: scp: sftp mode: simple copy remote file to local file 2913s 03:49:47.091124455 O: scp: sftp mode: copy local file to remote file in place 2913s 03:49:47.686221003 O: scp: sftp mode: copy remote file to local file in place 2914s 03:49:48.292984404 O: scp: sftp mode: copy local file to remote file clobber 2914s 03:49:48.812412666 O: -rw-r--r-- 1 openssh-tests openssh-tests 797736 May 8 03:49 /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/copy 2914s 03:49:48.865871378 O: -rw-rw-r-- 1 openssh-tests openssh-tests 797736 May 8 03:49 /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/data 2914s 03:49:48.963918667 O: scp: sftp mode: copy remote file to local file clobber 2915s 03:49:49.559808546 O: scp: sftp mode: simple copy local file to remote dir 2916s 03:49:50.179467926 O: scp: sftp mode: simple copy local file to local dir 2916s 03:49:50.715444479 O: scp: sftp mode: simple copy remote file to local dir 2917s 03:49:51.306843063 O: scp: sftp mode: recursive local dir to remote dir 2918s 03:49:52.634368944 O: scp: sftp mode: recursive local dir to local dir 2919s 03:49:53.478712389 O: scp: sftp mode: recursive remote dir to local dir 2920s 03:49:54.958239064 O: scp: sftp mode: unmatched glob file local->remote 2921s 03:49:55.623106414 O: scp: sftp mode: unmatched glob file remote->local 2922s 03:49:56.123657589 O: scp: sftp mode: unmatched glob dir recursive local->remote 2923s 03:49:57.077130459 O: scp: sftp mode: unmatched glob dir recursive remote->local 2923s 03:49:57.742495844 O: scp: sftp mode: shell metacharacters 2924s 03:49:58.093480815 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 2926s 03:50:00.629771546 O: scp: sftp mode: disallow bad server #0 2928s 03:50:02.248643972 O: scp: sftp mode: disallow bad server #1 2929s 03:50:03.877111502 O: scp: sftp mode: disallow bad server #2 2931s 03:50:05.475728167 O: scp: sftp mode: disallow bad server #3 2933s 03:50:07.080247413 O: scp: sftp mode: disallow bad server #4 2934s 03:50:08.869634645 O: scp: sftp mode: disallow bad server #5 2936s 03:50:10.683961764 O: scp: sftp mode: disallow bad server #6 2938s 03:50:12.320394852 O: scp: sftp mode: disallow bad server #7 2939s 03:50:13.870829207 O: scp: sftp mode: detect non-directory target 2940s 03:50:14.077774252 E: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/copy2: Not a directory 2940s 03:50:14.354305320 O: ok scp 2940s 03:50:14.389190680 E: run test scp3.sh ... 2946s 03:50:20.966426404 O: scp3: scp mode: simple copy remote file to remote file 2953s 03:50:27.575068069 O: scp3: scp mode: simple copy remote file to remote dir 2959s 03:50:33.483486196 O: scp3: scp mode: recursive remote dir to remote dir 2966s 03:50:40.895419252 O: scp3: scp mode: detect non-directory target 2977s 03:50:51.944653760 O: scp3: sftp mode: simple copy remote file to remote file 2978s 03:50:52.562653012 O: scp3: sftp mode: simple copy remote file to remote dir 2979s 03:50:53.214478854 O: scp3: sftp mode: recursive remote dir to remote dir 2981s 03:50:55.016740594 O: scp3: sftp mode: detect non-directory target 2981s 03:50:55.311062810 E: scp: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/copy2: destination is not a directory 2981s 03:50:55.370949239 E: scp: /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/copy2: destination is not a directory 2981s 03:50:55.636150695 O: ok scp3 2981s 03:50:55.670729585 E: run test scp-uri.sh ... 2989s 03:51:03.084144792 O: scp-uri: scp mode: simple copy local file to remote file 2989s 03:51:03.631953392 O: scp-uri: scp mode: simple copy remote file to local file 2990s 03:51:04.089990986 O: scp-uri: scp mode: simple copy local file to remote dir 2990s 03:51:04.608180829 O: scp-uri: scp mode: simple copy remote file to local dir 2991s 03:51:05.095459399 O: scp-uri: scp mode: recursive local dir to remote dir 2991s 03:51:05.635469577 O: scp-uri: scp mode: recursive remote dir to local dir 2992s 03:51:06.232622786 O: scp-uri: sftp mode: simple copy local file to remote file 2992s 03:51:06.742957306 O: scp-uri: sftp mode: simple copy remote file to local file 2993s 03:51:07.255145241 O: scp-uri: sftp mode: simple copy local file to remote dir 2993s 03:51:07.824699712 O: scp-uri: sftp mode: simple copy remote file to local dir 2994s 03:51:08.410194982 O: scp-uri: sftp mode: recursive local dir to remote dir 2995s 03:51:09.083362363 O: scp-uri: sftp mode: recursive remote dir to local dir 2995s 03:51:09.896369166 O: ok scp-uri 2995s 03:51:09.946424155 E: run test sftp.sh ... 3002s 03:51:16.071467022 O: test basic sftp put/get: buffer_size 5 num_requests 1 3096s 03:52:50.156984673 O: test basic sftp put/get: buffer_size 5 num_requests 2 3145s 03:53:39.775011366 O: test basic sftp put/get: buffer_size 5 num_requests 10 3170s 03:54:04.599973114 O: test basic sftp put/get: buffer_size 1000 num_requests 1 3171s 03:54:05.744958954 O: test basic sftp put/get: buffer_size 1000 num_requests 2 3172s 03:54:06.501707867 O: test basic sftp put/get: buffer_size 1000 num_requests 10 3173s 03:54:07.162097231 O: test basic sftp put/get: buffer_size 32000 num_requests 1 3173s 03:54:07.791862029 O: test basic sftp put/get: buffer_size 32000 num_requests 2 3174s 03:54:08.353113824 O: test basic sftp put/get: buffer_size 32000 num_requests 10 3174s 03:54:08.906816255 O: test basic sftp put/get: buffer_size 64000 num_requests 1 3175s 03:54:09.610285728 O: test basic sftp put/get: buffer_size 64000 num_requests 2 3176s 03:54:10.189150993 O: test basic sftp put/get: buffer_size 64000 num_requests 10 3176s 03:54:10.837082282 O: ok basic sftp put/get 3176s 03:54:10.873093904 E: run test sftp-chroot.sh ... 3185s 03:54:19.656152774 O: test sftp in chroot: get 3190s 03:54:24.259111852 O: test sftp in chroot: match 3196s 03:54:30.579006138 O: ok sftp in chroot 3196s 03:54:30.958309435 E: run test sftp-cmds.sh ... 3203s 03:54:37.597346285 O: sftp commands: lls 3203s 03:54:37.906447065 O: sftp commands: lls w/path 3204s 03:54:38.226846952 O: sftp commands: ls 3204s 03:54:38.475991877 O: sftp commands: shell 3204s 03:54:38.723879089 O: sftp commands: pwd 3204s 03:54:38.881036255 O: sftp commands: lpwd 3205s 03:54:39.036615907 O: sftp commands: quit 3205s 03:54:39.193179470 O: sftp commands: help 3205s 03:54:39.390625415 O: sftp commands: get 3206s 03:54:40.052752901 O: sftp commands: get quoted 3207s 03:54:40.991002380 O: sftp commands: get filename with quotes 3207s 03:54:41.643031890 O: sftp commands: get filename with spaces 3208s 03:54:42.194818963 O: sftp commands: get filename with glob metacharacters 3208s 03:54:42.630486851 O: sftp commands: get to directory 3209s 03:54:43.065500334 O: sftp commands: glob get to directory 3211s 03:54:45.337735510 O: sftp commands: get to local dir 3211s 03:54:45.850233840 O: sftp commands: glob get to local dir 3213s 03:54:47.799064642 O: sftp commands: put 3214s 03:54:48.257675480 O: sftp commands: put filename with quotes 3214s 03:54:48.798249196 O: sftp commands: put filename with spaces 3215s 03:54:49.302525373 O: sftp commands: put to directory 3215s 03:54:49.752338037 O: sftp commands: glob put to directory 3216s 03:54:50.320664928 O: sftp commands: put to local dir 3216s 03:54:50.761669414 O: sftp commands: glob put to local dir 3218s 03:54:52.288204825 O: sftp commands: rename 3218s 03:54:52.543146452 O: sftp commands: rename directory 3218s 03:54:52.691611565 O: sftp commands: ln 3218s 03:54:52.880948067 O: sftp commands: ln -s 3219s 03:54:53.071899586 O: sftp commands: cp 3219s 03:54:53.463542219 O: sftp commands: mkdir 3219s 03:54:53.617115883 O: sftp commands: chdir 3219s 03:54:53.770781747 O: sftp commands: rmdir 3219s 03:54:53.920904678 O: sftp commands: lmkdir 3220s 03:54:54.068689988 O: sftp commands: lchdir 3220s 03:54:54.352062593 O: ok sftp commands 3220s 03:54:54.384701704 E: run test sftp-badcmds.sh ... 3227s 03:55:01.080421603 O: sftp invalid commands: get nonexistent 3227s 03:55:01.340346509 O: sftp invalid commands: glob get to nonexistent directory 3227s 03:55:01.779079737 O: sftp invalid commands: put nonexistent 3228s 03:55:01.993923108 O: sftp invalid commands: glob put to nonexistent directory 3228s 03:55:02.269657369 O: sftp invalid commands: rename nonexistent 3228s 03:55:02.568666154 O: sftp invalid commands: rename target exists (directory) 3229s 03:55:03.050419705 O: sftp invalid commands: glob put files to local file 3229s 03:55:03.320544315 E: run test sftp-batch.sh ... 3229s 03:55:03.343508037 O: ok sftp invalid commands 3236s 03:55:10.164755947 O: sftp batchfile: good commands 3236s 03:55:10.620744395 O: sftp batchfile: bad commands 3237s 03:55:11.059530176 O: sftp batchfile: comments and blanks 3237s 03:55:11.281058240 O: sftp batchfile: junk command 3237s 03:55:11.470843594 O: ok sftp batchfile 3237s 03:55:11.507527652 E: run test sftp-glob.sh ... 3243s 03:55:17.822823995 O: sftp glob: file glob 3244s 03:55:18.200656118 O: sftp glob: dir glob 3244s 03:55:18.531684181 O: sftp glob: quoted glob 3244s 03:55:18.930969017 O: sftp glob: escaped glob 3245s 03:55:19.353019881 O: sftp glob: escaped quote 3245s 03:55:19.636084175 O: sftp glob: quoted quote 3245s 03:55:19.968201753 O: sftp glob: single-quoted quote 3246s 03:55:20.319776228 O: sftp glob: escaped space 3246s 03:55:20.604360040 O: sftp glob: quoted space 3246s 03:55:20.943822295 O: sftp glob: escaped slash 3247s 03:55:21.266327585 O: sftp glob: quoted slash 3247s 03:55:21.560402596 O: sftp glob: escaped slash at EOL 3247s 03:55:21.868222242 O: sftp glob: quoted slash at EOL 3248s 03:55:22.171083241 O: sftp glob: escaped slash+quote 3248s 03:55:22.479397595 O: sftp glob: quoted slash+quote 3248s 03:55:22.852423991 O: ok sftp glob 3248s 03:55:22.881443679 E: run test sftp-perm.sh ... 3255s 03:55:29.377191936 O: sftp permissions: read-only upload 3256s 03:55:30.411650481 O: sftp permissions: read-only setstat 3257s 03:55:31.204795121 O: sftp permissions: read-only rm 3257s 03:55:31.924133329 O: sftp permissions: read-only mkdir 3258s 03:55:32.672688338 O: sftp permissions: read-only rmdir 3259s 03:55:33.387382314 O: sftp permissions: read-only posix-rename 3260s 03:55:34.109522169 O: sftp permissions: read-only oldrename 3261s 03:55:34.976809692 O: sftp permissions: read-only symlink 3261s 03:55:35.681491088 O: sftp permissions: read-only hardlink 3262s 03:55:36.365677882 O: sftp permissions: explicit open 3264s 03:55:38.002922301 O: sftp permissions: explicit read 3265s 03:55:39.712441487 O: sftp permissions: explicit write 3267s 03:55:41.528144185 O: sftp permissions: explicit lstat 3269s 03:55:43.174847604 O: sftp permissions: explicit opendir 3270s 03:55:44.392973894 O: sftp permissions: explicit readdir 3271s 03:55:45.562251803 O: sftp permissions: explicit setstat 3273s 03:55:47.160589708 O: sftp permissions: explicit remove 3274s 03:55:48.521330615 O: sftp permissions: explicit mkdir 3275s 03:55:49.671870890 O: sftp permissions: explicit rmdir 3277s 03:55:51.092347649 O: sftp permissions: explicit rename 3278s 03:55:52.547792994 O: sftp permissions: explicit symlink 3280s 03:55:54.360632242 O: sftp permissions: explicit hardlink 3281s 03:55:55.821061992 O: sftp permissions: explicit statvfs 3282s 03:55:56.850873820 O: ok sftp permissions 3282s 03:55:56.881169533 E: run test sftp-uri.sh ... 3291s 03:56:05.378202506 O: sftp-uri: non-interactive fetch to local file 3295s 03:56:09.552073825 O: sftp-uri: non-interactive fetch to local dir 3299s 03:56:13.551093805 O: sftp-uri: put to remote directory (trailing slash) 3303s 03:56:17.532532681 O: sftp-uri: put to remote directory (no slash) 3309s 03:56:23.658123813 O: ok sftp-uri 3309s 03:56:23.696325719 E: run test reconfigure.sh ... 3347s 03:57:01.516046123 O: ok simple connect after reconfigure 3347s 03:57:01.565411868 E: run test dynamic-forward.sh ... 3355s 03:57:09.628097639 O: test -D forwarding 3375s 03:57:29.177137270 O: test -R forwarding 3395s 03:57:49.312390311 O: PermitRemoteOpen=any 3417s 03:58:11.016009052 O: PermitRemoteOpen=none 3422s 03:58:16.385214296 O: PermitRemoteOpen=explicit 3442s 03:58:36.353156997 O: PermitRemoteOpen=disallowed 3449s 03:58:42.985752087 O: ok dynamic forwarding 3449s 03:58:43.030273922 E: run test forwarding.sh ... 3522s 03:59:56.083369930 O: ok local and remote forwarding 3522s 03:59:56.125106755 E: run test multiplex.sh ... 3534s 04:00:08.641013961 O: test connection multiplexing: setenv 3535s 04:00:09.231839147 O: test connection multiplexing: envpass 3535s 04:00:09.815741249 O: test connection multiplexing: transfer 3537s 04:00:11.566253654 O: test connection multiplexing: transfer -Oproxy 3541s 04:00:15.453865444 O: test connection multiplexing: forward 3545s 04:00:19.799470979 O: test connection multiplexing: status 0 () 3552s 04:00:26.024081773 O: test connection multiplexing: status 0 (-Oproxy) 3558s 04:00:32.229244289 O: test connection multiplexing: status 1 () 3564s 04:00:38.486837642 O: test connection multiplexing: status 1 (-Oproxy) 3570s 04:00:44.709171406 O: test connection multiplexing: status 4 () 3577s 04:00:51.616125406 O: test connection multiplexing: status 4 (-Oproxy) 3583s 04:00:57.875318593 O: test connection multiplexing: status 5 () 3590s 04:01:04.083693674 O: test connection multiplexing: status 5 (-Oproxy) 3596s 04:01:10.283905698 O: test connection multiplexing: status 44 () 3602s 04:01:16.617759221 O: test connection multiplexing: status 44 (-Oproxy) 3608s 04:01:22.892655521 O: test connection multiplexing: cmd check 3609s 04:01:23.293180295 O: test connection multiplexing: cmd forward local (TCP) 3615s 04:01:29.066827137 O: test connection multiplexing: cmd forward remote (TCP) 3620s 04:01:34.883702056 O: test connection multiplexing: cmd forward local (UNIX) 3623s 04:01:37.115926929 O: test connection multiplexing: cmd forward remote (UNIX) 3625s 04:01:39.398842205 O: test connection multiplexing: cmd exit 3625s 04:01:39.839010946 O: test connection multiplexing: cmd stop 3641s 04:01:55.818942179 O: ok connection multiplexing 3641s 04:01:55.857092389 E: run test reexec.sh ... 3648s 04:02:02.052104205 O: test config passing 3654s 04:02:08.629229179 O: test reexec fallback 3654s 04:02:08.682775199 E: ln: failed to create hard link '/tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 3661s 04:02:15.270702566 O: ok reexec tests 3661s 04:02:15.301616783 E: run test brokenkeys.sh ... 3674s 04:02:28.536630350 O: ok broken keys 3674s 04:02:28.572493135 E: run test sshcfgparse.sh ... 3680s 04:02:34.658903616 O: reparse minimal config 3681s 04:02:35.499467037 O: ssh -W opts 3683s 04:02:37.850526701 O: user first match 3685s 04:02:39.884626179 O: pubkeyacceptedalgorithms 3688s 04:02:42.491811689 O: agentforwarding 3690s 04:02:44.432937750 O: command line override 3691s 04:02:45.585932977 O: ok ssh config parse 3691s 04:02:45.602175099 E: run test cfgparse.sh ... 3697s 04:02:51.837426270 O: reparse minimal config 3699s 04:02:53.422858459 O: reparse regress config 3701s 04:02:55.037311458 O: listenaddress order 3703s 04:02:56.990701016 O: ok sshd config parse 3703s 04:02:57.033459202 E: run test cfgmatch.sh ... 3768s 04:04:02.110689988 O: ok sshd_config match 3768s 04:04:02.149892737 E: run test cfgmatchlisten.sh ... 3880s 04:05:54.636886967 O: ok sshd_config matchlisten 3880s 04:05:54.672556284 E: run test percent.sh ... 3887s 04:06:01.579656891 O: percent expansions matchexec percent 3934s 04:06:48.414908626 O: percent expansions localcommand percent 3993s 04:07:47.575859530 O: percent expansions remotecommand percent 3999s 04:07:53.910303266 O: percent expansions controlpath percent 4006s 04:08:00.575976951 O: percent expansions identityagent percent 4013s 04:08:07.009883107 O: percent expansions forwardagent percent 4019s 04:08:13.475069348 O: percent expansions localforward percent 4026s 04:08:20.529779716 O: percent expansions remoteforward percent 4033s 04:08:27.336715912 O: percent expansions revokedhostkeys percent 4039s 04:08:33.655828427 O: percent expansions userknownhostsfile percent 4090s 04:09:24.722330349 O: percent expansions controlpath dollar 4091s 04:09:25.281273196 O: percent expansions identityagent dollar 4091s 04:09:25.820726049 O: percent expansions forwardagent dollar 4092s 04:09:26.384627372 O: percent expansions localforward dollar 4092s 04:09:26.968855804 O: percent expansions remoteforward dollar 4093s 04:09:27.553033435 O: percent expansions userknownhostsfile dollar 4098s 04:09:32.272519113 O: percent expansions controlpath tilde 4099s 04:09:33.349653712 O: percent expansions identityagent tilde 4100s 04:09:34.464353181 O: percent expansions forwardagent tilde 4101s 04:09:35.557141922 O: ok percent expansions 4101s 04:09:35.596286515 E: run test addrmatch.sh ... 4108s 04:09:42.613170392 O: test first entry for user 192.168.0.1 somehost 4109s 04:09:43.126985479 O: test negative match for user 192.168.30.1 somehost 4109s 04:09:43.699717660 O: test no match for user 19.0.0.1 somehost 4110s 04:09:44.250741012 O: test list middle for user 10.255.255.254 somehost 4110s 04:09:44.749359270 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 4111s 04:09:45.253482913 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 4111s 04:09:45.748147412 O: test localaddress for user 19.0.0.1 somehost 4112s 04:09:46.242262204 O: test localport for user 19.0.0.1 somehost 4112s 04:09:46.749081789 O: test bare IP6 address for user ::1 somehost.example.com 4113s 04:09:47.268637668 O: test deny IPv6 for user ::2 somehost.example.com 4113s 04:09:47.720362219 O: test IP6 negated for user ::3 somehost 4114s 04:09:48.240683910 O: test IP6 no match for user ::4 somehost 4114s 04:09:48.736889037 O: test IP6 network for user 2000::1 somehost 4115s 04:09:49.228868000 O: test IP6 network for user 2001::1 somehost 4115s 04:09:49.776230604 O: test IP6 localaddress for user ::5 somehost 4116s 04:09:50.324599223 O: test IP6 localport for user ::5 somehost 4116s 04:09:50.806417934 O: test invalid Match address 10.0.1.0/8 4117s 04:09:51.329791675 O: test invalid Match localaddress 10.0.1.0/8 4117s 04:09:51.786436405 O: test invalid Match address 10.0.0.1/24 4118s 04:09:52.246066880 O: test invalid Match localaddress 10.0.0.1/24 4118s 04:09:52.701330890 O: test invalid Match address 2000:aa:bb:01::/56 4119s 04:09:53.168367679 O: test invalid Match localaddress 2000:aa:bb:01::/56 4119s 04:09:53.717638517 O: ok address match 4119s 04:09:53.750779620 E: run test localcommand.sh ... 4126s 04:10:00.283567737 O: test localcommand: proto localcommand 4131s 04:10:05.102878793 E: run test forcecommand.sh ... 4131s 04:10:05.098842231 O: ok localcommand 4158s 04:10:32.730059830 E: Connection closed 4158s 04:10:32.800069399 E: Connection closed. 4167s 04:10:41.776574923 E: Connection closed. 4167s 04:10:41.831735766 E: Connection closed 4172s 04:10:46.896335808 O: ok forced command 4172s 04:10:46.926439268 E: run test portnum.sh ... 4179s 04:10:53.761144660 O: port number parsing: invalid port 0 4180s 04:10:54.146516960 O: port number parsing: invalid port 65536 4180s 04:10:54.547536701 O: port number parsing: invalid port 131073 4181s 04:10:55.118990651 O: port number parsing: invalid port 2000blah 4181s 04:10:55.556531851 O: port number parsing: invalid port blah2000 4181s 04:10:55.935815159 O: port number parsing: valid port 1 4186s 04:11:00.939864719 O: port number parsing: valid port 22 4191s 04:11:05.532433834 O: port number parsing: valid port 2222 4196s 04:11:10.038105090 O: port number parsing: valid port 22222 4200s 04:11:14.892622807 O: port number parsing: valid port 65535 4206s 04:11:20.195848063 O: ok port number parsing 4206s 04:11:20.252937702 E: run test keytype.sh ... 4212s 04:11:26.605333512 O: keygen ed25519, 512 bits 4213s 04:11:27.066771510 O: keygen ed25519-sk, n/a bits 4213s 04:11:27.640656546 O: keygen ecdsa, 256 bits 4214s 04:11:28.163901026 O: keygen ecdsa, 384 bits 4214s 04:11:28.603583633 O: keygen ecdsa, 521 bits 4215s 04:11:29.029597257 O: keygen ecdsa-sk, n/a bits 4215s 04:11:29.631760863 O: keygen rsa, 2048 bits 4216s 04:11:30.787922840 O: keygen rsa, 3072 bits 4222s 04:11:36.830716131 O: userkey ed25519-512, hostkey ed25519-512 4227s 04:11:41.074916945 O: userkey ed25519-512, hostkey ed25519-512 4231s 04:11:45.289272193 O: userkey ed25519-512, hostkey ed25519-512 4235s 04:11:49.612896878 O: userkey ed25519-sk, hostkey ed25519-sk 4241s 04:11:55.098405957 O: userkey ed25519-sk, hostkey ed25519-sk 4245s 04:11:59.725838264 O: userkey ed25519-sk, hostkey ed25519-sk 4250s 04:12:04.291668497 O: userkey ecdsa-256, hostkey ecdsa-256 4254s 04:12:08.602325190 O: userkey ecdsa-256, hostkey ecdsa-256 4258s 04:12:12.933233533 O: userkey ecdsa-256, hostkey ecdsa-256 4263s 04:12:17.574705857 O: userkey ecdsa-384, hostkey ecdsa-384 4268s 04:12:22.596566633 O: userkey ecdsa-384, hostkey ecdsa-384 4273s 04:12:27.166261118 O: userkey ecdsa-384, hostkey ecdsa-384 4277s 04:12:31.907492572 O: userkey ecdsa-521, hostkey ecdsa-521 4282s 04:12:36.163596190 O: userkey ecdsa-521, hostkey ecdsa-521 4286s 04:12:40.511493535 O: userkey ecdsa-521, hostkey ecdsa-521 4291s 04:12:45.271002341 O: userkey ecdsa-sk, hostkey ecdsa-sk 4296s 04:12:50.351743266 O: userkey ecdsa-sk, hostkey ecdsa-sk 4301s 04:12:55.666520293 O: userkey ecdsa-sk, hostkey ecdsa-sk 4306s 04:13:00.746391134 O: userkey rsa-2048, hostkey rsa-2048 4310s 04:13:04.906796425 O: userkey rsa-2048, hostkey rsa-2048 4315s 04:13:09.208611579 O: userkey rsa-2048, hostkey rsa-2048 4319s 04:13:13.774920767 O: userkey rsa-3072, hostkey rsa-3072 4324s 04:13:18.328776065 O: userkey rsa-3072, hostkey rsa-3072 4329s 04:13:23.405450960 O: userkey rsa-3072, hostkey rsa-3072 4333s 04:13:27.682046520 O: ok login with different key types 4333s 04:13:27.734667719 E: run test kextype.sh ... 4340s 04:13:34.681024383 O: kex diffie-hellman-group1-sha1 4354s 04:13:48.756412054 O: kex diffie-hellman-group14-sha1 4367s 04:14:01.207065400 O: kex diffie-hellman-group14-sha256 4381s 04:14:15.167068105 O: kex diffie-hellman-group16-sha512 4395s 04:14:29.640570367 O: kex diffie-hellman-group18-sha512 4410s 04:14:44.771308215 O: kex diffie-hellman-group-exchange-sha1 4427s 04:15:01.291123351 O: kex diffie-hellman-group-exchange-sha256 4443s 04:15:17.454828704 O: kex ecdh-sha2-nistp256 4457s 04:15:31.702664985 O: kex ecdh-sha2-nistp384 4470s 04:15:44.934115327 O: kex ecdh-sha2-nistp521 4483s 04:15:57.688573601 O: kex curve25519-sha256 4497s 04:16:11.335331853 O: kex curve25519-sha256@libssh.org 4511s 04:16:25.077004614 O: kex sntrup761x25519-sha512 4530s 04:16:43.975543300 O: kex sntrup761x25519-sha512@openssh.com 4549s 04:17:03.362372055 O: kex mlkem768x25519-sha256 4562s 04:17:16.844577718 O: ok login with different key exchange algorithms 4562s 04:17:16.877010660 E: run test cert-hostkey.sh ... 4575s 04:17:29.438679659 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/host_ca_key.pub 4575s 04:17:29.525022550 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/host_ca_key2.pub 4575s 04:17:29.585823195 O: certified host keys: sign host ed25519 cert 4575s 04:17:29.821100277 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 4576s 04:17:30.278238203 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 4576s 04:17:30.578967430 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 4577s 04:17:31.090460386 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 4577s 04:17:31.497537727 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 4577s 04:17:31.629906059 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 4578s 04:17:32.008101835 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 4578s 04:17:32.472808539 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 4578s 04:17:32.619073926 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 4579s 04:17:32.998000208 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 4579s 04:17:33.443877418 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 4579s 04:17:33.580459060 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 4579s 04:17:33.938209402 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 4580s 04:17:34.389967589 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 4580s 04:17:34.527002328 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 4581s 04:17:35.066100117 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 4581s 04:17:35.557020650 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 4581s 04:17:35.690481688 O: certified host keys: sign host rsa cert 4583s 04:17:37.426956663 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 4583s 04:17:37.911714214 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 4584s 04:17:38.074281244 O: certified host keys: sign host rsa-sha2-256 cert 4588s 04:17:42.173464393 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 4588s 04:17:42.680420448 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 4588s 04:17:42.818801123 O: certified host keys: sign host rsa-sha2-512 cert 4589s 04:17:43.908956304 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 4590s 04:17:44.421766173 O: Revoking from /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 4590s 04:17:44.626748027 O: certified host keys: host ed25519 cert connect 4590s 04:17:44.726540170 O: certified host keys: ed25519 basic connect expect success yes 4596s 04:17:50.352513634 O: certified host keys: ed25519 empty KRL expect success yes 4601s 04:17:55.522704970 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 4604s 04:17:58.900547674 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 4608s 04:18:02.210301084 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 4611s 04:18:05.734560858 O: certified host keys: ed25519 empty plaintext revocation expect success yes 4616s 04:18:10.488732619 O: certified host keys: ed25519 plain key plaintext revocation expect success no 4620s 04:18:13.984973578 O: certified host keys: ed25519 cert plaintext revocation expect success no 4623s 04:18:17.461566420 O: certified host keys: ed25519 CA plaintext revocation expect success no 4626s 04:18:20.937762973 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 4627s 04:18:21.020487819 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 4633s 04:18:27.000588702 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 4637s 04:18:31.885470171 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 4641s 04:18:35.312458301 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 4644s 04:18:38.854173043 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 4648s 04:18:42.723891158 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 4654s 04:18:48.515633770 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 4657s 04:18:51.908865296 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 4661s 04:18:55.651048531 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 4665s 04:18:59.704837326 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 4665s 04:18:59.811809362 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 4670s 04:19:04.787940057 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 4675s 04:19:09.566370477 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 4679s 04:19:12.986298155 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 4682s 04:19:16.681813540 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 4686s 04:19:20.185264408 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 4690s 04:19:24.849100670 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 4695s 04:19:29.211608479 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 4698s 04:19:32.731617716 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 4702s 04:19:36.114834184 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 4702s 04:19:36.220013160 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 4707s 04:19:41.299542644 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 4712s 04:19:46.536444720 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 4716s 04:19:50.017938793 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 4719s 04:19:53.337833693 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 4722s 04:19:56.771880853 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 4728s 04:20:02.712995024 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 4732s 04:20:06.126390585 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 4735s 04:20:09.824096368 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 4739s 04:20:13.176482192 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 4739s 04:20:13.272450510 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 4743s 04:20:17.926814945 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 4748s 04:20:22.792120508 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 4752s 04:20:26.210843204 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 4756s 04:20:30.476304631 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 4760s 04:20:34.004429122 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 4765s 04:20:39.001979631 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 4768s 04:20:42.437408791 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 4771s 04:20:45.967440747 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 4775s 04:20:49.379565913 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 4775s 04:20:49.473576517 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 4780s 04:20:54.446909103 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 4786s 04:21:00.133134495 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 4790s 04:21:04.011036623 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 4793s 04:21:07.689276606 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 4797s 04:21:11.852289717 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 4803s 04:21:17.039944323 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 4806s 04:21:20.637406407 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 4810s 04:21:24.580749897 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 4814s 04:21:28.426719709 O: certified host keys: host rsa cert connect 4814s 04:21:28.541209998 O: certified host keys: rsa basic connect expect success yes 4820s 04:21:34.201114230 O: certified host keys: rsa empty KRL expect success yes 4825s 04:21:39.026807195 O: certified host keys: rsa KRL w/ plain key revoked expect success no 4828s 04:21:42.546741664 O: certified host keys: rsa KRL w/ cert revoked expect success no 4832s 04:21:46.001822524 O: certified host keys: rsa KRL w/ CA revoked expect success no 4835s 04:21:49.835905776 O: certified host keys: rsa empty plaintext revocation expect success yes 4840s 04:21:54.686588912 O: certified host keys: rsa plain key plaintext revocation expect success no 4844s 04:21:58.125887597 O: certified host keys: rsa cert plaintext revocation expect success no 4848s 04:22:02.515825644 O: certified host keys: rsa CA plaintext revocation expect success no 4852s 04:22:06.247839025 O: certified host keys: host rsa-sha2-256 cert connect 4852s 04:22:06.359575516 O: certified host keys: rsa-sha2-256 basic connect expect success yes 4857s 04:22:11.463566988 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 4862s 04:22:16.450287262 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 4866s 04:22:20.006575952 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 4869s 04:22:23.822957432 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 4873s 04:22:27.522756718 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 4878s 04:22:32.640105217 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 4883s 04:22:37.016668292 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 4886s 04:22:40.714317152 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 4890s 04:22:44.129484010 O: certified host keys: host rsa-sha2-512 cert connect 4890s 04:22:44.229926973 O: certified host keys: rsa-sha2-512 basic connect expect success yes 4895s 04:22:49.181238489 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 4900s 04:22:54.046650614 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 4903s 04:22:57.512108760 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 4906s 04:23:00.907484995 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 4910s 04:23:04.370046478 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 4915s 04:23:09.925097680 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 4919s 04:23:13.521208160 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 4923s 04:23:17.193582061 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 4927s 04:23:21.205399317 O: certified host keys: host ed25519 revoked cert 4930s 04:23:24.689141336 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 4934s 04:23:28.183974248 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 4937s 04:23:31.683722011 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 4941s 04:23:35.132428904 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 4945s 04:23:39.539541311 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 4949s 04:23:43.528658327 O: certified host keys: host rsa revoked cert 4953s 04:23:47.023497521 O: certified host keys: host rsa-sha2-256 revoked cert 4956s 04:23:50.626876215 O: certified host keys: host rsa-sha2-512 revoked cert 4960s 04:23:54.370445876 O: certified host keys: host ed25519 revoked cert 4963s 04:23:57.717084916 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 4967s 04:24:01.134498240 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 4970s 04:24:04.714314062 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 4974s 04:24:08.260721021 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 4978s 04:24:12.681559187 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 4982s 04:24:16.869480544 O: certified host keys: host rsa revoked cert 4986s 04:24:20.452376911 O: certified host keys: host rsa-sha2-256 revoked cert 4990s 04:24:23.950210454 O: certified host keys: host rsa-sha2-512 revoked cert 5271s 04:29:05.457308024 O: certified host keys: host ed25519 cert downgrade to raw key 5281s 04:29:15.766982827 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 5292s 04:29:26.384570680 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 5303s 04:29:37.786486707 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 5315s 04:29:49.015156403 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 5325s 04:29:59.296856794 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 5337s 04:30:11.693658654 O: certified host keys: host rsa cert downgrade to raw key 5350s 04:30:24.157712777 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 5362s 04:30:36.405705817 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 5374s 04:30:48.434672507 O: certified host keys: host ed25519 connect wrong cert 5378s 04:30:52.412021688 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 5382s 04:30:56.814169492 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 5387s 04:31:01.020496701 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 5391s 04:31:05.109482046 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 5396s 04:31:10.193126811 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 5401s 04:31:15.059617700 O: certified host keys: host rsa connect wrong cert 5405s 04:31:19.731937395 O: certified host keys: host rsa-sha2-256 connect wrong cert 5411s 04:31:25.632831022 O: certified host keys: host rsa-sha2-512 connect wrong cert 5419s 04:31:33.319575923 O: ok certified host keys 5419s 04:31:33.357695369 E: run test cert-userkey.sh ... 5429s 04:31:43.290996864 O: certified user keys: sign user ed25519 cert 5429s 04:31:43.823860574 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 5430s 04:31:44.487394763 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 5431s 04:31:45.013596971 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 5431s 04:31:45.624141299 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 5432s 04:31:46.227871820 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 5433s 04:31:47.020867305 O: certified user keys: sign user rsa cert 5434s 04:31:48.661381477 O: certified user keys: sign user rsa-sha2-256 cert 5440s 04:31:54.613488589 O: certified user keys: sign user rsa-sha2-512 cert 5442s 04:31:56.773891484 O: certified user keys: ed25519 missing authorized_principals 5446s 04:32:00.781831157 O: certified user keys: ed25519 empty authorized_principals 5450s 04:32:04.450902783 O: certified user keys: ed25519 wrong authorized_principals 5454s 04:32:08.150400815 O: certified user keys: ed25519 correct authorized_principals 5459s 04:32:13.158282700 O: certified user keys: ed25519 authorized_principals bad key opt 5462s 04:32:16.827086753 O: certified user keys: ed25519 authorized_principals command=false 5468s 04:32:22.349073155 O: certified user keys: ed25519 authorized_principals command=true 5473s 04:32:27.374976385 O: certified user keys: ed25519 wrong principals key option 5477s 04:32:31.385128872 O: certified user keys: ed25519 correct principals key option 5482s 04:32:36.420257027 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 5486s 04:32:40.013582486 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 5490s 04:32:44.140314370 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 5493s 04:32:47.685712534 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 5498s 04:32:52.838236330 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 5503s 04:32:56.964350380 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 5508s 04:33:01.972391864 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 5513s 04:33:07.092547365 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 5516s 04:33:10.901362498 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 5522s 04:33:16.038671511 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 5525s 04:33:19.697115031 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 5529s 04:33:23.572143615 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 5534s 04:33:28.529546438 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 5540s 04:33:34.199543318 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 5544s 04:33:38.064007432 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 5548s 04:33:42.815449922 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 5553s 04:33:47.887747388 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 5557s 04:33:51.788006611 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 5563s 04:33:57.518937535 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 5567s 04:34:01.570666538 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 5571s 04:34:05.678703154 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 5575s 04:34:09.466559003 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 5580s 04:34:14.593024096 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 5584s 04:34:18.316085461 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 5589s 04:34:23.470908550 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 5595s 04:34:29.094737708 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 5599s 04:34:33.816381747 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 5605s 04:34:39.163361946 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 5609s 04:34:42.991099051 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 5612s 04:34:46.585536836 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 5616s 04:34:50.520324433 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 5621s 04:34:55.860368073 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 5625s 04:34:59.493162475 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 5631s 04:35:05.504030674 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 5638s 04:35:11.981424964 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 5643s 04:35:17.016840096 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 5647s 04:35:21.718203262 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 5651s 04:35:25.607121733 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 5655s 04:35:29.385122928 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 5659s 04:35:33.073567113 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 5665s 04:35:39.412275121 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 5669s 04:35:43.393942969 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 5674s 04:35:48.341725215 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 5679s 04:35:53.311608229 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 5683s 04:35:57.486491525 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 5688s 04:36:02.773906788 O: certified user keys: rsa missing authorized_principals 5693s 04:36:07.077762271 O: certified user keys: rsa empty authorized_principals 5696s 04:36:10.893662399 O: certified user keys: rsa wrong authorized_principals 5700s 04:36:14.504062831 O: certified user keys: rsa correct authorized_principals 5705s 04:36:19.193789796 O: certified user keys: rsa authorized_principals bad key opt 5708s 04:36:22.702027638 O: certified user keys: rsa authorized_principals command=false 5713s 04:36:27.405699032 O: certified user keys: rsa authorized_principals command=true 5718s 04:36:32.397624642 O: certified user keys: rsa wrong principals key option 5722s 04:36:36.098859489 O: certified user keys: rsa correct principals key option 5727s 04:36:41.836851848 O: certified user keys: rsa-sha2-256 missing authorized_principals 5731s 04:36:45.600101591 O: certified user keys: rsa-sha2-256 empty authorized_principals 5735s 04:36:49.261018505 O: certified user keys: rsa-sha2-256 wrong authorized_principals 5739s 04:36:52.979765549 O: certified user keys: rsa-sha2-256 correct authorized_principals 5744s 04:36:58.368753795 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 5748s 04:37:02.148350522 O: certified user keys: rsa-sha2-256 authorized_principals command=false 5752s 04:37:06.962364643 O: certified user keys: rsa-sha2-256 authorized_principals command=true 5758s 04:37:12.911553187 O: certified user keys: rsa-sha2-256 wrong principals key option 5763s 04:37:17.150727456 O: certified user keys: rsa-sha2-256 correct principals key option 5768s 04:37:22.501379778 O: certified user keys: rsa-sha2-512 missing authorized_principals 5772s 04:37:26.219699521 O: certified user keys: rsa-sha2-512 empty authorized_principals 5776s 04:37:30.077840467 O: certified user keys: rsa-sha2-512 wrong authorized_principals 5779s 04:37:33.830877340 O: certified user keys: rsa-sha2-512 correct authorized_principals 5784s 04:37:38.678937777 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 5789s 04:37:43.134959297 O: certified user keys: rsa-sha2-512 authorized_principals command=false 5794s 04:37:48.129869370 O: certified user keys: rsa-sha2-512 authorized_principals command=true 5799s 04:37:53.214398500 O: certified user keys: rsa-sha2-512 wrong principals key option 5802s 04:37:56.884665119 O: certified user keys: rsa-sha2-512 correct principals key option 5807s 04:38:01.965205793 O: certified user keys: ed25519 authorized_keys connect 5814s 04:38:07.990646390 O: certified user keys: ed25519 authorized_keys revoked key 5817s 04:38:11.801001877 O: certified user keys: ed25519 authorized_keys revoked via KRL 5822s 04:38:16.649581502 O: certified user keys: ed25519 authorized_keys empty KRL 5827s 04:38:21.862271075 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 5832s 04:38:26.868403821 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 5836s 04:38:30.552628213 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 5840s 04:38:34.356332049 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 5845s 04:38:39.817508927 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 5850s 04:38:44.616032863 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 5855s 04:38:49.558779521 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 5859s 04:38:53.700277496 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 5864s 04:38:58.753642836 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 5869s 04:39:03.593611756 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 5873s 04:39:07.451697430 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 5877s 04:39:11.632424785 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 5882s 04:39:16.649834011 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 5888s 04:39:22.807081195 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 5892s 04:39:26.683084334 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 5896s 04:39:30.640785161 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 5902s 04:39:36.075555187 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 5907s 04:39:41.112404218 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 5911s 04:39:45.224014613 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 5915s 04:39:49.272138061 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 5921s 04:39:55.377412250 O: certified user keys: rsa authorized_keys connect 5926s 04:40:00.330482405 O: certified user keys: rsa authorized_keys revoked key 5930s 04:40:04.160853399 O: certified user keys: rsa authorized_keys revoked via KRL 5933s 04:40:07.918643980 O: certified user keys: rsa authorized_keys empty KRL 5939s 04:40:13.180433947 O: certified user keys: rsa-sha2-256 authorized_keys connect 5944s 04:40:18.030164257 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 5948s 04:40:22.749744881 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 5952s 04:40:26.719084803 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 5957s 04:40:31.853651828 O: certified user keys: rsa-sha2-512 authorized_keys connect 5962s 04:40:36.935674386 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 5966s 04:40:40.946903178 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 5971s 04:40:45.033748349 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 5976s 04:40:50.136171603 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 5980s 04:40:54.675792799 O: certified user keys: authorized_keys CA does not authenticate 5980s 04:40:54.795163749 O: certified user keys: ensure CA key does not authenticate user 5984s 04:40:58.494119837 O: certified user keys: ed25519 TrustedUserCAKeys connect 5990s 04:41:04.059121915 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 5993s 04:41:07.914227102 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 5998s 04:41:12.148646844 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 6003s 04:41:17.431151464 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 6008s 04:41:22.548130674 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 6014s 04:41:27.977108128 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 6018s 04:41:32.934389655 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 6024s 04:41:38.940286873 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 6029s 04:41:43.810576217 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 6033s 04:41:47.709697253 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 6037s 04:41:51.809971887 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 6043s 04:41:57.464207329 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 6049s 04:42:03.582165139 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 6053s 04:42:07.660642587 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 6058s 04:42:12.208012090 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 6063s 04:42:17.518025364 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 6069s 04:42:23.389896490 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 6073s 04:42:27.170949199 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 6077s 04:42:31.388145194 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 6084s 04:42:38.159797054 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 6089s 04:42:43.580925156 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 6093s 04:42:47.874749936 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 6098s 04:42:52.543908487 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 6104s 04:42:57.998434946 O: certified user keys: rsa TrustedUserCAKeys connect 6109s 04:43:03.036626637 O: certified user keys: rsa TrustedUserCAKeys revoked key 6113s 04:43:07.277931579 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 6117s 04:43:11.163875466 O: certified user keys: rsa TrustedUserCAKeys empty KRL 6122s 04:43:16.645184112 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 6127s 04:43:21.488443037 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 6131s 04:43:25.821111975 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 6135s 04:43:29.945353018 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 6141s 04:43:35.029484393 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 6146s 04:43:40.813257470 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 6151s 04:43:45.629077587 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 6156s 04:43:50.500280209 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 6161s 04:43:55.798019995 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 6165s 04:43:59.707793102 O: certified user keys: TrustedUserCAKeys CA does not authenticate 6165s 04:43:59.808660116 O: certified user keys: ensure CA key does not authenticate user 6169s 04:44:03.468847044 O: certified user keys: correct principal auth authorized_keys expect success rsa 6175s 04:44:09.639146371 O: certified user keys: correct principal auth authorized_keys expect success ed25519 6181s 04:44:15.881186846 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 6187s 04:44:21.705782661 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 6193s 04:44:27.874224092 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 6198s 04:44:32.392527615 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 6202s 04:44:36.895757001 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 6207s 04:44:41.027912736 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 6212s 04:44:46.194826856 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 6216s 04:44:50.730006906 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 6221s 04:44:55.406701794 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 6226s 04:45:00.062718310 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 6232s 04:45:06.093365204 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 6236s 04:45:10.905089826 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 6241s 04:45:15.359139943 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 6245s 04:45:19.807157323 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 6251s 04:45:25.075805195 O: certified user keys: cert expired auth authorized_keys expect failure rsa 6255s 04:45:29.636584561 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 6260s 04:45:34.295809363 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 6264s 04:45:38.614091315 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 6269s 04:45:43.146607250 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 6274s 04:45:48.761080631 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 6280s 04:45:54.420465710 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 6287s 04:46:01.209104712 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 6293s 04:46:07.478665457 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 6297s 04:46:11.840526280 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 6302s 04:46:16.052601968 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 6306s 04:46:20.749918776 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 6311s 04:46:25.035675425 O: certified user keys: force-command auth authorized_keys expect failure rsa 6317s 04:46:31.845645936 O: certified user keys: force-command auth authorized_keys expect failure ed25519 6324s 04:46:37.984807492 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 6329s 04:46:43.772444587 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 6336s 04:46:50.574465921 O: certified user keys: empty principals auth authorized_keys expect success rsa 6342s 04:46:56.333089775 O: certified user keys: empty principals auth authorized_keys expect success ed25519 6347s 04:47:01.372810439 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 6351s 04:47:05.377623731 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 6356s 04:47:10.554727571 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 6361s 04:47:15.799796130 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 6368s 04:47:22.081618294 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 6372s 04:47:26.406996980 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 6376s 04:47:30.610212914 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 6382s 04:47:36.213928938 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 6388s 04:47:42.700035136 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 6393s 04:47:47.166659031 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 6397s 04:47:51.463967103 O: certified user keys: force-command match true auth authorized_keys expect success rsa 6402s 04:47:56.784236441 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 6408s 04:48:02.375864290 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 6413s 04:48:07.688906184 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 6420s 04:48:14.436441716 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 6425s 04:48:19.010713231 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 6429s 04:48:23.534477553 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 6433s 04:48:27.950435168 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 6438s 04:48:32.882579241 O: certified user keys: user ed25519 connect wrong cert 6443s 04:48:37.273676426 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 6447s 04:48:41.261495914 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 6452s 04:48:46.230000330 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 6456s 04:48:50.405937517 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 6460s 04:48:54.694667436 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 6464s 04:48:58.928148281 O: certified user keys: user rsa connect wrong cert 6469s 04:49:03.068272827 O: certified user keys: user rsa-sha2-256 connect wrong cert 6473s 04:49:07.373725178 O: certified user keys: user rsa-sha2-512 connect wrong cert 6477s 04:49:11.856527792 O: ok certified user keys 6477s 04:49:11.924915051 E: run test host-expand.sh ... 6491s 04:49:25.182363117 E: run test keys-command.sh ... 6491s 04:49:25.177741698 O: ok expand %h and %n 6499s 04:49:33.683051374 O: SKIPPED: /var/run/keycommand_openssh-tests.44355 not executable (/var/run mounted noexec?) 6500s 04:49:34.037581564 E: run test forward-control.sh ... 6520s 04:49:53.987863740 O: check_lfwd done (expecting Y): default configuration 6531s 04:50:05.057625680 O: check_rfwd done (expecting Y): default configuration 6540s 04:50:14.828524839 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 6552s 04:50:26.503528094 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 6560s 04:50:34.181346220 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 6571s 04:50:45.013254553 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 6583s 04:50:57.125845451 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 6593s 04:51:07.603996841 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 6600s 04:51:14.342395992 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 6611s 04:51:25.849294985 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 6622s 04:51:36.034440021 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 6633s 04:51:47.297811429 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 6640s 04:51:54.620615437 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 6646s 04:52:00.730386839 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 6654s 04:52:08.008388861 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 6659s 04:52:13.909639652 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 6669s 04:52:23.873916029 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 6681s 04:52:35.729800351 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 6691s 04:52:45.878709320 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 6697s 04:52:51.249617116 O: check_rfwd done (expecting N): AllowTcpForwarding=local 6704s 04:52:58.228998929 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 6710s 04:53:04.335659438 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 6720s 04:53:14.453835281 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 6726s 04:53:20.225572406 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 6731s 04:53:25.947434874 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 6738s 04:53:32.030200319 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 6746s 04:53:40.844222136 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 6752s 04:53:46.658334422 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 6759s 04:53:53.828171649 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 6766s 04:54:00.338095380 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 6772s 04:54:06.506055909 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 6778s 04:54:12.453549230 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 6789s 04:54:23.083085389 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 6795s 04:54:29.144486745 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 6803s 04:54:37.169874093 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 6814s 04:54:48.404225946 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 6821s 04:54:55.938866826 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 6834s 04:55:08.687649745 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 6841s 04:55:15.777679562 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 6852s 04:55:26.677353021 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 6859s 04:55:33.397733068 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 6871s 04:55:45.427586568 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 6878s 04:55:52.948769795 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 6891s 04:56:05.848608199 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 6899s 04:56:13.708683264 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 6905s 04:56:19.237679874 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 6911s 04:56:25.897274992 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 6917s 04:56:31.936828274 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 6923s 04:56:37.529275136 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 6935s 04:56:49.081678183 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 6942s 04:56:56.145785426 O: check_lfwd done (expecting N): AllowTcpForwarding=no 6947s 04:57:01.468220336 O: check_rfwd done (expecting N): AllowTcpForwarding=no 6952s 04:57:06.768714030 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 6959s 04:57:13.103552348 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 6965s 04:57:19.895770257 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 6970s 04:57:24.812441719 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 6977s 04:57:31.489994453 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 6983s 04:57:37.114269103 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 6990s 04:57:44.506901858 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 6995s 04:57:49.498773479 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 7001s 04:57:55.012902377 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 7006s 04:58:00.109546640 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 7013s 04:58:07.503884365 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 7018s 04:58:12.727419044 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 7025s 04:58:19.496281158 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 7030s 04:58:24.652009212 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 7041s 04:58:35.081208999 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 7046s 04:58:40.296974160 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 7056s 04:58:50.156838566 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 7066s 04:59:00.830466658 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 7076s 04:59:10.409998112 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 7081s 04:59:15.405157864 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 7090s 04:59:24.885744236 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 7096s 04:59:30.194306141 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 7103s 04:59:37.420577316 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 7108s 04:59:42.675073305 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 7115s 04:59:49.149853532 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 7125s 04:59:59.585074938 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 7133s 05:00:07.549418423 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 7139s 05:00:13.105707519 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 7145s 05:00:18.970681796 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 7150s 05:00:24.244309812 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 7151s 05:00:25.046807648 E: run test integrity.sh ... 7151s 05:00:25.044785853 O: ok sshd control of local and remote forwarding 7159s 05:00:33.331060900 O: test integrity: hmac-sha1 @2900 7164s 05:00:38.596935878 O: test integrity: hmac-sha1 @2901 7169s 05:00:43.491441191 O: test integrity: hmac-sha1 @2902 7174s 05:00:48.443483837 O: test integrity: hmac-sha1 @2903 7180s 05:00:54.300729020 O: test integrity: hmac-sha1 @2904 7185s 05:00:59.490796345 O: test integrity: hmac-sha1 @2905 7190s 05:01:04.758603996 O: test integrity: hmac-sha1 @2906 7195s 05:01:09.879155525 O: test integrity: hmac-sha1 @2907 7201s 05:01:15.019636985 O: test integrity: hmac-sha1 @2908 7206s 05:01:20.234769375 O: test integrity: hmac-sha1 @2909 7211s 05:01:25.639097100 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7212s 05:01:26.300733911 O: test integrity: hmac-sha1-96 @2900 7217s 05:01:31.821117761 O: test integrity: hmac-sha1-96 @2901 7222s 05:01:36.951960068 O: test integrity: hmac-sha1-96 @2902 7228s 05:01:42.222319004 O: test integrity: hmac-sha1-96 @2903 7233s 05:01:47.320438482 O: test integrity: hmac-sha1-96 @2904 7239s 05:01:53.595714403 O: test integrity: hmac-sha1-96 @2905 7244s 05:01:58.638889814 O: test integrity: hmac-sha1-96 @2906 7249s 05:02:03.746551570 O: test integrity: hmac-sha1-96 @2907 7254s 05:02:08.934058657 O: test integrity: hmac-sha1-96 @2908 7260s 05:02:14.137698169 O: test integrity: hmac-sha1-96 @2909 7265s 05:02:19.808474826 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7266s 05:02:20.548240276 O: test integrity: hmac-sha2-256 @2900 7272s 05:02:26.200140881 O: test integrity: hmac-sha2-256 @2901 7277s 05:02:31.339895476 O: test integrity: hmac-sha2-256 @2902 7282s 05:02:36.516905760 O: test integrity: hmac-sha2-256 @2903 7287s 05:02:41.512978075 O: test integrity: hmac-sha2-256 @2904 7293s 05:02:47.638162020 O: test integrity: hmac-sha2-256 @2905 7298s 05:02:52.788898256 O: test integrity: hmac-sha2-256 @2906 7303s 05:02:57.923869729 O: test integrity: hmac-sha2-256 @2907 7309s 05:03:03.050403730 O: test integrity: hmac-sha2-256 @2908 7314s 05:03:08.233541800 O: test integrity: hmac-sha2-256 @2909 7319s 05:03:13.438043170 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7320s 05:03:14.156814675 O: test integrity: hmac-sha2-512 @2900 7324s 05:03:18.531823946 O: test integrity: hmac-sha2-512 @2901 7328s 05:03:22.655856160 O: test integrity: hmac-sha2-512 @2902 7332s 05:03:26.720507452 O: test integrity: hmac-sha2-512 @2903 7336s 05:03:30.873840913 O: test integrity: hmac-sha2-512 @2904 7341s 05:03:35.373230281 O: test integrity: hmac-sha2-512 @2905 7345s 05:03:39.568687295 O: test integrity: hmac-sha2-512 @2906 7350s 05:03:44.476061225 O: test integrity: hmac-sha2-512 @2907 7354s 05:03:48.739442694 O: test integrity: hmac-sha2-512 @2908 7359s 05:03:53.092184052 O: test integrity: hmac-sha2-512 @2909 7362s 05:03:56.787898776 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7363s 05:03:57.413232769 O: test integrity: hmac-md5 @2900 7368s 05:04:02.877341105 O: test integrity: hmac-md5 @2901 7373s 05:04:07.822580375 O: test integrity: hmac-md5 @2902 7379s 05:04:13.771792347 O: test integrity: hmac-md5 @2903 7385s 05:04:18.990318613 O: test integrity: hmac-md5 @2904 7390s 05:04:24.180795783 O: test integrity: hmac-md5 @2905 7395s 05:04:29.866132215 O: test integrity: hmac-md5 @2906 7401s 05:04:35.033181084 O: test integrity: hmac-md5 @2907 7407s 05:04:41.208270656 O: test integrity: hmac-md5 @2908 7412s 05:04:46.680822784 O: test integrity: hmac-md5 @2909 7417s 05:04:51.397392794 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7418s 05:04:52.034686925 O: test integrity: hmac-md5-96 @2900 7423s 05:04:57.090109780 O: test integrity: hmac-md5-96 @2901 7428s 05:05:02.297249198 O: test integrity: hmac-md5-96 @2902 7434s 05:05:08.689058192 O: test integrity: hmac-md5-96 @2903 7440s 05:05:14.049465807 O: test integrity: hmac-md5-96 @2904 7445s 05:05:18.965942474 O: test integrity: hmac-md5-96 @2905 7450s 05:05:24.236155031 O: test integrity: hmac-md5-96 @2906 7455s 05:05:29.522068664 O: test integrity: hmac-md5-96 @2907 7462s 05:05:36.014204476 O: test integrity: hmac-md5-96 @2908 7467s 05:05:41.156579163 O: test integrity: hmac-md5-96 @2909 7471s 05:05:45.911837617 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7472s 05:05:46.576538875 O: test integrity: umac-64@openssh.com @2900 7477s 05:05:51.788170207 O: test integrity: umac-64@openssh.com @2901 7482s 05:05:56.963843389 O: test integrity: umac-64@openssh.com @2902 7489s 05:06:03.103054251 O: test integrity: umac-64@openssh.com @2903 7494s 05:06:08.449567438 O: test integrity: umac-64@openssh.com @2904 7499s 05:06:13.571132699 O: test integrity: umac-64@openssh.com @2905 7504s 05:06:18.829802868 O: test integrity: umac-64@openssh.com @2906 7510s 05:06:24.042864171 O: test integrity: umac-64@openssh.com @2907 7516s 05:06:30.206819291 O: test integrity: umac-64@openssh.com @2908 7521s 05:06:35.499703865 O: test integrity: umac-64@openssh.com @2909 7526s 05:06:40.314840730 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7527s 05:06:40.988821198 O: test integrity: umac-128@openssh.com @2900 7532s 05:06:46.300168892 O: test integrity: umac-128@openssh.com @2901 7537s 05:06:51.444048935 O: test integrity: umac-128@openssh.com @2902 7543s 05:06:57.484764086 O: test integrity: umac-128@openssh.com @2903 7548s 05:07:02.633441134 O: test integrity: umac-128@openssh.com @2904 7553s 05:07:07.718153974 O: test integrity: umac-128@openssh.com @2905 7558s 05:07:12.720066888 O: test integrity: umac-128@openssh.com @2906 7563s 05:07:17.814930297 O: test integrity: umac-128@openssh.com @2907 7569s 05:07:23.675307424 O: test integrity: umac-128@openssh.com @2908 7575s 05:07:29.047902632 O: test integrity: umac-128@openssh.com @2909 7579s 05:07:33.620843426 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7580s 05:07:34.277212964 O: test integrity: hmac-sha1-etm@openssh.com @2900 7585s 05:07:39.411417249 O: test integrity: hmac-sha1-etm@openssh.com @2901 7590s 05:07:44.501422121 O: test integrity: hmac-sha1-etm@openssh.com @2902 7596s 05:07:50.263114119 O: test integrity: hmac-sha1-etm@openssh.com @2903 7601s 05:07:55.529965916 O: test integrity: hmac-sha1-etm@openssh.com @2904 7606s 05:08:00.651540605 O: test integrity: hmac-sha1-etm@openssh.com @2905 7611s 05:08:05.760749002 O: test integrity: hmac-sha1-etm@openssh.com @2906 7617s 05:08:11.315758262 O: test integrity: hmac-sha1-etm@openssh.com @2907 7623s 05:08:17.101334697 O: test integrity: hmac-sha1-etm@openssh.com @2908 7628s 05:08:22.686904572 O: test integrity: hmac-sha1-etm@openssh.com @2909 7633s 05:08:27.602928069 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7634s 05:08:28.345653864 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 7639s 05:08:33.338961266 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 7644s 05:08:38.333057278 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 7649s 05:08:43.453245640 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 7655s 05:08:49.583583284 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 7660s 05:08:54.656874933 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 7666s 05:08:59.989725301 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 7671s 05:09:05.739521399 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 7677s 05:09:11.210623181 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 7683s 05:09:17.704764688 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 7688s 05:09:22.350623262 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7689s 05:09:22.972221068 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 7694s 05:09:28.043968499 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 7699s 05:09:33.539622397 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 7704s 05:09:38.621617249 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 7710s 05:09:44.681142247 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 7716s 05:09:50.006207553 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 7721s 05:09:55.128197277 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 7726s 05:10:00.577784569 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 7732s 05:10:06.262216512 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 7738s 05:10:12.902968389 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 7743s 05:10:17.870122305 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7744s 05:10:18.519908582 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 7748s 05:10:22.745860670 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 7752s 05:10:26.865807857 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 7757s 05:10:31.255146521 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 7761s 05:10:35.621270014 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 7766s 05:10:40.576840103 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 7771s 05:10:45.070912338 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 7775s 05:10:49.305807323 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 7779s 05:10:53.477101465 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 7783s 05:10:57.801229114 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 7787s 05:11:01.587110636 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7788s 05:11:02.253420636 O: test integrity: hmac-md5-etm@openssh.com @2900 7794s 05:11:08.728695699 O: test integrity: hmac-md5-etm@openssh.com @2901 7799s 05:11:13.954665583 O: test integrity: hmac-md5-etm@openssh.com @2902 7804s 05:11:18.937855239 O: test integrity: hmac-md5-etm@openssh.com @2903 7809s 05:11:23.942816911 O: test integrity: hmac-md5-etm@openssh.com @2904 7815s 05:11:29.041547023 O: test integrity: hmac-md5-etm@openssh.com @2905 7820s 05:11:34.520178780 O: test integrity: hmac-md5-etm@openssh.com @2906 7826s 05:11:40.402716644 O: test integrity: hmac-md5-etm@openssh.com @2907 7831s 05:11:45.766532084 O: test integrity: hmac-md5-etm@openssh.com @2908 7836s 05:11:50.811550635 O: test integrity: hmac-md5-etm@openssh.com @2909 7841s 05:11:55.796093051 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7842s 05:11:56.560906741 O: test integrity: hmac-md5-96-etm@openssh.com @2900 7847s 05:12:01.944897272 O: test integrity: hmac-md5-96-etm@openssh.com @2901 7854s 05:12:08.203085705 O: test integrity: hmac-md5-96-etm@openssh.com @2902 7859s 05:12:13.230808451 O: test integrity: hmac-md5-96-etm@openssh.com @2903 7864s 05:12:18.615790841 O: test integrity: hmac-md5-96-etm@openssh.com @2904 7869s 05:12:23.887902196 O: test integrity: hmac-md5-96-etm@openssh.com @2905 7875s 05:12:29.500388279 O: test integrity: hmac-md5-96-etm@openssh.com @2906 7882s 05:12:36.479721183 O: test integrity: hmac-md5-96-etm@openssh.com @2907 7887s 05:12:41.949296179 O: test integrity: hmac-md5-96-etm@openssh.com @2908 7893s 05:12:47.074794281 O: test integrity: hmac-md5-96-etm@openssh.com @2909 7898s 05:12:52.010121077 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7898s 05:12:52.692964024 O: test integrity: umac-64-etm@openssh.com @2900 7903s 05:12:57.755139244 O: test integrity: umac-64-etm@openssh.com @2901 7910s 05:13:04.024701046 O: test integrity: umac-64-etm@openssh.com @2902 7915s 05:13:09.524044795 O: test integrity: umac-64-etm@openssh.com @2903 7920s 05:13:14.442231540 O: test integrity: umac-64-etm@openssh.com @2904 7925s 05:13:19.332801090 O: test integrity: umac-64-etm@openssh.com @2905 7930s 05:13:24.264575683 O: test integrity: umac-64-etm@openssh.com @2906 7935s 05:13:29.904188898 O: test integrity: umac-64-etm@openssh.com @2907 7941s 05:13:35.211136298 O: test integrity: umac-64-etm@openssh.com @2908 7946s 05:13:40.549178131 O: test integrity: umac-64-etm@openssh.com @2909 7951s 05:13:45.384097873 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 7952s 05:13:46.038422150 O: test integrity: umac-128-etm@openssh.com @2900 7957s 05:13:51.064981559 O: test integrity: umac-128-etm@openssh.com @2901 7963s 05:13:57.026018218 O: test integrity: umac-128-etm@openssh.com @2902 7968s 05:14:02.529433377 O: test integrity: umac-128-etm@openssh.com @2903 7973s 05:14:07.664532111 O: test integrity: umac-128-etm@openssh.com @2904 7978s 05:14:12.806097018 O: test integrity: umac-128-etm@openssh.com @2905 7984s 05:14:17.983808363 O: test integrity: umac-128-etm@openssh.com @2906 7989s 05:14:23.433214663 O: test integrity: umac-128-etm@openssh.com @2907 7995s 05:14:28.988953360 O: test integrity: umac-128-etm@openssh.com @2908 7999s 05:14:33.945682399 O: test integrity: umac-128-etm@openssh.com @2909 8004s 05:14:38.509754818 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 8005s 05:14:39.163598272 O: test integrity: aes128-gcm@openssh.com @2900 8009s 05:14:43.322776237 O: test integrity: aes128-gcm@openssh.com @2901 8013s 05:14:47.621623452 O: test integrity: aes128-gcm@openssh.com @2902 8018s 05:14:52.416045720 O: test integrity: aes128-gcm@openssh.com @2903 8023s 05:14:57.206178526 O: test integrity: aes128-gcm@openssh.com @2904 8027s 05:15:01.477832802 O: test integrity: aes128-gcm@openssh.com @2905 8031s 05:15:05.725093959 O: test integrity: aes128-gcm@openssh.com @2906 8035s 05:15:09.769404089 O: test integrity: aes128-gcm@openssh.com @2907 8040s 05:15:14.182822098 O: test integrity: aes128-gcm@openssh.com @2908 8044s 05:15:18.293479148 O: test integrity: aes128-gcm@openssh.com @2909 8047s 05:15:21.914239500 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 8048s 05:15:22.595518474 O: test integrity: aes256-gcm@openssh.com @2900 8053s 05:15:27.500489135 O: test integrity: aes256-gcm@openssh.com @2901 8057s 05:15:31.554141658 O: test integrity: aes256-gcm@openssh.com @2902 8062s 05:15:36.080146717 O: test integrity: aes256-gcm@openssh.com @2903 8066s 05:15:40.106790044 O: test integrity: aes256-gcm@openssh.com @2904 8070s 05:15:44.158548760 O: test integrity: aes256-gcm@openssh.com @2905 8074s 05:15:48.281904476 O: test integrity: aes256-gcm@openssh.com @2906 8078s 05:15:52.384586874 O: test integrity: aes256-gcm@openssh.com @2907 8083s 05:15:57.289951810 O: test integrity: aes256-gcm@openssh.com @2908 8087s 05:16:01.355478888 O: test integrity: aes256-gcm@openssh.com @2909 8091s 05:16:05.040037383 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 8091s 05:16:05.691600741 O: test integrity: chacha20-poly1305@openssh.com @2900 8095s 05:16:09.740004939 O: test integrity: chacha20-poly1305@openssh.com @2901 8100s 05:16:14.341506296 O: test integrity: chacha20-poly1305@openssh.com @2902 8104s 05:16:18.621331314 O: test integrity: chacha20-poly1305@openssh.com @2903 8109s 05:16:23.032060481 O: test integrity: chacha20-poly1305@openssh.com @2904 8114s 05:16:27.984481297 O: test integrity: chacha20-poly1305@openssh.com @2905 8118s 05:16:32.162066467 O: test integrity: chacha20-poly1305@openssh.com @2906 8122s 05:16:36.262425077 O: test integrity: chacha20-poly1305@openssh.com @2907 8126s 05:16:40.268708685 O: test integrity: chacha20-poly1305@openssh.com @2908 8130s 05:16:44.274992319 O: test integrity: chacha20-poly1305@openssh.com @2909 8134s 05:16:48.003748396 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 8134s 05:16:48.179129129 O: ok integrity 8134s 05:16:48.203480389 E: run test krl.sh ... 8141s 05:16:55.584399224 O: key revocation lists: generating test keys 8160s 05:17:14.850554337 O: key revocation lists: generating KRLs 8164s 05:17:18.089913067 O: key revocation lists: checking revocations for revoked keys 8189s 05:17:43.016092763 O: key revocation lists: checking revocations for unrevoked keys 8212s 05:18:06.739829769 O: key revocation lists: checking revocations for revoked certs 8242s 05:18:36.542874103 O: key revocation lists: checking revocations for unrevoked certs 8270s 05:19:04.233263494 O: key revocation lists: testing KRL update 8297s 05:19:31.358748671 O: key revocation lists: checking revocations for revoked keys 8321s 05:19:55.390115562 O: key revocation lists: checking revocations for unrevoked keys 8344s 05:20:18.642036023 O: key revocation lists: checking revocations for revoked certs 8374s 05:20:48.738882643 O: key revocation lists: checking revocations for unrevoked certs 8402s 05:21:16.860238455 O: ok key revocation lists 8402s 05:21:16.918791681 E: run test multipubkey.sh ... 8447s 05:22:00.980560419 O: ok multiple pubkey 8447s 05:22:01.026091126 E: run test limit-keytype.sh ... 8481s 05:22:35.035159994 O: allow rsa,ed25519 8495s 05:22:49.207612686 O: allow ed25519 8506s 05:23:00.555159770 O: allow cert only 8518s 05:23:12.144155687 O: match w/ no match 8530s 05:23:24.750060996 O: match w/ matching 8543s 05:23:37.302066207 O: ok restrict pubkey type 8543s 05:23:37.337894032 E: run test hostkey-agent.sh ... 8556s 05:23:50.091679111 O: key type ssh-ed25519 8560s 05:23:54.762543957 O: key type sk-ssh-ed25519@openssh.com 8565s 05:23:59.135313764 O: key type ecdsa-sha2-nistp256 8569s 05:24:03.703673067 O: key type ecdsa-sha2-nistp384 8574s 05:24:08.218666985 O: key type ecdsa-sha2-nistp521 8578s 05:24:12.548789630 O: key type sk-ecdsa-sha2-nistp256@openssh.com 8584s 05:24:18.429281392 O: key type ssh-rsa 8589s 05:24:23.339727201 O: cert type ssh-ed25519-cert-v01@openssh.com 8594s 05:24:28.181059831 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 8599s 05:24:33.026989919 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 8603s 05:24:37.804226152 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 8608s 05:24:42.479750284 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 8614s 05:24:48.487896942 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 8619s 05:24:53.758765447 O: cert type ssh-rsa-cert-v01@openssh.com 8624s 05:24:58.635074250 O: cert type rsa-sha2-256-cert-v01@openssh.com 8629s 05:25:03.330060622 O: cert type rsa-sha2-512-cert-v01@openssh.com 8634s 05:25:08.407366528 O: ok hostkey agent 8634s 05:25:08.442749307 E: run test hostkey-rotate.sh ... 8645s 05:25:19.731577824 O: learn hostkey with StrictHostKeyChecking=no 8650s 05:25:24.833936038 O: learn additional hostkeys 8657s 05:25:31.214595722 O: learn additional hostkeys, type=ssh-ed25519 8662s 05:25:36.284233141 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 8667s 05:25:41.280404019 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 8673s 05:25:47.232498349 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 8678s 05:25:52.102248543 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 8683s 05:25:57.020441277 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 8688s 05:26:02.088430569 O: learn additional hostkeys, type=ssh-rsa 8692s 05:26:06.906138076 O: learn changed non-primary hostkey type=ssh-rsa 8701s 05:26:15.067396169 O: learn new primary hostkey 8706s 05:26:20.774432261 O: rotate primary hostkey 8712s 05:26:26.598973684 O: check rotate primary hostkey 8717s 05:26:31.532513127 O: ok hostkey rotate 8717s 05:26:31.559854196 E: run test principals-command.sh ... 8727s 05:26:41.449673983 O: SKIPPED: /var/run/principals_command_openssh-tests.60762 not executable (/var/run mounted noexec?) 8727s 05:26:41.799848286 E: run test cert-file.sh ... 8737s 05:26:51.325128886 O: identity cert with no plain public file 8742s 05:26:56.645930957 O: CertificateFile with no plain public file 8747s 05:27:01.212223475 O: plain keys 8751s 05:27:04.981195030 O: untrusted cert 8754s 05:27:08.714781217 O: good cert, bad key 8759s 05:27:13.104872466 O: single trusted 8764s 05:27:18.159828473 O: multiple trusted 8782s 05:27:36.191960984 O: ok ssh with certificates 8782s 05:27:36.219844741 E: run test cfginclude.sh ... 8789s 05:27:43.040938600 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8789s 05:27:43.424709612 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8789s 05:27:43.809586435 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8790s 05:27:44.130412470 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8790s 05:27:44.469099567 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8790s 05:27:44.810309987 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8791s 05:27:45.140685007 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8791s 05:27:45.473024343 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8791s 05:27:45.795617090 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8792s 05:27:46.620070917 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8792s 05:27:46.961942337 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8793s 05:27:47.307614590 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8793s 05:27:47.946629716 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8794s 05:27:48.269332857 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8794s 05:27:48.638851723 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8795s 05:27:48.976475199 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8795s 05:27:49.313979071 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8795s 05:27:49.652124648 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8795s 05:27:49.966499010 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8796s 05:27:50.300935751 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8796s 05:27:50.697644358 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8797s 05:27:51.494741502 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8797s 05:27:51.835447197 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8798s 05:27:52.169474731 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8798s 05:27:52.775550431 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8799s 05:27:53.187039665 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 8799s 05:27:53.456616309 O: ok config include 8799s 05:27:53.484540362 E: run test servcfginclude.sh ... 8816s 05:28:10.837493767 O: ok server config include 8816s 05:28:10.875636107 E: run test allow-deny-users.sh ... 8855s 05:28:49.649297475 O: ok AllowUsers/DenyUsers 8855s 05:28:49.688780715 E: run test authinfo.sh ... 8863s 05:28:57.041078594 O: ExposeAuthInfo=no 8868s 05:29:02.255083153 O: ExposeAuthInfo=yes 8872s 05:29:06.932154555 O: ok authinfo 8872s 05:29:06.959811090 E: run test sshsig.sh ... 8879s 05:29:13.854280969 O: sshsig: make certificates 8881s 05:29:15.468368387 O: sshsig: check signature for ssh-ed25519 8895s 05:29:29.943873789 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 8909s 05:29:43.408603809 O: sshsig: check signature for ecdsa-sha2-nistp256 8924s 05:29:58.336357112 O: sshsig: check signature for ecdsa-sha2-nistp384 8938s 05:30:12.902390638 O: sshsig: check signature for ecdsa-sha2-nistp521 8953s 05:30:27.547655336 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 8970s 05:30:44.275824958 O: sshsig: check signature for ssh-rsa 8984s 05:30:58.512777874 O: sshsig: check signature for ssh-ed25519-cert.pub 9006s 05:31:20.087119707 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 9029s 05:31:43.099028832 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 9053s 05:32:06.983678565 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 9076s 05:32:30.825857066 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 9101s 05:32:55.773430693 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 9127s 05:33:21.399578696 O: sshsig: check signature for ssh-rsa-cert.pub 9150s 05:33:44.462724235 O: sshsig: match principals 9151s 05:33:45.272938151 O: sshsig: nomatch principals 9152s 05:33:45.974076009 O: ok sshsig 9152s 05:33:46.026564073 E: run test knownhosts.sh ... 9191s 05:34:25.024824660 O: ok known hosts 9191s 05:34:25.069030059 E: run test knownhosts-command.sh ... 9197s 05:34:31.850603766 O: simple connection 9202s 05:34:36.825384155 O: no keys 9206s 05:34:40.252138868 O: bad exit status 9208s 05:34:42.395787242 O: keytype ssh-ed25519 9213s 05:34:47.230282894 O: keytype sk-ssh-ed25519@openssh.com 9219s 05:34:52.940168993 O: keytype ecdsa-sha2-nistp256 9224s 05:34:57.992374302 O: keytype ecdsa-sha2-nistp384 9228s 05:35:02.864394724 O: keytype ecdsa-sha2-nistp521 9233s 05:35:07.824100739 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 9239s 05:35:12.998775782 O: keytype ssh-rsa 9243s 05:35:17.770951685 O: ok known hosts command 9243s 05:35:17.803839401 E: run test agent-restrict.sh ... 9250s 05:35:24.567615571 O: generate keys 9253s 05:35:27.503564881 O: prepare client config 9254s 05:35:28.225685101 O: prepare known_hosts 9254s 05:35:28.464123159 O: prepare server configs 9255s 05:35:29.292374170 O: authentication w/o agent 9301s 05:36:15.229198088 O: start agent 9305s 05:36:19.470540421 O: authentication with agent (no restrict) 9349s 05:37:03.915100598 O: unrestricted keylist 9373s 05:37:27.554766977 O: authentication with agent (basic restrict) 9395s 05:37:49.543936347 O: authentication with agent incorrect key (basic restrict) 9411s 05:38:05.561009959 O: keylist (basic restrict) 9434s 05:38:28.755697019 O: username 9458s 05:38:52.504509171 O: username wildcard 9482s 05:39:16.484987482 O: username incorrect 9486s 05:39:20.065391590 O: agent restriction honours certificate principal 9487s 05:39:21.816745804 O: multihop without agent 9513s 05:39:46.962238567 O: multihop agent unrestricted 9538s 05:40:12.910495153 O: multihop restricted 9565s 05:40:39.471589116 O: multihop username 9589s 05:41:03.697962885 O: multihop wildcard username 9616s 05:41:30.101650882 O: multihop wrong username 9634s 05:41:48.585389227 O: multihop cycle no agent 9670s 05:42:24.365460461 O: multihop cycle agent unrestricted 9706s 05:43:00.318887787 O: multihop cycle restricted deny 9722s 05:43:16.383518432 O: multihop cycle restricted allow 9759s 05:43:53.307682096 O: ok agent restrictions 9759s 05:43:53.365427610 E: run test hostbased.sh ... 9765s 05:43:59.838877310 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 9765s 05:43:59.871898689 E: run test channel-timeout.sh ... 9772s 05:44:06.347770748 O: no timeout 9783s 05:44:17.037237205 O: command timeout 9788s 05:44:22.474090327 O: command long timeout 9793s 05:44:27.333586605 O: command wildcard timeout 9798s 05:44:32.498318896 O: command irrelevant timeout 9808s 05:44:42.288316789 O: multiplexed command timeout 9824s 05:44:58.280242758 O: irrelevant multiplexed command timeout 9846s 05:45:20.146493629 O: global command timeout 9873s 05:45:47.434864104 O: sftp no timeout 9884s 05:45:58.113909989 O: sftp timeout 9889s 05:46:03.451174175 E: Connection closed 9889s 05:46:03.501803343 O: sftp irrelevant timeout 9901s 05:46:15.142401420 O: ok channel timeout 9901s 05:46:15.200995428 E: run test connection-timeout.sh ... 9907s 05:46:21.348796190 O: no timeout 9917s 05:46:31.030753681 O: timeout 9929s 05:46:43.249806882 O: session inhibits timeout 9942s 05:46:56.140949959 O: timeout after session 9950s 05:47:04.672664234 O: timeout with listeners 9964s 05:47:18.274252066 O: ok unused connection timeout 9964s 05:47:18.299958900 E: run test match-subsystem.sh ... 10013s 05:48:07.205118646 O: ok sshd_config match subsystem 10013s 05:48:07.251675087 E: run test agent-pkcs11-restrict.sh ... 10019s 05:48:13.382359360 O: SKIPPED: No PKCS#11 library found 10019s 05:48:13.412960918 E: run test agent-pkcs11-cert.sh ... 10025s 05:48:19.094689145 O: SKIPPED: No PKCS#11 library found 10025s 05:48:19.123165392 E: run test penalty.sh ... 10033s 05:48:27.021271019 O: test connect 10037s 05:48:31.341192875 O: penalty for authentication failure 10060s 05:48:54.236644800 O: penalty for no authentication 10064s 05:48:58.975155917 O: ok penalties 10065s 05:48:59.009062491 E: run test penalty-expire.sh ... 10073s 05:49:07.544099952 O: test connect 10077s 05:49:11.279370087 O: penalty expiry 10098s 05:49:32.006233195 O: ok penalties 10098s 05:49:32.060208471 O: set -e ; if test -z "" ; then \ 10098s 05:49:32.115503953 O: V="" ; \ 10098s 05:49:32.193565252 O: test "x" = "x" || \ 10098s 05:49:32.274753966 O: V=/tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 10098s 05:49:32.353105266 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 10098s 05:49:32.408307148 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 10098s 05:49:32.460706416 O: -d /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 10098s 05:49:32.510647771 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 10098s 05:49:32.565442651 O: -d /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 10098s 05:49:32.615061004 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 10098s 05:49:32.667420772 O: -d /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 10098s 05:49:32.718467532 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 10098s 05:49:32.768187786 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 10098s 05:49:32.817672239 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 10098s 05:49:32.868131597 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 10098s 05:49:32.917635050 O: -d /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 10098s 05:49:32.966976202 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 10099s 05:49:33.017412659 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 10099s 05:49:33.068056718 O: if test "x" = "xyes" ; then \ 10099s 05:49:33.119296779 O: $V /tmp/autopkgtest.lPYZjO/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 10099s 05:49:33.171352145 O: fi \ 10099s 05:49:33.220779598 O: fi 10337s 05:53:31.247429004 O: test_sshbuf: ...................................................................................................... 103 tests ok 10600s autopkgtest [05:57:54]: ERROR: timed out on command "su -s /bin/bash root -c set -e; exec /tmp/autopkgtest.lPYZjO/wrapper.sh --artifacts=/tmp/autopkgtest.lPYZjO/regress-artifacts --chdir=/tmp/autopkgtest.lPYZjO/build.BcV/src --env=AUTOPKGTEST_TESTBED_ARCH=riscv64 --env=AUTOPKGTEST_TEST_ARCH=riscv64 --env=DEB_BUILD_OPTIONS=parallel=2 --env=DEBIAN_FRONTEND=noninteractive --env=LANG=C.UTF-8 --unset-env=LANGUAGE --unset-env=LC_ADDRESS --unset-env=LC_ALL --unset-env=LC_COLLATE --unset-env=LC_CTYPE --unset-env=LC_IDENTIFICATION --unset-env=LC_MEASUREMENT --unset-env=LC_MESSAGES --unset-env=LC_MONETARY --unset-env=LC_NAME --unset-env=LC_NUMERIC --unset-env=LC_PAPER --unset-env=LC_TELEPHONE --unset-env=LC_TIME --script-pid-file=/tmp/autopkgtest_script_pid --source-profile --stderr=/tmp/autopkgtest.lPYZjO/regress-stderr --stdout=/tmp/autopkgtest.lPYZjO/regress-stdout --tmp=/tmp/autopkgtest.lPYZjO/autopkgtest_tmp --env=AUTOPKGTEST_NORMAL_USER=ubuntu --env=ADT_NORMAL_USER=ubuntu --env=ADT_TEST_TRIGGERS=dropbear/2025.88-1 --make-executable=/tmp/autopkgtest.lPYZjO/build.BcV/src/debian/tests/regress -- /tmp/autopkgtest.lPYZjO/build.BcV/src/debian/tests/regress" (kind: test) 10600s autopkgtest [05:57:54]: test regress: -----------------------] 10603s regress FAIL timed out 10603s autopkgtest [05:57:57]: test regress: - - - - - - - - - - results - - - - - - - - - - 10606s autopkgtest [05:58:00]: test ssh-gssapi: preparing testbed 10861s autopkgtest [06:02:15]: testbed dpkg architecture: riscv64 10862s autopkgtest [06:02:16]: testbed apt version: 3.0.0 10864s autopkgtest [06:02:18]: @@@@@@@@@@@@@@@@@@@@ test bed setup 10866s autopkgtest [06:02:20]: testbed release detected to be: questing 10870s autopkgtest [06:02:24]: updating testbed package index (apt update) 10873s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 10873s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 10873s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 10873s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 10874s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [580 kB] 10875s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [13.2 kB] 10875s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [54.9 kB] 10875s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [106 kB] 10875s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [445 kB] 10876s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [3196 B] 10880s Fetched 1313 kB in 5s (241 kB/s) 10897s Reading package lists... 10902s autopkgtest [06:02:56]: upgrading testbed (apt dist-upgrade and autopurge) 10904s Reading package lists... 10906s Building dependency tree... 10906s Reading state information... 10908s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 10909s Starting 2 pkgProblemResolver with broken count: 0 10909s Done 10913s Entering ResolveByKeep 10915s 10916s Calculating upgrade... 10917s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10919s Reading package lists... 10921s Building dependency tree... 10921s Reading state information... 10923s Starting pkgProblemResolver with broken count: 0 10923s Starting 2 pkgProblemResolver with broken count: 0 10923s Done 10926s Solving dependencies... 10927s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10949s Reading package lists... 10951s Building dependency tree... 10951s Reading state information... 10952s Starting pkgProblemResolver with broken count: 0 10953s Starting 2 pkgProblemResolver with broken count: 0 10953s Done 10956s The following NEW packages will be installed: 10956s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 10956s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 10956s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 10956s openssh-server-gssapi 10956s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 10956s Need to get 824 kB of archives. 10956s After this operation, 2588 kB of additional disk space will be used. 10956s Get:1 http://ftpmaster.internal/ubuntu questing/main riscv64 krb5-config all 2.7 [22.0 kB] 10956s Get:2 http://ftpmaster.internal/ubuntu questing/main riscv64 libgssrpc4t64 riscv64 1.21.3-4ubuntu2 [62.4 kB] 10957s Get:3 http://ftpmaster.internal/ubuntu questing/main riscv64 libkadm5clnt-mit12 riscv64 1.21.3-4ubuntu2 [41.4 kB] 10957s Get:4 http://ftpmaster.internal/ubuntu questing/main riscv64 libkdb5-10t64 riscv64 1.21.3-4ubuntu2 [42.9 kB] 10957s Get:5 http://ftpmaster.internal/ubuntu questing/main riscv64 libkadm5srv-mit12 riscv64 1.21.3-4ubuntu2 [55.2 kB] 10957s Get:6 http://ftpmaster.internal/ubuntu questing/universe riscv64 krb5-user riscv64 1.21.3-4ubuntu2 [116 kB] 10957s Get:7 http://ftpmaster.internal/ubuntu questing/main riscv64 libevent-2.1-7t64 riscv64 2.1.12-stable-10 [151 kB] 10957s Get:8 http://ftpmaster.internal/ubuntu questing/main riscv64 libverto1t64 riscv64 0.3.1-1.2ubuntu3 [11.3 kB] 10957s Get:9 http://ftpmaster.internal/ubuntu questing/main riscv64 libverto-libevent1t64 riscv64 0.3.1-1.2ubuntu3 [6188 B] 10957s Get:10 http://ftpmaster.internal/ubuntu questing/universe riscv64 krb5-kdc riscv64 1.21.3-4ubuntu2 [206 kB] 10957s Get:11 http://ftpmaster.internal/ubuntu questing/universe riscv64 krb5-admin-server riscv64 1.21.3-4ubuntu2 [98.8 kB] 10957s Get:12 http://ftpmaster.internal/ubuntu questing/universe riscv64 openssh-client-gssapi all 1:9.9p1-3ubuntu3.1 [5034 B] 10957s Get:13 http://ftpmaster.internal/ubuntu questing/universe riscv64 openssh-server-gssapi all 1:9.9p1-3ubuntu3.1 [5038 B] 10963s Preconfiguring packages ... 10968s Fetched 824 kB in 1s (732 kB/s) 10969s Selecting previously unselected package krb5-config. 10971s (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 ... 114088 files and directories currently installed.) 10971s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 10971s Unpacking krb5-config (2.7) ... 10971s Selecting previously unselected package libgssrpc4t64:riscv64. 10971s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_riscv64.deb ... 10971s Unpacking libgssrpc4t64:riscv64 (1.21.3-4ubuntu2) ... 10972s Selecting previously unselected package libkadm5clnt-mit12:riscv64. 10972s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_riscv64.deb ... 10972s Unpacking libkadm5clnt-mit12:riscv64 (1.21.3-4ubuntu2) ... 10973s Selecting previously unselected package libkdb5-10t64:riscv64. 10973s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_riscv64.deb ... 10973s Unpacking libkdb5-10t64:riscv64 (1.21.3-4ubuntu2) ... 10973s Selecting previously unselected package libkadm5srv-mit12:riscv64. 10974s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_riscv64.deb ... 10974s Unpacking libkadm5srv-mit12:riscv64 (1.21.3-4ubuntu2) ... 10974s Selecting previously unselected package krb5-user. 10974s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_riscv64.deb ... 10974s Unpacking krb5-user (1.21.3-4ubuntu2) ... 10975s Selecting previously unselected package libevent-2.1-7t64:riscv64. 10975s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_riscv64.deb ... 10975s Unpacking libevent-2.1-7t64:riscv64 (2.1.12-stable-10) ... 10976s Selecting previously unselected package libverto1t64:riscv64. 10976s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_riscv64.deb ... 10976s Unpacking libverto1t64:riscv64 (0.3.1-1.2ubuntu3) ... 10976s Selecting previously unselected package libverto-libevent1t64:riscv64. 10977s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_riscv64.deb ... 10977s Unpacking libverto-libevent1t64:riscv64 (0.3.1-1.2ubuntu3) ... 10977s Selecting previously unselected package krb5-kdc. 10977s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_riscv64.deb ... 10977s Unpacking krb5-kdc (1.21.3-4ubuntu2) ... 10978s Selecting previously unselected package krb5-admin-server. 10978s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_riscv64.deb ... 10978s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ... 10979s Selecting previously unselected package openssh-client-gssapi. 10979s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ... 10979s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ... 10979s Selecting previously unselected package openssh-server-gssapi. 10980s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ... 10980s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ... 10980s Setting up libevent-2.1-7t64:riscv64 (2.1.12-stable-10) ... 10980s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ... 10980s Setting up libgssrpc4t64:riscv64 (1.21.3-4ubuntu2) ... 10980s Setting up krb5-config (2.7) ... 10989s Setting up libkadm5clnt-mit12:riscv64 (1.21.3-4ubuntu2) ... 10989s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ... 10989s Setting up libkdb5-10t64:riscv64 (1.21.3-4ubuntu2) ... 10989s Setting up libkadm5srv-mit12:riscv64 (1.21.3-4ubuntu2) ... 10989s Setting up krb5-user (1.21.3-4ubuntu2) ... 10989s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 10989s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 10989s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 10989s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 10989s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 10989s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 10989s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 10990s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 10990s Setting up libverto1t64:riscv64 (0.3.1-1.2ubuntu3) ... 10990s Setting up libverto-libevent1t64:riscv64 (0.3.1-1.2ubuntu3) ... 10990s Setting up krb5-kdc (1.21.3-4ubuntu2) ... 11002s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 11009s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 11009s Setting up krb5-admin-server (1.21.3-4ubuntu2) ... 11022s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 11030s Processing triggers for libc-bin (2.41-6ubuntu1) ... 11030s Processing triggers for man-db (2.13.1-1) ... 11063s autopkgtest [06:05:37]: test ssh-gssapi: [----------------------- 11072s Generating public/private ed25519 key pair. 11072s Your identification has been saved in /root/.ssh/id_ed25519 11072s Your public key has been saved in /root/.ssh/id_ed25519.pub 11072s The key fingerprint is: 11072s SHA256:YJb2F4oQb+t3/5d0bN5MqKfXPCNLQ9o2rx+98Pi2bcI root@autopkgtest 11072s The key's randomart image is: 11072s +--[ED25519 256]--+ 11072s | . | 11072s | o . | 11072s | . O . | 11072s | * = . . | 11072s | o S . . o | 11072s | . . + ..*| 11072s | . . .. X.O=| 11072s | . . .+.E=%| 11072s | oOO@=| 11072s +----[SHA256]-----+ 11072s ## Setting up test environment 11073s ## Creating Kerberos realm EXAMPLE.FAKE 11073s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 11073s master key name 'K/M@EXAMPLE.FAKE' 11074s ## Creating principals 11075s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 11075s Principal "testuser2101@EXAMPLE.FAKE" created. 11075s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 11075s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 11075s ## Extracting service principal host/sshd-gssapi.example.fake 11075s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 11075s 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. 11075s 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. 11075s ## Adjusting /etc/krb5.conf 11075s ## TESTS 11075s 11075s ## TEST test_gssapi_login 11075s ## Configuring sshd for gssapi-with-mic authentication 11075s ## Restarting ssh 11077s ## Obtaining TGT 11078s Password for testuser2101@EXAMPLE.FAKE: 11078s Ticket cache: FILE:/tmp/krb5cc_0 11078s Default principal: testuser2101@EXAMPLE.FAKE 11078s 11078s Valid starting Expires Service principal 11078s 05/08/25 06:05:52 05/08/25 16:05:52 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 11078s renew until 05/09/25 06:05:51 11078s 11078s ## ssh'ing into localhost using gssapi-with-mic auth 11080s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 11085s Thu May 8 06:05:59 UTC 2025 11085s 11085s ## checking that we got a service ticket for ssh (host/) 11085s 05/08/25 06:05:54 05/08/25 16:05:52 host/sshd-gssapi.example.fake@ 11085s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 11085s 11085s ## Checking ssh logs to confirm gssapi-with-mic auth was used 11085s May 08 06:05:54 sshd-gssapi.example.fake sshd-session[2204]: Accepted gssapi-with-mic for testuser2101 from 127.0.0.1 port 49086 ssh2: testuser2101@EXAMPLE.FAKE 11085s ## PASS test_gssapi_login 11085s 11085s ## TEST test_gssapi_keyex_login 11085s ## Configuring sshd for gssapi-keyex authentication 11086s ## Restarting ssh 11087s ## Obtaining TGT 11088s Password for testuser2101@EXAMPLE.FAKE: 11088s Ticket cache: FILE:/tmp/krb5cc_0 11088s Default principal: testuser2101@EXAMPLE.FAKE 11088s 11088s Valid starting Expires Service principal 11088s 05/08/25 06:06:02 05/08/25 16:06:02 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 11088s renew until 05/09/25 06:06:01 11088s 11088s ## ssh'ing into localhost using gssapi-keyex auth 11090s Thu May 8 06:06:04 UTC 2025 11090s 11090s ## checking that we got a service ticket for ssh (host/) 11090s 05/08/25 06:06:02 05/08/25 16:06:02 host/sshd-gssapi.example.fake@ 11090s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 11090s 11090s ## Checking ssh logs to confirm gssapi-keyex auth was used 11090s May 08 06:06:03 sshd-gssapi.example.fake sshd-session[2259]: Accepted gssapi-keyex for testuser2101 from 127.0.0.1 port 40670 ssh2: testuser2101@EXAMPLE.FAKE 11090s ## PASS test_gssapi_keyex_login 11090s 11090s ## TEST test_gssapi_keyex_pubkey_fallback 11090s ## Configuring sshd for gssapi-keyex authentication 11090s ## Restarting ssh 11093s ## Obtaining TGT 11093s Password for testuser2101@EXAMPLE.FAKE: 11093s Ticket cache: FILE:/tmp/krb5cc_0 11093s Default principal: testuser2101@EXAMPLE.FAKE 11093s 11093s Valid starting Expires Service principal 11093s 05/08/25 06:06:07 05/08/25 16:06:07 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 11093s renew until 05/09/25 06:06:07 11093s 11093s ## ssh'ing into localhost using gssapi-keyex auth 11100s Thu May 8 06:06:13 UTC 2025 11100s 11100s ## checking that we got a service ticket for ssh (host/) 11100s 05/08/25 06:06:08 05/08/25 16:06:07 host/sshd-gssapi.example.fake@ 11100s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 11100s 11100s ## Checking ssh logs to confirm publickey auth was used 11100s May 08 06:06:09 sshd-gssapi.example.fake sshd-session[2301]: Accepted publickey for testuser2101-2 from 127.0.0.1 port 41808 ssh2: ED25519 SHA256:YJb2F4oQb+t3/5d0bN5MqKfXPCNLQ9o2rx+98Pi2bcI 11100s ## PASS test_gssapi_keyex_pubkey_fallback 11100s 11100s ## ALL TESTS PASSED 11100s ## Cleaning up 11102s autopkgtest [06:06:16]: test ssh-gssapi: -----------------------] 11105s ssh-gssapi PASS 11105s autopkgtest [06:06:19]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 11107s autopkgtest [06:06:21]: test socket-activation: preparing testbed 11336s autopkgtest [06:10:10]: testbed dpkg architecture: riscv64 11337s autopkgtest [06:10:11]: testbed apt version: 3.0.0 11339s autopkgtest [06:10:13]: @@@@@@@@@@@@@@@@@@@@ test bed setup 11341s autopkgtest [06:10:15]: testbed release detected to be: questing 11345s autopkgtest [06:10:19]: updating testbed package index (apt update) 11348s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 11348s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 11348s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 11348s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 11349s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [13.2 kB] 11349s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [580 kB] 11350s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [54.9 kB] 11350s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [106 kB] 11350s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [445 kB] 11351s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [3196 B] 11354s Fetched 1313 kB in 5s (258 kB/s) 11372s Reading package lists... 11377s autopkgtest [06:10:51]: upgrading testbed (apt dist-upgrade and autopurge) 11379s Reading package lists... 11381s Building dependency tree... 11381s Reading state information... 11383s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 11384s Starting 2 pkgProblemResolver with broken count: 0 11384s Done 11388s Entering ResolveByKeep 11390s 11390s Calculating upgrade... 11391s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 11393s Reading package lists... 11395s Building dependency tree... 11395s Reading state information... 11398s Starting pkgProblemResolver with broken count: 0 11398s Starting 2 pkgProblemResolver with broken count: 0 11398s Done 11401s Solving dependencies... 11403s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 11425s Reading package lists... 11427s Building dependency tree... 11427s Reading state information... 11428s Starting pkgProblemResolver with broken count: 0 11428s Starting 2 pkgProblemResolver with broken count: 0 11428s Done 11432s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 11450s autopkgtest [06:12:04]: test socket-activation: [----------------------- 11456s Generating public/private ed25519 key pair. 11456s Your identification has been saved in /home/testuser1661/.ssh/id_ed25519 11456s Your public key has been saved in /home/testuser1661/.ssh/id_ed25519.pub 11456s The key fingerprint is: 11456s SHA256:mNrB39N+5fd/Y5eLsERMV0dN2unNLi1FRJwrs0MPWZs testuser1661@autopkgtest 11456s The key's randomart image is: 11456s +--[ED25519 256]--+ 11456s | *O| 11456s | .*=| 11456s | . .+.*| 11456s | . o o .*.E.| 11456s | = S o. *.+| 11456s | o o ... o =.| 11456s | . . . oo. +o+| 11456s | .oo o*=| 11456s | ..o..@| 11456s +----[SHA256]-----+ 11457s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 11457s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 11472s Disabling 'ssh.service', but its triggering units are still active: 11472s ssh.socket 11472s Stopping 'ssh.service', but its triggering units are still active: 11472s ssh.socket 11480s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 11486s Thu May 8 06:12:40 UTC 2025 11487s autopkgtest [06:12:41]: test socket-activation: -----------------------] 11490s autopkgtest [06:12:44]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 11490s socket-activation PASS 11493s autopkgtest [06:12:47]: test xinetd: preparing testbed 11497s Reading package lists... 11499s Building dependency tree... 11499s Reading state information... 11502s Starting pkgProblemResolver with broken count: 0 11502s Starting 2 pkgProblemResolver with broken count: 0 11502s Done 11505s The following NEW packages will be installed: 11505s xinetd 11505s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 11505s Need to get 118 kB of archives. 11505s After this operation, 296 kB of additional disk space will be used. 11505s Get:1 http://ftpmaster.internal/ubuntu questing/universe riscv64 xinetd riscv64 1:2.3.15.4-5 [118 kB] 11512s Fetched 118 kB in 1s (170 kB/s) 11513s Selecting previously unselected package xinetd. 11514s (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 ... 114088 files and directories currently installed.) 11514s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_riscv64.deb ... 11514s Unpacking xinetd (1:2.3.15.4-5) ... 11515s Setting up xinetd (1:2.3.15.4-5) ... 11525s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 11531s Processing triggers for man-db (2.13.1-1) ... 11546s autopkgtest [06:13:40]: test xinetd: [----------------------- 11552s Generating public/private ed25519 key pair. 11552s Your identification has been saved in /home/testuser2206/.ssh/id_ed25519 11552s Your public key has been saved in /home/testuser2206/.ssh/id_ed25519.pub 11552s The key fingerprint is: 11552s SHA256:iG9GRV+e5j780fGgAMYiYvit8hw8zkcflRMLCTRavb8 testuser2206@autopkgtest 11552s The key's randomart image is: 11552s +--[ED25519 256]--+ 11552s | .=o .. . | 11552s | . o .+o.. o . | 11552s |. + . .o=+. + | 11552s | o o o.==. o | 11552s | . o ooS.. . .. | 11552s | . ..o. . + . oo| 11552s |. =. .+. . = . o| 11552s | * o.o. E o . | 11552s | =. . | 11552s +----[SHA256]-----+ 11557s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 11557s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 11571s Disabling 'ssh.service', but its triggering units are still active: 11571s ssh.socket 11572s Stopping 'ssh.service', but its triggering units are still active: 11572s ssh.socket 11575s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 11581s Thu May 8 06:14:15 UTC 2025 11583s autopkgtest [06:14:17]: test xinetd: -----------------------] 11585s xinetd PASS 11585s autopkgtest [06:14:19]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 11587s autopkgtest [06:14:21]: test systemd-socket-activation: preparing testbed 11860s autopkgtest [06:18:54]: testbed dpkg architecture: riscv64 11861s autopkgtest [06:18:55]: testbed apt version: 3.0.0 11864s autopkgtest [06:18:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 11866s autopkgtest [06:19:00]: testbed release detected to be: questing 11871s autopkgtest [06:19:05]: updating testbed package index (apt update) 11875s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 11875s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 11875s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 11875s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 11876s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [580 kB] 11877s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [54.9 kB] 11877s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [13.2 kB] 11877s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main riscv64 Packages [106 kB] 11877s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe riscv64 Packages [445 kB] 11877s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse riscv64 Packages [3196 B] 11882s Fetched 1313 kB in 5s (249 kB/s) 11903s Reading package lists... 11909s autopkgtest [06:19:43]: upgrading testbed (apt dist-upgrade and autopurge) 11911s Reading package lists... 11914s Building dependency tree... 11914s Reading state information... 11917s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 11917s Starting 2 pkgProblemResolver with broken count: 0 11917s Done 11923s Entering ResolveByKeep 11925s 11926s Calculating upgrade... 11928s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 11931s Reading package lists... 11933s Building dependency tree... 11933s Reading state information... 11937s Starting pkgProblemResolver with broken count: 0 11937s Starting 2 pkgProblemResolver with broken count: 0 11937s Done 11940s Solving dependencies... 11941s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 11967s Reading package lists... 11970s Building dependency tree... 11970s Reading state information... 11973s Starting pkgProblemResolver with broken count: 0 11974s Starting 2 pkgProblemResolver with broken count: 0 11974s Done 11978s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 12002s autopkgtest [06:21:16]: test systemd-socket-activation: [----------------------- 12007s Stopping ssh.service... 12007s Checking that ssh.socket is active and listening... 12008s Checking that ssh.service is inactive/dead... 12009s Checking that a connection attempt activates ssh.service... 12013s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 12021s Checking that sshd can be re-executed... 12024s Checking sshd can run in debug mode... 12031s debug1: PAM: establishing credentials 12031s debug1: permanently_set_uid: 0/0 12031s debug3: Copy environment: XDG_SESSION_ID=7 12031s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 12031s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 12031s debug3: Copy environment: XDG_SESSION_TYPE=tty 12031s debug3: Copy environment: XDG_SESSION_CLASS=user 12031s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 12031s debug3: Copy environment: http_proxy=http://squid.internal:3128/ 12031s debug3: Copy environment: https_proxy=http://squid.internal:3128/ 12031s debug3: Copy environment: no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net 12031s debug3: Copy environment: TERM=linux 12031s debug3: Copy environment: LANG=C.UTF-8 12031s Environment: 12031s LANG=C.UTF-8 12031s USER=root 12031s LOGNAME=root 12031s HOME=/root 12031s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 12031s SHELL=/bin/bash 12031s XDG_SESSION_ID=7 12031s XDG_RUNTIME_DIR=/run/user/0 12031s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 12031s XDG_SESSION_TYPE=tty 12031s XDG_SESSION_CLASS=user 12031s http_proxy=http://squid.internal:3128/ 12031s https_proxy=http://squid.internal:3128/ 12031s no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchp 12031s TERM=linux 12031s SSH_CLIENT=::1 45838 22 12031s SSH_CONNECTION=::1 45838 ::1 22 12031s Done. 12032s autopkgtest [06:21:46]: test systemd-socket-activation: -----------------------] 12035s autopkgtest [06:21:49]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 12035s systemd-socket-activation PASS 12038s autopkgtest [06:21:52]: test sshd-socket-generator: preparing testbed 12044s Reading package lists... 12047s Building dependency tree... 12047s Reading state information... 12048s Starting pkgProblemResolver with broken count: 0 12049s Starting 2 pkgProblemResolver with broken count: 0 12049s Done 12052s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 12061s autopkgtest [06:22:15]: test sshd-socket-generator: [----------------------- 12064s test_default...PASS 12064s test_custom_port...PASS 12065s test_default_and_custom_port...PASS 12065s test_mutiple_custom_ports...PASS 12065s test_custom_listenaddress...PASS 12066s test_custom_listenaddress_and_port...PASS 12066s test_custom_ipv6_listenaddress...PASS 12066s test_custom_family_ipv4...PASS 12067s test_custom_family_ipv6...PASS 12067s test_custom_port_and_family_ipv4...PASS 12067s test_custom_port_and_family_ipv6...PASS 12068s test_match_on_port...PASS 12069s autopkgtest [06:22:23]: test sshd-socket-generator: -----------------------] 12072s sshd-socket-generator PASS 12072s autopkgtest [06:22:26]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 12075s autopkgtest [06:22:29]: @@@@@@@@@@@@@@@@@@@@ summary 12075s regress FAIL timed out 12075s ssh-gssapi PASS 12075s socket-activation PASS 12075s xinetd PASS 12075s systemd-socket-activation PASS 12075s sshd-socket-generator PASS 12094s nova [W] Using flock in prodstack6-riscv64 12094s Creating nova instance adt-questing-riscv64-openssh-20250508-030111-juju-7f2275-prod-proposed-migration-environment-2-e8bca6d1-5ded-4f5c-be32-9f990a6fb41b from image adt/ubuntu-questing-riscv64-server-20250508.img (UUID 11d6606c-7184-49cb-9952-bc7407a83324)... 12094s nova [W] Timed out waiting for aae00a9b-c52f-43d3-841b-f35687dde8f2 to get deleted. 12094s nova [W] Using flock in prodstack6-riscv64 12094s Creating nova instance adt-questing-riscv64-openssh-20250508-030111-juju-7f2275-prod-proposed-migration-environment-2-e8bca6d1-5ded-4f5c-be32-9f990a6fb41b from image adt/ubuntu-questing-riscv64-server-20250508.img (UUID 11d6606c-7184-49cb-9952-bc7407a83324)... 12094s nova [W] Timed out waiting for b48f0cc4-8c94-402c-a83c-78f9d51e4830 to get deleted. 12094s nova [W] Using flock in prodstack6-riscv64 12094s Creating nova instance adt-questing-riscv64-openssh-20250508-030111-juju-7f2275-prod-proposed-migration-environment-2-e8bca6d1-5ded-4f5c-be32-9f990a6fb41b from image adt/ubuntu-questing-riscv64-server-20250508.img (UUID 11d6606c-7184-49cb-9952-bc7407a83324)... 12094s nova [W] Timed out waiting for 105fc944-52bb-4cc1-9910-d8061368fa3f to get deleted. 12094s nova [W] Using flock in prodstack6-riscv64 12094s Creating nova instance adt-questing-riscv64-openssh-20250508-030111-juju-7f2275-prod-proposed-migration-environment-2-e8bca6d1-5ded-4f5c-be32-9f990a6fb41b from image adt/ubuntu-questing-riscv64-server-20250508.img (UUID 11d6606c-7184-49cb-9952-bc7407a83324)... 12094s nova [W] Timed out waiting for 900ecd31-6e1a-48d3-803c-3b64a566771c to get deleted.