proxy: imp log msg

proxy/dnscontext.go

@@ -63,9 +63,9 @@ type DNSContext struct {
 	// localIP - local IP address (for UDP socket to call udpMakeOOBWithSrc)
 	localIP net.IP
 
-	// QueryDuration is the time duration of the successful query from an
-	// upstream DNS server or a fallback DNS server, if exchange with an
-	// upstream wasn't successful.
+	// QueryDuration is the duration of the successful query from an upstream
+	// DNS server or a fallback DNS server, if exchange with an upstream wasn't
+	// successful.
 	QueryDuration time.Duration
 
 	// DoQVersion is the DoQ protocol version. It can (and should) be read from

proxy/proxy.go

@@ -508,6 +508,7 @@ func (p *Proxy) replyFromUpstream(d *DNSContext) (ok bool, err error) {
 	}
 
 	start := time.Now()
+	src := "upstream"
 
 	// Perform the DNS request.
 	resp, u, err := p.exchange(req, upstreams)
@@ -523,6 +524,7 @@ func (p *Proxy) replyFromUpstream(d *DNSContext) (ok bool, err error) {
 
 		// Reset the timer.
 		start = time.Now()
+		src = "fallback"
 
 		upstreams = p.Fallbacks.getUpstreamsForDomain(req.Question[0].Name)
 		if len(upstreams) == 0 {
@@ -533,9 +535,10 @@ func (p *Proxy) replyFromUpstream(d *DNSContext) (ok bool, err error) {
 	}
 
 	if resp != nil {
-		log.Debug("proxy: replying from upstream: rtt is %s", time.Since(start))
+		rtt := time.Since(start)
+		log.Debug("proxy: replying from %s: rtt is %s", src, rtt)
 
-		d.QueryDuration = time.Since(start)
+		d.QueryDuration = rtt
 	}
 
 	p.handleExchangeResult(d, req, resp, u)