From b4e6a58077a87a2ddcb679d3f16f550077096993 Mon Sep 17 00:00:00 2001
From: Jonathan Cobb <jonathan@kyuss.org>
Date: Mon, 20 Apr 2020 11:36:20 -0400
Subject: [PATCH] add debug logging for locks, only log ifDebugEnabled

---
 .../java/org/cobbzilla/wizard/cache/redis/RedisService.java  | 5 ++++-
 1 file changed, 4 insertions(+), 1 deletion(-)

diff --git a/wizard-server/src/main/java/org/cobbzilla/wizard/cache/redis/RedisService.java b/wizard-server/src/main/java/org/cobbzilla/wizard/cache/redis/RedisService.java
index ba55dac..3b601c5 100644
--- a/wizard-server/src/main/java/org/cobbzilla/wizard/cache/redis/RedisService.java
+++ b/wizard-server/src/main/java/org/cobbzilla/wizard/cache/redis/RedisService.java
@@ -220,18 +220,21 @@ public class RedisService {
     }
 
     public String lock(String key, long lockTimeout, long deadlockTimeout) {
+        log.debug("lock("+key+") starting");
         key = key + LOCK_SUFFIX;
         final String uuid = UUID.randomUUID().toString();
         String lockVal = get(key);
         final long start = now();
         while ((lockVal == null || !lockVal.equals(uuid)) && (now() - start < lockTimeout)) {
             set(key, uuid, NX, EX, deadlockTimeout/1000);
+            log.debug("lock("+key+") locked with uuid="+uuid);
             lockVal = get(key);
+            log.debug("lock("+key+") after locking with uuid="+uuid+", lockVal="+lockVal);
         }
         if (lockVal == null || !lockVal.equals(uuid)) {
             return die("lock: timeout locking "+key);
         }
-        log.info("lock: LOCKED "+key);
+        log.debug("lock: LOCKED "+key+" = "+lockVal);
         return uuid;
     }