XrdFileCachePurge.cc

#include "XrdFileCache.hh"
#include "XrdFileCacheTrace.hh"

#include <fcntl.h>
#include <sys/time.h>

#include "XrdOuc/XrdOucEnv.hh"
#include "XrdSys/XrdSysTrace.hh"

using namespace XrdFileCache;

namespace XrdFileCache
{

XrdSysTrace* GetTrace()
{
   // needed for logging macros
   return Cache::GetInstance().GetTrace();
}


//==============================================================================
// DirState
//==============================================================================

class DirState
{
   DirState    *m_parent;

   Stats        m_stats;        // access stats from client reads in this directory (and subdirs)

   long long    m_usage;        // collected / measured during purge traversal
   long long    m_usage_extra;  // collected from write events in this directory and subdirs
   long long    m_usage_purged; // amount of data purged from this directory (and subdirectories for leaf nodes)

   // begin purge traversal usage \_ so we can have a good estimate of what came in during the traversal
   // end purge traversal usage   /  (should be small, presumably)

   // quota info, enabled?

   int          m_depth;
   int          m_max_depth;
   bool         m_stat_report;  // storing of stats required

   typedef std::map<std::string, DirState> DsMap_t;
   typedef DsMap_t::iterator               DsMap_i;

   DsMap_t      m_subdirs;

   void init()
   {
      m_usage = 0;
      m_usage_extra  = 0;
      m_usage_purged = 0;
   }

   DirState* create_child(const std::string &dir)
   {
      std::pair<DsMap_i, bool> ir = m_subdirs.insert(std::make_pair(dir, DirState(this)));
      return &  ir.first->second;
   }

   DirState* find_path_tok(PathTokenizer &pt, int pos, bool create_subdirs)
   {
      if (pos == pt.get_n_dirs()) return this;

      DsMap_i i = m_subdirs.find(pt.m_dirs[pos]);

      DirState *ds = 0;

      if (i != m_subdirs.end())
      {
         ds = & i->second;
      }
      if (create_subdirs && m_depth < m_max_depth)
      {
         ds = create_child(pt.m_dirs[pos]);
      }
      if (ds) return ds->find_path_tok(pt, pos + 1, create_subdirs);

      return 0;
   }

public:

   DirState(int max_depth) : m_parent(0), m_depth(0), m_max_depth(max_depth)
   {
      init();
   }

   DirState(DirState *parent) : m_parent(parent), m_depth(m_parent->m_depth + 1), m_max_depth(m_parent->m_max_depth)
   {
      init();
   }

   DirState* get_parent()                     { return m_parent; }

   void      set_usage(long long u)           { m_usage = u; m_usage_extra = 0; }
   void      add_up_stats(const Stats& stats) { m_stats.AddUp(stats); }
   void      add_usage_purged(long long up)   { m_usage_purged += up; }

   DirState* find_path(const std::string &path, int max_depth, bool parse_as_lfn, bool create_subdirs)
   {
      PathTokenizer pt(path, max_depth, parse_as_lfn);

      return find_path_tok(pt, 0, create_subdirs);
   }

   DirState* find_dir(const std::string &dir, bool create_subdirs)
   {
      DsMap_i i = m_subdirs.find(dir);

      if (i != m_subdirs.end())  return & i->second;

      if (create_subdirs && m_depth < m_max_depth)  return create_child(dir);

      return 0;
   }

   void reset_stats()
   {
      m_stats.Reset();

      for (DsMap_i i = m_subdirs.begin(); i != m_subdirs.end(); ++i)
      {
         i->second.reset_stats();
      }
   }

   void upward_propagate_stats()
   {
      for (DsMap_i i = m_subdirs.begin(); i != m_subdirs.end(); ++i)
      {
         i->second.upward_propagate_stats();

         m_stats.AddUp(i->second.m_stats);
      }

      m_usage_extra += m_stats.m_BytesWritten;
   }

   long long upward_propagate_usage_purged()
   {
      for (DsMap_i i = m_subdirs.begin(); i != m_subdirs.end(); ++i)
      {
         m_usage_purged += i->second.upward_propagate_usage_purged();
      }
      m_usage -= m_usage_purged;

      long long ret = m_usage_purged;
      m_usage_purged = 0;
      return ret;
   }

   void dump_recursively(const char *name)
   {
      printf("%*d %s usage=%lld usage_extra=%lld usage_total=%lld num_ios=%d duration=%d b_hit=%lld b_miss=%lld b_byps=%lld b_wrtn=%lld\n",
             2 + 2*m_depth, m_depth, name, m_usage, m_usage_extra, m_usage + m_usage_extra,
             m_stats.m_NumIos, m_stats.m_Duration, m_stats.m_BytesHit, m_stats.m_BytesMissed, m_stats.m_BytesBypassed, m_stats.m_BytesWritten);

      for (DsMap_i i = m_subdirs.begin(); i != m_subdirs.end(); ++i)
      {
         i->second.dump_recursively(i->first.c_str());
      }
   }
};


//==============================================================================
// DataFsState
//==============================================================================

class DataFsState
{
   int       m_max_depth;
   DirState  m_root;
   time_t    m_prev_time;

public:
   DataFsState() :
      m_max_depth ( Cache::GetInstance().RefConfiguration().m_dirStatsStoreDepth ),
      m_root      ( m_max_depth ),
      m_prev_time ( time(0) )
   {}

   int       get_max_depth() const { return m_max_depth; }

   DirState* get_root()            { return & m_root; }

   DirState* find_dirstate_for_lfn(const std::string& lfn)
   {
      return m_root.find_path(lfn, m_max_depth, true, true);
   }

   void reset_stats()                   { m_root.reset_stats();                   }
   void upward_propagate_stats()        { m_root.upward_propagate_stats();        }
   void upward_propagate_usage_purged() { m_root.upward_propagate_usage_purged(); }

   void dump_recursively()
   {
      time_t now = time(0);

      printf("DataFsState::dump_recursively epoch = %lld delta_t = %lld max_depth = %d\n",
             (long long) now, (long long) (now - m_prev_time), m_max_depth);

      m_prev_time = now;

      m_root.dump_recursively("root");
   }
};


//==============================================================================
// FPurgeState
//==============================================================================

class FPurgeState
{
public:
   struct FS
   {
      std::string path;
      long long   nBytes;
      time_t      time;
      DirState   *dirState;

      FS(const std::string& p, long long n, time_t t, DirState *ds) :
         path(p), nBytes(n), time(t), dirState(ds)
      {}
   };

   typedef std::multimap<time_t, FS> map_t;
   typedef map_t::iterator           map_i;

   map_t   m_fmap; // map of files that are purge candidates

   typedef std::list<FS>    list_t;
   typedef list_t::iterator list_i;

   list_t  m_flist; // list of files to be removed unconditionally

   long long nBytesReq;
   long long nBytesAccum;
   long long nBytesTotal;
   time_t    tMinTimeStamp;

   // ------------------------------------
   // Directory handling & stat collection
   // ------------------------------------

   DirState    *m_dir_state;
   int          m_dir_level;
   int          m_max_dir_level_for_stat_collection; // until I honor globs from pfc.dirstats
   std::string  m_current_dir;
   std::string  m_current_path; // Note: without leading '/'!

   std::vector<std::string> m_dir_names_stack;
   std::vector<long long>   m_dir_usage_stack;

   void begin_traversal(DirState *root)
   {
      m_dir_state = root;
      m_dir_level = 0;
      m_max_dir_level_for_stat_collection = Cache::GetInstance().RefConfiguration().m_dirStatsStoreDepth;
      m_current_dir  = "";
      m_current_path = "";
      m_dir_names_stack.reserve(32);
      m_dir_usage_stack.reserve(m_max_dir_level_for_stat_collection + 1);
      m_dir_usage_stack.push_back(0);

      printf("FPurgeState::begin_traversal cur_path '%s', usage=%lld, level=%d\n", m_current_path.c_str(),
             m_dir_usage_stack.back(), m_dir_level);
   }

   void end_traversal()
   {
      printf("FPurgeState::end_traversal reporting for '%s', usage=%lld, nBytesTotal=%lld, level=%d\n", m_current_path.c_str(),
             m_dir_usage_stack.back(), nBytesTotal, m_dir_level);

      m_dir_state->set_usage(m_dir_usage_stack.back());

      m_dir_state = 0;
   }

   void cd_down(const std::string& dir_name, const std::string& full_path)
   {
      ++m_dir_level;
      if (m_dir_level <= m_max_dir_level_for_stat_collection)
      {
         m_dir_usage_stack.push_back(0);
         m_dir_state = m_dir_state->find_dir(dir_name, true);
      }
      m_dir_names_stack.push_back(dir_name);
      m_current_dir  = dir_name;
      m_current_path = full_path;
   }

   void cd_up(const std::string& full_path)
   {
      if (m_dir_level <= m_max_dir_level_for_stat_collection)
      {
         long long tail = m_dir_usage_stack.back();
         m_dir_usage_stack.pop_back();

         printf("FPurgeState::cd_up reporting for '%s', usage=%lld, level=%d\n", m_current_path.c_str(),
                tail, m_dir_level);

         m_dir_state->set_usage(tail);
         m_dir_state = m_dir_state->get_parent();

         m_dir_usage_stack.back() += tail;
      }

      m_current_path = full_path;
      m_current_dir  = m_dir_names_stack.back();
      m_dir_names_stack.pop_back();

      --m_dir_level;
   }

   // ------------------------------------------------------------------------
   // ------------------------------------------------------------------------

   FPurgeState(long long iNBytesReq) :
      nBytesReq(iNBytesReq), nBytesAccum(0), nBytesTotal(0), tMinTimeStamp(0),
      m_dir_state(0)
   {}

   // ------------------------------------------------------------------------

   void      setMinTime(time_t min_time) { tMinTimeStamp = min_time; }
   time_t    getMinTime()          const { return tMinTimeStamp; }

   long long getNBytesTotal()      const { return nBytesTotal; }

   void MoveListEntriesToMap()
   {
      for (list_i i = m_flist.begin(); i != m_flist.end(); ++i)
      {
         m_fmap.insert(std::make_pair(i->time, *i));
      }
      m_flist.clear();
   }

   void checkFile(const std::string& iPath, long long iNBytes, time_t iTime)
   {
      nBytesTotal += iNBytes;

      if (m_dir_state)  m_dir_usage_stack.back() += iNBytes;

      if (tMinTimeStamp > 0 && iTime < tMinTimeStamp)
      {
         m_flist.push_back(FS(iPath, iNBytes, iTime, m_dir_state));
         nBytesAccum += iNBytes;
      }
      else if (nBytesAccum < nBytesReq || ( ! m_fmap.empty() && iTime < m_fmap.rbegin()->first))
      {
         m_fmap.insert(std::make_pair(iTime, FS(iPath, iNBytes, iTime, m_dir_state)));
         nBytesAccum += iNBytes;

         // remove newest files from map if necessary
         while ( ! m_fmap.empty() && nBytesAccum - m_fmap.rbegin()->second.nBytes >= nBytesReq)
         {
            nBytesAccum -= m_fmap.rbegin()->second.nBytes;
            m_fmap.erase(--(m_fmap.rbegin().base()));
         }
      }
   }

   void FillFileMapRecurse(XrdOssDF* iOssDF, const std::string& path)
   {
      static const char* m_traceID = "Purge";

      const char   *InfoExt    = XrdFileCache::Info::s_infoExtension;
      const size_t  InfoExtLen = strlen(InfoExt);

      Cache        &cache = Cache::GetInstance();
      XrdOss       *oss   = cache.GetOss();
      const char   *uname = cache.RefConfiguration().m_username.c_str();

      char          fname[256];
      XrdOucEnv     env;

      while (iOssDF->Readdir(&fname[0], 256) >= 0)
      {
         // printf("readdir [%s]\n", fname);

         std::string new_path  = path + "/"; new_path += fname;
         size_t      fname_len = strlen(&fname[0]);

         if (fname_len == 0)
         {
            // std::cout << "Finish read dir.[" << new_path << "] Break loop.\n";
            break;
         }

         if (strncmp("..", &fname[0], 2) && strncmp(".", &fname[0], 1))
         {
            XrdOssDF* dh = oss->newDir (uname);
            XrdOssDF* fh = oss->newFile(uname);

            if (fname_len > InfoExtLen && strncmp(&fname[fname_len - InfoExtLen], InfoExt, InfoExtLen) == 0)
            {
               // Check if the file is currently opened / purge-protected is done before unlinking of the file.

               Info cinfo(cache.GetTrace());

               if (fh->Open(new_path.c_str(), O_RDONLY, 0600, env) == XrdOssOK && cinfo.Read(fh, new_path))
               {
                  bool   all_gauda = true;
                  time_t accessTime;
                  if ( ! cinfo.GetLatestDetachTime(accessTime))
                  {
                     // cinfo file does not contain any known accesses, use stat.mtime instead.
                     TRACE(Debug, "FillFileMapRecurse() could not get access time for " << new_path << ", trying stat");

                     struct stat fstat;
                     if (oss->Stat(new_path.c_str(), &fstat) == XrdOssOK)
                     {
                        accessTime = fstat.st_mtime;
                        TRACE(Dump, "FillFileMapRecurse() have access time for " << new_path << " via stat: " << accessTime);
                     }
                     else
                     {
                        // This really shouldn't happen ... but if it does remove cinfo and the data file right away.
                        TRACE(Warning, "FillFileMapRecurse() could not get access time for " << new_path << "; purging.");
                        oss->Unlink(new_path.c_str());
                        new_path = new_path.substr(0, new_path.size() - strlen(InfoExt));
                        oss->Unlink(new_path.c_str());
                        all_gauda = false;
                     }
                  }

                  if (all_gauda)
                  {
                     // TRACE(Dump, "FillFileMapRecurse() checking " << fname << " accessTime  " << accessTime);
                     checkFile(new_path, cinfo.GetNDownloadedBytes(), accessTime);
                  }
               }
               else
               {
                  TRACE(Warning, "FillFileMapRecurse() can't open or read " << new_path << ", err " << strerror(errno)
                        << "; purging.");
                  oss->Unlink(new_path.c_str());
                  new_path = new_path.substr(0, new_path.size() - InfoExtLen);
                  oss->Unlink(new_path.c_str());
               }
            }
            else if (dh->Opendir(new_path.c_str(), env) == XrdOssOK)
            {
               if (m_dir_state) cd_down(fname, new_path);

               FillFileMapRecurse(dh, new_path);

               if (m_dir_state) cd_up(path);
            }

            delete dh; dh = 0;
            delete fh; fh = 0;
         }
      }
   }

};

} // end XrdFileCache namespace


//==============================================================================
// Cache methods
//==============================================================================

void Cache::copy_out_active_stats_and_update_data_fs_state()
{
   static const char *trc_pfx = "Cache::copy_out_active_stats_and_update_data_fs_state() ";

   StatsMMap_t updates;
   {
      XrdSysCondVarHelper lock(&m_active_cond);

      updates.swap( m_closed_files_stats );

      for (ActiveMap_i i = m_active.begin(); i != m_active.end(); ++i)
      {
         updates.insert(std::make_pair(i->first, i->second->DeltaStatsFromLastCall()));
      }
   }

   m_fs_state->reset_stats();

   for (StatsMMap_i i = updates.begin(); i != updates.end(); ++i)
   {
      DirState *ds = m_fs_state->find_dirstate_for_lfn(i->first);

      if (ds == 0)
      {
         TRACE(Error, trc_pfx << "Failed finding DirState for file '" << i->first << "'.");
         continue;
      }

      ds->add_up_stats(i->second);
   }

   m_fs_state->upward_propagate_stats();
}


//==============================================================================

void Cache::Purge()
{
   static const char *trc_pfx = "Cache::Purge() ";

   XrdOucEnv    env;
   XrdOss*      oss = Cache::GetInstance().GetOss();
   XrdOssVSInfo sP;
   long long    disk_usage;
   long long    estimated_file_usage = m_configuration.m_diskUsageHWM;

   // Pause before initial run
   sleep(1);

   if (m_configuration.are_dirstats_enabled()) m_fs_state = new DataFsState;

   // { PathTokenizer p("/a/b/c/f.root", 2, true); p.deboog(); }
   // { PathTokenizer p("/a/b/f.root", 2, true); p.deboog(); }
   // { PathTokenizer p("/a/f.root", 2, true); p.deboog(); }
   // { PathTokenizer p("/f.root", 2, true); p.deboog(); }

   int  age_based_purge_countdown = 0; // enforce on first purge loop entry.
   bool is_first = true;

   while (true)
   {
      time_t purge_start = time(0);

      {
         XrdSysCondVarHelper lock(&m_active_cond);

         m_in_purge = true;
      }

      TRACE(Info, trc_pfx << "Started.");

      long long bytesToRemove_d = 0, bytesToRemove_f = 0;

      // get amount of space to potentially erase based on total disk usage
      if (oss->StatVS(&sP, m_configuration.m_data_space.c_str(), 1) < 0)
      {
         TRACE(Error, trc_pfx << "can't get StatVS for oss space " << m_configuration.m_data_space);
         continue;
      }
      else
      {
         disk_usage = sP.Total - sP.Free;
         TRACE(Debug, trc_pfx << "used disk space " << disk_usage << " bytes.");

         if (disk_usage > m_configuration.m_diskUsageHWM)
         {
            bytesToRemove_d = disk_usage - m_configuration.m_diskUsageLWM;
         }
      }

      // estimate amount of space to erase based on file usage
      if (m_configuration.are_file_usage_limits_set())
      {
         long long estimated_writes_since_last_purge;
         {
            XrdSysCondVarHelper lock(&m_writeQ.condVar);

            estimated_writes_since_last_purge = m_writeQ.writes_between_purges;
            m_writeQ.writes_between_purges = 0;
         }
         estimated_file_usage += estimated_writes_since_last_purge;

         TRACE(Debug, trc_pfx << "estimated usage by files " << estimated_file_usage << " bytes.");

         bytesToRemove_f = std::max(estimated_file_usage - m_configuration.m_fileUsageNominal, 0ll);

         // Here we estimate fractional usages -- to decide if full scan is necessary before actual purge.
         double frac_du = 0, frac_fu = 0;
         m_configuration.calculate_fractional_usages(disk_usage, estimated_file_usage, frac_du, frac_fu);

         if (frac_fu > 1.0 - frac_du)
         {
            bytesToRemove_f = std::max(bytesToRemove_f, disk_usage - m_configuration.m_diskUsageLWM);
         }
      }

      long long bytesToRemove = std::max(bytesToRemove_d, bytesToRemove_f);

      bool enforce_age_based_purge = false;
      if (m_configuration.is_age_based_purge_in_effect())
      {
         if (--age_based_purge_countdown <= 0)
         {
            enforce_age_based_purge   = true;
            age_based_purge_countdown = m_configuration.m_purgeColdFilesPeriod;
         }
      }

      bool enforce_traversal_for_usage_collection = false;

      if (m_fs_state)
      {
         copy_out_active_stats_and_update_data_fs_state();

         enforce_traversal_for_usage_collection = is_first;

         // XXX Other conditions? Periodic checks?
      }

      TRACE(Debug, trc_pfx << "Precheck:");
      TRACE(Debug, "\tbytes_to_remove_disk    = " << bytesToRemove_d << " B");
      TRACE(Debug, "\tbytes_to remove_files   = " << bytesToRemove_f << " B (" << (is_first ? "max possible for initial run" : "estimated") << ")");
      TRACE(Debug, "\tbytes_to_remove         = " << bytesToRemove   << " B");
      TRACE(Debug, "\tenforce_age_based_purge = " << enforce_age_based_purge);
      is_first = false;

      long long bytesToRemove_at_start = 0; // set after file scan
      int       deleted_file_count     = 0;

      bool purge_required = (bytesToRemove > 0 || enforce_age_based_purge);

      FPurgeState purgeState(2 * bytesToRemove); // prepare twice more volume than required

      if (purge_required || enforce_traversal_for_usage_collection)
      {
         // Make a sorted map of file paths sorted by access time.

         if (m_configuration.is_age_based_purge_in_effect())
         {
            purgeState.setMinTime(time(0) - m_configuration.m_purgeColdFilesAge);
         }

         XrdOssDF* dh = oss->newDir(m_configuration.m_username.c_str());
         if (dh->Opendir("", env) == XrdOssOK)
         {
            if (m_fs_state)
            {
               purgeState.begin_traversal(m_fs_state->get_root());
            }

            purgeState.FillFileMapRecurse(dh, "");

            if (m_fs_state)
            {
               purgeState.end_traversal();
            }

            dh->Close();
         }
         delete dh; dh = 0;

         estimated_file_usage = purgeState.getNBytesTotal();

         TRACE(Debug, trc_pfx << "actual usage by files " << estimated_file_usage << " bytes.");

         // Adjust bytesToRemove_f and then bytesToRemove based on actual file usage,
         // possibly retreating below nominal file usage (but not below baseline file usage).
         if (m_configuration.are_file_usage_limits_set())
         {
            bytesToRemove_f = std::max(estimated_file_usage - m_configuration.m_fileUsageNominal, 0ll);

            double frac_du = 0, frac_fu = 0;
            m_configuration.calculate_fractional_usages(disk_usage, estimated_file_usage, frac_du, frac_fu);

            if (frac_fu > 1.0 - frac_du)
            {
               bytesToRemove = std::max(bytesToRemove_f, disk_usage - m_configuration.m_diskUsageLWM);
               bytesToRemove = std::min(bytesToRemove,   estimated_file_usage - m_configuration.m_fileUsageBaseline);
            }
            else
            {
               bytesToRemove = std::max(bytesToRemove_d, bytesToRemove_f);
            }
         }
         else
         {
            bytesToRemove = std::max(bytesToRemove_d, bytesToRemove_f);
         }
         bytesToRemove_at_start = bytesToRemove;

         TRACE(Debug, trc_pfx << "After scan:");
         TRACE(Debug, "\tbytes_to_remove_disk    = " << bytesToRemove_d << " B");
         TRACE(Debug, "\tbytes_to remove_files   = " << bytesToRemove_f << " B (measured)");
         TRACE(Debug, "\tbytes_to_remove         = " << bytesToRemove   << " B");
         TRACE(Debug, "\tenforce_age_based_purge = " << enforce_age_based_purge);
         TRACE(Debug, "\tmin_time                = " << purgeState.getMinTime());

         if (enforce_age_based_purge)
         {
            purgeState.MoveListEntriesToMap();
         }
      }

      // Dump statistcs before actual purging so maximum usage values get recorded.
      if (m_fs_state)
      {
         m_fs_state->dump_recursively();
      }

      if (purge_required)
      {
         // Loop over map and remove files with oldest values of access time.
         struct stat fstat;
         int         protected_cnt = 0;
         long long   protected_sum = 0;
         for (FPurgeState::map_i it = purgeState.m_fmap.begin(); it != purgeState.m_fmap.end(); ++it)
         {
            // Finish when enough space has been freed but not while purging of cold files is in progress.
            if (bytesToRemove <= 0 && ! (m_configuration.is_age_based_purge_in_effect() && it->first < purgeState.getMinTime()))
            {
               break;
            }

            std::string infoPath = it->second.path;
            std::string dataPath = infoPath.substr(0, infoPath.size() - strlen(XrdFileCache::Info::s_infoExtension));

            if (IsFileActiveOrPurgeProtected(dataPath))
            {
               ++protected_cnt;
               protected_sum += it->second.nBytes;
               TRACE(Debug, trc_pfx << "File is active or purge-protected: " << dataPath << " size: " << it->second.nBytes);
               continue;
            }

            // remove info file
            if (oss->Stat(infoPath.c_str(), &fstat) == XrdOssOK)
            {
               // cinfo file can be on another oss.space, do not subtract for now.
               // Could be relevant for very small block sizes.
               // bytesToRemove        -= fstat.st_size;
               // estimated_file_usage -= fstat.st_size;
               // ++deleted_file_count;

               oss->Unlink(infoPath.c_str());
               TRACE(Dump, trc_pfx << "Removed file: '" << infoPath << "' size: " << fstat.st_size);
            }

            // remove data file
            if (oss->Stat(dataPath.c_str(), &fstat) == XrdOssOK)
            {
               bytesToRemove        -= it->second.nBytes;
               estimated_file_usage -= it->second.nBytes;
               ++deleted_file_count;

               oss->Unlink(dataPath.c_str());
               TRACE(Dump, trc_pfx << "Removed file: '" << dataPath << "' size: " << it->second.nBytes << ", time: " << it->first);

               if (m_fs_state)
               {
                  DirState *ds = m_fs_state->find_dirstate_for_lfn(dataPath);
                  if (ds != 0)
                     ds->add_usage_purged(it->second.nBytes);
                  else
                     TRACE(Error, trc_pfx << "Failed finding DirState for file '" << dataPath << "'.");
               }
            }
         }
         if (protected_cnt > 0)
         {
            TRACE(Info, trc_pfx << "Encountered " << protected_cnt << " protected files, sum of their size: " << protected_sum);
         }
         if (m_fs_state)
         {
            m_fs_state->upward_propagate_usage_purged();
         }
      }

      {
         XrdSysCondVarHelper lock(&m_active_cond);

         m_purge_delay_set.clear();
         m_in_purge = false;
      }

      int purge_duration = time(0) - purge_start;

      TRACE(Info, trc_pfx << "Finished, removed " << deleted_file_count << " data files, total size " <<
            bytesToRemove_at_start - bytesToRemove << ", bytes to remove at end " << bytesToRemove << ", purge duration " << purge_duration);

      int sleep_time = m_configuration.m_purgeInterval - purge_duration;
      if (sleep_time > 0)
      {
         sleep(sleep_time);
      }
   }
}


//==============================================================================
// DirStats specific stuff
//==============================================================================

/*

  RRDtool DB sketch

  # --start is not needed, default is "now - 10s"

  rrdtool create <dirname>.crrd --step <purge_interval> \
     DS:open_events:ABSOLUTE:<2*purge_interval>:0:1000 \
     DS:access_duration:ABSOLUTE:<2*purge_interval>:0:1000000 \
     DS:bytes_disk:ABSOLUTE:<2*purge_interval>:0:100000000000 \
     DS:bytes_fetch:ABSOLUTE:<2*purge_interval>:0:100000000000 \
     DS:bytes_bypass:ABSOLUTE:<2*purge_interval>:0:100000000000 \
     DS:bytes_served:COMPUTE:bytes_disk,bytes_fetch,bytes_bypass,+,+ \
     RRA:AVERAGE:0.5:<purge_interval>s:1w \
     RRA:AVERAGE:0.5:1h:1M \
     RRA:AVERAGE:0.5:1d:1y \

  Questions / Issues:
  1. DS min / max -- are they for values after division or before?
     I'm assuming after in the above.
  2. What is xxf (argument to RRA)? The thing that is usually 0.5.
     Ah, xfiles factor, how much can be unknown for consolidated value to eb known.
  3. Use COMPUTE for bytes_served (sum of the others).
     ARGH, can not change heartbeat for COMPUTE, will pass it in manually.
  4. Use rddtool tune -h to change heartbeat on start (if different, maybe).

  5. ADD disk_usage !!!!
     DS:disk_usage:GAUGE:...
 */