From: Peter Amstutz <peter.amstutz@curoverse.com>
Date: Wed, 22 Feb 2017 20:40:22 +0000 (+0000)
Subject: 10629: improve debug logging
X-Git-Tag: 1.1.0~402^2~2
X-Git-Url: https://git.arvados.org/arvados.git/commitdiff_plain/04fb9a1407a154f0d86d67b2bd2e35c07a6a9989?hp=8b75947ee3f99b87eec443763653ca6ae3eb21e1

10629: improve debug logging

--debug includes Keep logging.
--logfile includes timestamps.
---

diff --git a/services/fuse/arvados_fuse/__init__.py b/services/fuse/arvados_fuse/__init__.py
index 1d25aa83cb..b12a7d14ab 100644
--- a/services/fuse/arvados_fuse/__init__.py
+++ b/services/fuse/arvados_fuse/__init__.py
@@ -589,6 +589,7 @@ class Operations(llfuse.Operations):
     @catch_exceptions
     def release(self, fh):
         if fh in self._filehandles:
+            _logger.debug("arv-mount release fh %i", fh)
             try:
                 self._filehandles[fh].flush()
             except Exception:
diff --git a/services/fuse/arvados_fuse/command.py b/services/fuse/arvados_fuse/command.py
index ffcfc6500f..ca77cfcd7b 100644
--- a/services/fuse/arvados_fuse/command.py
+++ b/services/fuse/arvados_fuse/command.py
@@ -154,6 +154,9 @@ class Mount(object):
         # Configure a log handler based on command-line switches.
         if self.args.logfile:
             log_handler = logging.FileHandler(self.args.logfile)
+            log_handler.setFormatter(logging.Formatter(
+                '%(asctime)s %(name)s[%(process)d] %(levelname)s: %(message)s',
+                '%Y-%m-%d %H:%M:%S'))
         else:
             log_handler = None
 
@@ -163,6 +166,9 @@ class Mount(object):
 
         if self.args.debug:
             arvados.logger.setLevel(logging.DEBUG)
+            logging.getLogger('arvados.keep').setLevel(logging.DEBUG)
+            logging.getLogger('arvados.api').setLevel(logging.DEBUG)
+            logging.getLogger('arvados.collection').setLevel(logging.DEBUG)
             self.logger.debug("arv-mount debugging enabled")
 
         self.logger.info("enable write is %s", self.args.enable_write)