TapeReadTask.hpp

/******************************************************************************
 *
 * This file is part of the Castor project.
 * See http://castor.web.cern.ch/castor
 *
 * Copyright (C) 2003  CERN
 * This program is free software; you can redistribute it and/or
 * modify it under the terms of the GNU General Public License
 * as published by the Free Software Foundation; either version 2
 * of the License, or (at your option) any later version.
 * This program is distributed in the hope that it will be useful,
 * but WITHOUT ANY WARRANTY; without even the implied warranty of
 * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
 * GNU General Public License for more details.
 * You should have received a copy of the GNU General Public License
 * along with this program; if not, write to the Free Software
 * Foundation, Inc., 59 Temple Place - Suite 330, Boston, MA 02111-1307, USA.
 *
 * 
 *
 * @author Castor Dev team, castor-dev@cern.ch
 *****************************************************************************/

#pragma once


#include "castor/tape/tapeserver/daemon/DataPipeline.hpp"
#include "castor/tape/tapeserver/daemon/RecallMemoryManager.hpp"
#include "castor/tape/tapeserver/daemon/DataConsumer.hpp"
#include "castor/exception/Exception.hpp"
#include "castor/tape/tapeserver/daemon/AutoReleaseBlock.hpp"
#include "castor/tape/tapeserver/daemon/TaskWatchDog.hpp"
#include "castor/tape/tapeserver/daemon/TapeSessionStats.hpp"
#include "castor/utils/Timer.hpp"

namespace castor {
namespace tape {
namespace tapeserver {
namespace daemon {
  /**
   * This class is in charge of 
   * 
   */
class TapeReadTask {
public:
  /**
   * COnstructor
   * @param ftr The file being recalled. We acquire the ownership on the pointer
   * @param destination the task that will consume the memory blocks
   * @param mm The memory manager to get free block
   */
  TapeReadTask(cta::RetrieveJob *retrieveJob,
    DataConsumer & destination, RecallMemoryManager & mm): 
    m_retrieveJob(retrieveJob), m_fifo(destination), m_mm(mm) {}
    
    /**
     * @param rs the read session holding all we need to be able to read from the tape
     * @param lc the log context for .. logging purpose
     * The actual function that will do the job.
     * The main loop is :
     * Acquire a free memory block from the memory manager , fill it, push it 
     */
  void execute(castor::tape::tapeFile::ReadSession & rs,
    castor::log::LogContext & lc,RecallWatchDog& watchdog,
    TapeSessionStats & stats, castor::utils::Timer & timer) {

    using castor::log::Param;
    typedef castor::log::LogContext::ScopedParam ScopedParam;
    
    // Set the common context for all the coming logs (file info)
    log::ScopedParamContainer params(lc);
    params.add("NSFILEID", m_retrieveJob->archiveFile.archiveFileID)
          .add("BlockId", m_retrieveJob->tapeFile.blockId)
          .add("fSeq", m_retrieveJob->tapeFile.fSeq)
          .add("path", m_retrieveJob->remotePath);
    
    // We will clock the stats for the file itself, and eventually add those
    // stats to the session's.
    TapeSessionStats localStats;
    std::string LBPMode;
    castor::utils::Timer localTime;
    castor::utils::Timer totalTime(localTime);

    // Read the file and transmit it
    bool stillReading = true;
    //for counting how many mem blocks have used and how many tape blocks
    //(because one mem block can hold several tape blocks
    int fileBlock = 0;
    int tapeBlock = 0;
    // This out-of-try-catch variables allows us to record the stage of the 
    // process we're in, and to count the error if it occurs.
    // We will not record errors for an empty string. This will allow us to
    // prevent counting where error happened upstream.
    std::string currentErrorToCount = "";
    MemBlock* mb=NULL;
    try {
      currentErrorToCount = "Error_tapePositionForRead";
      std::unique_ptr<castor::tape::tapeFile::ReadFile> rf(openReadFile(rs,lc));
      LBPMode = rf->getLBPMode();
      // At that point we already read the header.
      localStats.headerVolume += TapeSessionStats::headerVolumePerFile;

      lc.log(LOG_INFO, "Successfully positioned for reading");
      localStats.positionTime += timer.secs(castor::utils::Timer::resetCounter);
      watchdog.notifyBeginNewJob(m_retrieveJob->archiveFile.archiveFileID, m_retrieveJob->tapeFile.fSeq);
      localStats.waitReportingTime += timer.secs(castor::utils::Timer::resetCounter);
      currentErrorToCount = "Error_tapeReadData";
      while (stillReading) {
        // Get a memory block and add information to its metadata
        mb=m_mm.getFreeBlock();
        localStats.waitFreeMemoryTime += timer.secs(castor::utils::Timer::resetCounter);
        
        mb->m_fSeq = m_retrieveJob->tapeFile.fSeq;
        mb->m_fileBlock = fileBlock++;
        mb->m_fileid = m_retrieveJob->archiveFile.archiveFileID;
        mb->m_tapeFileBlock = tapeBlock;
        mb->m_tapeBlockSize = rf->getBlockSize();
        try {
          // Fill up the memory block with tape block
          // append conveniently returns false when there will not be more space
          // for an extra tape block, and throws an exception if we reached the
          // end of file. append() also protects against reading too big tape blocks.
          while (mb->m_payload.append(*rf)) {
            tapeBlock++;
          }
        } catch (const castor::exception::EndOfFile&) {
          // append() signaled the end of the file.
          stillReading = false;
        }
        localStats.readWriteTime += timer.secs(castor::utils::Timer::resetCounter);
        localStats.dataVolume += mb->m_payload.size();
        // Pass the block to the disk write task
        m_fifo.pushDataBlock(mb);
        mb=NULL;
        watchdog.notify();
        localStats.waitReportingTime += timer.secs(castor::utils::Timer::resetCounter);
      } //end of while(stillReading)
      //  we have to signal the end of the tape read to the disk write task.
      m_fifo.pushDataBlock(NULL);
      // Log the successful transfer
      localStats.totalTime = localTime.secs();
      // Count the trailer size
      localStats.headerVolume += TapeSessionStats::trailerVolumePerFile;
      // We now transmitted one file:
      localStats.filesCount++;
      params.add("positionTime", localStats.positionTime)
            .add("readWriteTime", localStats.readWriteTime)
            .add("waitFreeMemoryTime",localStats.waitFreeMemoryTime)
            .add("waitReportingTime",localStats.waitReportingTime)
            .add("transferTime",localStats.transferTime())
            .add("totalTime", localStats.totalTime)
            .add("dataVolume",localStats.dataVolume)
            .add("headerVolume",localStats.headerVolume)
            .add("driveTransferSpeedMBps",
                    localStats.totalTime?(1.0*localStats.dataVolume+1.0*localStats.headerVolume)
                     /1000/1000/localStats.totalTime:0)
            .add("payloadTransferSpeedMBps",
                     localStats.totalTime?1.0*localStats.dataVolume/1000/1000/localStats.totalTime:0)
            .add("LBPMode", LBPMode);
      lc.log(LOG_INFO, "File successfully read from tape");
      // Add the local counts to the session's
      stats.add(localStats);
    } //end of try
    catch (const castor::exception::Exception & ex) {
      //we end up there because :
      //-- openReadFile brought us here (cant position to the file)
      //-- m_payload.append brought us here (error while reading the file)
      // Record the error in the watchdog
      if (currentErrorToCount.size()) {
        watchdog.addToErrorCount(currentErrorToCount);
      }
      // This is an error case. Log and signal to the disk write task
      { 
        castor::log::LogContext::ScopedParam sp0(lc, Param("fileBlock", fileBlock));
        castor::log::LogContext::ScopedParam sp1(lc, Param("ErrorMessage", ex.getMessageValue()));
        castor::log::LogContext::ScopedParam sp2(lc, Param("ErrorCode", ex.code()));
        lc.log(LOG_ERR, "Error reading a file in TapeReadFileTask (backtrace follows)");
      }
      {
        castor::log::LogContext lc2(lc.logger());
        lc2.logBacktrace(LOG_ERR, ex.backtrace());
      }
      
      // mb might or might not be allocated at this point, but 
      // reportErrorToDiskTask will deal with the allocation if required.
      reportErrorToDiskTask(ex.getMessageValue(),ex.code(),mb);
    } //end of catch
    watchdog.fileFinished();
  }
  /**
   * Get a valid block and ask to cancel the disk write task
   */
  void reportCancellationToDiskTask(){
    MemBlock* mb =m_mm.getFreeBlock();
    mb->m_fSeq = m_retrieveJob->tapeFile.fSeq;
    mb->m_fileid = m_retrieveJob->archiveFile.archiveFileID;
    //mark the block cancelled and push it (plus signal the end)
     mb->markAsCancelled();
     m_fifo.pushDataBlock(mb);
  }
private:
  /**
   * Do the actual report to the disk write task
   * @param errorMsg The error message we will give to the client
   * @param mb The mem block we will use
   */
  void reportErrorToDiskTask(const std::string& msg,int code,MemBlock* mb = NULL){
    //If we are not provided with a block, allocate it and
    // fill it up
    if (!mb) {
      mb=m_mm.getFreeBlock();
      mb->m_fSeq = m_retrieveJob->tapeFile.fSeq;
      mb->m_fileid = m_retrieveJob->archiveFile.archiveFileID;
    }
    //mark the block failed and push it (plus signal the end)
     mb->markAsFailed(msg,code);
     m_fifo.pushDataBlock(mb);
     m_fifo.pushDataBlock(NULL);
   }
  /** 
   * Open the file on the tape. In case of failure, log and throw
   * Copying the unique_ptr on the calling point will give us the ownership of the 
   * object.
   * @return if successful, return an unique_ptr on the ReadFile we want
   */
  std::unique_ptr<castor::tape::tapeFile::ReadFile> openReadFile(
  castor::tape::tapeFile::ReadSession & rs, castor::log::LogContext & lc){

    using castor::log::Param;
    typedef castor::log::LogContext::ScopedParam ScopedParam;

    std::unique_ptr<castor::tape::tapeFile::ReadFile> rf;
    try {
      rf.reset(new castor::tape::tapeFile::ReadFile(&rs, *m_retrieveJob));
      lc.log(LOG_DEBUG, "Successfully opened the tape file");
    } catch (castor::exception::Exception & ex) {
      // Log the error
      ScopedParam sp0(lc, Param("ErrorMessage", ex.getMessageValue()));
      ScopedParam sp1(lc, Param("ErrorCode", ex.code()));
      lc.log(LOG_ERR, "Failed to open tape file for reading");
      throw;
    }
    return rf;
  }
  
  /**
   * All we need to know about the file we are recalling
   */
  cta::RetrieveJob *m_retrieveJob;
  
  /**
   * The task (seen as a Y) that will consume all the blocks we read
   */
  DataConsumer & m_fifo;
  
  /**
   *  The MemoryManager from whom we get free memory blocks 
   */
  RecallMemoryManager & m_mm;

};
}
}
}
}