test

Author: geropl

components/server/src/authorization/spicedb-authorizer.ts

@@ -29,6 +29,7 @@ export class SpiceDBAuthorizer {
         if (!this.client) {
             return true;
         }
+        const timing = startTimer();
 
         const featureEnabled = await getExperimentsClientForBackend().getValueAsync("centralizedPermissions", false, {
             user: {
@@ -54,13 +55,15 @@ export class SpiceDBAuthorizer {
             return false;
         } finally {
             observeSpicedbClientLatency("check", error, timer());
+            stopTimer("CHECK", timing);
         }
     }
 
     async writeRelationships(...updates: v1.RelationshipUpdate[]): Promise<v1.WriteRelationshipsResponse | undefined> {
         if (!this.client) {
             return undefined;
         }
+        const timing = startTimer();
 
         const timer = spicedbClientLatency.startTimer();
         let error: Error | undefined;
@@ -78,13 +81,15 @@ export class SpiceDBAuthorizer {
             log.error("[spicedb] Failed to write relationships.", err, { updates: new TrustedValue(updates) });
         } finally {
             observeSpicedbClientLatency("write", error, timer());
+            stopTimer("WRITE", timing);
         }
     }
 
     async deleteRelationships(req: v1.DeleteRelationshipsRequest): Promise<v1.ReadRelationshipsResponse[]> {
         if (!this.client) {
             return [];
         }
+        const timing = startTimer();
 
         const timer = spicedbClientLatency.startTimer();
         let error: Error | undefined;
@@ -111,6 +116,7 @@ export class SpiceDBAuthorizer {
             return [];
         } finally {
             observeSpicedbClientLatency("delete", error, timer());
+            stopTimer("DELETE", timing);
         }
     }
 
@@ -121,3 +127,11 @@ export class SpiceDBAuthorizer {
         return this.client.readRelationships(req);
     }
 }
+function startTimer() {
+    return process.hrtime();
+}
+
+function stopTimer(method: string, hrtime: [number, number]) {
+    const seconds = (hrtime[0] + hrtime[1] / 1e6).toFixed(3);
+    log.info(`[SPICEDB] ${method} (${seconds} ms)`);
+}