autosign not waiting long enough for zone to be signed v9_11 and maybe others

Job #661978 failed for 8d0b59a5:

I:autosign:checking scheduled key activation (72)
I:autosign:waiting for changes to take effect
I:autosign:failed

echo_i "checking scheduled key activation ($n)"
ret=0
$SETTIME -K ns3 -A now+3s $zsk > settime.out.test$n.zsk || ret=1
$SETTIME -K ns3 -A now+3s $ksk > settime.out.test$n.ksk || ret=1
($RNDCCMD 10.53.0.3 loadkeys delay.example. 2>&1 | sed 's/^/ns2 /' | cat_i) || ret=1
echo_i "waiting for changes to take effect"
sleep 3
wait_for_notifies "delay.example" "ns3" || ret=1
$DIG $DIGOPTS +noall +answer dnskey delay.example. @10.53.0.3 > dig.out.ns3.1.test$n || ret=1
# DNSKEY expected:
awk 'BEGIN {r=1} $4=="DNSKEY" {r=0} END {exit r}' dig.out.ns3.1.test$n || ret=1
# RRSIG expected:
awk 'BEGIN {r=1} $4=="RRSIG" {r=0} END {exit r}' dig.out.ns3.1.test$n || ret=1
$DIG $DIGOPTS +noall +answer a a.delay.example. @10.53.0.3 > dig.out.ns3.2.test$n || ret=1
# A expected:
awk 'BEGIN {r=1} $4=="A" {r=0} END {exit r}' dig.out.ns3.2.test$n || ret=1
# RRSIG expected:
awk 'BEGIN {r=1} $4=="RRSIG" {r=0} END {exit r}' dig.out.ns3.2.test$n || ret=1
n=`expr $n + 1`
if [ $ret != 0 ]; then echo_i "failed"; fi
status=`expr $status + $ret

head autosign/*test72*
==> autosign/dig.out.ns3.1.test72 <==
delay.example.		300	IN	DNSKEY	256 3 7 AwEAAcpWc1D81h1jDWcL3DuvIgB9cqYNusfcPMyh0/GAypygpJHaOGlV dbPbEbwtgjv9Kj0LYGhswjesQcTjayJAgQfqFeh1QPcq5TpNGUvptybS F+iw3Vnc6QqZZ9uW0H9dcpmWrcSxp3z+FAqfqC+eEgp7jQog9DHcGrqS XKRDLuR/
delay.example.		300	IN	DNSKEY	257 3 7 AwEAAc1i4SBAfPGkfKeVCHbhKsZ3zNPAvfWTZUQQ7jXu8APcv4/zFeyr IXs8e1itifPNpbZrOqT0lx/4sEH0eYOKaliWYDJd3A+gIpRhHcUTQQmM mQsSVkk/zXWCnJpTHxtiz8nT2cAwcKrUKdh8rSOfs/MfHjV657qCKgK4 UCWkl/H0vARcnKD0xE261GzKWq3YPu5+XnFOcXC5Bn7aceUjhrCmm7Lc DJBXjdg+t7kKH+AFZoN95qQnCKFnfg72nH4cITZz1Bca/W+PRKuCjsn5 TIkobYSZGlUU+8xgQToIZv7D544SfyOctvGL+J8SXL2zhHIcfKEdrWcy siWZDuMzAVc=

==> autosign/dig.out.ns3.2.test72 <==
a.delay.example.	300	IN	A	10.0.0.1

==> autosign/settime.out.test72.ksk <==
ns3/Kdelay.example.+007+64764.key
ns3/Kdelay.example.+007+64764.private

==> autosign/settime.out.test72.zsk <==
ns3/Kdelay.example.+007+07832.key
ns3/Kdelay.example.+007+07832.private

11-Feb-2020 13:09:24.248 received control channel command 'loadkeys delay.example.'

11-Feb-2020 13:09:27.250 writing to journal
11-Feb-2020 13:09:27.250 add re-sign delay.example.             300     IN      RRSIG   DNSKEY 7 2 300 20200312130927 20200211120927 7832 delay.example. WGIO8hlCkXJ+3U4yDLsdPI14suvhF/ATYA4ulGVjbwre0TLkXf7cmhf4 VaNGbRiFpYY/r9Lv0GXWjqjUbZET20raH4ngQmHz1lKrHdHqvXEkqZW4 DNIM9StLs1ylke5WNT4nT7SGkWibycBa+CAdcFOix1m4oK/WSbR+ihto zoU=
11-Feb-2020 13:09:27.250 add re-sign delay.example.             300     IN      RRSIG   DNSKEY 7 2 300 20200312130927 20200211120927 64764 delay.example. vEIGbSFdYF5UNouyrnrGO3peuKkZYNqp1I8qeEsM1Lm5HTNWFPtndp4N 0uPkv1PN3yPqpNL74LuCU5fy8RnziVS+V5ggDzpOi3dio9yb0vh4PJdP 0W0ltY3IJXRmgTjpYxZRlvP2kT3Lozg+bg2gerQAUMY2A3YjKycG/sCl 2Im1dIEi/3o/+PUdgWRYB3UHVizMgpBWo/JEAF2lvEIeV7HQd1f/RnFc hUYaTicKSENkvwxzT/7bcNU3+w69Ot1zk5PYe8Xiwou2MyV3VoVufG2F 2tGxia0QqzHTSJe1mahnowhgGp8zAPTnHRWkd+mH+do66HBgEQdFgEYc SDFIvA==
11-Feb-2020 13:09:28.691 zone_needdump: zone delay.example/IN: enter

11-Feb-2020 13:09:28.692 zone delay.example/IN: sending notifies (serial 2009102724)

11-Feb-2020 13:09:28.694 writing to journal
11-Feb-2020 13:09:28.694 add re-sign a.delay.example.   300     IN      RRSIG   A 7 3 300 20200228153842 20200211120928 7832 delay.example. vn8NKJWewQk2d6ZyiRpeNb5uvnbRDOoFfY+7d2+IjWdtXAJp40EIItxn GnfQEv1XjsbhbM6NspiSE/p6tI2lz2pu/U/UNwSaOn5iFrhU4qE24Nvs kK3dJWBMF8vGL6U5bwDMwTBdtaQeh2JVUQj9oX2E3vlDOLQNmCPTLt7p bmA=

11-Feb-2020 13:09:29.473 client @0x7f261c1e4d90 10.53.0.1#54783 (delay.example): query 'delay.example/DNSKEY/IN' approved
11-Feb-2020 13:09:29.489 client @0x7f2630f8ddc0 10.53.0.1#59149 (a.delay.example): query 'a.delay.example/A/IN' approved

11-Feb-2020 13:09:31.874 zone_needdump: zone delay.example/IN: enter

11-Feb-2020 13:09:31.903 writing to journal
11-Feb-2020 13:09:31.904 add delay.example.             3600    IN      NSEC    a.delay.example. NS SOA RRSIG NSEC DNSKEY
11-Feb-2020 13:09:31.909 zone_needdump: zone delay.example/IN: enter

11-Feb-2020 13:09:33.692 zone delay.example/IN: sending notifies (serial 2009102726)