0s autopkgtest [15:38:49]: starting date and time: 2024-07-12 15:38:49+0000 0s autopkgtest [15:38:49]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [15:38:49]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.oz3z6x0_/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade knot-resolver --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.39-3.1ubuntu3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@lcy02-13.secgroup --name adt-oracular-amd64-knot-resolver-20240712-153849-juju-7f2275-prod-proposed-migration-environment-2-520d5585-67f8-416f-afea-e164eeefeb4c --image adt/ubuntu-oracular-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 129s autopkgtest [15:40:58]: testbed dpkg architecture: amd64 129s autopkgtest [15:40:58]: testbed apt version: 2.9.6 129s autopkgtest [15:40:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 129s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 130s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.3 kB] 130s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 130s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [344 kB] 130s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B] 130s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 Packages [97.7 kB] 130s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main i386 Packages [70.7 kB] 130s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 c-n-f Metadata [2104 B] 130s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted amd64 Packages [34.9 kB] 130s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/restricted i386 Packages [6244 B] 130s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/restricted amd64 c-n-f Metadata [368 B] 130s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/universe i386 Packages [244 kB] 130s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/universe amd64 Packages [387 kB] 130s Get:14 http://ftpmaster.internal/ubuntu oracular-proposed/universe amd64 c-n-f Metadata [9228 B] 130s Get:15 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse i386 Packages [3052 B] 130s Get:16 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse amd64 Packages [7644 B] 130s Get:17 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse amd64 c-n-f Metadata [276 B] 131s Fetched 1387 kB in 0s (5979 kB/s) 131s Reading package lists... 144s Reading package lists... 144s Building dependency tree... 144s Reading state information... 145s Calculating upgrade... 145s The following packages will be upgraded: 145s binutils binutils-common binutils-x86-64-linux-gnu gir1.2-glib-2.0 145s libbinutils libc-bin libc-dev-bin libc-devtools libc6 libc6-dev 145s libctf-nobfd0 libctf0 libglib2.0-0t64 libglib2.0-data libgprofng0 145s libnghttp2-14 libnss3 libsframe1 libssl3t64 locales openssh-client 145s openssh-server openssh-sftp-server openssl 145s 24 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 145s Need to get 22.7 MB of archives. 145s After this operation, 428 kB of additional disk space will be used. 145s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 libc-devtools amd64 2.39-3.1ubuntu3 [29.3 kB] 145s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 libc6-dev amd64 2.39-3.1ubuntu3 [2120 kB] 145s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 libc-dev-bin amd64 2.39-3.1ubuntu3 [20.4 kB] 145s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 libc6 amd64 2.39-3.1ubuntu3 [3262 kB] 145s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 libc-bin amd64 2.39-3.1ubuntu3 [680 kB] 145s Get:6 http://ftpmaster.internal/ubuntu oracular/main amd64 libssl3t64 amd64 3.2.2-1ubuntu1 [2138 kB] 145s Get:7 http://ftpmaster.internal/ubuntu oracular/main amd64 openssh-sftp-server amd64 1:9.6p1-3ubuntu17 [37.1 kB] 145s Get:8 http://ftpmaster.internal/ubuntu oracular/main amd64 openssh-server amd64 1:9.6p1-3ubuntu17 [509 kB] 145s Get:9 http://ftpmaster.internal/ubuntu oracular/main amd64 openssh-client amd64 1:9.6p1-3ubuntu17 [905 kB] 145s Get:10 http://ftpmaster.internal/ubuntu oracular/main amd64 gir1.2-glib-2.0 amd64 2.80.4-1ubuntu1 [182 kB] 145s Get:11 http://ftpmaster.internal/ubuntu oracular/main amd64 libglib2.0-0t64 amd64 2.80.4-1ubuntu1 [1545 kB] 145s Get:12 http://ftpmaster.internal/ubuntu oracular/main amd64 libglib2.0-data all 2.80.4-1ubuntu1 [49.3 kB] 145s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 locales all 2.39-3.1ubuntu3 [4220 kB] 146s Get:14 http://ftpmaster.internal/ubuntu oracular/main amd64 openssl amd64 3.2.2-1ubuntu1 [1122 kB] 146s Get:15 http://ftpmaster.internal/ubuntu oracular/main amd64 libnghttp2-14 amd64 1.62.1-2 [75.1 kB] 146s Get:16 http://ftpmaster.internal/ubuntu oracular/main amd64 libgprofng0 amd64 2.42.50.20240710-1ubuntu1 [860 kB] 146s Get:17 http://ftpmaster.internal/ubuntu oracular/main amd64 libctf0 amd64 2.42.50.20240710-1ubuntu1 [95.6 kB] 146s Get:18 http://ftpmaster.internal/ubuntu oracular/main amd64 libctf-nobfd0 amd64 2.42.50.20240710-1ubuntu1 [97.6 kB] 146s Get:19 http://ftpmaster.internal/ubuntu oracular/main amd64 binutils-x86-64-linux-gnu amd64 2.42.50.20240710-1ubuntu1 [2477 kB] 146s Get:20 http://ftpmaster.internal/ubuntu oracular/main amd64 libbinutils amd64 2.42.50.20240710-1ubuntu1 [574 kB] 146s Get:21 http://ftpmaster.internal/ubuntu oracular/main amd64 binutils amd64 2.42.50.20240710-1ubuntu1 [18.1 kB] 146s Get:22 http://ftpmaster.internal/ubuntu oracular/main amd64 binutils-common amd64 2.42.50.20240710-1ubuntu1 [243 kB] 146s Get:23 http://ftpmaster.internal/ubuntu oracular/main amd64 libsframe1 amd64 2.42.50.20240710-1ubuntu1 [14.4 kB] 146s Get:24 http://ftpmaster.internal/ubuntu oracular/main amd64 libnss3 amd64 2:3.102-1 [1399 kB] 146s Preconfiguring packages ... 146s Fetched 22.7 MB in 1s (38.1 MB/s) 146s (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 ... 74487 files and directories currently installed.) 146s Preparing to unpack .../libc-devtools_2.39-3.1ubuntu3_amd64.deb ... 146s Unpacking libc-devtools (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 146s Preparing to unpack .../libc6-dev_2.39-3.1ubuntu3_amd64.deb ... 146s Unpacking libc6-dev:amd64 (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 147s Preparing to unpack .../libc-dev-bin_2.39-3.1ubuntu3_amd64.deb ... 147s Unpacking libc-dev-bin (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 147s Preparing to unpack .../libc6_2.39-3.1ubuntu3_amd64.deb ... 147s Unpacking libc6:amd64 (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 147s Setting up libc6:amd64 (2.39-3.1ubuntu3) ... 147s Error: Could not restart systemd, systemd binary not working 147s (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 ... 74487 files and directories currently installed.) 147s Preparing to unpack .../libc-bin_2.39-3.1ubuntu3_amd64.deb ... 147s Unpacking libc-bin (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 147s Setting up libc-bin (2.39-3.1ubuntu3) ... 147s (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 ... 74487 files and directories currently installed.) 148s Preparing to unpack .../libssl3t64_3.2.2-1ubuntu1_amd64.deb ... 148s Unpacking libssl3t64:amd64 (3.2.2-1ubuntu1) over (3.2.1-3ubuntu1) ... 148s Setting up libssl3t64:amd64 (3.2.2-1ubuntu1) ... 148s (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 ... 74487 files and directories currently installed.) 148s Preparing to unpack .../00-openssh-sftp-server_1%3a9.6p1-3ubuntu17_amd64.deb ... 148s Unpacking openssh-sftp-server (1:9.6p1-3ubuntu17) over (1:9.6p1-3ubuntu15) ... 148s Preparing to unpack .../01-openssh-server_1%3a9.6p1-3ubuntu17_amd64.deb ... 148s Unpacking openssh-server (1:9.6p1-3ubuntu17) over (1:9.6p1-3ubuntu15) ... 148s Preparing to unpack .../02-openssh-client_1%3a9.6p1-3ubuntu17_amd64.deb ... 148s Unpacking openssh-client (1:9.6p1-3ubuntu17) over (1:9.6p1-3ubuntu15) ... 148s Preparing to unpack .../03-gir1.2-glib-2.0_2.80.4-1ubuntu1_amd64.deb ... 148s Unpacking gir1.2-glib-2.0:amd64 (2.80.4-1ubuntu1) over (2.80.3-1ubuntu1) ... 148s Preparing to unpack .../04-libglib2.0-0t64_2.80.4-1ubuntu1_amd64.deb ... 148s Unpacking libglib2.0-0t64:amd64 (2.80.4-1ubuntu1) over (2.80.3-1ubuntu1) ... 148s Preparing to unpack .../05-libglib2.0-data_2.80.4-1ubuntu1_all.deb ... 148s Unpacking libglib2.0-data (2.80.4-1ubuntu1) over (2.80.3-1ubuntu1) ... 148s Preparing to unpack .../06-locales_2.39-3.1ubuntu3_all.deb ... 148s Unpacking locales (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 148s Preparing to unpack .../07-openssl_3.2.2-1ubuntu1_amd64.deb ... 148s Unpacking openssl (3.2.2-1ubuntu1) over (3.2.1-3ubuntu1) ... 148s Preparing to unpack .../08-libnghttp2-14_1.62.1-2_amd64.deb ... 148s Unpacking libnghttp2-14:amd64 (1.62.1-2) over (1.62.1-1) ... 148s Preparing to unpack .../09-libgprofng0_2.42.50.20240710-1ubuntu1_amd64.deb ... 148s Unpacking libgprofng0:amd64 (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../10-libctf0_2.42.50.20240710-1ubuntu1_amd64.deb ... 149s Unpacking libctf0:amd64 (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../11-libctf-nobfd0_2.42.50.20240710-1ubuntu1_amd64.deb ... 149s Unpacking libctf-nobfd0:amd64 (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../12-binutils-x86-64-linux-gnu_2.42.50.20240710-1ubuntu1_amd64.deb ... 149s Unpacking binutils-x86-64-linux-gnu (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../13-libbinutils_2.42.50.20240710-1ubuntu1_amd64.deb ... 149s Unpacking libbinutils:amd64 (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../14-binutils_2.42.50.20240710-1ubuntu1_amd64.deb ... 149s Unpacking binutils (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../15-binutils-common_2.42.50.20240710-1ubuntu1_amd64.deb ... 149s Unpacking binutils-common:amd64 (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../16-libsframe1_2.42.50.20240710-1ubuntu1_amd64.deb ... 149s Unpacking libsframe1:amd64 (2.42.50.20240710-1ubuntu1) over (2.42.50.20240625-1ubuntu1) ... 149s Preparing to unpack .../17-libnss3_2%3a3.102-1_amd64.deb ... 149s Unpacking libnss3:amd64 (2:3.102-1) over (2:3.101-1) ... 149s Setting up openssh-client (1:9.6p1-3ubuntu17) ... 149s Setting up binutils-common:amd64 (2.42.50.20240710-1ubuntu1) ... 149s Setting up libnghttp2-14:amd64 (1.62.1-2) ... 149s Setting up libctf-nobfd0:amd64 (2.42.50.20240710-1ubuntu1) ... 149s Setting up libnss3:amd64 (2:3.102-1) ... 149s Setting up locales (2.39-3.1ubuntu3) ... 150s Generating locales (this might take a while)... 151s en_US.UTF-8... done 151s Generation complete. 151s Setting up libsframe1:amd64 (2.42.50.20240710-1ubuntu1) ... 151s Setting up libglib2.0-0t64:amd64 (2.80.4-1ubuntu1) ... 151s No schema files found: doing nothing. 151s Setting up libglib2.0-data (2.80.4-1ubuntu1) ... 151s Setting up gir1.2-glib-2.0:amd64 (2.80.4-1ubuntu1) ... 151s Setting up libbinutils:amd64 (2.42.50.20240710-1ubuntu1) ... 151s Setting up libc-dev-bin (2.39-3.1ubuntu3) ... 151s Setting up openssl (3.2.2-1ubuntu1) ... 151s Installing new version of config file /etc/ssl/openssl.cnf ... 151s Setting up libc-devtools (2.39-3.1ubuntu3) ... 151s Setting up libctf0:amd64 (2.42.50.20240710-1ubuntu1) ... 151s Setting up openssh-sftp-server (1:9.6p1-3ubuntu17) ... 151s Setting up openssh-server (1:9.6p1-3ubuntu17) ... 151s Installing new version of config file /etc/pam.d/sshd ... 153s Setting up libgprofng0:amd64 (2.42.50.20240710-1ubuntu1) ... 153s Setting up libc6-dev:amd64 (2.39-3.1ubuntu3) ... 153s Setting up binutils-x86-64-linux-gnu (2.42.50.20240710-1ubuntu1) ... 153s Setting up binutils (2.42.50.20240710-1ubuntu1) ... 153s Processing triggers for libc-bin (2.39-3.1ubuntu3) ... 153s Processing triggers for ufw (0.36.2-6) ... 153s Processing triggers for systemd (256-1ubuntu1) ... 153s Processing triggers for man-db (2.12.1-2) ... 155s Reading package lists... 155s Building dependency tree... 155s Reading state information... 156s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 156s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 156s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 156s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 156s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 157s Reading package lists... 157s Reading package lists... 158s Building dependency tree... 158s Reading state information... 158s Calculating upgrade... 159s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 159s Reading package lists... 159s Building dependency tree... 159s Reading state information... 160s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 160s autopkgtest [15:41:29]: rebooting testbed after setup commands that affected boot 173s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 197s autopkgtest [15:42:06]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 00:40:06 UTC 2024 217s autopkgtest [15:42:26]: @@@@@@@@@@@@@@@@@@@@ apt-source knot-resolver 220s Get:1 http://ftpmaster.internal/ubuntu oracular/universe knot-resolver 5.7.3-1 (dsc) [3184 B] 220s Get:2 http://ftpmaster.internal/ubuntu oracular/universe knot-resolver 5.7.3-1 (tar) [1931 kB] 220s Get:3 http://ftpmaster.internal/ubuntu oracular/universe knot-resolver 5.7.3-1 (asc) [833 B] 220s Get:4 http://ftpmaster.internal/ubuntu oracular/universe knot-resolver 5.7.3-1 (diff) [401 kB] 220s gpgv: Signature made Tue Jun 4 18:13:51 2024 UTC 220s gpgv: using RSA key 23EBCF7E8FC47556787100DFA4254072E373042C 220s gpgv: Can't check signature: No public key 220s dpkg-source: warning: cannot verify inline signature for ./knot-resolver_5.7.3-1.dsc: no acceptable signature found 221s autopkgtest [15:42:30]: testing package knot-resolver version 5.7.3-1 224s autopkgtest [15:42:33]: build not needed 246s autopkgtest [15:42:55]: test roundtrip: preparing testbed 254s Reading package lists... 254s Building dependency tree... 254s Reading state information... 254s Starting pkgProblemResolver with broken count: 0 255s Starting 2 pkgProblemResolver with broken count: 0 255s Done 255s The following additional packages will be installed: 255s dns-root-data gnutls-bin knot-dnsutils knot-resolver libdnssec9t64 255s libevent-2.1-7t64 libfstrm0 libgnutls-dane0t64 libjemalloc2 libknot14t64 255s libluajit-5.1-2 libluajit-5.1-common libunbound8 libxdp1 libzscanner4t64 255s lua-sec lua-socket socat 255s Recommended packages: 255s knot-resolver-module-http lua-basexx lua-cqueues 255s The following NEW packages will be installed: 255s autopkgtest-satdep dns-root-data gnutls-bin knot-dnsutils knot-resolver 255s libdnssec9t64 libevent-2.1-7t64 libfstrm0 libgnutls-dane0t64 libjemalloc2 255s libknot14t64 libluajit-5.1-2 libluajit-5.1-common libunbound8 libxdp1 255s libzscanner4t64 lua-sec lua-socket socat 255s 0 upgraded, 19 newly installed, 0 to remove and 0 not upgraded. 255s Need to get 2985 kB/2986 kB of archives. 255s After this operation, 10.9 MB of additional disk space will be used. 255s Get:1 /tmp/autopkgtest.q1ICTc/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [732 B] 255s Get:2 http://ftpmaster.internal/ubuntu oracular/main amd64 dns-root-data all 2024041801 [4330 B] 255s Get:3 http://ftpmaster.internal/ubuntu oracular/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB] 255s Get:4 http://ftpmaster.internal/ubuntu oracular/main amd64 libunbound8 amd64 1.19.2-1ubuntu3 [440 kB] 255s Get:5 http://ftpmaster.internal/ubuntu oracular/main amd64 libgnutls-dane0t64 amd64 3.8.6-2ubuntu1 [24.0 kB] 255s Get:6 http://ftpmaster.internal/ubuntu oracular/universe amd64 gnutls-bin amd64 3.8.6-2ubuntu1 [270 kB] 255s Get:7 http://ftpmaster.internal/ubuntu oracular/universe amd64 libdnssec9t64 amd64 3.3.7-1 [30.2 kB] 255s Get:8 http://ftpmaster.internal/ubuntu oracular/main amd64 libxdp1 amd64 1.4.2-1ubuntu4 [62.5 kB] 255s Get:9 http://ftpmaster.internal/ubuntu oracular/universe amd64 libknot14t64 amd64 3.3.7-1 [204 kB] 255s Get:10 http://ftpmaster.internal/ubuntu oracular/universe amd64 libzscanner4t64 amd64 3.3.7-1 [140 kB] 255s Get:11 http://ftpmaster.internal/ubuntu oracular/universe amd64 libfstrm0 amd64 0.6.1-1build4 [21.0 kB] 255s Get:12 http://ftpmaster.internal/ubuntu oracular/universe amd64 knot-dnsutils amd64 3.3.7-1 [224 kB] 255s Get:13 http://ftpmaster.internal/ubuntu oracular/universe amd64 lua-socket amd64 3.1.0-1 [87.7 kB] 255s Get:14 http://ftpmaster.internal/ubuntu oracular/universe amd64 lua-sec amd64 1.3.2-2 [40.7 kB] 255s Get:15 http://ftpmaster.internal/ubuntu oracular/universe amd64 libjemalloc2 amd64 5.3.0-2build1 [256 kB] 255s Get:16 http://ftpmaster.internal/ubuntu oracular/universe amd64 libluajit-5.1-common all 2.1.0+openresty20240626-1 [48.3 kB] 255s Get:17 http://ftpmaster.internal/ubuntu oracular/universe amd64 libluajit-5.1-2 amd64 2.1.0+openresty20240626-1 [278 kB] 255s Get:18 http://ftpmaster.internal/ubuntu oracular/universe amd64 knot-resolver amd64 5.7.3-1 [337 kB] 255s Get:19 http://ftpmaster.internal/ubuntu oracular/main amd64 socat amd64 1.8.0.0-4build3 [374 kB] 256s Preconfiguring packages ... 256s Fetched 2985 kB in 0s (13.8 MB/s) 256s Selecting previously unselected package dns-root-data. 256s (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 ... 74486 files and directories currently installed.) 256s Preparing to unpack .../00-dns-root-data_2024041801_all.deb ... 256s Unpacking dns-root-data (2024041801) ... 256s Selecting previously unselected package libevent-2.1-7t64:amd64. 256s Preparing to unpack .../01-libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ... 256s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 256s Selecting previously unselected package libunbound8:amd64. 256s Preparing to unpack .../02-libunbound8_1.19.2-1ubuntu3_amd64.deb ... 256s Unpacking libunbound8:amd64 (1.19.2-1ubuntu3) ... 256s Selecting previously unselected package libgnutls-dane0t64:amd64. 256s Preparing to unpack .../03-libgnutls-dane0t64_3.8.6-2ubuntu1_amd64.deb ... 256s Unpacking libgnutls-dane0t64:amd64 (3.8.6-2ubuntu1) ... 256s Selecting previously unselected package gnutls-bin. 256s Preparing to unpack .../04-gnutls-bin_3.8.6-2ubuntu1_amd64.deb ... 256s Unpacking gnutls-bin (3.8.6-2ubuntu1) ... 256s Selecting previously unselected package libdnssec9t64. 256s Preparing to unpack .../05-libdnssec9t64_3.3.7-1_amd64.deb ... 256s Unpacking libdnssec9t64 (3.3.7-1) ... 256s Selecting previously unselected package libxdp1:amd64. 256s Preparing to unpack .../06-libxdp1_1.4.2-1ubuntu4_amd64.deb ... 256s Unpacking libxdp1:amd64 (1.4.2-1ubuntu4) ... 256s Selecting previously unselected package libknot14t64. 256s Preparing to unpack .../07-libknot14t64_3.3.7-1_amd64.deb ... 256s Unpacking libknot14t64 (3.3.7-1) ... 256s Selecting previously unselected package libzscanner4t64. 256s Preparing to unpack .../08-libzscanner4t64_3.3.7-1_amd64.deb ... 256s Unpacking libzscanner4t64 (3.3.7-1) ... 256s Selecting previously unselected package libfstrm0:amd64. 256s Preparing to unpack .../09-libfstrm0_0.6.1-1build4_amd64.deb ... 256s Unpacking libfstrm0:amd64 (0.6.1-1build4) ... 256s Selecting previously unselected package knot-dnsutils. 256s Preparing to unpack .../10-knot-dnsutils_3.3.7-1_amd64.deb ... 256s Unpacking knot-dnsutils (3.3.7-1) ... 256s Selecting previously unselected package lua-socket:amd64. 256s Preparing to unpack .../11-lua-socket_3.1.0-1_amd64.deb ... 256s Unpacking lua-socket:amd64 (3.1.0-1) ... 256s Selecting previously unselected package lua-sec:amd64. 256s Preparing to unpack .../12-lua-sec_1.3.2-2_amd64.deb ... 256s Unpacking lua-sec:amd64 (1.3.2-2) ... 256s Selecting previously unselected package libjemalloc2:amd64. 256s Preparing to unpack .../13-libjemalloc2_5.3.0-2build1_amd64.deb ... 256s Unpacking libjemalloc2:amd64 (5.3.0-2build1) ... 256s Selecting previously unselected package libluajit-5.1-common. 256s Preparing to unpack .../14-libluajit-5.1-common_2.1.0+openresty20240626-1_all.deb ... 256s Unpacking libluajit-5.1-common (2.1.0+openresty20240626-1) ... 257s Selecting previously unselected package libluajit-5.1-2:amd64. 257s Preparing to unpack .../15-libluajit-5.1-2_2.1.0+openresty20240626-1_amd64.deb ... 257s Unpacking libluajit-5.1-2:amd64 (2.1.0+openresty20240626-1) ... 257s Selecting previously unselected package knot-resolver. 257s Preparing to unpack .../16-knot-resolver_5.7.3-1_amd64.deb ... 257s Unpacking knot-resolver (5.7.3-1) ... 257s Selecting previously unselected package socat. 257s Preparing to unpack .../17-socat_1.8.0.0-4build3_amd64.deb ... 257s Unpacking socat (1.8.0.0-4build3) ... 257s Selecting previously unselected package autopkgtest-satdep. 257s Preparing to unpack .../18-1-autopkgtest-satdep.deb ... 257s Unpacking autopkgtest-satdep (0) ... 257s Setting up libzscanner4t64 (3.3.7-1) ... 257s Setting up libfstrm0:amd64 (0.6.1-1build4) ... 257s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 257s Setting up lua-socket:amd64 (3.1.0-1) ... 257s Setting up libjemalloc2:amd64 (5.3.0-2build1) ... 257s Setting up dns-root-data (2024041801) ... 257s Setting up libxdp1:amd64 (1.4.2-1ubuntu4) ... 257s Setting up libunbound8:amd64 (1.19.2-1ubuntu3) ... 257s Setting up libgnutls-dane0t64:amd64 (3.8.6-2ubuntu1) ... 257s Setting up socat (1.8.0.0-4build3) ... 257s Setting up libdnssec9t64 (3.3.7-1) ... 257s Setting up libluajit-5.1-common (2.1.0+openresty20240626-1) ... 257s Setting up libknot14t64 (3.3.7-1) ... 257s Setting up gnutls-bin (3.8.6-2ubuntu1) ... 257s Setting up lua-sec:amd64 (1.3.2-2) ... 257s Setting up knot-dnsutils (3.3.7-1) ... 257s Setting up libluajit-5.1-2:amd64 (2.1.0+openresty20240626-1) ... 257s Setting up knot-resolver (5.7.3-1) ... 257s Created symlink '/etc/systemd/system/kresd.target.wants/kres-cache-gc.service' → '/usr/lib/systemd/system/kres-cache-gc.service'. 258s Created symlink '/etc/systemd/system/multi-user.target.wants/kresd.target' → '/usr/lib/systemd/system/kresd.target'. 258s Setting up autopkgtest-satdep (0) ... 258s Processing triggers for man-db (2.12.1-2) ... 259s Processing triggers for libc-bin (2.39-3.1ubuntu3) ... 267s (Reading database ... 74886 files and directories currently installed.) 267s Removing autopkgtest-satdep (0) ... 267s autopkgtest [15:43:16]: test roundtrip: [----------------------- 267s /usr/sbin/kresd + /usr/bin/kdig roundtrip tests 267s ------------ 267s workdir: /tmp/autopkgtest.q1ICTc/roundtrip-artifacts 267s IP addr: 127.2.254.85 267s kresd args: --addr=127.2.254.85@8053 --tls=127.2.254.85@8853 --noninteractive --config=/tmp/autopkgtest.q1ICTc/roundtrip-artifacts/kresd.conf --verbose --verbose --verbose 267s 267s make Certificate Authority key and certificate 267s ---------------------------------------------- 267s Generating a 3072 bit RSA private key... 267s Generating a self signed certificate... 267s X.509 Certificate Information: 267s Version: 3 267s Serial Number (hex): 68d1a5dd90323e6611fe39ed3b0f3b5398946280 267s Validity: 267s Not Before: Fri Jul 12 15:43:16 UTC 2024 267s Not After: Wed Jul 24 15:43:16 UTC 2024 267s Subject: CN=testing certificate authority (NOT FOR PRODUCTION) 267s Subject Public Key Algorithm: RSA 267s Algorithm Security Level: High (3072 bits) 267s Modulus (bits 3072): 267s 00:b8:f2:61:40:3a:21:52:71:8d:61:e6:25:cc:8e:92 267s 02:6e:47:40:b3:a4:02:c8:d1:d7:09:eb:6c:30:39:21 267s c8:c3:68:69:aa:68:85:01:4f:3d:53:29:5c:30:19:d3 267s 27:91:2c:01:53:e5:3d:72:db:d0:0d:d8:27:87:6f:c7 267s 9a:d6:38:26:1e:76:8e:09:74:52:23:92:31:74:ec:15 267s 67:c6:46:2d:b6:82:59:36:90:28:da:10:e8:fd:50:c0 267s 07:47:9b:95:3c:7e:f1:bf:f5:c8:cd:49:6c:7c:62:01 267s 9a:b2:e3:2e:20:f0:99:5e:2a:5e:29:f9:66:ff:ea:8a 267s f8:f9:82:04:9f:32:5e:ff:0b:a2:c8:10:8d:a1:50:73 267s b4:3b:85:c7:4d:af:13:74:27:39:29:54:1b:b3:c9:78 267s c5:f2:73:7e:ce:ff:44:71:12:52:fd:22:24:bb:c6:1a 267s 05:09:f0:96:3e:0f:aa:77:52:4e:15:4c:f7:d0:91:3d 267s 07:bd:ac:a8:52:c8:5d:e3:0f:3b:ba:e7:9e:cf:0e:92 267s 44:e6:ac:42:7a:b2:15:b4:87:d5:d9:70:81:2b:0b:29 267s af:61:44:57:6a:d8:67:05:01:b4:09:5f:93:cf:b3:e6 267s ca:78:54:6f:24:23:ab:e3:fe:46:fe:57:9c:9b:ff:bc 267s 44:3b:5f:a7:79:4b:41:a1:9a:8a:1f:d6:d5:ac:c4:bb 267s e2:51:16:65:35:69:b6:db:77:4b:62:99:7f:b3:50:a6 267s b1:fb:f9:c1:1d:e4:2f:1f:7b:84:18:e5:4a:f2:1c:0c 267s 26:52:0d:5f:72:7a:bd:30:47:da:7a:21:90:e6:ef:aa 267s 7d:bb:3a:51:49:7e:57:f5:6b:1f:d0:27:57:b0:1e:d3 267s f9:2d:13:bf:38:c0:98:3d:d3:c0:e8:06:77:f5:be:a4 267s 67:61:18:32:93:c2:3f:ac:ea:6e:be:d0:b0:04:16:16 267s 55:ae:44:5f:00:4d:4a:1b:cd:4a:a3:50:b4:0c:3b:ed 267s dd 267s Exponent (bits 24): 267s 01:00:01 267s Extensions: 267s Basic Constraints (critical): 267s Certificate Authority (CA): TRUE 267s Path Length Constraint: 1 267s Name Constraints (critical): 267s Permitted: 267s DNSname: example 267s Key Usage (critical): 267s Certificate signing. 267s Subject Key Identifier (not critical): 267s deea097d1facf6a167bb9d4dedf8cb3649268169 267s Other Information: 267s Public Key ID: 267s sha1:deea097d1facf6a167bb9d4dedf8cb3649268169 267s sha256:77a5a3ac721f6c9074286b62fd2718f82fdae1b84b376626b99c002c1ac602d2 267s Public Key PIN: 267s pin-sha256:d6WjrHIfbJB0KGti/ScY+C/a4bhLN2YmuZwALBrGAtI= 267s 267s 267s 267s Signing certificate... 267s 267s make Bogus Certificate Authority key and certificate 267s ---------------------------------------------------- 267s Generating a 3072 bit RSA private key... 267s Generating a self signed certificate... 267s X.509 Certificate Information: 267s Version: 3 267s Serial Number (hex): 58a64a802225b211f8266318cb00bf60c3db5401 267s Validity: 267s Not Before: Fri Jul 12 15:43:16 UTC 2024 267s Not After: Wed Jul 24 15:43:16 UTC 2024 267s Subject: CN=testing certificate authority (NOT FOR PRODUCTION) 267s Subject Public Key Algorithm: RSA 267s Algorithm Security Level: High (3072 bits) 267s Modulus (bits 3072): 267s 00:ac:5c:9e:6d:36:d0:30:53:16:73:89:e2:38:b4:01 267s 83:be:32:b8:2c:3a:85:ab:2a:b9:3e:7b:87:03:d4:08 267s f8:75:79:12:0b:1a:5d:f6:2c:04:60:c7:0c:35:ac:1c 267s 2b:69:21:30:54:aa:91:5a:ac:cd:49:29:61:ce:83:ac 267s 26:de:1b:d4:c2:f0:cf:4c:a7:20:f0:2c:45:52:8c:8c 267s ab:f7:60:17:e6:f5:12:50:60:2e:8e:00:0c:bb:e0:71 267s fd:8e:26:e4:42:be:24:b8:62:7c:b5:0d:fb:a9:e8:63 267s e9:09:55:23:6c:18:9f:11:2c:06:b6:97:a6:84:35:65 267s 16:fb:77:cb:eb:d5:ab:88:ec:ab:65:e2:d2:18:e0:bd 267s 60:b4:76:42:36:37:66:aa:bc:ed:61:fe:fc:69:b5:75 267s 08:02:f5:31:6b:e3:b2:23:0a:72:6c:8d:7b:2c:28:d9 267s 92:63:40:a1:24:18:bd:c1:c7:16:e9:30:12:15:b0:ad 267s 2b:67:95:a3:b8:72:b6:0d:8a:66:e3:94:ec:09:eb:db 267s 1d:f2:5b:d4:6d:f4:ea:39:7c:7f:18:5e:e8:d2:37:54 267s 22:64:2b:91:89:d4:d1:5d:58:2d:c1:a9:18:76:a1:0b 267s bc:2c:3c:2b:77:75:8e:47:e4:ac:ae:5b:64:65:a1:3b 267s 11:82:5f:a0:0e:94:ea:e9:a0:e5:44:21:18:39:f0:01 267s 6c:fa:d8:d6:c1:af:59:52:0f:b8:ff:f0:05:a1:a2:df 267s 7c:c7:31:b3:32:a6:fc:cd:f6:56:34:08:76:8e:2b:ba 267s 70:cd:3f:68:3f:d9:c1:bc:36:45:1a:07:d3:45:de:9a 267s 9e:bf:f8:09:c0:90:3a:34:be:37:16:7f:59:c1:b4:ce 267s 9d:1c:ff:1e:3c:cd:7d:44:b4:08:7d:7a:73:5d:65:b0 267s 95:9f:5c:ca:2b:d9:54:4b:95:e4:48:9e:82:5f:74:58 267s 39:6c:2b:1d:e2:46:cc:63:2b:24:79:77:50:59:a4:df 267s 7b 267s Exponent (bits 24): 267s 01:00:01 267s Extensions: 267s Basic Constraints (critical): 267s Certificate Authority (CA): TRUE 267s Path Length Constraint: 1 267s Name Constraints (critical): 267s Permitted: 267s DNSname: example 267s Key Usage (critical): 267s Certificate signing. 267s Subject Key Identifier (not critical): 267s 764265c8711854083060cbf883085978755a65b0 267s Other Information: 267s Public Key ID: 267s sha1:764265c8711854083060cbf883085978755a65b0 267s sha256:545be2db9d013586cf7e2e69d5769b4b871213a95db69805c87f80a74b55600f 267s Public Key PIN: 267s pin-sha256:VFvi250BNYbPfi5p1XabS4cSE6ldtpgFyH+Ap0tVYA8= 267s 267s 267s 267s Signing certificate... 267s 267s make End Entity key and certificate 267s ----------------------------------- 268s Generating a 3072 bit RSA private key... 268s Generating a signed certificate... 268s X.509 Certificate Information: 268s Version: 3 268s Serial Number (hex): 6630622f832c47f376ae1450361709055245aa14 268s Validity: 268s Not Before: Fri Jul 12 15:43:17 UTC 2024 268s Not After: Mon Jul 22 15:43:17 UTC 2024 268s Subject: CN=test.example 268s Subject Public Key Algorithm: RSA 268s Algorithm Security Level: High (3072 bits) 268s Modulus (bits 3072): 268s 00:a1:78:61:92:91:51:7f:29:2f:8a:8e:f1:6a:83:41 268s 30:76:a8:c7:2e:ea:26:0e:4d:cf:e0:43:85:42:ea:49 268s 3c:59:2d:ea:07:d2:b4:5b:d4:43:31:2c:9f:c7:3a:d7 268s 43:ff:94:00:a6:82:6a:08:d5:9d:a6:1c:a6:63:10:0a 268s ef:d7:eb:7f:c6:90:f9:03:d3:7c:96:ae:a2:bf:d1:e8 268s c5:00:6c:8d:b4:6b:20:e2:a9:b1:bf:30:03:2b:d5:a9 268s 09:76:17:8b:e6:a7:f8:ba:21:aa:81:c1:5e:2d:20:8a 268s cb:c6:79:c2:1a:2e:c3:b0:7d:3e:44:ce:32:8b:f0:1f 268s 75:d4:da:71:28:45:d4:d5:32:5c:c5:91:ea:cf:e6:dc 268s c4:46:40:ba:60:8d:c1:c5:55:6b:fe:58:53:e8:d6:dc 268s fe:89:ae:5b:20:76:8c:9f:d6:14:1d:d9:26:47:15:52 268s 70:5b:4f:5b:08:da:4d:62:b5:76:f8:50:39:08:0d:e2 268s 2e:e9:9a:4c:1e:55:51:09:b9:2f:38:46:36:3e:9f:74 268s 47:3a:f5:a7:04:a9:89:33:20:66:ff:fe:1c:c3:e5:46 268s bc:3a:b8:4b:13:be:e0:0a:39:3d:66:bc:59:b3:70:8a 268s 74:f6:13:c3:a9:24:8c:9b:4d:45:a7:78:40:b7:f7:4f 268s 4f:2e:0c:6f:74:f9:64:e1:07:bb:54:63:3e:ad:19:bd 268s 86:b8:e3:d7:29:96:e5:7b:73:69:24:c6:f0:2e:9e:85 268s 4c:74:1e:f3:cb:89:6d:5c:de:2f:b5:c8:51:3a:82:c2 268s 6b:cf:eb:bf:76:73:f0:e5:b3:29:71:e4:e9:13:6f:6b 268s 5c:98:b1:80:ab:de:84:8a:d2:e9:e1:02:ea:ba:37:fa 268s dd:9e:f9:ab:73:d2:1f:a4:ba:cf:b4:76:f8:bb:b2:cb 268s 74:78:89:8d:19:50:bf:88:00:de:ea:41:e7:78:c6:98 268s 46:70:85:f1:4a:6b:3a:14:f4:7b:22:88:d3:f5:e2:8f 268s 7b 268s Exponent (bits 24): 268s 01:00:01 268s Extensions: 268s Basic Constraints (critical): 268s Certificate Authority (CA): FALSE 268s Subject Alternative Name (not critical): 268s DNSname: test.example 268s Key Purpose (not critical): 268s TLS WWW Server. 268s Key Usage (critical): 268s Digital signature. 268s Subject Key Identifier (not critical): 268s e3e6a32389f12b59b280140b67ceedbd4766b8a4 268s Authority Key Identifier (not critical): 268s deea097d1facf6a167bb9d4dedf8cb3649268169 268s Other Information: 268s Public Key ID: 268s sha1:e3e6a32389f12b59b280140b67ceedbd4766b8a4 268s sha256:d276a847c291e6b853b92f43728cc7d2b550c8a79974683b1a84b5ce50248ada 268s Public Key PIN: 268s pin-sha256:0naoR8KR5rhTuS9DcozH0rVQyKeZdGg7GoS1zlAkito= 268s 268s 268s 268s Signing certificate... 268s 268s set up kresd daemon on 127.2.254.85 on ports 8053 (UDP, TCP) and 8853 (TLS) 268s --------------------------------------------------------------------------- 269s 269s test UDP with kdig 269s ------------------ 269s successful UDP request to 127.2.254.85 on port 8053 269s 269s test TCP with kdig 269s ------------------ 269s successful TCP request to 127.2.254.85 on port 8053 269s 269s test opportunistic DNS-over-TLS with kdig 269s ----------------------------------------- 269s successful opportunistic DNS-over-TLS request to 127.2.254.85 on port 8853 269s 269s test strict DNS-over-TLS with kdig 269s ---------------------------------- 269s successful strict DNS-over-TLS request to 127.2.254.85 on port 8853 269s 269s test invalid name with strict DNS-over-TLS with kdig 269s ---------------------------------------------------- 269s successful strict DNS-over-TLS request failure when name mismatch to 127.2.254.85 on port 8853 269s 269s test bad authority with strict DNS-over-TLS with kdig 269s ----------------------------------------------------- 269s successful strict DNS-over-TLS request failure to 127.2.254.85 on port 8853 269s 269s cleaning up 269s ----------- 269s 535792 4 drwxrwxrwt 2 root root 4096 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts 269s 535807 72 -rw-r--r-- 1 root root 73159 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/kresd.err 269s 535804 4 -rw-r--r-- 1 root root 2218 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/ee-pubkey.pem 269s 536153 4 -rw-r--r-- 1 root root 118 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/badca.err 269s 536051 4 -rw-r----- 1 root root 8192 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/lock.mdb 269s 536152 4 -rw-r--r-- 1 root root 118 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/badname.err 269s 535798 8 -rw------- 1 root root 8170 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/ca-key.pem 269s 535797 4 -rw-r--r-- 1 root root 136 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/ca.template 269s 535806 4 -rw-r--r-- 1 root root 387 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/kresd.conf 269s 535805 4 -rw-r--r-- 1 root root 1602 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/ee-cert.pem 269s 535800 8 -rw------- 1 root root 8170 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/bogus-key.pem 269s 535803 8 -rw------- 1 root root 8167 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/ee-key.pem 269s 535802 4 -rw-r--r-- 1 root root 92 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/ee.template 269s 535799 4 -rw-r--r-- 1 root root 1590 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/ca-cert.pem 269s 536151 102400 -rw-r----- 1 root root 104857600 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/data.mdb 269s 535801 4 -rw-r--r-- 1 root root 1590 Jul 12 15:43 /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/bogus-cert.pem 269s ==> /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/badca.err <== 269s ;; WARNING: TLS, handshake failed (Error in the certificate.) 269s ;; ERROR: failed to query server 127.2.254.85@8853(TCP) 269s 269s ==> /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/badname.err <== 269s ;; WARNING: TLS, handshake failed (Error in the certificate.) 269s ;; ERROR: failed to query server 127.2.254.85@8853(TCP) 269s 269s ==> /tmp/autopkgtest.q1ICTc/roundtrip-artifacts/kresd.err <== 269s [system] increasing file-descriptor limit: 1024 -> 1073741816 269s [tls ] session ticket: epoch 420116, scheduling rotation check in 234505 ms 269s [wtchdg] disabled in systemd (WatchdogSec= not specified) 269s [ta ] installed trust anchors for domain . are: 269s . 86400 DNSKEY 257 3 8 AwEAAaz/tAm8yTn4Mfeh5eyI96WSVexTBAvkMgJzkKTOiW1vkIbzxeF3+/4RgWOq7HrxRixHlFlExOLAJr5emLvN7SWXgnLh4+B5xQlNVz8Og8kvArMtNROxVQuCaSnIDdD5LKyWbRd2n9WGe2R8PzgCmr3EgVLrjyBxWezF0jLHwVN8efS3rCj/EWgvIWgb9tarpVUDK/b58Da+sqqls3eNbuv7pr+eoZG+SrDK6nWeL3c6H5Apxz7LjVc1uTIdsIXxuOLYA4/ilBmSVIzuDWfdRUfhHdY6+cn8HFRm+2hM8AnXGXws9555KrUB5qihylGa8subX2Nn6UwNR1AkUTV74bU= ; Valid: ; KeyTag:20326 269s 269s [system] loading config '/tmp/autopkgtest.q1ICTc/roundtrip-artifacts/kresd.conf' (workdir '/tmp/autopkgtest.q1ICTc/roundtrip-artifacts') 269s [system] deprecation WARNING: use log_level() instead of verbose() 269s [gnutls] (3) ASSERT: ../../../lib/x509/dn.c[_gnutls_x509_compare_raw_dn]:974 269s [gnutls] (3) ASSERT: ../../../lib/x509/dn.c[_gnutls_x509_compare_raw_dn]:974 269s [gnutls] (3) ASSERT: ../../../lib/x509/x509_ext.c[gnutls_subject_alt_names_get]:111 269s [gnutls] (3) ASSERT: ../../../lib/x509/x509.c[get_alt_name]:2012 269s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 269s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 269s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 269s [tls ] RFC 7858 OOB key-pin (0): pin-sha256="" 269s [gnutls] (3) ASSERT: ../../lib/cert-cred-x509.c[gnutls_certificate_get_x509_crt]:872 269s [system] loading config '/usr/lib/x86_64-linux-gnu/knot-resolver/postconfig.lua' (workdir '/tmp/autopkgtest.q1ICTc/roundtrip-artifacts') 269s [plan ][00000.00] plan '.' type 'NS' uid [65536.00] 269s [iterat][65536.00] '.' type 'NS' new uid was assigned .01, parent uid .00 269s [resolv][65536.01] => using root hints 269s [iterat][65536.01] '.' type 'NS' new uid was assigned .02, parent uid .00 269s [resolv][65536.02] >< TA: '.' 269s [plan ][65536.02] plan '.' type 'DNSKEY' uid [65536.03] 269s [iterat][65536.03] '.' type 'DNSKEY' new uid was assigned .04, parent uid .02 269s [select][65536.04] => id: '39717' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65536.04] => id: '39717' choosing: 'I.ROOT-SERVERS.NET.'@'2001:7fe::53#00053' with timeout 400 ms zone cut: '.' 269s [resolv][65536.04] => id: '39717' querying: 'I.ROOT-SERVERS.NET.'@'2001:7fe::53#00053' zone cut: '.' qname: '.' qtype: 'DNSKEY' proto: 'udp' 269s [plan ][00000.00] plan '.' type 'NS' uid [65537.00] 269s [iterat][65537.00] '.' type 'NS' new uid was assigned .01, parent uid .00 269s [resolv][65537.01] => using root hints 269s [iterat][65537.01] '.' type 'NS' new uid was assigned .02, parent uid .00 269s [select][65537.02] => id: '07363' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65537.02] => id: '07363' choosing: 'C.ROOT-SERVERS.NET.'@'2001:500:2::c#00053' with timeout 400 ms zone cut: '.' 269s [resolv][65537.02] => id: '07363' querying: 'C.ROOT-SERVERS.NET.'@'2001:500:2::c#00053' zone cut: '.' qname: '.' qtype: 'NS' proto: 'udp' 269s [taupd ] refreshing TA for . 269s [plan ][00000.00] plan '.' type 'DNSKEY' uid [65538.00] 269s [iterat][65538.00] '.' type 'DNSKEY' new uid was assigned .01, parent uid .00 269s [resolv][65538.01] => using root hints 269s [iterat][65538.01] '.' type 'DNSKEY' new uid was assigned .02, parent uid .00 269s [resolv][65538.02] >< TA: '.' 269s [select][65538.02] => id: '38269' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65538.02] => id: '38269' choosing: 'J.ROOT-SERVERS.NET.'@'2001:503:c27::2:30#00053' with timeout 400 ms zone cut: '.' 269s [select][65536.04] NO6: timed out, appended, timeouts 1/6 269s [select][65536.04] => id: '39717' noting selection error: 'I.ROOT-SERVERS.NET.'@'2001:7fe::53#00053' zone cut: '.' error: 1 QUERY_TIMEOUT 269s [iterat][65538.02] '.' type 'DNSKEY' new uid was assigned .03, parent uid .00 269s [select][65538.03] => id: '46355' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65538.03] => id: '46355' choosing: 'H.ROOT-SERVERS.NET.'@'2001:500:1::53#00053' with timeout 400 ms zone cut: '.' 269s [resolv][65538.03] => id: '46355' querying: 'H.ROOT-SERVERS.NET.'@'2001:500:1::53#00053' zone cut: '.' qname: '.' qtype: 'DNSKEY' proto: 'udp' 269s [iterat][65536.04] '.' type 'DNSKEY' new uid was assigned .05, parent uid .02 269s [select][65536.05] => id: '55916' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65536.05] => id: '55916' choosing: 'B.ROOT-SERVERS.NET.'@'2801:1b8:10::b#00053' with timeout 800 ms zone cut: '.' 269s [select][65537.02] NO6: timed out, appended, timeouts 2/6 269s [select][65537.02] => id: '07363' noting selection error: 'C.ROOT-SERVERS.NET.'@'2001:500:2::c#00053' zone cut: '.' error: 1 QUERY_TIMEOUT 269s [iterat][65537.02] '.' type 'NS' new uid was assigned .03, parent uid .00 269s [select][65537.03] => id: '33529' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65537.03] => id: '33529' choosing: 'H.ROOT-SERVERS.NET.'@'2001:500:1::53#00053' with timeout 800 ms zone cut: '.' 269s [resolv][65537.03] => id: '33529' querying: 'H.ROOT-SERVERS.NET.'@'2001:500:1::53#00053' zone cut: '.' qname: '.' qtype: 'NS' proto: 'udp' 269s [select][65538.03] NO6: timed out, appended, timeouts 3/6 269s [select][65538.03] => id: '46355' noting selection error: 'H.ROOT-SERVERS.NET.'@'2001:500:1::53#00053' zone cut: '.' error: 1 QUERY_TIMEOUT 269s [iterat][65536.05] '.' type 'DNSKEY' new uid was assigned .06, parent uid .02 269s [select][65536.06] => id: '34889' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65536.06] => id: '34889' choosing: 'K.ROOT-SERVERS.NET.'@'2001:7fd::1#00053' with timeout 800 ms zone cut: '.' 269s [resolv][65536.06] => id: '34889' querying: 'K.ROOT-SERVERS.NET.'@'2001:7fd::1#00053' zone cut: '.' qname: '.' qtype: 'DNSKEY' proto: 'udp' 269s [iterat][65538.03] '.' type 'DNSKEY' new uid was assigned .04, parent uid .00 269s [select][65538.04] => id: '51912' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 269s [select][65538.04] => id: '51912' choosing: 'F.ROOT-SERVERS.NET.'@'2001:500:2f::f#00053' with timeout 800 ms zone cut: '.' 269s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [63845.00] 269s [iterat][63845.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 269s [hint ][63845.01] <= answered from hints 269s [iterat][63845.01] <= rcode: NOERROR 269s [resolv][63845.01] AD: request NOT classified as SECURE 269s [resolv][63845.01] finished in state: 4, queries: 1, mempool: 16400 B 269s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [01895.00] 269s [iterat][01895.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 269s [hint ][01895.01] <= answered from hints 269s [iterat][01895.01] <= rcode: NOERROR 269s [resolv][01895.01] AD: request NOT classified as SECURE 269s [resolv][01895.01] finished in state: 4, queries: 1, mempool: 81952 B 269s [io ] => connection to '127.0.0.1#59789' closed by peer (end of file) 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #0 269s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Handshake packet received. Epoch 0, length: 374 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Handshake(22) with length: 374 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Handshake(22) with length: 374 269s [gnutls] (4) HSK[0x7fecc66f1000]: CLIENT HELLO (1) was received. Length 370[370], frag offset 0, frag length: 370, sequence: 0 269s [gnutls] (4) HSK[0x7fecc66f1000]: Client's version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Versions/43' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Negotiated version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Record Size Limit/28' (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: record_size_limit 16385 negotiated 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'ALPN/16' (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 01 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 00 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Session Ticket/35' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Groups/10' (22 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP256R1 (0x17) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP384R1 (0x18) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP521R1 (0x19) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X25519 (0x1d) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X448 (0x1e) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE2048 (0x100) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE3072 (0x101) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE4096 (0x102) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE6144 (0x103) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE8192 (0x104) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Selected group SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.1) RSA-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.9) RSA-PSS-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.7) EdDSA-Ed25519 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.1) RSA-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.10) RSA-PSS-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.8) EdDSA-Ed448 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.1) RSA-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.11) RSA-PSS-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.1) RSA-SHA1 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.3) ECDSA-SHA1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 269s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 269s [gnutls] (3) ASSERT: ../../../lib/ext/server_name.c[gnutls_server_name_get]:234 269s [gnutls] (4) HSK[0x7fecc66f1000]: Requested server name: '' 269s [gnutls] (4) HSK[0x7fecc66f1000]: checking compat of GNUTLS_AES_256_GCM_SHA384 with certificate[0] (RSA/X.509) 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 269s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected version TLS1.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Key Share/51' (107 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received key share for SECP256R1 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected group SECP256R1 (2) 269s [gnutls] (2) EXT[0x7fecc66f1000]: server generated SECP256R1 shared key 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: OCSP status was requested 269s [gnutls] (4) HSK[0x7fecc66f1000]: Safe renegotiation succeeded 269s [gnutls] (4) HSK[0x7fecc66f1000]: SessionID: f6e7371d946c6b6368e159221ba687467832d2dc752f04a4565d3deeb909ac05 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Supported Versions/43 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: sending key share for SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Key Share/51 (69 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 269s [gnutls] (4) HSK[0x7fecc66f1000]: SERVER HELLO was queued [155 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 160 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 269s [gnutls] (4) REC[0x7fecc66f1000]: Sent ChangeCipherSpec 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #1 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Record Size Limit/28 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (ALPN/16) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension ALPN/16 (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Early Data/42) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (SRTP/14) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Key Share/51) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 269s [gnutls] (4) HSK[0x7fecc66f1000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE was queued [1155 bytes] 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) HSK[0x7fecc66f1000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 269s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE VERIFY was queued [392 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: sending finished 269s [gnutls] (4) HSK[0x7fecc66f1000]: FINISHED was queued [52 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 269s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #2 269s [gnutls] (4) HSK[0x7fecc66f1000]: unauthenticated session eligible for early start 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #2 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: switching early to application traffic keys 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 ChangeCipherSpec packet received. Epoch 1, length: 1 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet ChangeCipherSpec(20) with length: 1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Application Data packet received. Epoch 1, length: 69 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Application Data(23) with length: 69 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Handshake(22) with length: 52 269s [gnutls] (4) HSK[0x7fecc66f1000]: FINISHED (20) was received. Length 48[48], frag offset 0, frag length: 48, sequence: 0 269s [gnutls] (4) HSK[0x7fecc66f1000]: parsing finished 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 set read key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (5) REC[0x7fecc66f1000]: Start of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #0 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: End of epoch cleanup 269s [tls ] TLS handshake with 127.0.0.1#59467 has completed 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Application Data packet received. Epoch 2, length: 147 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Application Data(23) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Application Data(23) with length: 147 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Application Data(23) with length: 130 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 269s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [45508.00] 269s [iterat][45508.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 269s [hint ][45508.01] <= answered from hints 269s [iterat][45508.01] <= rcode: NOERROR 269s [resolv][45508.01] AD: request NOT classified as SECURE 269s [resolv][45508.01] finished in state: 4, queries: 1, mempool: 81952 B 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Application Data(23) with length: 470 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[3] Application Data(23) in epoch 2 and length: 492 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Application Data packet received. Epoch 2, length: 19 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Application Data(23) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Application Data(23) with length: 19 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[1] Alert(21) with length: 2 269s [gnutls] (5) REC[0x7fecc66f1000]: Alert[1|0] - Close notify - was received 269s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_in_buffers]:1565 269s [io ] => connection to '127.0.0.1#59467' closed by peer (end of file) 269s [gnutls] (5) REC[0x7fecc66f1000]: Start of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: End of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #0 269s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Handshake packet received. Epoch 0, length: 395 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Handshake(22) with length: 395 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Handshake(22) with length: 395 269s [gnutls] (4) HSK[0x7fecc66f1000]: CLIENT HELLO (1) was received. Length 391[391], frag offset 0, frag length: 391, sequence: 0 269s [gnutls] (4) HSK[0x7fecc66f1000]: Client's version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Versions/43' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Negotiated version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Record Size Limit/28' (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: record_size_limit 16385 negotiated 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'ALPN/16' (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Server Name Indication/0' (17 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.1) RSA-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.9) RSA-PSS-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.7) EdDSA-Ed25519 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.1) RSA-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.10) RSA-PSS-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.8) EdDSA-Ed448 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.1) RSA-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.11) RSA-PSS-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.1) RSA-SHA1 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.3) ECDSA-SHA1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 01 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 00 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Groups/10' (22 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP256R1 (0x17) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP384R1 (0x18) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP521R1 (0x19) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X25519 (0x1d) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X448 (0x1e) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE2048 (0x100) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE3072 (0x101) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE4096 (0x102) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE6144 (0x103) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE8192 (0x104) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Selected group SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Session Ticket/35' (0 bytes) 269s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 269s [gnutls] (4) HSK[0x7fecc66f1000]: Requested server name: 'test.example' 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 269s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected version TLS1.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: OCSP status was requested 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Key Share/51' (107 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received key share for SECP256R1 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected group SECP256R1 (2) 269s [gnutls] (2) EXT[0x7fecc66f1000]: server generated SECP256R1 shared key 269s [gnutls] (4) HSK[0x7fecc66f1000]: Safe renegotiation succeeded 269s [gnutls] (4) HSK[0x7fecc66f1000]: SessionID: 88c0114b36748055f9a74a23dbc27d1fb9c17225415a56b0ccdab32159e2764e 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: sending key share for SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Key Share/51 (69 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Supported Versions/43 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 269s [gnutls] (4) HSK[0x7fecc66f1000]: SERVER HELLO was queued [155 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 160 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 269s [gnutls] (4) REC[0x7fecc66f1000]: Sent ChangeCipherSpec 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #1 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Record Size Limit/28 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (SRTP/14) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Early Data/42) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Key Share/51) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (ALPN/16) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension ALPN/16 (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 269s [gnutls] (4) HSK[0x7fecc66f1000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE was queued [1155 bytes] 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) HSK[0x7fecc66f1000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 269s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE VERIFY was queued [392 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: sending finished 269s [gnutls] (4) HSK[0x7fecc66f1000]: FINISHED was queued [52 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 269s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #2 269s [gnutls] (4) HSK[0x7fecc66f1000]: unauthenticated session eligible for early start 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #2 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: switching early to application traffic keys 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 ChangeCipherSpec packet received. Epoch 1, length: 1 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet ChangeCipherSpec(20) with length: 1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Application Data packet received. Epoch 1, length: 69 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Application Data(23) with length: 69 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Handshake(22) with length: 52 269s [gnutls] (4) HSK[0x7fecc66f1000]: FINISHED (20) was received. Length 48[48], frag offset 0, frag length: 48, sequence: 0 269s [gnutls] (4) HSK[0x7fecc66f1000]: parsing finished 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 set read key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (5) REC[0x7fecc66f1000]: Start of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #0 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: End of epoch cleanup 269s [tls ] TLS handshake with 127.0.0.1#39937 has completed 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Application Data packet received. Epoch 2, length: 147 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Application Data(23) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Application Data(23) with length: 147 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Application Data(23) with length: 130 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 269s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [45618.00] 269s [iterat][45618.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 269s [hint ][45618.01] <= answered from hints 269s [iterat][45618.01] <= rcode: NOERROR 269s [resolv][45618.01] AD: request NOT classified as SECURE 269s [resolv][45618.01] finished in state: 4, queries: 1, mempool: 81952 B 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Application Data(23) with length: 470 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[3] Application Data(23) in epoch 2 and length: 492 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Application Data packet received. Epoch 2, length: 19 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Application Data(23) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Application Data(23) with length: 19 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[1] Alert(21) with length: 2 269s [gnutls] (5) REC[0x7fecc66f1000]: Alert[1|0] - Close notify - was received 269s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_in_buffers]:1565 269s [io ] => connection to '127.0.0.1#39937' closed by peer (end of file) 269s [gnutls] (5) REC[0x7fecc66f1000]: Start of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: End of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #0 269s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Handshake packet received. Epoch 0, length: 399 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Handshake(22) with length: 399 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Handshake(22) with length: 399 269s [gnutls] (4) HSK[0x7fecc66f1000]: CLIENT HELLO (1) was received. Length 395[395], frag offset 0, frag length: 395, sequence: 0 269s [gnutls] (4) HSK[0x7fecc66f1000]: Client's version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Versions/43' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Negotiated version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Server Name Indication/0' (21 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Record Size Limit/28' (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: record_size_limit 16385 negotiated 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'ALPN/16' (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.1) RSA-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.9) RSA-PSS-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.7) EdDSA-Ed25519 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.1) RSA-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.10) RSA-PSS-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.8) EdDSA-Ed448 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.1) RSA-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.11) RSA-PSS-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.1) RSA-SHA1 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.3) ECDSA-SHA1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Groups/10' (22 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP256R1 (0x17) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP384R1 (0x18) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP521R1 (0x19) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X25519 (0x1d) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X448 (0x1e) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE2048 (0x100) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE3072 (0x101) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE4096 (0x102) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE6144 (0x103) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE8192 (0x104) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Selected group SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 01 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 00 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Session Ticket/35' (0 bytes) 269s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 269s [gnutls] (4) HSK[0x7fecc66f1000]: Requested server name: 'notright.example' 269s [gnutls] (4) HSK[0x7fecc66f1000]: checking compat of GNUTLS_AES_256_GCM_SHA384 with certificate[0] (RSA/X.509) 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 269s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected version TLS1.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Key Share/51' (107 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received key share for SECP256R1 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected group SECP256R1 (2) 269s [gnutls] (2) EXT[0x7fecc66f1000]: server generated SECP256R1 shared key 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: OCSP status was requested 269s [gnutls] (4) HSK[0x7fecc66f1000]: Safe renegotiation succeeded 269s [gnutls] (4) HSK[0x7fecc66f1000]: SessionID: 315018b361f36bd3caf98f939ab836397fe5b4b5962ce610971a7d8c84987610 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: sending key share for SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Key Share/51 (69 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Supported Versions/43 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 269s [gnutls] (4) HSK[0x7fecc66f1000]: SERVER HELLO was queued [155 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 160 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 269s [gnutls] (4) REC[0x7fecc66f1000]: Sent ChangeCipherSpec 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #1 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (SRTP/14) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Record Size Limit/28 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (ALPN/16) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension ALPN/16 (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Early Data/42) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Key Share/51) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 269s [gnutls] (4) HSK[0x7fecc66f1000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE was queued [1155 bytes] 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) HSK[0x7fecc66f1000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 269s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE VERIFY was queued [392 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: sending finished 269s [gnutls] (4) HSK[0x7fecc66f1000]: FINISHED was queued [52 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 269s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #2 269s [gnutls] (4) HSK[0x7fecc66f1000]: unauthenticated session eligible for early start 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #2 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: switching early to application traffic keys 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 ChangeCipherSpec packet received. Epoch 1, length: 1 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet ChangeCipherSpec(20) with length: 1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [io ] => connection to '127.0.0.1#49927' closed by peer (connection reset by peer) 269s [gnutls] (5) REC[0x7fecc66f1000]: Start of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #0 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: End of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #0 269s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 Handshake packet received. Epoch 0, length: 395 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet Handshake(22) with length: 395 269s [gnutls] (5) REC[0x7fecc66f1000]: Decrypted Packet[0] Handshake(22) with length: 395 269s [gnutls] (4) HSK[0x7fecc66f1000]: CLIENT HELLO (1) was received. Length 391[391], frag offset 0, frag length: 391, sequence: 0 269s [gnutls] (4) HSK[0x7fecc66f1000]: Client's version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Versions/43' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Found version: 3.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Negotiated version: 3.4 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'ALPN/16' (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Record Size Limit/28' (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: record_size_limit 16385 negotiated 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Server Name Indication/0' (17 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported Groups/10' (22 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP256R1 (0x17) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP384R1 (0x18) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group SECP521R1 (0x19) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X25519 (0x1d) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group X448 (0x1e) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE2048 (0x100) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE3072 (0x101) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE4096 (0x102) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE6144 (0x103) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received group FFDHE8192 (0x104) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Selected group SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 01 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: PSK KE mode 00 received 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Session Ticket/35' (0 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.1) RSA-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.9) RSA-PSS-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.7) EdDSA-Ed25519 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.1) RSA-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.10) RSA-PSS-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.8) EdDSA-Ed448 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.1) RSA-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.11) RSA-PSS-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.1) RSA-SHA1 269s [gnutls] (4) EXT[0x7fecc66f1000]: rcvd signature algo (2.3) ECDSA-SHA1 269s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 269s [gnutls] (4) HSK[0x7fecc66f1000]: Requested server name: 'test.example' 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 269s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected version TLS1.3 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'Key Share/51' (107 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Received key share for SECP256R1 269s [gnutls] (4) HSK[0x7fecc66f1000]: Selected group SECP256R1 (2) 269s [gnutls] (2) EXT[0x7fecc66f1000]: server generated SECP256R1 shared key 269s [gnutls] (4) EXT[0x7fecc66f1000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: OCSP status was requested 269s [gnutls] (4) HSK[0x7fecc66f1000]: Safe renegotiation succeeded 269s [gnutls] (4) HSK[0x7fecc66f1000]: SessionID: 291a51ad8b682a1b0721111a9257c1bc336d1acea797c5acbbff5a6b0c327f5a 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: sending key share for SECP256R1 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Key Share/51 (69 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Supported Versions/43 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 269s [gnutls] (4) HSK[0x7fecc66f1000]: SERVER HELLO was queued [155 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 160 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 269s [gnutls] (4) REC[0x7fecc66f1000]: Sent ChangeCipherSpec 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #1 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension Record Size Limit/28 (2 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Early Data/42) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Key Share/51) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (SRTP/14) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Cookie/44) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Preparing extension (ALPN/16) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Sending extension ALPN/16 (6 bytes) 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 269s [gnutls] (4) EXT[0x7fecc66f1000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 269s [gnutls] (4) HSK[0x7fecc66f1000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE was queued [1155 bytes] 269s [gnutls] (4) checking cert compat with RSA-SHA256 269s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 269s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 269s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 269s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 269s [gnutls] (4) HSK[0x7fecc66f1000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 269s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 269s [gnutls] (4) HSK[0x7fecc66f1000]: CERTIFICATE VERIFY was queued [392 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: sending finished 269s [gnutls] (4) HSK[0x7fecc66f1000]: FINISHED was queued [52 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 269s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 269s [gnutls] (5) REC[0x7fecc66f1000]: Allocating epoch #2 269s [gnutls] (4) HSK[0x7fecc66f1000]: unauthenticated session eligible for early start 269s [gnutls] (5) REC[0x7fecc66f1000]: Initializing epoch #2 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 ready 269s [gnutls] (4) HSK[0x7fecc66f1000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 269s [gnutls] (4) HSK[0x7fecc66f1000]: switching early to application traffic keys 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (4) HSK[0x7fecc66f1000]: NEW SESSION TICKET was queued [251 bytes] 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (5) REC[0x7fecc66f1000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 269s [gnutls] (5) REC[0x7fecc66f1000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 269s [gnutls] (5) REC[0x7fecc66f1000]: SSL 3.3 ChangeCipherSpec packet received. Epoch 1, length: 1 269s [gnutls] (5) REC[0x7fecc66f1000]: Expected Packet Handshake(22) 269s [gnutls] (5) REC[0x7fecc66f1000]: Received Packet ChangeCipherSpec(20) with length: 1 269s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 269s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 269s [io ] => connection to '127.0.0.1#37901' closed by peer (connection reset by peer) 269s [gnutls] (5) REC[0x7fecc66f1000]: Start of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #0 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: End of epoch cleanup 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #1 freed 269s [gnutls] (5) REC[0x7fecc66f1000]: Epoch #2 freed 269s > 269s > autopkgtest [15:43:18]: test roundtrip: -----------------------] 275s autopkgtest [15:43:24]: test roundtrip: - - - - - - - - - - results - - - - - - - - - - 275s roundtrip PASS 285s autopkgtest [15:43:34]: @@@@@@@@@@@@@@@@@@@@ summary 285s roundtrip PASS 324s nova [W] Skipping flock for amd64 324s Creating nova instance adt-oracular-amd64-knot-resolver-20240712-153849-juju-7f2275-prod-proposed-migration-environment-2-520d5585-67f8-416f-afea-e164eeefeb4c from image adt/ubuntu-oracular-amd64-server-20240711.img (UUID 4d65b1cc-a829-44fc-a8ac-cdd52093f9db)...