<div dir="ltr">I've been seeing intermittent lookup failures for '<a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>'.  Looking at the logs, I see these messages during the time when the lookup fails:<div><br></div><div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Wants DNSSEC processing, auth data in query for A</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Looking for CNAME cache hit of '<a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>|CNAME'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: No CNAME cache hit of '<a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>|CNAME' found</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: No cache hit for '<a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>|A', trying to find an appropriate NS record</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] : got TA for '.'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] : setting cut state for . to Secure</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Checking if we have NS in cache for '<a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: no valid/useful NS in cache for '<a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Checking if we have NS in cache for '<a href="http://clouds.archive.ubuntu.com">clouds.archive.ubuntu.com</a>'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: NS (with ip, or non-glue) in cache for '<a href="http://clouds.archive.ubuntu.com">clouds.archive.ubuntu.com</a>' -> '<a href="http://piru.canonical.com">piru.canonical.com</a>'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: within bailiwick: 0, not in cache / did not look at cache</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: We have NS in cache for '<a href="http://clouds.archive.ubuntu.com">clouds.archive.ubuntu.com</a>' (flawedNSSet=0)</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: initial validation status for <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a> is Indeterminate</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Cache consultations done, have 1 NS to contact</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>.: Nameservers: piru.canonical.com.(38.62ms)</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Trying to resolve NS '<a href="http://piru.canonical.com">piru.canonical.com</a>' (1/1)</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333]    <a href="http://piru.canonical.com">piru.canonical.com</a>: Wants DNSSEC processing, NO auth data in query for A</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333]    <a href="http://piru.canonical.com">piru.canonical.com</a>: Looking for CNAME cache hit of '<a href="http://piru.canonical.com">piru.canonical.com</a>|CNAME'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333]    <a href="http://piru.canonical.com">piru.canonical.com</a>: No CNAME cache hit of '<a href="http://piru.canonical.com">piru.canonical.com</a>|CNAME' found</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333]    <a href="http://piru.canonical.com">piru.canonical.com</a>: Found cache hit for A: 91.189.95.68[ttl=1340]</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333]    <a href="http://piru.canonical.com">piru.canonical.com</a>: updating validation state with cache content for <a href="http://piru.canonical.com">piru.canonical.com</a> to Indeterminate</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Resolved '<a href="http://clouds.archive.ubuntu.com">clouds.archive.ubuntu.com</a>' NS <a href="http://piru.canonical.com">piru.canonical.com</a> to: 91.189.95.68</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Trying IP <a href="http://91.189.95.68:53">91.189.95.68:53</a>, asking '<a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>|A'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: timeout resolving after 5535.35msec</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: Failed to resolve via any of the 1 offered NS at level '<a href="http://clouds.archive.ubuntu.com">clouds.archive.ubuntu.com</a>'</font></div><div><font face="monospace, monospace">Jan 23 05:47:55 n422 pdns_recursor[8739]: [414333] <a href="http://nova.clouds.archive.ubuntu.com">nova.clouds.archive.ubuntu.com</a>: failed (res=-1)</font></div><div><br></div><div>My recursor config has 'network-timeout' set to 5500ms (instead of the default 1500ms), so I can understand the "timeout resolving after 5535.35msec" message, but I was surprised by the timestamps of the messages all being the same.  Are these messages flushed to syslog all at once or is the timeout really happening immediately?</div><div><br></div><div>Aside from the timing question, can anyone offer any insight about this sequence of events that lead up to the timeout?  Would this "just" indicate a network or transport layer issue or is there anything in the events leading up to the timeout that look suspicious?  This has been happening about 10-15 times per day and started happening on Jan 18th.  I see similar timeouts to 3 other sites, but this one most frequently.</div><div><br></div><div>Lastly, is there any way to retry here?  I'd rather lower the network timeout and attempt a retry if possible.</div><div><br></div><div>This is version 4.1.0</div><div><br></div><div>Thanks</div><div><br></div>-- <br><div class="gmail_signature"><div dir="ltr"><div>Brian Talley<br></div></div></div>
</div></div>