Opened 8 years ago

Closed 8 years ago

Last modified 8 years ago

#1834 closed defect (fixed)

ZONEMGR_REFRESH_ZONE happening too often

Reported by: jreed Owned by:
Priority: medium Milestone:
Component: secondary manager Version:
Keywords: Cc:
CVSS Scoring: Parent Tickets:
Sensitive: no Defect Severity: Medium
Sub-Project: DNS Feature Depending on Ticket:
Estimated Difficulty: 3 Add Hours to Ticket: 0
Total Hours: 0 Internal?: no

Description

Maybe this is related to #1786.

In about 30 hours, ZONEMGR_REFRESH_ZONE happened 3570 times.

2012-03-21 14:21:15.q DEBUG [b10-zonemgr.zonemgr] ZONEMGR_REFRESH_ZONE refreshing zone bind10.isc.org. (class IN)
2012-03-21 14:21:15.q DEBUG [b10-xfrin.datasrc] DATASRC_SQLITE_NEWCONN SQLite3Database is being initialized
2012-03-21 14:21:15.q DEBUG [b10-xfrin.datasrc] DATASRC_SQLITE_CONNOPEN Opening sqlite database file '/var/bind10-devel/zone.sqlite3'
2012-03-21 14:21:15.q INFO  [b10-xfrout.xfrout] XFROUT_NOTIFY_COMMAND received command to send notifies for bind10.isc.org./IN
2012-03-21 14:21:15.q ERROR [b10-zonemgr.zonemgr] ZONEMGR_NO_MASTER_ADDRESS internal BIND 10 command did not contain address of master
2012-03-21 14:21:15.q DEBUG [b10-xfrin.datasrc] DATASRC_SQLITE_DROPCONN SQLite3Database is being deinitialized
2012-03-21 14:21:15.q DEBUG [b10-xfrin.datasrc] DATASRC_SQLITE_CONNCLOSE Closing sqlite database

...

2012-03-22 20:18:03.q DEBUG [b10-auth.datasrc] DATASRC_QUERY_NO_ZONE no zone containing 'n10.isc.org.' in class 'IN'
2012-03-22 20:18:03.q DEBUG [b10-auth.datasrc] DATASRC_QUERY_NO_ZONE no zone containing 'ord.sns-pb.isc.org.' in class 'IN'
2012-03-22 20:18:03.q DEBUG [b10-auth.datasrc] DATASRC_QUERY_NO_ZONE no zone containing 'sfba.sns-pb.isc.org.' in class 'IN'
2012-03-22 20:18:21.q DEBUG [b10-zonemgr.zonemgr] ZONEMGR_REFRESH_ZONE refreshing zone bind10.isc.org. (class IN)
2012-03-22 20:18:21.q DEBUG [b10-xfrin.datasrc] DATASRC_SQLITE_NEWCONN SQLite3Database is being initialized
2012-03-22 20:18:21.q DEBUG [b10-xfrin.datasrc] DATASRC_SQLITE_CONNOPEN Opening sqlite database file '/var/bind10-devel/zone.sqlite3'
2012-03-22 20:18:21.q INFO  [b10-xfrout.xfrout] XFROUT_NOTIFY_COMMAND received command to send notifies for bind10.isc.org./IN

From looking at logs this appeared to happen every 30 seconds.

The sqlite3 database contains:

1|2252|bind10.isc.org.|org.isc.bind10.|3600|RRSIG|SOA|SOA 5 3 3600 20120418233243 20120319233243 8724 bind10.isc.org. DhkI8A9ynENZKiSvqP7PkmgoHZFuIpmBiiRtYIp8gTOxVXvTxlnTH7BAiREr8m9y+0Yu1V8aGBEAegvztQPgLVq9XeoTDtmC/Xjl3KpNnPXApebkgUwl4s+G1DLyQPDDYpdtzF+KG9QQ69aprkgNGxlprghWcjT5VCIZJYkE2ec=

...

31|2252|bind10.isc.org.|org.isc.bind10.|3600|SOA||ns-int.isc.org. hostmaster.isc.org. 2012032000 7200 3600 604800 3600

Any ideas why the refresh happens so frequently?

(If I am overlooking something, then maybe the fix is simply to improve the documentation.)

Subtickets

Change History (8)

comment:1 follow-up: Changed 8 years ago by vorner

A wild guess would be the zone is not available in the DB and can't be downloaded for some reason (it doesn't know where from or the other server is broken or whatever). Then the 30s could be some kind of default for first-time-load zone. Could you confirm any of these guesses?

Thank you

comment:2 in reply to: ↑ 1 Changed 8 years ago by jreed

Replying to vorner:

A wild guess would be the zone is not available in the DB and can't be downloaded for some reason (it doesn't know where from or the other server is broken or whatever). Then the 30s could be some kind of default for first-time-load zone. Could you confirm any of these guesses?

Thank you

I am not sure what you need me to test or look at. It is in the sqlite3 database (see my ticket description).

I noticed this again today as my logs have this repeated 43561 times.

comment:3 follow-up: Changed 8 years ago by shane

  • Defect Severity changed from N/A to Medium
  • Milestone New Tasks deleted

Yeah, lets see if #1786 fixes this, so w

comment:4 in reply to: ↑ 3 Changed 8 years ago by jreed

Replying to shane:

Yeah, lets see if #1786 fixes this, so w

Part of this sentence got chopped off?

comment:5 Changed 8 years ago by muks

Please retest with master to see if the fixes that went in for #2023 also fixes this issue.

I'm not sure if that can cause this issue, but #2023 is likely responsible for #1786.

comment:6 Changed 8 years ago by jreed

I upgraded this system in early May and again today. It was still logging this around 3000 times per day. Since I restarted today I no longer see this.

It would be nice if bindctl offered a way to see the current timers so I can see if the transfers are happening or will happen as expected.

comment:7 Changed 8 years ago by jreed

  • Resolution set to fixed
  • Status changed from new to closed

comment:8 Changed 8 years ago by shane

Making timers visible is something we plan during our usability phase of BIND 10, so sometime in the next 14 weeks.

Note: See TracTickets for help on using tickets.