Log warning when aborting operation due to clock skew

Improves visibility into clock/NTP-related problems. Logs distributor
index to make it easier to identify the skewing node(s).

1 files changed, 6 insertions, 2 deletions

diff --git a/storage/src/vespa/storage/storageserver/bouncer.cpp b/storage/src/vespa/storage/storageserver/bouncer.cpp
index edd3d946aea..97ae3fb1a65 100644
--- a/storage/src/vespa/storage/storageserver/bouncer.cpp
+++ b/storage/src/vespa/storage/storageserver/bouncer.cpp
@@ -8,6 +8,7 @@
 #include <sstream>
 
 #include <vespa/log/log.h>
+#include <vespa/log/bufferedlogger.h>
 LOG_SETUP(".bouncer");
 
 namespace storage {
@@ -108,11 +109,14 @@ void
 Bouncer::abortCommandWithTooHighClockSkew(api::StorageMessage& msg,
                                           int maxClockSkewInSeconds)
 {
-    std::shared_ptr<api::StorageReply> reply(
-            static_cast<api::StorageCommand&>(msg).makeReply().release());
+    auto& as_cmd = dynamic_cast<api::StorageCommand&>(msg);
     std::ostringstream ost;
     ost << "Message " << msg.getType() << " is more than "
         << maxClockSkewInSeconds << " seconds in the future.";
+    LOGBP(warning, "Aborting operation from distributor %u: %s",
+          as_cmd.getSourceIndex(), ost.str().c_str());
+
+    std::shared_ptr<api::StorageReply> reply(as_cmd.makeReply().release());
     reply->setResult(api::ReturnCode(api::ReturnCode::ABORTED, ost.str()));
     sendUp(reply);
 }