don't log known slow queries

src/becca/becca_loader.js

@@ -15,18 +15,22 @@ const entityConstructor = require("../becca/entity_constructor");
 
 const beccaLoaded = new Promise((res, rej) => {
     sqlInit.dbReady.then(() => {
+        cls.init(() => {
             load();
 
-        cls.init(() => require('../services/options_init').initStartupOptions());
+            require('../services/options_init').initStartupOptions();
 
             res();
         });
     });
+});
 
 function load() {
     const start = Date.now();
     becca.reset();
 
+    // we know this is slow and the total becca load time is logged
+    sql.disableSlowQueryLogging(() => {
         // using a raw query and passing arrays to avoid allocating new objects,
         // this is worth it for the becca load since it happens every run and blocks the app until finished
 
@@ -53,6 +57,7 @@ function load() {
         for (const row of sql.getRows(`SELECT etapiTokenId, name, tokenHash, utcDateCreated, utcDateModified FROM etapi_tokens WHERE isDeleted = 0`)) {
             new BEtapiToken(row);
         }
+    });
 
     for (const noteId in becca.notes) {
         becca.notes[noteId].sortParents();

src/services/cls.js

@@ -48,6 +48,14 @@ function isEntityEventsDisabled() {
     return !!namespace.get('disableEntityEvents');
 }
 
+function disableSlowQueryLogging(disable) {
+    namespace.set('disableSlowQueryLogging', disable);
+}
+
+function isSlowQueryLoggingDisabled() {
+    return !!namespace.get('disableSlowQueryLogging');
+}
+
 function getAndClearEntityChangeIds() {
     const entityChangeIds = namespace.get('entityChangeIds') || [];
 
@@ -93,4 +101,6 @@ module.exports = {
     getAndClearEntityChangeIds,
     putEntityChange,
     ignoreEntityChangeIds,
+    disableSlowQueryLogging,
+    isSlowQueryLoggingDisabled
 };

src/services/content_hash.js

@@ -11,14 +11,17 @@ function getEntityHashes() {
 
     const startTime = new Date();
 
-    const hashRows = sql.getRawRows(`
+    // we know this is slow and the total content hash calculation time is logged
+    const hashRows = sql.disableSlowQueryLogging(
+        () => sql.getRawRows(`
             SELECT entityName,
                    entityId,
                    hash,
                    isErased
             FROM entity_changes
             WHERE isSynced = 1
-          AND entityName != 'note_reordering'`);
+              AND entityName != 'note_reordering'`)
+    );
 
     // sorting is faster in memory
     // sorting by entityId is enough, hashes will be segmented by entityName later on anyway

src/services/sql.js

@@ -225,7 +225,7 @@ function wrap(query, func) {
 
     const milliseconds = Date.now() - startTimestamp;
 
-    if (milliseconds >= 20) {
+    if (milliseconds >= 20 && !cls.isSlowQueryLoggingDisabled()) {
         if (query.includes("WITH RECURSIVE")) {
             log.info(`Slow recursive query took ${milliseconds}ms.`);
         }
@@ -295,6 +295,19 @@ async function copyDatabase(targetFilePath) {
     await dbConnection.backup(targetFilePath);
 }
 
+function disableSlowQueryLogging(cb) {
+    const orig = cls.isSlowQueryLoggingDisabled();
+
+    try {
+        cls.disableSlowQueryLogging(true);
+
+        return cb();
+    }
+    finally {
+        cls.disableSlowQueryLogging(orig);
+    }
+}
+
 module.exports = {
     dbConnection,
     insert,
@@ -367,5 +380,6 @@ module.exports = {
     transactional,
     upsert,
     fillParamList,
-    copyDatabase
+    copyDatabase,
+    disableSlowQueryLogging
 };