inline system test failed
Job #485428 failed for 68b9df92:
I:inline:testing updating dynamic serial via 'rndc signing -serial' (64)
I:inline:failed
I:inline:testing updating dynamic serial via 'rndc signing -serial' with negative change (65)[beetle:~/git/bind9/art] marka% cat bin/tests/system/inline/dig.out.ns2.pre.test64
; <<>> DiG 9.15.7 <<>> +tcp +dnssec -p 8600 bits. SOA @10.53.0.2
;; global options: +cmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 6398
;; flags: qr aa rd; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 1
;; WARNING: recursion requested but not available
;; OPT PSEUDOSECTION:
; EDNS: version: 0, flags: do; udp: 4096
; COOKIE: d002aaae9aa2d2bb010000005df81fd0cd03a161bb275167 (good)
;; QUESTION SECTION:
;bits. IN SOA
;; ANSWER SECTION:
bits. 0 IN SOA ns2.bits. . 2011072460 20 20 1814400 3600
;; Query time: 8 msec
;; SERVER: 10.53.0.2#8600(10.53.0.2)
;; WHEN: Tue Dec 17 00:22:40 UTC 2019
;; MSG SIZE rcvd: 100
[beetle:~/git/bind9/art] marka% [beetle:~/git/bind9/art] marka% cat bin/tests/system/inline/dig.out.ns2.post.test64
; <<>> DiG 9.15.7 <<>> +tcp +dnssec -p 8600 bits. SOA @10.53.0.2
;; global options: +cmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 12194
;; flags: qr aa rd; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 1
;; WARNING: recursion requested but not available
;; OPT PSEUDOSECTION:
; EDNS: version: 0, flags: do; udp: 4096
; COOKIE: bcfc93baa244520a010000005df81fd1404c83bd457e385e (good)
;; QUESTION SECTION:
;bits. IN SOA
;; ANSWER SECTION:
bits. 0 IN SOA ns2.bits. . 2011072460 20 20 1814400 3600
;; Query time: 8 msec
;; SERVER: 10.53.0.2#8600(10.53.0.2)
;; WHEN: Tue Dec 17 00:22:41 UTC 2019
;; MSG SIZE rcvd: 100
[beetle:~/git/bind9/art] marka% 17-Dec-2019 00:22:40.182 received control channel command 'signing -serial 2011072470 bits'
17-Dec-2019 00:22:40.182 socket 0x7f358802d008: socket_recv: event 0x7f35c5273440 -> task 0x7f35c5272020
17-Dec-2019 00:22:40.182 setserial: zone bits/IN: enter
17-Dec-2019 00:22:40.182 zone bits/IN: could not get zone keys for secure dynamic update
17-Dec-2019 00:22:40.182 zone_journal: zone bits/IN: enter
17-Dec-2019 00:22:40.182 writing to journal
17-Dec-2019 00:22:40.182 del bits. 0 IN SOA ns2.bits. . 2011072460 20 20 1814400 3600
17-Dec-2019 00:22:40.182 add bits. 0 IN SOA ns2.bits. . 2011072470 20 20 1814400 3600
17-Dec-2019 00:22:40.182 sockmgr 0x7f35c5260020 thread 0: watcher got message -3 for socket 64
17-Dec-2019 00:22:40.182 sockmgr 0x7f35c5260020 thread 0: watcher got message -2 for socket -1
17-Dec-2019 00:22:40.182 socket 0x7f358802d008: internal_recv: event 0x7f35c5273440 -> task 0x7f35c5272020
17-Dec-2019 00:22:41.838 clientmgr @0x7f35c51ed020: clientmctx
17-Dec-2019 00:22:41.838 clientmgr @0x7f35c51ed020 attach: 18
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (<unknown-query>): query_reset
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 (no-peer): ns_client_newnamebuf
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 (no-peer): ns_client_newnamebuf: done
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 (no-peer): client_setup
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 (no-peer): allocate new client
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369: TCP request
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369: using view '_default'
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369: request is not signed
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369: recursion not available
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369: query
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (<unknown-query>): ns_query_start
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): qctx_init
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): client attr:0x22311, query attr:0x700, restarts:0, origqname:bits, timer:0, authdb:0, referral:0
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): ns__query_start
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): query 'bits/SOA/IN' approved
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_lookup
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): ns_client_getnamebuf
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): ns_client_getnamebuf: done
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): ns_client_newname
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): ns_client_newname: done
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_gotanswer
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): rrl=(nil), HAVECOOKIE=0, result=ISC_R_SUCCESS, fname=0x7f358c01a2e0(1), is_zone=1, RECURSIONOK=0, query.rpz_st=(nil)(0), RRL_CHECKED=0
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_checkrpz
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): rpz_rewrite
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_prepresponse
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_zerottl_refetch
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_respond
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_getexpire
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_addanswer
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_addrrset
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): ns_client_keepname
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_setorder
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_additional
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_additional: done
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_addrrset: done
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_addnoqnameproof
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_addauth
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): ns_query_done
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): send
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): senddone
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): reset client
17-Dec-2019 00:22:41.838 client @0x7f358c057bb0 10.53.0.1#53369 (bits): endrequest
17-Dec-2019 00:22:41.838 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (bits/SOA): query_reset
17-Dec-2019 00:22:41.846 client @0x7f358c057bb0 10.53.0.1#53369: freeing client
17-Dec-2019 00:22:42.038 query client=0x7f358c057bb0 thread=0x7f35c04d8700 (<unknown-query>): query_reset
17-Dec-2019 00:22:42.038 clientmgr @0x7f35c51ed020 detach: 17
17-Dec-2019 00:22:42.046 zone_needdump: zone bits/IN: enter
17-Dec-2019 00:22:42.046 zone_settimer: zone bits/IN: enter
17-Dec-2019 00:22:42.078 socket 0x7f358802d008: destroying
17-Dec-2019 00:22:42.078 sockmgr 0x7f35c5260020 thread 0: watcher got message -5 for socket 64
17-Dec-2019 00:22:42.078 sockmgr 0x7f35c5260020 thread 0: watcher got message -2 for socket -1