0s autopkgtest [03:09:13]: starting date and time: 2025-05-04 03:09:13+0000 0s autopkgtest [03:09:13]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [03:09:13]: host juju-7f2275-prod-proposed-migration-environment-23; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.usfqo_wn/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:twitter-bootstrap3 --apt-upgrade knot-resolver --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=twitter-bootstrap3/3.4.1+dfsg-4 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-23@bos03-16.secgroup --name adt-questing-amd64-knot-resolver-20250504-030913-juju-7f2275-prod-proposed-migration-environment-23-b67ecf09-5b48-4513-b3cf-cb6e240b034e --image adt/ubuntu-questing-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-23 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 68s autopkgtest [03:10:21]: testbed dpkg architecture: amd64 69s autopkgtest [03:10:22]: testbed apt version: 3.0.0 69s autopkgtest [03:10:22]: @@@@@@@@@@@@@@@@@@@@ test bed setup 69s autopkgtest [03:10:22]: testbed release detected to be: None 70s autopkgtest [03:10:23]: updating testbed package index (apt update) 70s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 70s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 71s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 71s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 71s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [141 kB] 71s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1375 kB] 71s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [33.2 kB] 71s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [193 kB] 71s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [137 kB] 71s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [567 kB] 71s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [1419 kB] 71s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [26.8 kB] 71s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [51.1 kB] 71s Fetched 4055 kB in 1s (3680 kB/s) 72s Reading package lists... 73s autopkgtest [03:10:26]: upgrading testbed (apt dist-upgrade and autopurge) 73s Reading package lists... 73s Building dependency tree... 73s Reading state information... 74s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 74s Starting 2 pkgProblemResolver with broken count: 0 74s Done 74s Entering ResolveByKeep 75s 75s Calculating upgrade... 75s The following packages will be upgraded: 75s cloud-init cloud-init-base debianutils iso-codes libmbim-glib4 libmbim-proxy 75s libnftnl11 libqmi-glib5 libqmi-proxy libsepol2 python3-lazr.restfulclient 75s sos 75s 12 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 75s Need to get 6396 kB of archives. 75s After this operation, 1103 kB of additional disk space will be used. 75s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 debianutils amd64 5.22 [93.0 kB] 75s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libsepol2 amd64 3.8.1-1 [327 kB] 75s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 iso-codes all 4.18.0-1 [3703 kB] 75s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 libnftnl11 amd64 1.2.9-1 [69.1 kB] 75s Get:5 http://ftpmaster.internal/ubuntu questing/main amd64 cloud-init-base all 25.2~1g7a0265d3-0ubuntu1 [619 kB] 75s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 libmbim-proxy amd64 1.32.0-1 [6276 B] 75s Get:7 http://ftpmaster.internal/ubuntu questing/main amd64 libmbim-glib4 amd64 1.32.0-1 [233 kB] 75s Get:8 http://ftpmaster.internal/ubuntu questing/main amd64 libqmi-proxy amd64 1.36.0-1 [6220 B] 75s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libqmi-glib5 amd64 1.36.0-1 [920 kB] 75s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 python3-lazr.restfulclient all 0.14.6-3 [51.0 kB] 75s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 sos all 4.9.1-1 [367 kB] 75s Get:12 http://ftpmaster.internal/ubuntu questing/main amd64 cloud-init all 25.2~1g7a0265d3-0ubuntu1 [2106 B] 76s Preconfiguring packages ... 76s Fetched 6396 kB in 1s (8768 kB/s) 76s (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 ... 80514 files and directories currently installed.) 76s Preparing to unpack .../debianutils_5.22_amd64.deb ... 76s Unpacking debianutils (5.22) over (5.21) ... 76s Setting up debianutils (5.22) ... 76s (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 ... 80514 files and directories currently installed.) 76s Preparing to unpack .../libsepol2_3.8.1-1_amd64.deb ... 76s Unpacking libsepol2:amd64 (3.8.1-1) over (3.7-1) ... 76s Setting up libsepol2:amd64 (3.8.1-1) ... 76s (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 ... 80514 files and directories currently installed.) 76s Preparing to unpack .../0-iso-codes_4.18.0-1_all.deb ... 76s Unpacking iso-codes (4.18.0-1) over (4.17.0-1) ... 77s Preparing to unpack .../1-libnftnl11_1.2.9-1_amd64.deb ... 77s Unpacking libnftnl11:amd64 (1.2.9-1) over (1.2.8-1) ... 77s Preparing to unpack .../2-cloud-init-base_25.2~1g7a0265d3-0ubuntu1_all.deb ... 77s Unpacking cloud-init-base (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 77s Preparing to unpack .../3-libmbim-proxy_1.32.0-1_amd64.deb ... 77s Unpacking libmbim-proxy (1.32.0-1) over (1.31.2-0ubuntu4) ... 77s Preparing to unpack .../4-libmbim-glib4_1.32.0-1_amd64.deb ... 77s Unpacking libmbim-glib4:amd64 (1.32.0-1) over (1.31.2-0ubuntu4) ... 77s Preparing to unpack .../5-libqmi-proxy_1.36.0-1_amd64.deb ... 77s Unpacking libqmi-proxy (1.36.0-1) over (1.35.6-1) ... 77s Preparing to unpack .../6-libqmi-glib5_1.36.0-1_amd64.deb ... 77s Unpacking libqmi-glib5:amd64 (1.36.0-1) over (1.35.6-1) ... 77s Preparing to unpack .../7-python3-lazr.restfulclient_0.14.6-3_all.deb ... 77s Unpacking python3-lazr.restfulclient (0.14.6-3) over (0.14.6-2) ... 77s Preparing to unpack .../8-sos_4.9.1-1_all.deb ... 78s Unpacking sos (4.9.1-1) over (4.9.0-6) ... 78s Preparing to unpack .../9-cloud-init_25.2~1g7a0265d3-0ubuntu1_all.deb ... 78s Unpacking cloud-init (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 78s Setting up sos (4.9.1-1) ... 78s Setting up libnftnl11:amd64 (1.2.9-1) ... 78s Setting up cloud-init-base (25.2~1g7a0265d3-0ubuntu1) ... 80s Setting up python3-lazr.restfulclient (0.14.6-3) ... 80s Setting up iso-codes (4.18.0-1) ... 80s Setting up libmbim-glib4:amd64 (1.32.0-1) ... 80s Setting up cloud-init (25.2~1g7a0265d3-0ubuntu1) ... 80s Setting up libmbim-proxy (1.32.0-1) ... 80s Setting up libqmi-glib5:amd64 (1.36.0-1) ... 80s Setting up libqmi-proxy (1.36.0-1) ... 80s Processing triggers for libc-bin (2.41-6ubuntu1) ... 80s Processing triggers for rsyslog (8.2412.0-2ubuntu2) ... 80s Processing triggers for man-db (2.13.0-1) ... 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 83s Starting pkgProblemResolver with broken count: 0 83s Starting 2 pkgProblemResolver with broken count: 0 83s Done 83s Solving dependencies... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 84s autopkgtest [03:10:37]: rebooting testbed after setup commands that affected boot 107s autopkgtest [03:11:00]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 15:05:05 UTC 2025 109s autopkgtest [03:11:02]: @@@@@@@@@@@@@@@@@@@@ apt-source knot-resolver 112s Get:1 http://ftpmaster.internal/ubuntu questing/universe knot-resolver 5.7.4-2build1 (dsc) [3202 B] 112s Get:2 http://ftpmaster.internal/ubuntu questing/universe knot-resolver 5.7.4-2build1 (tar) [1931 kB] 112s Get:3 http://ftpmaster.internal/ubuntu questing/universe knot-resolver 5.7.4-2build1 (asc) [833 B] 112s Get:4 http://ftpmaster.internal/ubuntu questing/universe knot-resolver 5.7.4-2build1 (diff) [402 kB] 112s gpgv: Signature made Tue Nov 12 20:24:39 2024 UTC 112s gpgv: using RSA key B8340990283D8D9BC1949AC74799A35146D12B35 112s gpgv: Can't check signature: No public key 112s dpkg-source: warning: cannot verify inline signature for ./knot-resolver_5.7.4-2build1.dsc: no acceptable signature found 113s autopkgtest [03:11:06]: testing package knot-resolver version 5.7.4-2build1 113s autopkgtest [03:11:06]: build not needed 114s autopkgtest [03:11:07]: test roundtrip: preparing testbed 114s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Starting pkgProblemResolver with broken count: 0 115s Starting 2 pkgProblemResolver with broken count: 0 115s Done 115s The following NEW packages will be installed: 115s dns-root-data gnutls-bin knot-dnsutils knot-resolver libdnssec9t64 115s libevent-2.1-7t64 libfstrm0 libgnutls-dane0t64 libknot15 libluajit-5.1-2 115s libluajit-5.1-common libngtcp2-16 libngtcp2-crypto-gnutls8 libunbound8 115s libxdp1 libzscanner4t64 lua-sec lua-socket socat 116s 0 upgraded, 19 newly installed, 0 to remove and 0 not upgraded. 116s Need to get 2944 kB of archives. 116s After this operation, 10.6 MB of additional disk space will be used. 116s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 dns-root-data all 2024071801 [5802 B] 116s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB] 116s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 libunbound8 amd64 1.22.0-1ubuntu1 [519 kB] 116s Get:4 http://ftpmaster.internal/ubuntu questing/main amd64 libgnutls-dane0t64 amd64 3.8.9-2ubuntu3 [24.4 kB] 116s Get:5 http://ftpmaster.internal/ubuntu questing/universe amd64 gnutls-bin amd64 3.8.9-2ubuntu3 [283 kB] 116s Get:6 http://ftpmaster.internal/ubuntu questing/universe amd64 libdnssec9t64 amd64 3.4.4-1 [32.0 kB] 116s Get:7 http://ftpmaster.internal/ubuntu questing/universe amd64 libngtcp2-16 amd64 1.11.0-1 [147 kB] 116s Get:8 http://ftpmaster.internal/ubuntu questing/universe amd64 libngtcp2-crypto-gnutls8 amd64 1.11.0-1 [17.9 kB] 116s Get:9 http://ftpmaster.internal/ubuntu questing/main amd64 libxdp1 amd64 1.5.1-1ubuntu2 [61.2 kB] 116s Get:10 http://ftpmaster.internal/ubuntu questing/universe amd64 libknot15 amd64 3.4.4-1 [123 kB] 116s Get:11 http://ftpmaster.internal/ubuntu questing/universe amd64 libzscanner4t64 amd64 3.4.4-1 [142 kB] 116s Get:12 http://ftpmaster.internal/ubuntu questing/universe amd64 libfstrm0 amd64 0.6.1-1build4 [21.0 kB] 116s Get:13 http://ftpmaster.internal/ubuntu questing/universe amd64 knot-dnsutils amd64 3.4.4-1 [127 kB] 116s Get:14 http://ftpmaster.internal/ubuntu questing/universe amd64 lua-socket amd64 3.1.0-1 [87.7 kB] 116s Get:15 http://ftpmaster.internal/ubuntu questing/universe amd64 lua-sec amd64 1.3.2-2 [40.7 kB] 116s Get:16 http://ftpmaster.internal/ubuntu questing/universe amd64 libluajit-5.1-common all 2.1.0+openresty20250117-2 [54.4 kB] 116s Get:17 http://ftpmaster.internal/ubuntu questing/universe amd64 libluajit-5.1-2 amd64 2.1.0+openresty20250117-2 [356 kB] 116s Get:18 http://ftpmaster.internal/ubuntu questing/universe amd64 knot-resolver amd64 5.7.4-2build1 [356 kB] 116s Get:19 http://ftpmaster.internal/ubuntu questing/main amd64 socat amd64 1.8.0.2-1 [401 kB] 116s Preconfiguring packages ... 117s Fetched 2944 kB in 1s (4176 kB/s) 117s Selecting previously unselected package dns-root-data. 117s (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 ... 80517 files and directories currently installed.) 117s Preparing to unpack .../00-dns-root-data_2024071801_all.deb ... 117s Unpacking dns-root-data (2024071801) ... 117s Selecting previously unselected package libevent-2.1-7t64:amd64. 117s Preparing to unpack .../01-libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ... 117s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 117s Selecting previously unselected package libunbound8:amd64. 117s Preparing to unpack .../02-libunbound8_1.22.0-1ubuntu1_amd64.deb ... 117s Unpacking libunbound8:amd64 (1.22.0-1ubuntu1) ... 117s Selecting previously unselected package libgnutls-dane0t64:amd64. 117s Preparing to unpack .../03-libgnutls-dane0t64_3.8.9-2ubuntu3_amd64.deb ... 117s Unpacking libgnutls-dane0t64:amd64 (3.8.9-2ubuntu3) ... 117s Selecting previously unselected package gnutls-bin. 117s Preparing to unpack .../04-gnutls-bin_3.8.9-2ubuntu3_amd64.deb ... 117s Unpacking gnutls-bin (3.8.9-2ubuntu3) ... 117s Selecting previously unselected package libdnssec9t64. 117s Preparing to unpack .../05-libdnssec9t64_3.4.4-1_amd64.deb ... 117s Unpacking libdnssec9t64 (3.4.4-1) ... 117s Selecting previously unselected package libngtcp2-16:amd64. 117s Preparing to unpack .../06-libngtcp2-16_1.11.0-1_amd64.deb ... 117s Unpacking libngtcp2-16:amd64 (1.11.0-1) ... 117s Selecting previously unselected package libngtcp2-crypto-gnutls8:amd64. 117s Preparing to unpack .../07-libngtcp2-crypto-gnutls8_1.11.0-1_amd64.deb ... 117s Unpacking libngtcp2-crypto-gnutls8:amd64 (1.11.0-1) ... 117s Selecting previously unselected package libxdp1:amd64. 117s Preparing to unpack .../08-libxdp1_1.5.1-1ubuntu2_amd64.deb ... 117s Unpacking libxdp1:amd64 (1.5.1-1ubuntu2) ... 117s Selecting previously unselected package libknot15. 117s Preparing to unpack .../09-libknot15_3.4.4-1_amd64.deb ... 117s Unpacking libknot15 (3.4.4-1) ... 117s Selecting previously unselected package libzscanner4t64. 117s Preparing to unpack .../10-libzscanner4t64_3.4.4-1_amd64.deb ... 117s Unpacking libzscanner4t64 (3.4.4-1) ... 117s Selecting previously unselected package libfstrm0:amd64. 117s Preparing to unpack .../11-libfstrm0_0.6.1-1build4_amd64.deb ... 117s Unpacking libfstrm0:amd64 (0.6.1-1build4) ... 117s Selecting previously unselected package knot-dnsutils. 117s Preparing to unpack .../12-knot-dnsutils_3.4.4-1_amd64.deb ... 117s Unpacking knot-dnsutils (3.4.4-1) ... 117s Selecting previously unselected package lua-socket:amd64. 117s Preparing to unpack .../13-lua-socket_3.1.0-1_amd64.deb ... 117s Unpacking lua-socket:amd64 (3.1.0-1) ... 118s Selecting previously unselected package lua-sec:amd64. 118s Preparing to unpack .../14-lua-sec_1.3.2-2_amd64.deb ... 118s Unpacking lua-sec:amd64 (1.3.2-2) ... 118s Selecting previously unselected package libluajit-5.1-common. 118s Preparing to unpack .../15-libluajit-5.1-common_2.1.0+openresty20250117-2_all.deb ... 118s Unpacking libluajit-5.1-common (2.1.0+openresty20250117-2) ... 118s Selecting previously unselected package libluajit-5.1-2:amd64. 118s Preparing to unpack .../16-libluajit-5.1-2_2.1.0+openresty20250117-2_amd64.deb ... 118s Unpacking libluajit-5.1-2:amd64 (2.1.0+openresty20250117-2) ... 118s Selecting previously unselected package knot-resolver. 118s Preparing to unpack .../17-knot-resolver_5.7.4-2build1_amd64.deb ... 118s Unpacking knot-resolver (5.7.4-2build1) ... 118s Selecting previously unselected package socat. 118s Preparing to unpack .../18-socat_1.8.0.2-1_amd64.deb ... 118s Unpacking socat (1.8.0.2-1) ... 118s Setting up libzscanner4t64 (3.4.4-1) ... 118s Setting up libfstrm0:amd64 (0.6.1-1build4) ... 118s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 118s Setting up lua-socket:amd64 (3.1.0-1) ... 118s Setting up dns-root-data (2024071801) ... 118s Setting up libxdp1:amd64 (1.5.1-1ubuntu2) ... 118s Setting up libunbound8:amd64 (1.22.0-1ubuntu1) ... 118s Setting up libgnutls-dane0t64:amd64 (3.8.9-2ubuntu3) ... 118s Setting up socat (1.8.0.2-1) ... 118s Setting up libdnssec9t64 (3.4.4-1) ... 118s Setting up libluajit-5.1-common (2.1.0+openresty20250117-2) ... 118s Setting up libngtcp2-16:amd64 (1.11.0-1) ... 118s Setting up libngtcp2-crypto-gnutls8:amd64 (1.11.0-1) ... 118s Setting up gnutls-bin (3.8.9-2ubuntu3) ... 118s Setting up lua-sec:amd64 (1.3.2-2) ... 118s Setting up libknot15 (3.4.4-1) ... 118s Setting up libluajit-5.1-2:amd64 (2.1.0+openresty20250117-2) ... 118s Setting up knot-resolver (5.7.4-2build1) ... 118s Created symlink '/etc/systemd/system/kresd.target.wants/kres-cache-gc.service' → '/usr/lib/systemd/system/kres-cache-gc.service'. 118s Created symlink '/etc/systemd/system/multi-user.target.wants/kresd.target' → '/usr/lib/systemd/system/kresd.target'. 119s Setting up knot-dnsutils (3.4.4-1) ... 119s Processing triggers for man-db (2.13.0-1) ... 120s Processing triggers for libc-bin (2.41-6ubuntu1) ... 121s autopkgtest [03:11:14]: test roundtrip: [----------------------- 121s /usr/sbin/kresd + /usr/bin/kdig roundtrip tests 121s ------------ 121s workdir: /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts 121s IP addr: 127.195.51.96 121s kresd args: --addr=127.195.51.96@8053 --tls=127.195.51.96@8853 --noninteractive --config=/tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/kresd.conf --verbose --verbose --verbose 121s 121s make Certificate Authority key and certificate 121s ---------------------------------------------- 121s Generating a 3072 bit RSA private key... 121s Generating a self signed certificate... 121s X.509 Certificate Information: 121s Version: 3 121s Serial Number (hex): 74294f7a649f29fb022821098b1dc12de2e18a91 121s Validity: 121s Not Before: Sun May 04 03:11:14 UTC 2025 121s Not After: Fri May 16 03:11:14 UTC 2025 121s Subject: CN=testing certificate authority (NOT FOR PRODUCTION) 121s Subject Public Key Algorithm: RSA 121s Algorithm Security Level: High (3072 bits) 121s Modulus (bits 3072): 121s 00:c6:e4:23:b3:58:46:72:a8:b5:d3:7b:f6:92:a6:d1 121s 9f:f2:01:cd:18:b1:3b:1e:1a:02:e9:f9:c3:c4:1f:2f 121s 8c:1c:90:8e:f1:94:28:86:0a:61:27:84:6b:fc:57:f8 121s de:e8:43:12:41:a1:e7:88:33:87:79:30:09:e4:2d:24 121s 7b:8b:50:6b:f0:d0:ff:9b:2a:11:55:37:31:b3:6e:bf 121s 2b:ea:50:cd:a9:63:d6:b3:87:0f:ab:da:fa:8d:00:ed 121s 14:47:bf:19:5d:f7:4b:9a:f0:91:36:34:67:84:ba:2b 121s 75:62:40:b8:11:99:6d:ae:3d:b5:2f:ef:69:e4:ef:5d 121s 15:46:21:20:a6:81:9e:64:76:0f:c5:29:ab:85:48:52 121s 51:93:69:00:55:9e:d2:e7:01:a3:2a:b0:30:23:09:72 121s e3:2b:22:d7:65:c2:e0:33:60:6e:2a:4c:fd:72:3d:36 121s c9:54:75:2d:c7:7e:de:a9:6d:92:39:fe:1c:d5:7c:b0 121s 66:21:f2:96:ab:8f:5c:4e:27:4f:82:0b:e7:52:ff:cc 121s 9d:17:21:69:b0:c7:b1:37:94:99:98:c2:d9:f4:33:8e 121s 6b:3f:ac:a6:60:bb:d9:49:bc:80:69:d0:a0:40:6c:25 121s f5:2e:fc:2c:5b:92:23:f5:94:4e:22:ed:81:0e:07:aa 121s 1e:b7:11:12:16:07:ca:64:46:ae:d5:bc:a2:38:d6:24 121s 1d:9c:2b:f4:ee:e8:07:12:f5:e2:6c:c7:d4:cb:42:11 121s 2d:02:6a:4b:75:8b:74:da:7b:4c:ac:2b:94:ac:f0:42 121s 29:71:c5:57:60:52:00:ce:bc:00:4a:da:3c:df:f4:85 121s 76:14:65:f9:2e:8d:87:6d:4f:32:3a:a0:ac:08:73:cc 121s 1c:08:c7:98:fc:d1:30:37:e3:3f:84:51:57:eb:37:2c 121s 9c:a3:53:8e:a0:ed:8f:53:0d:2b:08:a3:86:da:22:fe 121s 2e:24:2a:10:ce:69:20:4e:47:11:8b:39:b0:ac:e1:cd 121s df 121s Exponent (bits 24): 121s 01:00:01 121s Extensions: 121s Basic Constraints (critical): 121s Certificate Authority (CA): TRUE 121s Path Length Constraint: 1 121s Name Constraints (critical): 121s Permitted: 121s DNSname: example 121s Key Usage (critical): 121s Certificate signing. 121s Subject Key Identifier (not critical): 121s e1fe0fca43213700a5510f02dd20f329a79e5c8a 121s Other Information: 121s Public Key ID: 121s sha1:e1fe0fca43213700a5510f02dd20f329a79e5c8a 121s sha256:92292465e11a3de2c227e65e7dcaefefc9762e3ea1b51ae47aba4907c815364f 121s Public Key PIN: 121s pin-sha256:kikkZeEaPeLCJ+Zefcrv78l2Lj6htRrkerpJB8gVNk8= 121s 121s 121s 121s Signing certificate... 121s 121s make Bogus Certificate Authority key and certificate 121s ---------------------------------------------------- 121s Generating a 3072 bit RSA private key... 121s Generating a self signed certificate... 121s X.509 Certificate Information: 121s Version: 3 121s Serial Number (hex): 673e2a577f37b1d4a3bb619be1bc02f10f9e2f91 121s Validity: 121s Not Before: Sun May 04 03:11:15 UTC 2025 121s Not After: Fri May 16 03:11:15 UTC 2025 121s Subject: CN=testing certificate authority (NOT FOR PRODUCTION) 121s Subject Public Key Algorithm: RSA 121s Algorithm Security Level: High (3072 bits) 121s Modulus (bits 3072): 121s 00:a1:b7:4f:58:19:fb:7c:68:c8:9b:f2:99:19:06:a1 121s 1b:af:fd:8a:52:86:f8:2e:5e:01:af:5e:63:09:9c:cb 121s b9:26:e7:89:a1:7b:03:c3:61:28:8b:55:7e:1c:03:89 121s 57:0b:90:8f:ca:a0:9a:84:3e:6c:db:5e:ca:df:b6:67 121s d6:2c:d8:e8:d7:58:c5:e8:b3:99:67:53:63:04:d0:4a 121s 9d:52:31:f4:9e:e4:2e:47:44:fc:f2:ea:b6:f8:a6:cb 121s 00:9d:44:01:68:93:3f:58:8d:e0:5c:35:14:34:07:b3 121s 49:47:46:4e:af:11:44:18:e1:7e:2b:f3:47:4c:43:24 121s 76:26:8d:57:2b:c5:14:af:d3:12:31:53:ef:de:f3:59 121s 14:e9:db:e4:32:ee:c6:e9:bf:c4:e8:ac:5f:8b:e0:dd 121s b6:7e:e2:33:06:0a:a0:7c:b4:d8:20:cb:c4:eb:eb:ad 121s 84:66:6e:5f:d6:9b:b9:a8:80:a1:48:f6:7c:34:4e:89 121s b4:5e:da:21:40:ec:9d:e1:ce:a8:33:87:18:48:55:18 121s 46:4f:7b:86:f8:ac:da:4e:fa:49:35:7d:93:7f:25:47 121s b4:1b:cc:6f:2a:37:47:e1:d6:2a:14:9c:46:b2:63:c1 121s c1:a1:83:79:01:6f:35:23:1f:e7:04:75:2c:c1:fe:46 121s 3d:f7:7c:27:f6:d8:be:60:27:ce:2f:ef:d3:f6:fe:c2 121s 69:f6:24:c1:8f:91:c1:69:1d:ef:0c:45:52:ae:04:06 121s 21:97:fa:b4:13:16:86:7f:1b:18:1a:b8:37:b3:0b:b5 121s 55:59:db:a7:d5:70:60:15:52:3e:c9:8a:7c:53:66:ea 121s 32:d1:50:51:1d:1e:04:e0:49:c0:69:a9:90:6d:a3:98 121s e1:7f:ff:ad:7c:86:cb:5d:a3:26:de:62:74:ca:e3:2b 121s dc:43:d4:c0:3b:af:e6:0b:3e:62:86:3a:f3:f8:f0:69 121s c9:cd:13:db:b7:1e:13:d8:f7:88:53:11:69:31:40:79 121s 61 121s Exponent (bits 24): 121s 01:00:01 121s Extensions: 121s Basic Constraints (critical): 121s Certificate Authority (CA): TRUE 121s Path Length Constraint: 1 121s Name Constraints (critical): 121s Permitted: 121s DNSname: example 121s Key Usage (critical): 121s Certificate signing. 121s Subject Key Identifier (not critical): 121s 460c8bee7cfe4d80a22313daa19cb343402fbab9 121s Other Information: 121s Public Key ID: 121s sha1:460c8bee7cfe4d80a22313daa19cb343402fbab9 121s sha256:29351400cb622bc2f0493a08173094661589dff7ad0afc2d261a60c6c25d8ef8 121s Public Key PIN: 121s pin-sha256:KTUUAMtiK8LwSToIFzCUZhWJ3/etCvwtJhpgxsJdjvg= 121s 121s 121s 121s Signing certificate... 121s 121s make End Entity key and certificate 121s ----------------------------------- 122s Generating a 3072 bit RSA private key... 122s Generating a signed certificate... 122s X.509 Certificate Information: 122s Version: 3 122s Serial Number (hex): 5e59f85cfdfab1f52af433e3768e1c239daeae00 122s Validity: 122s Not Before: Sun May 04 03:11:15 UTC 2025 122s Not After: Wed May 14 03:11:15 UTC 2025 122s Subject: CN=test.example 122s Subject Public Key Algorithm: RSA 122s Algorithm Security Level: High (3072 bits) 122s Modulus (bits 3072): 122s 00:b5:f4:59:bd:ab:df:fa:94:b3:b1:a7:36:a8:32:ab 122s 11:3b:6c:49:89:d6:c9:a0:2a:c5:4e:b0:a0:87:47:a8 122s d1:6b:e7:e9:34:fb:2a:22:34:87:07:5e:02:6d:ce:d3 122s 0f:f7:eb:c5:89:3f:8a:01:a4:30:63:44:17:27:a0:e1 122s 47:53:c6:bc:cb:91:42:02:63:e7:c3:07:b5:44:ae:b3 122s 01:50:83:f9:b4:38:d1:24:32:e4:ab:f6:6b:94:03:99 122s 5d:19:07:72:ca:09:e8:b6:93:05:89:63:c6:e7:5c:0e 122s 71:1c:53:7b:b6:a6:70:db:70:c8:57:15:07:68:ca:d6 122s 9f:2d:28:ec:b8:64:9b:ca:48:78:d8:bf:12:8f:08:82 122s 9f:59:2b:a3:08:f4:06:49:8e:28:ba:e8:82:23:24:5a 122s ea:19:b5:1c:ce:ca:46:d8:7f:0e:79:93:ec:54:ab:a5 122s b0:dc:23:61:92:62:67:5f:b6:5e:c2:19:f8:13:75:78 122s 0d:3d:e4:d4:68:7f:80:e1:b6:e1:97:3f:2d:61:b5:08 122s dc:a9:6b:f0:a1:bd:30:a9:19:dc:f6:a1:ed:f2:f6:72 122s 53:92:34:c5:76:aa:50:35:b2:0d:93:0a:94:f1:7c:92 122s cf:f8:24:17:e7:3c:82:d0:45:d7:29:fb:86:35:dd:37 122s 03:c2:9d:02:aa:27:d2:e0:c0:4f:b7:a3:c6:d2:46:c9 122s b6:f6:10:0e:79:a5:9b:60:12:ed:62:ad:cf:17:5f:0e 122s 58:92:5f:8b:e5:91:41:ae:55:3c:68:c0:c3:c6:83:3d 122s f2:a7:22:ac:b9:2a:29:90:f6:90:fb:6c:39:a0:d9:da 122s 81:3a:ce:b8:47:de:0e:da:5f:e0:73:00:94:39:f4:59 122s 87:e5:18:3f:c0:df:12:43:b9:8d:96:98:e9:3d:f3:d9 122s 49:23:d2:77:93:92:4d:02:44:1c:95:f0:a3:f9:69:41 122s 47:71:9f:29:f5:8e:13:23:c6:cb:03:f6:c3:d3:c9:c9 122s cb 122s Exponent (bits 24): 122s 01:00:01 122s Extensions: 122s Basic Constraints (critical): 122s Certificate Authority (CA): FALSE 122s Subject Alternative Name (not critical): 122s DNSname: test.example 122s Key Purpose (not critical): 122s TLS WWW Server. 122s Key Usage (critical): 122s Digital signature. 122s Subject Key Identifier (not critical): 122s cdfd1e42d25bafe4f456322e4a177813600864de 122s Authority Key Identifier (not critical): 122s e1fe0fca43213700a5510f02dd20f329a79e5c8a 122s Other Information: 122s Public Key ID: 122s sha1:cdfd1e42d25bafe4f456322e4a177813600864de 122s sha256:7ad958a98756384cd4b4738c45719ef538f51a3aaa4c939e4416e47919ff353e 122s Public Key PIN: 122s pin-sha256:etlYqYdWOEzUtHOMRXGe9Tj1GjqqTJOeRBbkeRn/NT4= 122s 122s 122s 122s Signing certificate... 122s 122s set up kresd daemon on 127.195.51.96 on ports 8053 (UDP, TCP) and 8853 (TLS) 122s ---------------------------------------------------------------------------- 123s 123s test UDP with kdig 123s ------------------ 123s successful UDP request to 127.195.51.96 on port 8053 123s 123s test TCP with kdig 123s ------------------ 123s successful TCP request to 127.195.51.96 on port 8053 123s 123s test opportunistic DNS-over-TLS with kdig 123s ----------------------------------------- 123s successful opportunistic DNS-over-TLS request to 127.195.51.96 on port 8853 123s 123s test strict DNS-over-TLS with kdig 123s ---------------------------------- 123s successful strict DNS-over-TLS request to 127.195.51.96 on port 8853 123s 123s test invalid name with strict DNS-over-TLS with kdig 123s ---------------------------------------------------- 123s successful strict DNS-over-TLS request failure when name mismatch to 127.195.51.96 on port 8853 123s 123s test bad authority with strict DNS-over-TLS with kdig 123s ----------------------------------------------------- 123s successful strict DNS-over-TLS request failure to 127.195.51.96 on port 8853 123s 123s cleaning up 123s ----------- 123s 257993 4 drwxrwxrwt 2 root root 4096 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts 123s 258360 4 -rw-r--r-- 1 root root 387 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/kresd.conf 123s 258001 8 -rw------- 1 root root 8167 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/ca-key.pem 123s 258361 72 -rw-r--r-- 1 root root 71722 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/kresd.err 123s 258003 8 -rw------- 1 root root 8177 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/bogus-key.pem 123s 258362 4 -rw-r----- 1 root root 8192 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/lock.mdb 123s 258004 4 -rw-r--r-- 1 root root 1590 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/bogus-cert.pem 123s 258363 102400 -rw-r----- 1 root root 104857600 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/data.mdb 123s 258000 4 -rw-r--r-- 1 root root 136 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/ca.template 123s 258007 4 -rw-r--r-- 1 root root 2218 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/ee-pubkey.pem 123s 258365 4 -rw-r--r-- 1 root root 119 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/badca.err 123s 258006 8 -rw------- 1 root root 8170 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/ee-key.pem 123s 258260 4 -rw-r--r-- 1 root root 1602 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/ee-cert.pem 123s 258005 4 -rw-r--r-- 1 root root 92 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/ee.template 123s 258002 4 -rw-r--r-- 1 root root 1590 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/ca-cert.pem 123s 258364 4 -rw-r--r-- 1 root root 119 May 4 03:11 /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/badname.err 123s ==> /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/badca.err <== 123s ;; WARNING: TLS, handshake failed (Error in the certificate.) 123s ;; ERROR: failed to query server 127.195.51.96@8853(TCP) 123s 123s ==> /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/badname.err <== 123s ;; WARNING: TLS, handshake failed (Error in the certificate.) 123s ;; ERROR: failed to query server 127.195.51.96@8853(TCP) 123s 123s ==> /tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/kresd.err <== 123s [system] increasing file-descriptor limit: 1024 -> 1073741816 123s [tls ] session ticket: epoch 426349, scheduling rotation check in 1324658 ms 123s [wtchdg] disabled in systemd (WatchdogSec= not specified) 123s [ta ] installed trust anchors for domain . are: 123s . 3600 DNSKEY 257 3 8 AwEAAaz/tAm8yTn4Mfeh5eyI96WSVexTBAvkMgJzkKTOiW1vkIbzxeF3+/4RgWOq7HrxRixHlFlExOLAJr5emLvN7SWXgnLh4+B5xQlNVz8Og8kvArMtNROxVQuCaSnIDdD5LKyWbRd2n9WGe2R8PzgCmr3EgVLrjyBxWezF0jLHwVN8efS3rCj/EWgvIWgb9tarpVUDK/b58Da+sqqls3eNbuv7pr+eoZG+SrDK6nWeL3c6H5Apxz7LjVc1uTIdsIXxuOLYA4/ilBmSVIzuDWfdRUfhHdY6+cn8HFRm+2hM8AnXGXws9555KrUB5qihylGa8subX2Nn6UwNR1AkUTV74bU= ; Valid: ; KeyTag:20326 123s . 3600 DNSKEY 257 3 8 AwEAAa96jeuknZlaeSrvyAJj6ZHv28hhOKkx3rLGXVaC6rXTsDc449/cidltpkyGwCJNnOAlFNKF2jBosZBU5eeHspaQWOmOElZsjICMQMC3aeHbGiShvZsx4wMYSjH8e7Vrhbu6irwCzVBApESjbUdpWWmEnhathWu1jo+siFUiRAAxm9qyJNg/wOZqqzL/dL/q8PkcRU5oUKEpUge71M3ej2/7CPqpdVwuMoTvoB+ZOT4YeGyxMvHmbrxlFzGOHOijtzN+u1TQNatX2XBuzZNQ1K+s2CXkPIZo7s6JgZyvaBevYtxPvYLw4z9mR7K2vaF18UYH9Z9GNUUeayffKC73PYc= ; Valid: ; KeyTag:38696 123s 123s [system] loading config '/tmp/autopkgtest.m3K6OJ/roundtrip-artifacts/kresd.conf' (workdir '/tmp/autopkgtest.m3K6OJ/roundtrip-artifacts') 123s [system] deprecation WARNING: use log_level() instead of verbose() 123s [gnutls] (3) ASSERT: ../../../lib/x509/dn.c[_gnutls_x509_compare_raw_dn]:974 123s [gnutls] (3) ASSERT: ../../../lib/x509/dn.c[_gnutls_x509_compare_raw_dn]:974 123s [gnutls] (3) ASSERT: ../../../lib/x509/x509_ext.c[gnutls_subject_alt_names_get]:107 123s [gnutls] (3) ASSERT: ../../../lib/x509/x509.c[get_alt_name]:2012 123s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 123s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 123s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 123s [tls ] RFC 7858 OOB key-pin (0): pin-sha256="" 123s [gnutls] (3) ASSERT: ../../lib/cert-cred-x509.c[gnutls_certificate_get_x509_crt]:872 123s [system] loading config '/usr/lib/x86_64-linux-gnu/knot-resolver/postconfig.lua' (workdir '/tmp/autopkgtest.m3K6OJ/roundtrip-artifacts') 123s [plan ][00000.00] plan '.' type 'NS' uid [65536.00] 123s [iterat][65536.00] '.' type 'NS' new uid was assigned .01, parent uid .00 123s [resolv][65536.01] => using root hints 123s [iterat][65536.01] '.' type 'NS' new uid was assigned .02, parent uid .00 123s [resolv][65536.02] >< TA: '.' 123s [plan ][65536.02] plan '.' type 'DNSKEY' uid [65536.03] 123s [iterat][65536.03] '.' type 'DNSKEY' new uid was assigned .04, parent uid .02 123s [select][65536.04] => id: '18575' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65536.04] => id: '18575' choosing: 'M.ROOT-SERVERS.NET.'@'2001:dc3::35#00053' with timeout 400 ms zone cut: '.' 123s [resolv][65536.04] => id: '18575' querying: 'M.ROOT-SERVERS.NET.'@'2001:dc3::35#00053' zone cut: '.' qname: '.' qtype: 'DNSKEY' proto: 'udp' 123s [plan ][00000.00] plan '.' type 'NS' uid [65537.00] 123s [iterat][65537.00] '.' type 'NS' new uid was assigned .01, parent uid .00 123s [resolv][65537.01] => using root hints 123s [iterat][65537.01] '.' type 'NS' new uid was assigned .02, parent uid .00 123s [select][65537.02] => id: '49787' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65537.02] => id: '49787' choosing: 'I.ROOT-SERVERS.NET.'@'2001:7fe::53#00053' with timeout 400 ms zone cut: '.' 123s [resolv][65537.02] => id: '49787' querying: 'I.ROOT-SERVERS.NET.'@'2001:7fe::53#00053' zone cut: '.' qname: '.' qtype: 'NS' proto: 'udp' 123s [taupd ] refreshing TA for . 123s [plan ][00000.00] plan '.' type 'DNSKEY' uid [65538.00] 123s [iterat][65538.00] '.' type 'DNSKEY' new uid was assigned .01, parent uid .00 123s [resolv][65538.01] => using root hints 123s [iterat][65538.01] '.' type 'DNSKEY' new uid was assigned .02, parent uid .00 123s [resolv][65538.02] >< TA: '.' 123s [select][65538.02] => id: '41704' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65538.02] => id: '41704' choosing: 'B.ROOT-SERVERS.NET.'@'170.247.170.2#00053' with timeout 400 ms zone cut: '.' 123s [select][65536.04] NO6: timed out, appended, timeouts 1/6 123s [select][65536.04] => id: '18575' noting selection error: 'M.ROOT-SERVERS.NET.'@'2001:dc3::35#00053' zone cut: '.' error: 1 QUERY_TIMEOUT 123s [iterat][65538.02] '.' type 'DNSKEY' new uid was assigned .03, parent uid .00 123s [select][65538.03] => id: '46126' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65538.03] => id: '46126' choosing: 'G.ROOT-SERVERS.NET.'@'192.112.36.4#00053' with timeout 400 ms zone cut: '.' 123s [resolv][65538.03] => id: '46126' querying: 'G.ROOT-SERVERS.NET.'@'192.112.36.4#00053' zone cut: '.' qname: '.' qtype: 'DNSKEY' proto: 'udp' 123s [iterat][65536.04] '.' type 'DNSKEY' new uid was assigned .05, parent uid .02 123s [select][65536.05] => id: '09328' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65536.05] => id: '09328' choosing: 'E.ROOT-SERVERS.NET.'@'2001:500:a8::e#00053' with timeout 800 ms zone cut: '.' 123s [select][65537.02] NO6: timed out, appended, timeouts 2/6 123s [select][65537.02] => id: '49787' noting selection error: 'I.ROOT-SERVERS.NET.'@'2001:7fe::53#00053' zone cut: '.' error: 1 QUERY_TIMEOUT 123s [iterat][65537.02] '.' type 'NS' new uid was assigned .03, parent uid .00 123s [select][65537.03] => id: '57477' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65537.03] => id: '57477' choosing: 'K.ROOT-SERVERS.NET.'@'193.0.14.129#00053' with timeout 800 ms zone cut: '.' 123s [resolv][65537.03] => id: '57477' querying: 'K.ROOT-SERVERS.NET.'@'193.0.14.129#00053' zone cut: '.' qname: '.' qtype: 'NS' proto: 'udp' 123s [select][65538.03] => id: '46126' noting selection error: 'G.ROOT-SERVERS.NET.'@'192.112.36.4#00053' zone cut: '.' error: 1 QUERY_TIMEOUT 123s [iterat][65536.05] '.' type 'DNSKEY' new uid was assigned .06, parent uid .02 123s [select][65536.06] => id: '04082' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65536.06] => id: '04082' choosing: 'A.ROOT-SERVERS.NET.'@'2001:503:ba3e::2:30#00053' with timeout 800 ms zone cut: '.' 123s [resolv][65536.06] => id: '04082' querying: 'A.ROOT-SERVERS.NET.'@'2001:503:ba3e::2:30#00053' zone cut: '.' qname: '.' qtype: 'DNSKEY' proto: 'udp' 123s [iterat][65538.03] '.' type 'DNSKEY' new uid was assigned .04, parent uid .00 123s [select][65538.04] => id: '18920' choosing from addresses: 13 v4 + 13 v6; names to resolve: 0 v4 + 0 v6; force_resolve: 0; NO6: IPv6 is OK 123s [select][65538.04] => id: '18920' choosing: 'B.ROOT-SERVERS.NET.'@'2801:1b8:10::b#00053' with timeout 800 ms zone cut: '.' 123s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [45200.00] 123s [iterat][45200.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 123s [hint ][45200.01] <= answered from hints 123s [iterat][45200.01] <= rcode: NOERROR 123s [resolv][45200.01] AD: request NOT classified as SECURE 123s [resolv][45200.01] finished in state: 4, queries: 1, mempool: 16400 B 123s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [14842.00] 123s [iterat][14842.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 123s [hint ][14842.01] <= answered from hints 123s [iterat][14842.01] <= rcode: NOERROR 123s [resolv][14842.01] AD: request NOT classified as SECURE 123s [resolv][14842.01] finished in state: 4, queries: 1, mempool: 81952 B 123s [io ] => connection to '127.0.0.1#49269' closed by peer (end of file) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #0 123s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #1 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.1 Handshake packet received. Epoch 0, length: 310 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Handshake(22) with length: 310 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Handshake(22) with length: 310 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CLIENT HELLO (1) was received. Length 306[306], frag offset 0, frag length: 306, sequence: 0 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Client's version: 3.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Versions/43' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Found version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Negotiated version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'ALPN/16' (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Record Size Limit/28' (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: record_size_limit 16385 negotiated 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Session Ticket/35' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 01 received 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 00 received 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Groups/10' (10 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group X25519 (0x1d) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP256R1 (0x17) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP384R1 (0x18) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP521R1 (0x19) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Selected group X25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.1) RSA-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.9) RSA-PSS-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.7) EdDSA-Ed25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.1) RSA-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.10) RSA-PSS-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.8) EdDSA-Ed448 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.1) RSA-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.11) RSA-PSS-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.1) RSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.3) ECDSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 123s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 123s [gnutls] (3) ASSERT: ../../../lib/ext/server_name.c[gnutls_server_name_get]:234 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Requested server name: '' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: checking compat of GNUTLS_AES_256_GCM_SHA384 with certificate[0] (RSA/X.509) 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 123s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected version TLS1.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: OCSP status was requested 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Key Share/51' (107 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received key share for X25519 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected group X25519 (6) 123s [gnutls] (2) EXT[0x75b7bc0f3000]: server generated X25519 shared key 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Safe renegotiation succeeded 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SessionID: 39bcab93f427b9b1b17ec35add5cc6f7fa95347c90e0742843fa812676722e6a 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: sending key share for X25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Key Share/51 (36 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Supported Versions/43 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SERVER HELLO was queued [122 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 122 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 127 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 123s [gnutls] (4) REC[0x75b7bc0f3000]: Sent ChangeCipherSpec 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #1 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (SRTP/14) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (ALPN/16) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension ALPN/16 (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Key Share/51) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Early Data/42) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Record Size Limit/28 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE was queued [1155 bytes] 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) HSK[0x75b7bc0f3000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 123s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE VERIFY was queued [392 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: sending finished 123s [gnutls] (4) HSK[0x75b7bc0f3000]: FINISHED was queued [52 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 123s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #2 123s [gnutls] (4) HSK[0x75b7bc0f3000]: unauthenticated session eligible for early start 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #2 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: switching early to application traffic keys 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 ChangeCipherSpec packet received. Epoch 1, length: 1 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet ChangeCipherSpec(20) with length: 1 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 Application Data packet received. Epoch 1, length: 69 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Application Data(23) with length: 69 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Handshake(22) with length: 52 123s [gnutls] (4) HSK[0x75b7bc0f3000]: FINISHED (20) was received. Length 48[48], frag offset 0, frag length: 48, sequence: 0 123s [gnutls] (4) HSK[0x75b7bc0f3000]: parsing finished 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 set read key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (5) REC[0x75b7bc0f3000]: Start of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #0 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: End of epoch cleanup 123s [tls ] TLS handshake with 127.0.0.1#52149 has completed 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 Application Data packet received. Epoch 2, length: 147 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Application Data(23) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Application Data(23) with length: 147 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Application Data(23) with length: 130 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 123s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [63197.00] 123s [iterat][63197.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 123s [hint ][63197.01] <= answered from hints 123s [iterat][63197.01] <= rcode: NOERROR 123s [resolv][63197.01] AD: request NOT classified as SECURE 123s [resolv][63197.01] finished in state: 4, queries: 1, mempool: 81952 B 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Application Data(23) with length: 470 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[3] Application Data(23) in epoch 2 and length: 492 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 Application Data packet received. Epoch 2, length: 19 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Application Data(23) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Application Data(23) with length: 19 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[1] Alert(21) with length: 2 123s [gnutls] (5) REC[0x75b7bc0f3000]: Alert[1|0] - Close notify - was received 123s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_in_buffers]:1565 123s [io ] => connection to '127.0.0.1#52149' closed by peer (end of file) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Start of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: End of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #0 123s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #1 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.1 Handshake packet received. Epoch 0, length: 331 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Handshake(22) with length: 331 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Handshake(22) with length: 331 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CLIENT HELLO (1) was received. Length 327[327], frag offset 0, frag length: 327, sequence: 0 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Client's version: 3.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Versions/43' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Found version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Negotiated version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Server Name Indication/0' (17 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Record Size Limit/28' (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: record_size_limit 16385 negotiated 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'ALPN/16' (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 01 received 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 00 received 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Session Ticket/35' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.1) RSA-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.9) RSA-PSS-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.7) EdDSA-Ed25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.1) RSA-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.10) RSA-PSS-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.8) EdDSA-Ed448 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.1) RSA-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.11) RSA-PSS-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.1) RSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.3) ECDSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Groups/10' (10 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group X25519 (0x1d) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP256R1 (0x17) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP384R1 (0x18) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP521R1 (0x19) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Selected group X25519 123s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Requested server name: 'test.example' 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 123s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected version TLS1.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: OCSP status was requested 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Key Share/51' (107 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received key share for X25519 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected group X25519 (6) 123s [gnutls] (2) EXT[0x75b7bc0f3000]: server generated X25519 shared key 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Safe renegotiation succeeded 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SessionID: 32dc2cc587d9e9c304e0763321f98e2e0cc9104d062d4379afb9cac5194fa072 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: sending key share for X25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Key Share/51 (36 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Supported Versions/43 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SERVER HELLO was queued [122 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 122 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 127 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 123s [gnutls] (4) REC[0x75b7bc0f3000]: Sent ChangeCipherSpec 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #1 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (SRTP/14) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (ALPN/16) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension ALPN/16 (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Key Share/51) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Early Data/42) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Record Size Limit/28 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE was queued [1155 bytes] 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) HSK[0x75b7bc0f3000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 123s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE VERIFY was queued [392 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: sending finished 123s [gnutls] (4) HSK[0x75b7bc0f3000]: FINISHED was queued [52 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 123s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #2 123s [gnutls] (4) HSK[0x75b7bc0f3000]: unauthenticated session eligible for early start 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #2 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: switching early to application traffic keys 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 ChangeCipherSpec packet received. Epoch 1, length: 1 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet ChangeCipherSpec(20) with length: 1 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 Application Data packet received. Epoch 1, length: 69 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Application Data(23) with length: 69 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Handshake(22) with length: 52 123s [gnutls] (4) HSK[0x75b7bc0f3000]: FINISHED (20) was received. Length 48[48], frag offset 0, frag length: 48, sequence: 0 123s [gnutls] (4) HSK[0x75b7bc0f3000]: parsing finished 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 set read key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (5) REC[0x75b7bc0f3000]: Start of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #0 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: End of epoch cleanup 123s [tls ] TLS handshake with 127.0.0.1#36339 has completed 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 Application Data packet received. Epoch 2, length: 147 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Application Data(23) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Application Data(23) with length: 147 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Application Data(23) with length: 130 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_int]:1759 123s [plan ][00000.00] plan 'monkeys.example.' type 'A' uid [32808.00] 123s [iterat][32808.00] 'monkeys.example.' type 'A' new uid was assigned .01, parent uid .00 123s [hint ][32808.01] <= answered from hints 123s [iterat][32808.01] <= rcode: NOERROR 123s [resolv][32808.01] AD: request NOT classified as SECURE 123s [resolv][32808.01] finished in state: 4, queries: 1, mempool: 81952 B 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Application Data(23) with length: 470 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[3] Application Data(23) in epoch 2 and length: 492 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 Application Data packet received. Epoch 2, length: 19 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Application Data(23) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Application Data(23) with length: 19 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[1] Alert(21) with length: 2 123s [gnutls] (5) REC[0x75b7bc0f3000]: Alert[1|0] - Close notify - was received 123s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_recv_in_buffers]:1565 123s [io ] => connection to '127.0.0.1#36339' closed by peer (end of file) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Start of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: End of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #0 123s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #1 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.1 Handshake packet received. Epoch 0, length: 335 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Handshake(22) with length: 335 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Handshake(22) with length: 335 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CLIENT HELLO (1) was received. Length 331[331], frag offset 0, frag length: 331, sequence: 0 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Client's version: 3.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Versions/43' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Found version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Negotiated version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Server Name Indication/0' (21 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Record Size Limit/28' (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: record_size_limit 16385 negotiated 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'ALPN/16' (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 01 received 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 00 received 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Session Ticket/35' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.1) RSA-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.9) RSA-PSS-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.7) EdDSA-Ed25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.1) RSA-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.10) RSA-PSS-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.8) EdDSA-Ed448 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.1) RSA-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.11) RSA-PSS-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.1) RSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.3) ECDSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Groups/10' (10 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group X25519 (0x1d) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP256R1 (0x17) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP384R1 (0x18) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP521R1 (0x19) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Selected group X25519 123s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Requested server name: 'notright.example' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: checking compat of GNUTLS_AES_256_GCM_SHA384 with certificate[0] (RSA/X.509) 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 123s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected version TLS1.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Key Share/51' (107 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received key share for X25519 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected group X25519 (6) 123s [gnutls] (2) EXT[0x75b7bc0f3000]: server generated X25519 shared key 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: OCSP status was requested 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Safe renegotiation succeeded 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SessionID: 79257adebd512784b79d2acaceb2604ca8f44f3350db0804d5b325cd9d866e8a 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: sending key share for X25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Key Share/51 (36 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Supported Versions/43 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SERVER HELLO was queued [122 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 122 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 127 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 123s [gnutls] (4) REC[0x75b7bc0f3000]: Sent ChangeCipherSpec 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #1 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (SRTP/14) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (ALPN/16) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension ALPN/16 (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Key Share/51) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Early Data/42) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Record Size Limit/28 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE was queued [1155 bytes] 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) HSK[0x75b7bc0f3000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 123s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE VERIFY was queued [392 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: sending finished 123s [gnutls] (4) HSK[0x75b7bc0f3000]: FINISHED was queued [52 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 123s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #2 123s [gnutls] (4) HSK[0x75b7bc0f3000]: unauthenticated session eligible for early start 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #2 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: switching early to application traffic keys 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 123s [tls ] uv_try_write error: connection reset by peer 123s [gnutls] (2) WRITE: -1 returned from 0x75b7bc0e3300, errno: 5 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[errno_to_gerr]:218 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_write_flush]:693 123s [gnutls] (3) ASSERT: ../../lib/handshake-tls13.c[_gnutls13_handshake_server]:536 123s [tls ] gnutls_handshake failed: GNUTLS_E_PUSH_ERROR (-53) 123s [gnutls] (5) REC: Sending Alert[2|80] - Internal error 123s [tls ] uv_try_write error: broken pipe 123s [gnutls] (2) WRITE: -1 returned from 0x75b7bc0e3300, errno: 5 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[errno_to_gerr]:218 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_write_flush]:693 123s [gnutls] (3) ASSERT: ../../lib/record.c[_gnutls_send_tlen_int]:586 123s [io ] => connection to '127.0.0.1#60037': error processing TLS data, close 123s [gnutls] (5) REC[0x75b7bc0f3000]: Start of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #0 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: End of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #0 123s [gnutls] (2) added 3 protocols, 29 ciphersuites, 19 sig algos and 10 groups into priority list 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #1 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.1 Handshake packet received. Epoch 0, length: 331 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet Handshake(22) with length: 331 123s [gnutls] (5) REC[0x75b7bc0f3000]: Decrypted Packet[0] Handshake(22) with length: 331 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CLIENT HELLO (1) was received. Length 327[327], frag offset 0, frag length: 327, sequence: 0 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Client's version: 3.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Versions/43' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Found version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Negotiated version: 3.4 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Record Size Limit/28' (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: record_size_limit 16385 negotiated 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Server Name Indication/0' (17 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'ALPN/16' (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Safe Renegotiation/65281' (1 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Extended Master Secret/23' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Encrypt-then-MAC/22' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Signature Algorithms/13' (34 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.1) RSA-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.9) RSA-PSS-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.4) RSA-PSS-RSAE-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (4.3) ECDSA-SECP256R1-SHA256 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.7) EdDSA-Ed25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.1) RSA-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.10) RSA-PSS-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.5) RSA-PSS-RSAE-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (5.3) ECDSA-SECP384R1-SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.8) EdDSA-Ed448 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.1) RSA-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.11) RSA-PSS-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (8.6) RSA-PSS-RSAE-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (6.3) ECDSA-SECP521R1-SHA512 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.1) RSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: rcvd signature algo (2.3) ECDSA-SHA1 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Session Ticket/35' (0 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported Groups/10' (10 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group X25519 (0x1d) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP256R1 (0x17) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP384R1 (0x18) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received group SECP521R1 (0x19) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Selected group X25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Supported EC Point Formats/11' (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'PSK Key Exchange Modes/45' (3 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 01 received 123s [gnutls] (4) EXT[0x75b7bc0f3000]: PSK KE mode 00 received 123s [gnutls] (2) checking 13.02 (GNUTLS_AES_256_GCM_SHA384) for compatibility 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Requested server name: 'test.example' 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) Selected signature algorithm: RSA-PSS-RSAE-SHA256 123s [gnutls] (2) Selected (RSA) cert based on ciphersuite 13.2: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected version TLS1.3 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'Key Share/51' (107 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Received key share for X25519 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Selected group X25519 (6) 123s [gnutls] (2) EXT[0x75b7bc0f3000]: server generated X25519 shared key 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Parsing extension 'OCSP Status Request/5' (5 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: OCSP status was requested 123s [gnutls] (4) HSK[0x75b7bc0f3000]: Safe renegotiation succeeded 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SessionID: a6255f9e654765a314652f4641e47f265ba4408a6cbc187eae540275e9b7b8cb 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Client Certificate Type/19) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Certificate Type/20) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Groups/10) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (SRTP/14) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ALPN/16) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Key Share/51) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: sending key share for X25519 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Key Share/51 (36 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Versions/43) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Supported Versions/43 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Server Name Indication/0) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Early Data/42) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (PSK Key Exchange Modes/45) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Record Size Limit/28) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Maximum Record Size/1) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'TLS 1.3 server hello' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Pre Shared Key/41) for 'TLS 1.3 server hello' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: SERVER HELLO was queued [122 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 122 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 0 and length: 127 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet ChangeCipherSpec(20) with length: 1 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] ChangeCipherSpec(20) in epoch 0 and length: 6 123s [gnutls] (4) REC[0x75b7bc0f3000]: Sent ChangeCipherSpec 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #1 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 re-key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (OCSP Status Request/5) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Client Certificate Type/19) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Certificate Type/20) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Supported Groups/10) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported EC Point Formats/11) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Signature Algorithms/13) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (SRTP/14) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (ALPN/16) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension ALPN/16 (6 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Encrypt-then-MAC/22) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Extended Master Secret/23) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Session Ticket/35) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Key Share/51) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Supported Versions/43) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Post Handshake Auth/49) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Safe Renegotiation/65281) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Server Name Indication/0) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Cookie/44) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Early Data/42) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (PSK Key Exchange Modes/45) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Record Size Limit/28) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Sending extension Record Size Limit/28 (2 bytes) 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Preparing extension (Maximum Record Size/1) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Compress Certificate/27) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (ClientHello Padding/21) for 'encrypted extensions' 123s [gnutls] (4) EXT[0x75b7bc0f3000]: Not sending extension (Pre Shared Key/41) for 'encrypted extensions' 123s [gnutls] (4) HSK[0x75b7bc0f3000]: ENCRYPTED EXTENSIONS was queued [22 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE was queued [1155 bytes] 123s [gnutls] (4) checking cert compat with RSA-SHA256 123s [gnutls] (3) ASSERT: ../../../lib/ext/signature.c[_gnutls_session_sign_algo_enabled]:428 123s [gnutls] (4) Signature algorithm RSA-SHA256 is not enabled 123s [gnutls] (4) checking cert compat with RSA-PSS-SHA256 123s [gnutls] (4) checking cert compat with RSA-PSS-RSAE-SHA256 123s [gnutls] (4) HSK[0x75b7bc0f3000]: signing TLS 1.3 handshake data: using RSA-PSS-RSAE-SHA256 and PRF: SHA384 123s [gnutls] (3) ASSERT: ../../../lib/nettle/mpi.c[wrap_nettle_mpi_print]:59 123s [gnutls] (4) HSK[0x75b7bc0f3000]: CERTIFICATE VERIFY was queued [392 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: sending finished 123s [gnutls] (4) HSK[0x75b7bc0f3000]: FINISHED was queued [52 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 22 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 1 and length: 44 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 1155 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 1 and length: 1177 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 392 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[3] Handshake(22) in epoch 1 and length: 414 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 52 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[4] Handshake(22) in epoch 1 and length: 74 123s [gnutls] (3) ASSERT: ../../lib/constate.c[_gnutls_epoch_get]:965 123s [gnutls] (5) REC[0x75b7bc0f3000]: Allocating epoch #2 123s [gnutls] (4) HSK[0x75b7bc0f3000]: unauthenticated session eligible for early start 123s [gnutls] (5) REC[0x75b7bc0f3000]: Initializing epoch #2 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 ready 123s [gnutls] (4) HSK[0x75b7bc0f3000]: TLS 1.3 set write key with cipher suite: GNUTLS_AES_256_GCM_SHA384 123s [gnutls] (4) HSK[0x75b7bc0f3000]: switching early to application traffic keys 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (4) HSK[0x75b7bc0f3000]: NEW SESSION TICKET was queued [251 bytes] 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[1] Handshake(22) in epoch 2 and length: 273 123s [gnutls] (5) REC[0x75b7bc0f3000]: Preparing Packet Handshake(22) with length: 251 and min pad: 0 123s [gnutls] (5) REC[0x75b7bc0f3000]: Sent Packet[2] Handshake(22) in epoch 2 and length: 273 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[get_last_packet]:1138 123s [gnutls] (5) REC[0x75b7bc0f3000]: SSL 3.3 ChangeCipherSpec packet received. Epoch 1, length: 1 123s [gnutls] (5) REC[0x75b7bc0f3000]: Expected Packet Handshake(22) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Received Packet ChangeCipherSpec(20) with length: 1 123s [gnutls] (3) ASSERT: ../../lib/buffers.c[_gnutls_io_read_buffered]:568 123s [gnutls] (3) ASSERT: ../../lib/tls13/finished.c[_gnutls13_recv_finished]:91 123s [io ] => connection to '127.0.0.1#50761' closed by peer (connection reset by peer) 123s [gnutls] (5) REC[0x75b7bc0f3000]: Start of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #0 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: End of epoch cleanup 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #1 freed 123s [gnutls] (5) REC[0x75b7bc0f3000]: Epoch #2 freed 123s > 123s > autopkgtest [03:11:16]: test roundtrip: -----------------------] 124s roundtrip PASS 124s autopkgtest [03:11:17]: test roundtrip: - - - - - - - - - - results - - - - - - - - - - 128s autopkgtest [03:11:21]: @@@@@@@@@@@@@@@@@@@@ summary 128s roundtrip PASS 157s nova [W] Skipping flock for amd64 157s Creating nova instance adt-questing-amd64-knot-resolver-20250504-030913-juju-7f2275-prod-proposed-migration-environment-23-b67ecf09-5b48-4513-b3cf-cb6e240b034e from image adt/ubuntu-questing-amd64-server-20250503.img (UUID 57b7c1b6-ad80-4bdb-b031-36f045f7f3b3)... 157s nova [W] Timed out waiting for 6379edac-1b39-4586-b45f-f237619cb2eb to get deleted.