problem with resolving

Dmitry Koltsov root at hostonfly.com
Mon Mar 25 00:16:41 UTC 2002


Hello,

On some caching nameservers running BIND 8.3.1 (maybe some other versions too) my
zones is giving "timeout" error.
Please suggest something.

This is debug information:

> select() returns 1 (err: none)
> Dispatch.File: fd 30, mask 0x1, func 0x8061420, uap 0x810c5a0
> datagram from [127.0.0.1].3726, fd 30, len 35
> ns_req(from [127.0.0.1].3726)
> ;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 10916
> ;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
> ;;    www.hostonfly.net, type = A, class = IN
> req: nlookup(www.hostonfly.net) id 10916 type=1 class=1
> req: found 'www.hostonfly.net' as 'www.hostonfly.net' (cname=0)
> stale: ttl 1017024297 -672 (x2)
> stale: ttl 1017024297 -672 (x2)
> delete_all(0x81012f4:"www" IN A)
> rm_datum(810ca8c, 810ca8c, 0, 0) -> 0
> findns: np 0x81012f4 'www'
> findns: np 0x810b080 'hostonfly'
> findns: 2 NS's added for 'hostonfly'
> ns_forw()
> qnew(0x812e000)
> find_zone(www.hostonfly.net, 1)
> find_zone: unknown zone
> find_zone(hostonfly.net, 1)
> find_zone: unknown zone
> find_zone(net, 1)
> find_zone: unknown zone
> find_zone(., 1)
> find_zone: existing zone 1
> nslookup(nsp=0xbfbfdca4, qp=0x812e000, "www.hostonfly.net", d=0)
> nslookup: NS "ns1.hostonfly.net" c=1 t=2 (flags 0x2)
> nslookup: 1 ns addrs
> nslookup: NS "ns2.hostonfly.net" c=1 t=2 (flags 0x2)
> nslookup: 2 ns addrs
> nslookup: 2 ns addrs total
> qcomp(216.65.107.100, 216.65.106.100) 34912 (34944) - 52833 (52864) = 42949493
75
> retrytime: nstime34ms t69 nretry0 u69 : v45
> schedretry(0x812e000, 45 sec)
> evSetTimer(ctx 0x8141000, func 0x805e4a8, uap 0, due 1017025014.000000000, int
er 0.000000000)
> timers after evSetTimer:
>   func 0x805e4a8, uap 0x0, due 1017025014.000000000, inter 0.000000000
>   func 0x80645b4, uap 0x0, due 1017027169.695140000, inter 3600.000000000
>   func 0x8073af4, uap 0x0, due 1017027169.695170000, inter 3600.000000000
>   func 0x8064628, uap 0x0, due 1017027169.695176000, inter 3600.000000000
>   func 0x80787d8, uap 0x0, due 1017027169.695163000, inter 3600.000000000
> forw: forw -> [216.65.107.100].53 ds=5 nsid=5493 id=10916 34912ms retry 45sec
> ;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 5493
> ;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
> ;;    www.hostonfly.net, type = A, class = IN
> main loop
> evGetNext: fdCount 0
> pselect(32, 0xfff00060, 0x0, 0x0, 44.166159000)
> select() returns 1 (err: none)
> Dispatch.File: fd 30, mask 0x1, func 0x8061420, uap 0x810c5a0
> datagram from [127.0.0.1].3726, fd 30, len 35
> ns_req(from [127.0.0.1].3726)
> ;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 10916
> ;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
> ;;    www.hostonfly.net, type = A, class = IN
> req: nlookup(www.hostonfly.net) id 10916 type=1 class=1
> req: found 'www.hostonfly.net' as 'www.hostonfly.net' (cname=0)
> findns: np 0x81012f4 'www'
> findns: np 0x810b080 'hostonfly'
> findns: 2 NS's added for 'hostonfly'
> ns_forw()
> forw: dropped DUP id=10916
> main loop
> evGetNext: fdCount 0
> pselect(32, 0xfff00060, 0x0, 0x0, 39.157066000)
> select() returns 0 (err: none)
> Dispatch.Timer: func 0x805e4a8, uap 0
> retry(0x812e000) id=10916
> resend(addr=1 n=0) -> [216.65.106.100].53 ds=5 nsid=5493 id=10916 52833ms
> ;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 5493
> ;; flags:; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
> ;;    www.hostonfly.net, type = A, class = IN
> unsched(0x812e000, 10916)
> retrytime: nstime52ms t105 nretry0 u105 : v45
> schedretry(0x812e000, 45 sec)
> evSetTimer(ctx 0x8141000, func 0x805e4a8, uap 0, due 1017025059.000000000, int
er 0.000000000)
> timers after evSetTimer:
>   func 0x805e4a8, uap 0x0, due 1017025014.000000000, inter 0.000000000
>   func 0x80645b4, uap 0x0, due 1017027169.695140000, inter 3600.000000000
>   func 0x805e4a8, uap 0x0, due 1017025059.000000000, inter 0.000000000
>   func 0x8064628, uap 0x0, due 1017027169.695176000, inter 3600.000000000
>   func 0x80787d8, uap 0x0, due 1017027169.695163000, inter 3600.000000000
>   func 0x8073af4, uap 0x0, due 1017027169.695170000, inter 3600.000000000
> timers after evClearTimer:
>   func 0x805e4a8, uap 0x0, due 1017025059.000000000, inter 0.000000000
>   func 0x80645b4, uap 0x0, due 1017027169.695140000, inter 3600.000000000
>   func 0x8073af4, uap 0x0, due 1017027169.695170000, inter 3600.000000000
>   func 0x8064628, uap 0x0, due 1017027169.695176000, inter 3600.000000000
>   func 0x80787d8, uap 0x0, due 1017027169.695163000, inter 3600.000000000
> main loop
> evGetNext: fdCount 0
> pselect(32, 0xfff00060, 0x0, 0x0, 44.986336000)
> select() returns 0 (err: none)
> Dispatch.Timer: func 0x805e4a8, uap 0
> retry(0x812e000) id=10916
> resend(addr=0 n=1) -> [216.65.107.100].53 ds=5 nsid=5493 id=10916 34912ms
> ;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 5493
> ;; flags:; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0
> ;;    www.hostonfly.net, type = A, class = IN
> unsched(0x812e000, 10916)
> retrytime: nstime34ms t69 nretry1 u138 : v45
> schedretry(0x812e000, 45 sec)
> evSetTimer(ctx 0x8141000, func 0x805e4a8, uap 0, due 1017025104.000000000, int
er 0.000000000)
> timers after evSetTimer:
>   func 0x805e4a8, uap 0x0, due 1017025059.000000000, inter 0.000000000
>   func 0x80645b4, uap 0x0, due 1017027169.695140000, inter 3600.000000000
>   func 0x805e4a8, uap 0x0, due 1017025104.000000000, inter 0.000000000
>   func 0x8064628, uap 0x0, due 1017027169.695176000, inter 3600.000000000
>   func 0x80787d8, uap 0x0, due 1017027169.695163000, inter 3600.000000000
>   func 0x8073af4, uap 0x0, due 1017027169.695170000, inter 3600.000000000
> timers after evClearTimer:
>   func 0x805e4a8, uap 0x0, due 1017025104.000000000, inter 0.000000000
>   func 0x80645b4, uap 0x0, due 1017027169.695140000, inter 3600.000000000
>   func 0x8073af4, uap 0x0, due 1017027169.695170000, inter 3600.000000000
>   func 0x8064628, uap 0x0, due 1017027169.695176000, inter 3600.000000000
>   func 0x80787d8, uap 0x0, due 1017027169.695163000, inter 3600.000000000
> main loop
> evGetNext: fdCount 0
> pselect(32, 0xfff00060, 0x0, 0x0, 44.985577000)
> select() returns 1 (err: none)
> Dispatch.File: fd 5, mask 0x1, func 0x8061420, uap 0
> datagram from [216.65.107.100].53, fd 5, len 127
> qfindid(5493) -> 0x812e000
> Response (USER NORMAL -) nsid=5493 id=10916
> stime 1017024969/831701  now 1017025059/279683 rtt 89448
> NS #0 addr [216.65.107.100].53 used, rtt 51272
> NS #1 [216.65.106.100].53 rtt now 65535
> rrextract: dname www.hostonfly.net type 1 class 1 ttl 5
> rrextract: dname hostonfly.net type 2 class 1 ttl 86400
> rrextract: dname hostonfly.net type 2 class 1 ttl 86400
> rrextract: dname ns1.hostonfly.net type 1 class 1 ttl 86400
> rrextract: dname ns2.hostonfly.net type 1 class 1 ttl 86400
> rrsetupdate: www.hostonfly.net
> rrsetcmp: no records in database
> db_set_update(www.hostonfly.net)
> db_set_update(www.hostonfly.net), creating new list
> rrsetupdate: www.hostonfly.net 0
> rrsetupdate: www.hostonfly.net 0
> rrsetupdate: hostonfly.net
> rrsetcmp: rrsets matched
> rrsetupdate: ns1.hostonfly.net
> rrsetcmp: rrsets matched
> rrsetupdate: ns2.hostonfly.net
> rrsetcmp: rrsets matched
> db_set_update(<NULL>)
> update_rrset_list(www.hostonfly.net, A): set verified
> db_update(www.hostonfly.net, 0x810c828, 0x810c828, 0x0, 031, 0x811aca0)
> db_update: adding 0x810c828
> rrset_free(www.hostonfly.net)
> resp: got as much answer as there is
> send_msg -> [127.0.0.1].3726 (UDP 30) id=10916
> qp 0x812e000 q_id: 42026  q_nsid: 29973 q_msglen: 35
>       q_naddr: 2 q_curaddr: 0
>       q_next: 0 q_link: 0
> ;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 10916
> ;; flags: qr aa rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 2, ADDITIONAL: 2
> ;;    www.hostonfly.net, type = A, class = IN
> www.hostonfly.net.    5S IN A         216.65.107.33
> hostonfly.net.                1D IN NS        ns1.hostonfly.net.
> hostonfly.net.                1D IN NS        ns2.hostonfly.net.
> ns1.hostonfly.net.    1D IN A         216.65.107.100
> ns2.hostonfly.net.    1D IN A         216.65.106.100
> qremove(0x812e000)
> unsched(0x812e000, 10916)
> timers after evClearTimer:
>   func 0x80645b4, uap 0x0, due 1017027169.695140000, inter 3600.000000000
>   func 0x80787d8, uap 0x0, due 1017027169.695163000, inter 3600.000000000
>   func 0x8073af4, uap 0x0, due 1017027169.695170000, inter 3600.000000000
>   func 0x8064628, uap 0x0, due 1017027169.695176000, inter 3600.000000000
> ns_freeqry(0x812e000)
> main loop
> evGetNext: fdCount 0
> pselect(32, 0xfff00060, 0x0, 0x0, 2110.412940000)

Best regards,
 Dmitry Koltsov
 Host On Fly S.A.
 tel: + 7 812 9404403
 tel: + 41 78 8286002
 fax: + 1 775 2426205
 ICQ: 44656213
 mailto:root at hostonfly.com




More information about the bind-users mailing list