DiskReadThreadPool.cpp 9.71 KB
Newer Older
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
/******************************************************************************
 *
 * 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
 *****************************************************************************/

#include "castor/tape/tapeserver/daemon/DiskReadThreadPool.hpp"
25
#include "castor/tape/tapeserver/daemon/MigrationTaskInjector.hpp"
26
#include "castor/tape/tapeserver/daemon/MigrationReportPacker.hpp"
27
#include "castor/common/CastorConfiguration.hpp"
28
29
#include <memory>
#include <sstream>
30

31
32
33
34
namespace castor {
namespace tape {
namespace tapeserver {
namespace daemon {
35
36
37
38

//------------------------------------------------------------------------------
// DiskReadThreadPool constructor
//------------------------------------------------------------------------------
39
40
DiskReadThreadPool::DiskReadThreadPool(int nbThread, uint64_t maxFilesReq,uint64_t maxBytesReq,
    castor::tape::tapeserver::daemon::MigrationWatchDog & migrationWatchDog,
41
    castor::log::LogContext lc, const std::string & remoteFileProtocol,
42
43
    const std::string & xrootPrivateKeyPath) : 
    m_diskFileFactory(remoteFileProtocol, xrootPrivateKeyPath),
44
    m_watchdog(migrationWatchDog),
45
    m_lc(lc),m_maxFilesReq(maxFilesReq),
46
    m_maxBytesReq(maxBytesReq), m_nbActiveThread(0) {
47
48
49
50
  for(int i=0; i<nbThread; i++) {
    DiskReadWorkerThread * thr = new DiskReadWorkerThread(*this);
    m_threads.push_back(thr);
    m_lc.pushOrReplace(log::Param("threadID",i));
51
    m_lc.log(LOG_DEBUG, "DiskReadWorkerThread created");
52
  }
53
54
55
56
57
58
59
60
61
}

//------------------------------------------------------------------------------
// DiskReadThreadPool destructor
//------------------------------------------------------------------------------
DiskReadThreadPool::~DiskReadThreadPool() { 
  while (m_threads.size()) {
    delete m_threads.back();
    m_threads.pop_back();
62
  }
63
  m_lc.log(LOG_DEBUG, "Deleted threads in DiskReadThreadPool::~DiskReadThreadPool");
64
65
66
67
68
69
70
71
72
}

//------------------------------------------------------------------------------
// DiskReadThreadPool::startThreads
//------------------------------------------------------------------------------
void DiskReadThreadPool::startThreads() {
  for (std::vector<DiskReadWorkerThread *>::iterator i=m_threads.begin();
          i != m_threads.end(); i++) {
    (*i)->start();
73
  }
74
75
76
77
78
79
80
81
82
83
  m_lc.log(LOG_INFO, "All the DiskReadWorkerThreads are started");
}

//------------------------------------------------------------------------------
// DiskReadThreadPool::waitThreads
//------------------------------------------------------------------------------
void DiskReadThreadPool::waitThreads() {
  for (std::vector<DiskReadWorkerThread *>::iterator i=m_threads.begin();
          i != m_threads.end(); i++) {
    (*i)->wait();
84
  }
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
}

//------------------------------------------------------------------------------
// DiskReadThreadPool::push
//------------------------------------------------------------------------------
void DiskReadThreadPool::push(DiskReadTask *t) { 
  m_tasks.push(t); 
  m_lc.log(LOG_INFO, "Push a task into the DiskReadThreadPool");
}

//------------------------------------------------------------------------------
// DiskReadThreadPool::finish
//------------------------------------------------------------------------------
void DiskReadThreadPool::finish() {
  /* Insert one endOfSession per thread */
  for (size_t i=0; i<m_threads.size(); i++) {
    m_tasks.push(NULL);
102
  }
103
104
105
106
107
108
}

//------------------------------------------------------------------------------
// DiskReadThreadPool::popAndRequestMore
//------------------------------------------------------------------------------
DiskReadTask* DiskReadThreadPool::popAndRequestMore(castor::log::LogContext &lc){
109
  castor::server::BlockingQueue<DiskReadTask*>::valueRemainingPair 
110
111
112
113
114
115
116
117
118
119
  vrp = m_tasks.popGetSize();
  log::LogContext::ScopedParam sp(lc, log::Param("m_maxFilesReq", m_maxFilesReq));
  log::LogContext::ScopedParam sp0(lc, log::Param("m_maxBytesReq", m_maxBytesReq));

  if(0==vrp.remaining){
    m_injector->requestInjection(true);
    lc.log(LOG_DEBUG, "Requested injection from MigrationTaskInjector (with last call)");
  }else if(vrp.remaining + 1 ==  m_maxFilesReq/2){
    m_injector->requestInjection(false);
    lc.log(LOG_DEBUG, "Requested injection from MigrationTaskInjector (without last call)");
120
  }
121
122
  return vrp.value;
}
123
124
125
//------------------------------------------------------------------------------
//addThreadStats
//------------------------------------------------------------------------------
126
void DiskReadThreadPool::addThreadStats(const DiskStats& other){
127
  castor::server::MutexLocker lock(&m_statAddingProtection);
128
129
  m_pooldStat+=other;
}
130
//------------------------------------------------------------------------------
131
132
133
//logWithStat
//------------------------------------------------------------------------------
void DiskReadThreadPool::logWithStat(int level, const std::string& message){
134
  m_pooldStat.totalTime = m_totalTime.secs();
135
  log::ScopedParamContainer params(m_lc);
136
137
138
139
140
141
  params.add("poolReadWriteTime", m_pooldStat.readWriteTime)
        .add("poolWaitFreeMemoryTime",m_pooldStat.waitFreeMemoryTime)
        .add("poolCheckingErrorTime",m_pooldStat.checkingErrorTime)
        .add("poolOpeningTime",m_pooldStat.openingTime)
        .add("poolTransferTime", m_pooldStat.transferTime)
        .add("poolRealTime",m_pooldStat.totalTime)
142
        .add("poolFileCount",m_pooldStat.filesCount)
Eric Cano's avatar
Eric Cano committed
143
        .add("poolDataVolume", m_pooldStat.dataVolume)
144
        .add("poolGlobalPayloadTransferSpeedMBps",
145
           m_pooldStat.totalTime?1.0*m_pooldStat.dataVolume/1000/1000/m_pooldStat.totalTime:0)
146
        .add("poolAverageDiskPerformanceMBps",
147
           m_pooldStat.transferTime?1.0*m_pooldStat.dataVolume/1000/1000/m_pooldStat.transferTime:0.0)
148
        .add("poolOpenRWCloseToTransferTimeRatio",
149
           m_pooldStat.transferTime?(m_pooldStat.openingTime+m_pooldStat.readWriteTime+m_pooldStat.closingTime)/m_pooldStat.transferTime:0.0);
150
  m_lc.log(level,message);
151
152
}
//------------------------------------------------------------------------------
153
// DiskReadWorkerThread::run
154
155
//------------------------------------------------------------------------------
void DiskReadThreadPool::DiskReadWorkerThread::run() {
156
157
158
159
  castor::log::ScopedParamContainer logParams(m_lc);
  logParams.add("thread", "DiskRead")
           .add("threadID", m_threadID);
  m_lc.log(LOG_DEBUG, "Starting DiskReadWorkerThread");
160
  
161
  std::unique_ptr<DiskReadTask> task;
162
  castor::utils::Timer localTime;
163
  castor::utils::Timer totalTime;
164
  
165
166
  while(1) {
    task.reset( m_parent.popAndRequestMore(m_lc));
167
    m_threadStat.waitInstructionsTime += localTime.secs(castor::utils::Timer::resetCounter);
168
    if (NULL!=task.get()) {
169
      task->execute(m_lc, m_parent.m_diskFileFactory,m_parent.m_watchdog);
170
      m_threadStat += task->getTaskStats();
171
    }
172
173
    else {
      break;
174
    }
175
  } //end of while(1)
176
177
178
  m_threadStat.totalTime = totalTime.secs();
  m_parent.addThreadStats(m_threadStat);
  logWithStat(LOG_INFO, "Finishing of DiskReadWorkerThread");
179
180
  // We now acknowledge to the task injector that read reached the end. There
  // will hence be no more requests for more. (last thread turns off the light)
181
182
  int remainingThreads = --m_parent.m_nbActiveThread;
  if (!remainingThreads) {
183
    m_parent.m_injector->finish();
184
185
    m_lc.log(LOG_INFO, "Signalled to task injector the end of disk read threads");
    m_parent.logWithStat(LOG_INFO, "All the DiskReadWorkerThreads have completed");
186
187
188
189
  } else {
    castor::log::ScopedParamContainer params(m_lc);
    params.add("remainingThreads", remainingThreads);
    m_lc.log(LOG_DEBUG, "Will not signal the end to task injector yet");
190
  }
191
192
193
194
195
196
197
198
}

//------------------------------------------------------------------------------
// DiskReadWorkerThread::logWithStat
//------------------------------------------------------------------------------
void DiskReadThreadPool::DiskReadWorkerThread::
logWithStat(int level, const std::string& message){
  log::ScopedParamContainer params(m_lc);
199
200
201
202
203
204
     params.add("threadReadWriteTime", m_threadStat.readWriteTime)
           .add("threadWaitFreeMemoryTime",m_threadStat.waitFreeMemoryTime)
           .add("threadCheckingErrorTime",m_threadStat.checkingErrorTime)
           .add("threadOpeningTime",m_threadStat.openingTime)
           .add("threadTransferTime",m_threadStat.transferTime)
           .add("threadTotalTime",m_threadStat.totalTime)
205
206
           .add("threadDataVolume",m_threadStat.dataVolume)
           .add("threadFileCount",m_threadStat.filesCount)
207
           .add("threadGlobalPayloadTransferSpeedMBps",
208
              m_threadStat.totalTime?1.0*m_threadStat.dataVolume/1000/1000/m_threadStat.totalTime:0)
209
           .add("threadAverageDiskPerformanceMBps",
210
              m_threadStat.transferTime?1.0*m_threadStat.dataVolume/1000/1000/m_threadStat.transferTime:0.0)
211
           .add("threadOpenRWCloseToTransferTimeRatio",
212
              m_threadStat.transferTime?(m_threadStat.openingTime+m_threadStat.readWriteTime+m_threadStat.closingTime)/m_threadStat.transferTime:0.0);
213
    m_lc.log(level,message);
214
}
215
}}}}
216