Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

[couchbase] add debug-level phase timings. #4466

Merged
merged 1 commit into from
Sep 20, 2021
Merged

[couchbase] add debug-level phase timings. #4466

Changes from all commits
Commits
Show all changes
1 commit
Select commit
File filter

Filter by extension

Filter by extension
Conversations
Failed to load comments.
Loading
Jump to
Jump to file
Failed to load files.
Loading
Diff view
Diff view
66 changes: 43 additions & 23 deletions modules/couchbase/src/main/java/org/testcontainers/couchbase/CouchbaseContainer.java
View file
Open in desktop
Original file line number Diff line number Diff line change
Expand Up @@ -221,20 +221,22 @@ protected void configure() {
protected void containerIsStarting(final InspectContainerResponse containerInfo) {
logger().debug("Couchbase container is starting, performing configuration.");

waitUntilNodeIsOnline();
initializeIsEnterprise();
renameNode();
initializeServices();
configureAdminUser();
configureExternalPorts();
timePhase("waitUntilNodeIsOnline", this::waitUntilNodeIsOnline);
timePhase("initializeIsEnterprise", this::initializeIsEnterprise);
timePhase("renameNode", this::renameNode);
timePhase("initializeServices", this::initializeServices);
timePhase("configureAdminUser", this::configureAdminUser);
timePhase("configureExternalPorts", this::configureExternalPorts);

if (enabledServices.contains(CouchbaseService.INDEX)) {
configureIndexer();
timePhase("configureIndexer", this::configureIndexer);
}
}

@Override
protected void containerIsStarted(InspectContainerResponse containerInfo) {
createBuckets();
timePhase("createBuckets", this::createBuckets);

logger().info("Couchbase container is ready! UI available at http://{}:{}", getHost(), getMappedPort(MGMT_PORT));
}

Expand Down Expand Up @@ -389,30 +391,34 @@ private void createBuckets() {

checkSuccessfulResponse(response, "Could not create bucket " + bucket.getName());

new HttpWaitStrategy()
timePhase("createBucket:" + bucket.getName() + ":waitForAllServicesEnabled", () ->
new HttpWaitStrategy()
.forPath("/pools/default/b/" + bucket.getName())
.forPort(MGMT_PORT)
.withBasicCredentials(username, password)
.forStatusCode(200)
.forResponsePredicate(new AllServicesEnabledPredicate())
.waitUntilReady(this);
.waitUntilReady(this)
);

if (enabledServices.contains(CouchbaseService.QUERY)) {
// If the query service is enabled, make sure that we only proceed if the query engine also
// knows about the bucket in its metadata configuration.
Unreliables.retryUntilTrue(1, TimeUnit.MINUTES, () -> {
@Cleanup Response queryResponse = doHttpRequest(QUERY_PORT, "/query/service", "POST", new FormBody.Builder()
.add("statement", "SELECT COUNT(*) > 0 as present FROM system:keyspaces WHERE name = \"" + bucket.getName() + "\"")
.build(), true);

String body = queryResponse.body() != null ? queryResponse.body().string() : null;
checkSuccessfulResponse(queryResponse, "Could not poll query service state for bucket: " + bucket.getName());

return Optional.of(MAPPER.readTree(body))
.map(n -> n.at("/results/0/present"))
.map(JsonNode::asBoolean)
.orElse(false);
});
timePhase(
"createBucket:" + bucket.getName() + ":queryKeyspacePresent",
() -> Unreliables.retryUntilTrue(1, TimeUnit.MINUTES, () -> {
@Cleanup Response queryResponse = doHttpRequest(QUERY_PORT, "/query/service", "POST", new FormBody.Builder()
.add("statement", "SELECT COUNT(*) > 0 as present FROM system:keyspaces WHERE name = \"" + bucket.getName() + "\"")
.build(), true);

String body = queryResponse.body() != null ? queryResponse.body().string() : null;
checkSuccessfulResponse(queryResponse, "Could not poll query service state for bucket: " + bucket.getName());

return Optional.of(MAPPER.readTree(body))
.map(n -> n.at("/results/0/present"))
.map(JsonNode::asBoolean)
.orElse(false);
}));
}

if (bucket.hasPrimaryIndex()) {
Expand Down Expand Up @@ -501,6 +507,20 @@ private Response doHttpRequest(final int port, final String path, final String m
}
}

/**
* Helper method which times an individual phase and logs it for debugging and optimization purposes.
*
* @param name the name of the phase.
* @param toTime the runnable that should be timed.
*/
private void timePhase(final String name, final Runnable toTime) {
long start = System.nanoTime();
toTime.run();
long end = System.nanoTime();

logger().debug("Phase {} took {}ms", name, TimeUnit.NANOSECONDS.toMillis(end - start));
}

/**
* In addition to getting a 200, we need to make sure that all services we need are enabled and available on
* the bucket.
Expand Down