Skip to content

Instantly share code, notes, and snippets.

@patryk
Last active June 15, 2018 15:09
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save patryk/9b0a655a5ff85b104a4ea4820829f194 to your computer and use it in GitHub Desktop.
Save patryk/9b0a655a5ff85b104a4ea4820829f194 to your computer and use it in GitHub Desktop.
Knot DNS Resolver, version 2.3.0
--Automatically generated file; DO NOT EDIT
modules = {
'hints > iterate'
, 'policy'
, 'stats'
, predict = {
window = 30 -- 30 minutes sampling window
, period = 24*(60/30) -- track last 24 hours
}
}
hints.config('/tmp/kresd/hints.tmp')
net.bufsize(4096)
net.ipv4=true
net.ipv6=true
cache.open(20*MB)
--- Included custom configuration file from: ---
--- /etc/kresd/kresd.custom.conf
policy.add(policy.all(policy.TLS_FORWARD({
{'1.1.1.1', insecure=true},
{'2606:4700:4700::1111', insecure=true},
{'1.0.0.1', insecure=true},
{'2606:4700:4700::1001', insecure=true}
})))
2018-06-15 11:59:16 info kresd[17550]: [ 0][plan] plan 'repo.turris.cz.' type 'A'
2018-06-15 11:59:16 info kresd[17550]: [38048][iter] 'repo.turris.cz.' type 'A' id was assigned, parent id 0
2018-06-15 11:59:16 info kresd[17550]: [38048][cach] => skipping unfit CNAME RR: rank 060, new TTL -13809
2018-06-15 11:59:16 info kresd[17550]: [38048][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [38048][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [38048][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [38048][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [38048][plan] plan '.' type 'DNSKEY'
2018-06-15 11:59:16 info kresd[17550]: [10167][iter] '.' type 'DNSKEY' id was assigned, parent id 38048
2018-06-15 11:59:16 info kresd[17550]: [10167][cach] => satisfied by exact RR or CNAME: rank 060, new TTL 1662
2018-06-15 11:59:16 info kresd[17550]: [10167][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [10167][vldr] <= parent: updating DNSKEY
2018-06-15 11:59:16 info kresd[17550]: [10167][vldr] <= answer valid, OK
2018-06-15 11:59:16 info kresd[17550]: [65105][iter] 'repo.turris.cz.' type 'A' id was assigned, parent id 0
2018-06-15 11:59:16 info kresd[17550]: [65105][plan] plan 'cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [22558][iter] 'cz.' type 'DS' id was assigned, parent id 65105
2018-06-15 11:59:16 info kresd[17550]: [22558][cach] => satisfied by exact RR or CNAME: rank 060, new TTL 7640
2018-06-15 11:59:16 info kresd[17550]: [22558][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [22558][vldr] <= DS: OK
2018-06-15 11:59:16 info kresd[17550]: [22558][vldr] <= parent: updating DS
2018-06-15 11:59:16 info kresd[17550]: [22558][vldr] <= answer valid, OK
2018-06-15 11:59:16 info kresd[17550]: [12669][iter] 'repo.turris.cz.' type 'A' id was assigned, parent id 0
2018-06-15 11:59:16 info kresd[17550]: [12669][plan] plan 'cz.' type 'DNSKEY'
2018-06-15 11:59:16 info kresd[17550]: [ 3595][iter] 'cz.' type 'DNSKEY' id was assigned, parent id 12669
2018-06-15 11:59:16 info kresd[17550]: [ 3595][cach] => satisfied by exact RR or CNAME: rank 060, new TTL 3410
2018-06-15 11:59:16 info kresd[17550]: [ 3595][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [ 3595][vldr] <= parent: updating DNSKEY
2018-06-15 11:59:16 info kresd[17550]: [ 3595][vldr] <= answer valid, OK
2018-06-15 11:59:16 info kresd[17550]: [49179][iter] 'repo.turris.cz.' type 'A' id was assigned, parent id 0
2018-06-15 11:59:16 info kresd[17550]: [49179][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [11078][iter] 'turris.cz.' type 'DS' id was assigned, parent id 49179
2018-06-15 11:59:16 info kresd[17550]: [11078][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [11078][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [11078][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [11078][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [11078][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 12 for 2606:4700:4700::1111; cached RTT: 12
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [11078][resl] => querying: '2606:4700:4700::1111' score: 12 zone cut: 'cz.' qname: 'tUrRIs.cZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3337] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3336] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [11078][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [11078][resl] <= server: '2606:4700:4700::1111' rtt: 4 ms
2018-06-15 11:59:16 info kresd[17550]: [11078][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [11078][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [11078][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [16196][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 11078
2018-06-15 11:59:16 info kresd[17550]: [16196][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 11 for 2606:4700:4700::1111; cached RTT: 11
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [16196][resl] => querying: '2606:4700:4700::1111' score: 11 zone cut: 'cz.' qname: 'TUrRis.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3338] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3337] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [16196][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [16196][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [16196][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [16196][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [16196][resl] <= server: '2606:4700:4700::1111' rtt: 38 ms
2018-06-15 11:59:16 info kresd[17550]: [14085][iter] 'turris.cz.' type 'DS' id was assigned, parent id 16196
2018-06-15 11:59:16 info kresd[17550]: [14085][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [14085][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [14085][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [14085][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [14085][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 24 for 2606:4700:4700::1111; cached RTT: 24
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [14085][resl] => querying: '2606:4700:4700::1111' score: 24 zone cut: 'cz.' qname: 'tUrrIS.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3339] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3338] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [14085][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [14085][resl] <= server: '2606:4700:4700::1111' rtt: 2 ms
2018-06-15 11:59:16 info kresd[17550]: [14085][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [14085][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [14085][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [64393][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 14085
2018-06-15 11:59:16 info kresd[17550]: [64393][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 17 for 2606:4700:4700::1111; cached RTT: 17
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [64393][resl] => querying: '2606:4700:4700::1111' score: 17 zone cut: 'cz.' qname: 'TURRiS.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3340] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3339] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [64393][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [64393][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [64393][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [64393][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [64393][resl] <= server: '2606:4700:4700::1111' rtt: 87 ms
2018-06-15 11:59:16 info kresd[17550]: [41904][iter] 'turris.cz.' type 'DS' id was assigned, parent id 64393
2018-06-15 11:59:16 info kresd[17550]: [41904][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [41904][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [41904][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [41904][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [41904][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 52 for 2606:4700:4700::1111; cached RTT: 52
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [41904][resl] => querying: '2606:4700:4700::1111' score: 52 zone cut: 'cz.' qname: 'turriS.CZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3341] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3340] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [41904][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [41904][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [41904][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [41904][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [41904][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [58257][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 41904
2018-06-15 11:59:16 info kresd[17550]: [58257][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 31 for 2606:4700:4700::1111; cached RTT: 31
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [58257][resl] => querying: '2606:4700:4700::1111' score: 31 zone cut: 'cz.' qname: 'TuRrIs.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3342] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3341] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [58257][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [58257][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [58257][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [58257][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [58257][resl] <= server: '2606:4700:4700::1111' rtt: 35 ms
2018-06-15 11:59:16 info kresd[17550]: [ 2267][iter] 'turris.cz.' type 'DS' id was assigned, parent id 58257
2018-06-15 11:59:16 info kresd[17550]: [ 2267][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [ 2267][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [ 2267][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [ 2267][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [ 2267][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 33 for 2606:4700:4700::1111; cached RTT: 33
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ 2267][resl] => querying: '2606:4700:4700::1111' score: 33 zone cut: 'cz.' qname: 'TUrRIs.cZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3343] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3342] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [ 2267][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [ 2267][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [ 2267][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [ 2267][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [ 2267][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [17129][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 2267
2018-06-15 11:59:16 info kresd[17550]: [17129][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 22 for 2606:4700:4700::1111; cached RTT: 22
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [17129][resl] => querying: '2606:4700:4700::1111' score: 22 zone cut: 'cz.' qname: 'turrIS.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3344] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3343] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [17129][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [17129][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [17129][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [17129][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [17129][resl] <= server: '2606:4700:4700::1111' rtt: 101 ms
2018-06-15 11:59:16 info kresd[17550]: [17129][resl] <= server: '1.1.1.1' rtt: >= 1 ms
2018-06-15 11:59:16 info kresd[17550]: [31599][iter] 'turris.cz.' type 'DS' id was assigned, parent id 17129
2018-06-15 11:59:16 info kresd[17550]: [31599][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [31599][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [31599][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [31599][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [31599][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 61 for 2606:4700:4700::1111; cached RTT: 61
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [31599][resl] => querying: '2606:4700:4700::1111' score: 61 zone cut: 'cz.' qname: 'tuRRiS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3345] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3344] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [31599][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [31599][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [31599][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [31599][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [31599][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [ 7299][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 31599
2018-06-15 11:59:16 info kresd[17550]: [ 7299][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 36 for 2606:4700:4700::1111; cached RTT: 36
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ 7299][resl] => querying: '2606:4700:4700::1111' score: 36 zone cut: 'cz.' qname: 'tuRRiS.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3346] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3345] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [ 7299][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [ 7299][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [ 7299][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [ 7299][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [ 7299][resl] <= server: '2606:4700:4700::1111' rtt: 37 ms
2018-06-15 11:59:16 info kresd[17550]: [47740][iter] 'turris.cz.' type 'DS' id was assigned, parent id 7299
2018-06-15 11:59:16 info kresd[17550]: [47740][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [47740][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [47740][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [47740][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [47740][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 36 for 2606:4700:4700::1111; cached RTT: 36
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [47740][resl] => querying: '2606:4700:4700::1111' score: 36 zone cut: 'cz.' qname: 'TurRIS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3347] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3346] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [47740][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [47740][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [47740][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [47740][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [47740][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [17705][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 47740
2018-06-15 11:59:16 info kresd[17550]: [17705][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 23 for 2606:4700:4700::1111; cached RTT: 23
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [17705][resl] => querying: '2606:4700:4700::1111' score: 23 zone cut: 'cz.' qname: 'turrIs.cZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3348] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3347] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [17705][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [17705][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [17705][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [17705][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [17705][resl] <= server: '2606:4700:4700::1111' rtt: 116 ms
2018-06-15 11:59:16 info kresd[17550]: [17705][resl] <= server: '1.1.1.1' rtt: >= 16 ms
2018-06-15 11:59:16 info kresd[17550]: [58737][iter] 'turris.cz.' type 'DS' id was assigned, parent id 17705
2018-06-15 11:59:16 info kresd[17550]: [58737][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [58737][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [58737][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [58737][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [58737][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 1 for 2606:4700:4700::1111; cached RTT: 69
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [58737][resl] => querying: '2606:4700:4700::1111' score: 1 zone cut: 'cz.' qname: 'TUrris.CZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3349] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3348] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [58737][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [58737][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [58737][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [58737][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [58737][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [44676][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 58737
2018-06-15 11:59:16 info kresd[17550]: [44676][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 1 for 2606:4700:4700::1111; cached RTT: 40
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [44676][resl] => querying: '2606:4700:4700::1111' score: 1 zone cut: 'cz.' qname: 'tUrRIS.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3350] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3349] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [44676][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [44676][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [44676][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [44676][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [44676][resl] <= server: '2606:4700:4700::1111' rtt: 35 ms
2018-06-15 11:59:16 info kresd[17550]: [44016][iter] 'turris.cz.' type 'DS' id was assigned, parent id 44676
2018-06-15 11:59:16 info kresd[17550]: [44016][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [44016][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [44016][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [44016][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [44016][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 1 for 2606:4700:4700::1111; cached RTT: 37
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [44016][resl] => querying: '2606:4700:4700::1111' score: 1 zone cut: 'cz.' qname: 'TuRriS.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3351] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3350] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [44016][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [44016][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [44016][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [44016][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [44016][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [53858][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 44016
2018-06-15 11:59:16 info kresd[17550]: [53858][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 24 for 2606:4700:4700::1111; cached RTT: 24
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [53858][resl] => querying: '2606:4700:4700::1111' score: 24 zone cut: 'cz.' qname: 'tUrRiS.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3352] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3351] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [53858][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [53858][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [53858][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [53858][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [53858][resl] <= server: '2606:4700:4700::1111' rtt: 16 ms
2018-06-15 11:59:16 info kresd[17550]: [ 5792][iter] 'turris.cz.' type 'DS' id was assigned, parent id 53858
2018-06-15 11:59:16 info kresd[17550]: [ 5792][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [ 5792][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [ 5792][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [ 5792][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [ 5792][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 20 for 2606:4700:4700::1111; cached RTT: 20
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ 5792][resl] => querying: '2606:4700:4700::1111' score: 20 zone cut: 'cz.' qname: 'turRIS.cZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3353] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3352] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [ 5792][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [ 5792][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [ 5792][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [ 5792][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [ 5792][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [34516][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 5792
2018-06-15 11:59:16 info kresd[17550]: [34516][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 15 for 2606:4700:4700::1111; cached RTT: 15
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [34516][resl] => querying: '2606:4700:4700::1111' score: 15 zone cut: 'cz.' qname: 'TUrris.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3354] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3353] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [34516][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [34516][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [34516][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [34516][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [34516][resl] <= server: '2606:4700:4700::1111' rtt: 121 ms
2018-06-15 11:59:16 info kresd[17550]: [34516][resl] <= server: '1.1.1.1' rtt: >= 21 ms
2018-06-15 11:59:16 info kresd[17550]: [22917][iter] 'turris.cz.' type 'DS' id was assigned, parent id 34516
2018-06-15 11:59:16 info kresd[17550]: [22917][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [22917][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [22917][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [22917][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [22917][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 68 for 2606:4700:4700::1111; cached RTT: 68
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [22917][resl] => querying: '2606:4700:4700::1111' score: 68 zone cut: 'cz.' qname: 'turRIS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3355] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3354] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [22917][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [22917][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [22917][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [22917][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [22917][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [ 6719][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 22917
2018-06-15 11:59:16 info kresd[17550]: [ 6719][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 39 for 2606:4700:4700::1111; cached RTT: 39
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ 6719][resl] => querying: '2606:4700:4700::1111' score: 39 zone cut: 'cz.' qname: 'tUrRIs.cZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3356] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3355] Application Data(23) with length: 1406
2018-06-15 11:59:16 info kresd[17550]: [ 6719][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [ 6719][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [ 6719][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:16 info kresd[17550]: [ 6719][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:16 info kresd[17550]: [ 6719][resl] <= server: '2606:4700:4700::1111' rtt: 16 ms
2018-06-15 11:59:16 info kresd[17550]: [39850][iter] 'turris.cz.' type 'DS' id was assigned, parent id 6719
2018-06-15 11:59:16 info kresd[17550]: [39850][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1187
2018-06-15 11:59:16 info kresd[17550]: [39850][cach] => skipping unfit NS RR: rank 060, new TTL -15575
2018-06-15 11:59:16 info kresd[17550]: [39850][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:16 info kresd[17550]: [39850][cach] => trying zone: .
2018-06-15 11:59:16 info kresd[17550]: [39850][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 27 for 2606:4700:4700::1111; cached RTT: 27
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [39850][resl] => querying: '2606:4700:4700::1111' score: 27 zone cut: 'cz.' qname: 'turrIs.cZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3357] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3356] Application Data(23) with length: 470
2018-06-15 11:59:16 info kresd[17550]: [39850][iter] <= rcode: NOERROR
2018-06-15 11:59:16 info kresd[17550]: [39850][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:16 info kresd[17550]: [39850][resl] => resuming yielded answer
2018-06-15 11:59:16 info kresd[17550]: [39850][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:16 info kresd[17550]: [39850][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:16 info kresd[17550]: [21940][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 39850
2018-06-15 11:59:16 info kresd[17550]: [21940][cach] => skipping RR type RRSIG
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 19 for 2606:4700:4700::1111; cached RTT: 19
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:16 info kresd[17550]: [21940][resl] => querying: '2606:4700:4700::1111' score: 19 zone cut: 'cz.' qname: 'TuRRis.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:16 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3358] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:16 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3357] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [21940][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [21940][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [21940][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [21940][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [21940][resl] <= server: '2606:4700:4700::1111' rtt: 91 ms
2018-06-15 11:59:17 info kresd[17550]: [48926][iter] 'turris.cz.' type 'DS' id was assigned, parent id 21940
2018-06-15 11:59:17 info kresd[17550]: [48926][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [48926][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [48926][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [48926][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [48926][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 55 for 2606:4700:4700::1111; cached RTT: 55
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [48926][resl] => querying: '2606:4700:4700::1111' score: 55 zone cut: 'cz.' qname: 'tURRIs.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3359] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3358] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [48926][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [48926][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [48926][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [48926][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [48926][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [17134][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 48926
2018-06-15 11:59:17 info kresd[17550]: [17134][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 33 for 2606:4700:4700::1111; cached RTT: 33
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [17134][resl] => querying: '2606:4700:4700::1111' score: 33 zone cut: 'cz.' qname: 'TUrris.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3360] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3359] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [17134][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [17134][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [17134][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [17134][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [17134][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [49256][iter] 'turris.cz.' type 'DS' id was assigned, parent id 17134
2018-06-15 11:59:17 info kresd[17550]: [49256][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [49256][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [49256][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [49256][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [49256][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 25 for 2606:4700:4700::1111; cached RTT: 25
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [49256][resl] => querying: '2606:4700:4700::1111' score: 25 zone cut: 'cz.' qname: 'tuRRiS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3361] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3360] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [49256][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [49256][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [49256][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [49256][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [49256][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [25554][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 49256
2018-06-15 11:59:17 info kresd[17550]: [25554][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [25554][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'TUrrIS.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3362] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3361] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [25554][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [25554][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [25554][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [25554][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [25554][resl] <= server: '2606:4700:4700::1111' rtt: 96 ms
2018-06-15 11:59:17 info kresd[17550]: [ 6248][iter] 'turris.cz.' type 'DS' id was assigned, parent id 25554
2018-06-15 11:59:17 info kresd[17550]: [ 6248][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [ 6248][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [ 6248][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [ 6248][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [ 6248][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 57 for 2606:4700:4700::1111; cached RTT: 57
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ 6248][resl] => querying: '2606:4700:4700::1111' score: 57 zone cut: 'cz.' qname: 'TuRRIS.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3363] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3362] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [ 6248][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [ 6248][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [ 6248][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [ 6248][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [ 6248][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [24416][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 6248
2018-06-15 11:59:17 info kresd[17550]: [24416][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 34 for 2606:4700:4700::1111; cached RTT: 34
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [24416][resl] => querying: '2606:4700:4700::1111' score: 34 zone cut: 'cz.' qname: 'tURRis.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3364] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3363] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [24416][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [24416][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [24416][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [24416][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [24416][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [45790][iter] 'turris.cz.' type 'DS' id was assigned, parent id 24416
2018-06-15 11:59:17 info kresd[17550]: [45790][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [45790][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [45790][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [45790][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [45790][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 25 for 2606:4700:4700::1111; cached RTT: 25
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [45790][resl] => querying: '2606:4700:4700::1111' score: 25 zone cut: 'cz.' qname: 'tURRiS.CZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3365] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3364] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [45790][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [45790][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [45790][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [45790][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [45790][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [40918][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 45790
2018-06-15 11:59:17 info kresd[17550]: [40918][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [40918][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'TurRIS.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3366] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3365] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [40918][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [40918][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [40918][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [40918][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [40918][resl] <= server: '2606:4700:4700::1111' rtt: 100 ms
2018-06-15 11:59:17 info kresd[17550]: [40918][resl] <= server: '1.1.1.1' rtt: >= 0 ms
2018-06-15 11:59:17 info kresd[17550]: [62472][iter] 'turris.cz.' type 'DS' id was assigned, parent id 40918
2018-06-15 11:59:17 info kresd[17550]: [62472][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [62472][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [62472][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [62472][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [62472][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 59 for 2606:4700:4700::1111; cached RTT: 59
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [62472][resl] => querying: '2606:4700:4700::1111' score: 59 zone cut: 'cz.' qname: 'turRis.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3367] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3366] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [62472][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [62472][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [62472][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [62472][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [62472][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [ 1632][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 62472
2018-06-15 11:59:17 info kresd[17550]: [ 1632][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 1 for 2606:4700:4700::1111; cached RTT: 35
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ 1632][resl] => querying: '2606:4700:4700::1111' score: 1 zone cut: 'cz.' qname: 'turris.cZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3368] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3367] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [ 1632][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [ 1632][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [ 1632][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [ 1632][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [ 1632][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [23701][iter] 'turris.cz.' type 'DS' id was assigned, parent id 1632
2018-06-15 11:59:17 info kresd[17550]: [23701][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [23701][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [23701][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [23701][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [23701][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 26 for 2606:4700:4700::1111; cached RTT: 26
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [23701][resl] => querying: '2606:4700:4700::1111' score: 26 zone cut: 'cz.' qname: 'TUrRiS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3369] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3368] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [23701][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [23701][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [23701][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [23701][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [23701][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [ 9561][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 23701
2018-06-15 11:59:17 info kresd[17550]: [ 9561][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ 9561][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'tUrris.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3370] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3369] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [ 9561][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [ 9561][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [ 9561][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [ 9561][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [ 9561][resl] <= server: '2606:4700:4700::1111' rtt: 99 ms
2018-06-15 11:59:17 info kresd[17550]: [ 4152][iter] 'turris.cz.' type 'DS' id was assigned, parent id 9561
2018-06-15 11:59:17 info kresd[17550]: [ 4152][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [ 4152][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [ 4152][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [ 4152][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [ 4152][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 58 for 2606:4700:4700::1111; cached RTT: 58
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ 4152][resl] => querying: '2606:4700:4700::1111' score: 58 zone cut: 'cz.' qname: 'TUrRiS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3371] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3370] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [ 4152][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [ 4152][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [ 4152][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [ 4152][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [ 4152][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [38917][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 4152
2018-06-15 11:59:17 info kresd[17550]: [38917][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 34 for 2606:4700:4700::1111; cached RTT: 34
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [38917][resl] => querying: '2606:4700:4700::1111' score: 34 zone cut: 'cz.' qname: 'turRis.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3372] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3371] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [38917][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [38917][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [38917][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [38917][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [38917][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [61696][iter] 'turris.cz.' type 'DS' id was assigned, parent id 38917
2018-06-15 11:59:17 info kresd[17550]: [61696][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [61696][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [61696][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [61696][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [61696][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 25 for 2606:4700:4700::1111; cached RTT: 25
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [61696][resl] => querying: '2606:4700:4700::1111' score: 25 zone cut: 'cz.' qname: 'TURrIs.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3373] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3372] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [61696][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [61696][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [61696][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [61696][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [61696][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [10374][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 61696
2018-06-15 11:59:17 info kresd[17550]: [10374][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [10374][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'turrIS.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3374] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3373] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [10374][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [10374][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [10374][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [10374][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [10374][resl] <= server: '2606:4700:4700::1111' rtt: 101 ms
2018-06-15 11:59:17 info kresd[17550]: [10374][resl] <= server: '1.1.1.1' rtt: >= 1 ms
2018-06-15 11:59:17 info kresd[17550]: [ 3303][iter] 'turris.cz.' type 'DS' id was assigned, parent id 10374
2018-06-15 11:59:17 info kresd[17550]: [ 3303][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [ 3303][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [ 3303][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [ 3303][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [ 3303][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 59 for 2606:4700:4700::1111; cached RTT: 59
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ 3303][resl] => querying: '2606:4700:4700::1111' score: 59 zone cut: 'cz.' qname: 'TurrIs.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3375] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3374] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [ 3303][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [ 3303][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [ 3303][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [ 3303][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [ 3303][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [36539][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 3303
2018-06-15 11:59:17 info kresd[17550]: [36539][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 35 for 2606:4700:4700::1111; cached RTT: 35
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [36539][resl] => querying: '2606:4700:4700::1111' score: 35 zone cut: 'cz.' qname: 'TUrRiS.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3376] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3375] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [36539][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [36539][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [36539][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [36539][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [36539][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [52452][iter] 'turris.cz.' type 'DS' id was assigned, parent id 36539
2018-06-15 11:59:17 info kresd[17550]: [52452][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [52452][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [52452][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [52452][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [52452][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 26 for 2606:4700:4700::1111; cached RTT: 26
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [52452][resl] => querying: '2606:4700:4700::1111' score: 26 zone cut: 'cz.' qname: 'tuRRiS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3377] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3376] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [52452][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [52452][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [52452][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [52452][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [52452][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [46643][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 52452
2018-06-15 11:59:17 info kresd[17550]: [46643][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [46643][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'TURRIS.cZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3378] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3377] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [46643][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [46643][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [46643][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [46643][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [46643][resl] <= server: '2606:4700:4700::1111' rtt: 104 ms
2018-06-15 11:59:17 info kresd[17550]: [46643][resl] <= server: '1.1.1.1' rtt: >= 4 ms
2018-06-15 11:59:17 info kresd[17550]: [16974][iter] 'turris.cz.' type 'DS' id was assigned, parent id 46643
2018-06-15 11:59:17 info kresd[17550]: [16974][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [16974][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [16974][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [16974][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [16974][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 61 for 2606:4700:4700::1111; cached RTT: 61
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [16974][resl] => querying: '2606:4700:4700::1111' score: 61 zone cut: 'cz.' qname: 'TURRiS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3379] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3378] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [16974][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [16974][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [16974][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [16974][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [16974][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [ 4219][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 16974
2018-06-15 11:59:17 info kresd[17550]: [ 4219][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 36 for 2606:4700:4700::1111; cached RTT: 36
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ 4219][resl] => querying: '2606:4700:4700::1111' score: 36 zone cut: 'cz.' qname: 'TurRis.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3380] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3379] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [ 4219][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [ 4219][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [ 4219][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [ 4219][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [ 4219][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [13853][iter] 'turris.cz.' type 'DS' id was assigned, parent id 4219
2018-06-15 11:59:17 info kresd[17550]: [13853][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [13853][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [13853][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [13853][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [13853][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 26 for 2606:4700:4700::1111; cached RTT: 26
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [13853][resl] => querying: '2606:4700:4700::1111' score: 26 zone cut: 'cz.' qname: 'tURrIS.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3381] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3380] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [13853][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [13853][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [13853][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [13853][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [13853][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [31051][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 13853
2018-06-15 11:59:17 info kresd[17550]: [31051][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [31051][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'TUrRiS.cZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3382] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3381] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [31051][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [31051][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [31051][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [31051][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [31051][resl] <= server: '2606:4700:4700::1111' rtt: 103 ms
2018-06-15 11:59:17 info kresd[17550]: [31051][resl] <= server: '1.1.1.1' rtt: >= 3 ms
2018-06-15 11:59:17 info kresd[17550]: [ 5837][iter] 'turris.cz.' type 'DS' id was assigned, parent id 31051
2018-06-15 11:59:17 info kresd[17550]: [ 5837][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [ 5837][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [ 5837][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [ 5837][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [ 5837][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 60 for 2606:4700:4700::1111; cached RTT: 60
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ 5837][resl] => querying: '2606:4700:4700::1111' score: 60 zone cut: 'cz.' qname: 'TuRRiS.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3383] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3382] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [ 5837][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [ 5837][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [ 5837][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [ 5837][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [ 5837][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [19775][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 5837
2018-06-15 11:59:17 info kresd[17550]: [19775][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 1 for 2606:4700:4700::1111; cached RTT: 35
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [19775][resl] => querying: '2606:4700:4700::1111' score: 1 zone cut: 'cz.' qname: 'tuRrIs.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3384] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3383] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [19775][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [19775][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [19775][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [19775][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [19775][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [22239][iter] 'turris.cz.' type 'DS' id was assigned, parent id 19775
2018-06-15 11:59:17 info kresd[17550]: [22239][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [22239][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [22239][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [22239][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [22239][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 26 for 2606:4700:4700::1111; cached RTT: 26
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [22239][resl] => querying: '2606:4700:4700::1111' score: 26 zone cut: 'cz.' qname: 'tUrRiS.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3385] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3384] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [22239][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [22239][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [22239][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [22239][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [22239][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [33635][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 22239
2018-06-15 11:59:17 info kresd[17550]: [33635][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 1 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [33635][resl] => querying: '2606:4700:4700::1111' score: 1 zone cut: 'cz.' qname: 'turrIs.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3386] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3385] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [33635][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [33635][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [33635][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [33635][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [33635][resl] <= server: '2606:4700:4700::1111' rtt: 102 ms
2018-06-15 11:59:17 info kresd[17550]: [33635][resl] <= server: '1.1.1.1' rtt: >= 2 ms
2018-06-15 11:59:17 info kresd[17550]: [16045][iter] 'turris.cz.' type 'DS' id was assigned, parent id 33635
2018-06-15 11:59:17 info kresd[17550]: [16045][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [16045][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [16045][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [16045][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [16045][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 60 for 2606:4700:4700::1111; cached RTT: 60
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [16045][resl] => querying: '2606:4700:4700::1111' score: 60 zone cut: 'cz.' qname: 'turRis.cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3387] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3386] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [16045][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [16045][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [16045][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [16045][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [16045][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [10331][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 16045
2018-06-15 11:59:17 info kresd[17550]: [10331][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 35 for 2606:4700:4700::1111; cached RTT: 35
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [10331][resl] => querying: '2606:4700:4700::1111' score: 35 zone cut: 'cz.' qname: 'tURRis.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3388] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3387] Application Data(23) with length: 1406
2018-06-15 11:59:17 info kresd[17550]: [10331][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [10331][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [10331][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:17 info kresd[17550]: [10331][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:17 info kresd[17550]: [10331][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:17 info kresd[17550]: [11598][iter] 'turris.cz.' type 'DS' id was assigned, parent id 10331
2018-06-15 11:59:17 info kresd[17550]: [11598][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1188
2018-06-15 11:59:17 info kresd[17550]: [11598][cach] => skipping unfit NS RR: rank 060, new TTL -15576
2018-06-15 11:59:17 info kresd[17550]: [11598][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:17 info kresd[17550]: [11598][cach] => trying zone: .
2018-06-15 11:59:17 info kresd[17550]: [11598][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 26 for 2606:4700:4700::1111; cached RTT: 26
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [11598][resl] => querying: '2606:4700:4700::1111' score: 26 zone cut: 'cz.' qname: 'tURRis.CZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3389] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3388] Application Data(23) with length: 470
2018-06-15 11:59:17 info kresd[17550]: [11598][iter] <= rcode: NOERROR
2018-06-15 11:59:17 info kresd[17550]: [11598][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:17 info kresd[17550]: [11598][resl] => resuming yielded answer
2018-06-15 11:59:17 info kresd[17550]: [11598][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:17 info kresd[17550]: [11598][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:17 info kresd[17550]: [36630][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 11598
2018-06-15 11:59:17 info kresd[17550]: [36630][cach] => skipping RR type RRSIG
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:17 info kresd[17550]: [36630][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'Turris.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:17 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3390] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:17 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3389] Application Data(23) with length: 1406
2018-06-15 11:59:18 info kresd[17550]: [36630][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [36630][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [36630][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:18 info kresd[17550]: [36630][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [36630][resl] <= server: '2606:4700:4700::1111' rtt: 101 ms
2018-06-15 11:59:18 info kresd[17550]: [36630][resl] <= server: '1.1.1.1' rtt: >= 1 ms
2018-06-15 11:59:18 info kresd[17550]: [46556][iter] 'turris.cz.' type 'DS' id was assigned, parent id 36630
2018-06-15 11:59:18 info kresd[17550]: [46556][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1189
2018-06-15 11:59:18 info kresd[17550]: [46556][cach] => skipping unfit NS RR: rank 060, new TTL -15577
2018-06-15 11:59:18 info kresd[17550]: [46556][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:18 info kresd[17550]: [46556][cach] => trying zone: .
2018-06-15 11:59:18 info kresd[17550]: [46556][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 59 for 2606:4700:4700::1111; cached RTT: 59
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [46556][resl] => querying: '2606:4700:4700::1111' score: 59 zone cut: 'cz.' qname: 'tURrIS.Cz.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3391] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3390] Application Data(23) with length: 470
2018-06-15 11:59:18 info kresd[17550]: [46556][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [46556][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:18 info kresd[17550]: [46556][resl] => resuming yielded answer
2018-06-15 11:59:18 info kresd[17550]: [46556][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:18 info kresd[17550]: [46556][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:18 info kresd[17550]: [23387][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 46556
2018-06-15 11:59:18 info kresd[17550]: [23387][cach] => skipping RR type RRSIG
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 35 for 2606:4700:4700::1111; cached RTT: 35
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [23387][resl] => querying: '2606:4700:4700::1111' score: 35 zone cut: 'cz.' qname: 'tuRris.cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3392] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3391] Application Data(23) with length: 1406
2018-06-15 11:59:18 info kresd[17550]: [23387][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [23387][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [23387][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:18 info kresd[17550]: [23387][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [23387][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:18 info kresd[17550]: [ 7179][iter] 'turris.cz.' type 'DS' id was assigned, parent id 23387
2018-06-15 11:59:18 info kresd[17550]: [ 7179][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1189
2018-06-15 11:59:18 info kresd[17550]: [ 7179][cach] => skipping unfit NS RR: rank 060, new TTL -15577
2018-06-15 11:59:18 info kresd[17550]: [ 7179][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:18 info kresd[17550]: [ 7179][cach] => trying zone: .
2018-06-15 11:59:18 info kresd[17550]: [ 7179][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 26 for 2606:4700:4700::1111; cached RTT: 26
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ 7179][resl] => querying: '2606:4700:4700::1111' score: 26 zone cut: 'cz.' qname: 'tURRIS.cZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3393] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3392] Application Data(23) with length: 470
2018-06-15 11:59:18 info kresd[17550]: [ 7179][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [ 7179][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:18 info kresd[17550]: [ 7179][resl] => resuming yielded answer
2018-06-15 11:59:18 info kresd[17550]: [ 7179][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:18 info kresd[17550]: [ 7179][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:18 info kresd[17550]: [55486][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 7179
2018-06-15 11:59:18 info kresd[17550]: [55486][cach] => skipping RR type RRSIG
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [55486][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'TUrris.Cz.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3394] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3393] Application Data(23) with length: 1406
2018-06-15 11:59:18 info kresd[17550]: [55486][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [55486][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [55486][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:18 info kresd[17550]: [55486][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [55486][resl] <= server: '2606:4700:4700::1111' rtt: 103 ms
2018-06-15 11:59:18 info kresd[17550]: [55486][resl] <= server: '1.1.1.1' rtt: >= 3 ms
2018-06-15 11:59:18 info kresd[17550]: [52990][iter] 'turris.cz.' type 'DS' id was assigned, parent id 55486
2018-06-15 11:59:18 info kresd[17550]: [52990][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1189
2018-06-15 11:59:18 info kresd[17550]: [52990][cach] => skipping unfit NS RR: rank 060, new TTL -15577
2018-06-15 11:59:18 info kresd[17550]: [52990][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:18 info kresd[17550]: [52990][cach] => trying zone: .
2018-06-15 11:59:18 info kresd[17550]: [52990][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 60 for 2606:4700:4700::1111; cached RTT: 60
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [52990][resl] => querying: '2606:4700:4700::1111' score: 60 zone cut: 'cz.' qname: 'TUrrIs.CZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3395] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3394] Application Data(23) with length: 470
2018-06-15 11:59:18 info kresd[17550]: [52990][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [52990][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:18 info kresd[17550]: [52990][resl] => resuming yielded answer
2018-06-15 11:59:18 info kresd[17550]: [52990][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:18 info kresd[17550]: [52990][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:18 info kresd[17550]: [38204][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 52990
2018-06-15 11:59:18 info kresd[17550]: [38204][cach] => skipping RR type RRSIG
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 35 for 2606:4700:4700::1111; cached RTT: 35
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [38204][resl] => querying: '2606:4700:4700::1111' score: 35 zone cut: 'cz.' qname: 'TURRIs.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3396] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3395] Application Data(23) with length: 1406
2018-06-15 11:59:18 info kresd[17550]: [38204][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [38204][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [38204][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:18 info kresd[17550]: [38204][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [38204][resl] <= server: '2606:4700:4700::1111' rtt: 17 ms
2018-06-15 11:59:18 info kresd[17550]: [37448][iter] 'turris.cz.' type 'DS' id was assigned, parent id 38204
2018-06-15 11:59:18 info kresd[17550]: [37448][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1189
2018-06-15 11:59:18 info kresd[17550]: [37448][cach] => skipping unfit NS RR: rank 060, new TTL -15577
2018-06-15 11:59:18 info kresd[17550]: [37448][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:18 info kresd[17550]: [37448][cach] => trying zone: .
2018-06-15 11:59:18 info kresd[17550]: [37448][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 26 for 2606:4700:4700::1111; cached RTT: 26
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [37448][resl] => querying: '2606:4700:4700::1111' score: 26 zone cut: 'cz.' qname: 'tURRiS.CZ.' qtype: 'DS' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3397] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 494
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3396] Application Data(23) with length: 470
2018-06-15 11:59:18 info kresd[17550]: [37448][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [37448][resl] <= server: '2606:4700:4700::1111' rtt: 3 ms
2018-06-15 11:59:18 info kresd[17550]: [37448][resl] => resuming yielded answer
2018-06-15 11:59:18 info kresd[17550]: [37448][vldr] >< no valid RRSIGs found for turris.cz. DS
2018-06-15 11:59:18 info kresd[17550]: [37448][plan] plan 'turris.cz.' type 'RRSIG'
2018-06-15 11:59:18 info kresd[17550]: [ 1401][iter] 'turris.cz.' type 'RRSIG' id was assigned, parent id 37448
2018-06-15 11:59:18 info kresd[17550]: [ 1401][cach] => skipping RR type RRSIG
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 18 for 2606:4700:4700::1111; cached RTT: 18
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ 1401][resl] => querying: '2606:4700:4700::1111' score: 18 zone cut: 'cz.' qname: 'tUrRIs.CZ.' qtype: 'RRSIG' proto: 'tcp'
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Preparing Packet Application Data(23) with length: 40 and min pad: 0
2018-06-15 11:59:18 info kresd[17550]: [ 0][wrkr] [tls_client] push 69 <0x20f1540>
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Sent Packet[3398] Application Data(23) in epoch 1 and length: 69
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: SSL 3.3 Application Data packet received. Epoch 0, length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Expected Packet Application Data(23)
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Received Packet Application Data(23) with length: 1430
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (5) REC[0x1dfe220]: Decrypted Packet[3397] Application Data(23) with length: 1406
2018-06-15 11:59:18 info kresd[17550]: [ 1401][iter] <= rcode: NOERROR
2018-06-15 11:59:18 info kresd[17550]: [ 1401][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [ 1401][vldr] >< cut changed, needs revalidation
2018-06-15 11:59:18 info kresd[17550]: [ 1401][plan] plan 'turris.cz.' type 'DS'
2018-06-15 11:59:18 info kresd[17550]: [ 1401][resl] <= server: '2606:4700:4700::1111' rtt: 101 ms
2018-06-15 11:59:18 info kresd[17550]: [ 1401][resl] <= server: '1.1.1.1' rtt: >= 1 ms
2018-06-15 11:59:18 info kresd[17550]: [26613][iter] 'turris.cz.' type 'DS' id was assigned, parent id 1401
2018-06-15 11:59:18 info kresd[17550]: [26613][cach] => skipping exact RR: rank 060 (min. 030), new TTL -1189
2018-06-15 11:59:18 info kresd[17550]: [26613][cach] => skipping unfit NS RR: rank 060, new TTL -15577
2018-06-15 11:59:18 info kresd[17550]: [26613][cach] => not even root NS in cache, but let's try NSEC
2018-06-15 11:59:18 info kresd[17550]: [26613][cach] => trying zone: .
2018-06-15 11:59:18 info kresd[17550]: [26613][cach] => NSEC sname: range search found stale or insecure entry
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 59 for 2606:4700:4700::1111; cached RTT: 59
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.1.1.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 1.0.0.1; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ ][nsre] score 2000 for 2606:4700:4700::1001; cached RTT: 2000
2018-06-15 11:59:18 info kresd[17550]: [ 0][resl] AD: secure (start)
2018-06-15 11:59:18 info kresd[17550]: [ 0][resl] AD: secure (between ANS and AUTH)
2018-06-15 11:59:18 info kresd[17550]: [ 0][resl] AD: secure (1)
2018-06-15 11:59:18 info kresd[17550]: [26613][resl] finished: 0, queries: 3, mempool: 508128 B
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: buffers.c[_gnutls_io_read_buffered]:587
2018-06-15 11:59:18 info kresd[17550]: [gnutls] (3) ASSERT: record.c[_gnutls_recv_int]:1473
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment