/** * Populates timing information in the specified harEntry for failed rquests. Populates as much timing information * as possible, up to the point of failure. * * @param harEntry HAR entry to populate timing information in */ private void populateTimingsForFailedCONNECT(HarEntry harEntry) { HarTimings timings = harEntry.getTimings(); if (connectionQueuedNanos > 0L && dnsResolutionStartedNanos > 0L) { timings.setBlocked(dnsResolutionStartedNanos - connectionQueuedNanos, TimeUnit.NANOSECONDS); } if (dnsResolutionStartedNanos > 0L && dnsResolutionFinishedNanos > 0L) { timings.setDns(dnsResolutionFinishedNanos - dnsResolutionStartedNanos, TimeUnit.NANOSECONDS); } if (connectionStartedNanos > 0L && connectionSucceededTimeNanos > 0L) { timings.setConnect(connectionSucceededTimeNanos - connectionStartedNanos, TimeUnit.NANOSECONDS); if (sslHandshakeStartedNanos > 0L) { timings.setSsl(connectionSucceededTimeNanos - this.sslHandshakeStartedNanos, TimeUnit.NANOSECONDS); } } if (sendStartedNanos > 0L && sendFinishedNanos >= 0L) { timings.setSend(sendFinishedNanos - sendStartedNanos, TimeUnit.NANOSECONDS); } if (sendFinishedNanos > 0L && responseReceiveStartedNanos >= 0L) { timings.setWait(responseReceiveStartedNanos - sendFinishedNanos, TimeUnit.NANOSECONDS); } // since this method is for HTTP CONNECT failures only, we can't populate a "received" time, since that would // require the CONNECT to be successful, in which case this method wouldn't be called. }
@Override public void serverToProxyResponseTimedOut() { HarEntry harEntry = createHarEntryForFailedCONNECT(HarCaptureUtil.getResponseTimedOutErrorMessage()); har.getLog().addEntry(harEntry); // include this timeout time in the HarTimings object long timeoutTimestampNanos = System.nanoTime(); // if the proxy started to send the request but has not yet finished, we are currently "sending" if (sendStartedNanos > 0L && sendFinishedNanos == 0L) { harEntry.getTimings().setSend(timeoutTimestampNanos - sendStartedNanos, TimeUnit.NANOSECONDS); } // if the entire request was sent but the proxy has not begun receiving the response, we are currently "waiting" else if (sendFinishedNanos > 0L && responseReceiveStartedNanos == 0L) { harEntry.getTimings().setWait(timeoutTimestampNanos - sendFinishedNanos, TimeUnit.NANOSECONDS); } // if the proxy has already begun to receive the response, we are currenting "receiving" else if (responseReceiveStartedNanos > 0L) { harEntry.getTimings().setReceive(timeoutTimestampNanos - responseReceiveStartedNanos, TimeUnit.NANOSECONDS); } }
if (timings.getBlocked(TimeUnit.NANOSECONDS) > 0) { timeNanos += timings.getBlocked(TimeUnit.NANOSECONDS); if (timings.getDns(TimeUnit.NANOSECONDS) > 0) { timeNanos += timings.getDns(TimeUnit.NANOSECONDS); if (timings.getConnect(TimeUnit.NANOSECONDS) > 0) { timeNanos += timings.getConnect(TimeUnit.NANOSECONDS); if (timings.getSend(TimeUnit.NANOSECONDS) > 0) { timeNanos += timings.getSend(TimeUnit.NANOSECONDS); if (timings.getWait(TimeUnit.NANOSECONDS) > 0) { timeNanos += timings.getWait(TimeUnit.NANOSECONDS); if (timings.getReceive(TimeUnit.NANOSECONDS) > 0) { timeNanos += timings.getReceive(TimeUnit.NANOSECONDS);
/** * Populates ssl and connect timing info in the HAR if an entry for this client and server exist in the cache. */ protected void captureConnectTiming() { HttpConnectTiming httpConnectTiming = HttpConnectHarCaptureFilter.consumeConnectTimingForConnection(clientAddress); if (httpConnectTiming != null) { harEntry.getTimings().setSsl(httpConnectTiming.getSslHandshakeTimeNanos(), TimeUnit.NANOSECONDS); harEntry.getTimings().setConnect(httpConnectTiming.getConnectTimeNanos(), TimeUnit.NANOSECONDS); harEntry.getTimings().setBlocked(httpConnectTiming.getBlockedTimeNanos(), TimeUnit.NANOSECONDS); harEntry.getTimings().setDns(httpConnectTiming.getDnsTimeNanos(), TimeUnit.NANOSECONDS); } }
@Nullable @Override public Network.ResourceTiming getTiming() { HarTimings timings = harEntry.getTimings(); Network.ResourceTiming resourceTiming = new Network.ResourceTiming(); resourceTiming.requestTime = harEntry.getRequestTime(); resourceTiming.proxyStart = timings.getWait(); resourceTiming.proxyEnd = resourceTiming.proxyStart; resourceTiming.dnsStart = resourceTiming.proxyEnd; resourceTiming.dnsEnd = resourceTiming.dnsStart + timings.getDns(); resourceTiming.connectStart = resourceTiming.dnsEnd; resourceTiming.connectEnd = resourceTiming.connectStart + timings.getConnect(); resourceTiming.sslStart = resourceTiming.connectEnd; resourceTiming.sslEnd = resourceTiming.sslStart + timings.getSsl(); resourceTiming.sendStart = resourceTiming.sslEnd; resourceTiming.sendEnd = resourceTiming.sendStart + timings.getSend(); resourceTiming.receiveHeadersEnd = harEntry.getTotalTime(); return resourceTiming; } }
public void setConnect(long connect) { setConnect(connect, TimeUnit.MILLISECONDS); }
public void setDns(long dns) { setDns(dns, TimeUnit.MILLISECONDS); }
public void setSend(long send) { setSend(send, TimeUnit.MILLISECONDS); }
public void setWait(long wait) { setWait(wait, TimeUnit.MILLISECONDS); }
public void setBlocked(long blocked) { setBlocked(blocked, TimeUnit.MILLISECONDS); }
public void setReceive(long receive) { setReceive(receive, TimeUnit.MILLISECONDS); }
public void setSsl(long ssl) { setSsl(ssl, TimeUnit.MILLISECONDS); }
public Long getConnect() { return getConnect(TimeUnit.MILLISECONDS); }
public Long getBlocked() { return getBlocked(TimeUnit.MILLISECONDS); }
public Long getDns() { return getDns(TimeUnit.MILLISECONDS); }
/** * Populates ssl and connect timing info in the HAR if an entry for this client and server exist in the cache. */ protected void captureConnectTiming() { HttpConnectTiming httpConnectTiming = HttpConnectHarCaptureFilter.consumeConnectTimingForConnection(clientAddress); if (httpConnectTiming != null) { harEntry.getTimings().setSsl(httpConnectTiming.getSslHandshakeTimeNanos(), TimeUnit.NANOSECONDS); harEntry.getTimings().setConnect(httpConnectTiming.getConnectTimeNanos(), TimeUnit.NANOSECONDS); harEntry.getTimings().setBlocked(httpConnectTiming.getBlockedTimeNanos(), TimeUnit.NANOSECONDS); harEntry.getTimings().setDns(httpConnectTiming.getDnsTimeNanos(), TimeUnit.NANOSECONDS); } }
@Override public void proxyToServerConnectionSucceeded(ChannelHandlerContext serverCtx) { long connectionSucceededTimeNanos = System.nanoTime(); // make sure the previous timestamp was captured, to avoid setting an absurd value in the har (see serverToProxyResponseReceiving()) if (connectionStartedNanos > 0L) { harEntry.getTimings().setConnect(connectionSucceededTimeNanos - connectionStartedNanos, TimeUnit.NANOSECONDS); } else { harEntry.getTimings().setConnect(0L, TimeUnit.NANOSECONDS); } }
@Override public void proxyToServerResolutionSucceeded(String serverHostAndPort, InetSocketAddress resolvedRemoteAddress) { long dnsResolutionFinishedNanos = System.nanoTime(); if (dnsResolutionStartedNanos > 0L) { harEntry.getTimings().setDns(dnsResolutionFinishedNanos - dnsResolutionStartedNanos, TimeUnit.NANOSECONDS); } else { harEntry.getTimings().setDns(0L, TimeUnit.NANOSECONDS); } // the address *should* always be resolved at this point InetAddress resolvedAddress = resolvedRemoteAddress.getAddress(); if (resolvedAddress != null) { addressResolved = true; harEntry.setServerIPAddress(resolvedAddress.getHostAddress()); } }
@Override public void proxyToServerRequestSent() { this.sendFinishedNanos = System.nanoTime(); // make sure the previous timestamp was captured, to avoid setting an absurd value in the har (see serverToProxyResponseReceiving()) if (sendStartedNanos > 0L) { harEntry.getTimings().setSend(sendFinishedNanos - sendStartedNanos, TimeUnit.NANOSECONDS); } else { harEntry.getTimings().setSend(0L, TimeUnit.NANOSECONDS); } }
@Override public void serverToProxyResponseReceiving() { this.responseReceiveStartedNanos = System.nanoTime(); // started to receive response, so populate the 'wait' time. if we started receiving a response from the server before we finished // sending (for example, the server replied with a 404 while we were uploading a large file), there was no wait time, so // make sure the wait is set to 0. if (sendFinishedNanos > 0L && sendFinishedNanos < responseReceiveStartedNanos) { harEntry.getTimings().setWait(responseReceiveStartedNanos - sendFinishedNanos, TimeUnit.NANOSECONDS); } else { harEntry.getTimings().setWait(0L, TimeUnit.NANOSECONDS); } }