DiskReadThreadPool.cpp 9.76 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
44
    const std::string & xrootPrivateKeyPath,
    uint16_t moverHandlerPort) : 
    m_diskFileFactory(remoteFileProtocol, xrootPrivateKeyPath, moverHandlerPort),
45
    m_watchdog(migrationWatchDog),
46
    m_lc(lc),m_maxFilesReq(maxFilesReq),
47
    m_maxBytesReq(maxBytesReq), m_nbActiveThread(0) {
48
49
50
51
  for(int i=0; i<nbThread; i++) {
    DiskReadWorkerThread * thr = new DiskReadWorkerThread(*this);
    m_threads.push_back(thr);
    m_lc.pushOrReplace(log::Param("threadID",i));
52
    m_lc.log(LOG_DEBUG, "DiskReadWorkerThread created");
53
  }
54
55
56
57
58
59
60
61
62
}

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

//------------------------------------------------------------------------------
// DiskReadThreadPool::startThreads
//------------------------------------------------------------------------------
void DiskReadThreadPool::startThreads() {
  for (std::vector<DiskReadWorkerThread *>::iterator i=m_threads.begin();
          i != m_threads.end(); i++) {
    (*i)->start();
74
  }
75
76
77
78
79
80
81
82
83
84
  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();
85
  }
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
}

//------------------------------------------------------------------------------
// 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);
103
  }
104
105
106
107
108
109
}

//------------------------------------------------------------------------------
// DiskReadThreadPool::popAndRequestMore
//------------------------------------------------------------------------------
DiskReadTask* DiskReadThreadPool::popAndRequestMore(castor::log::LogContext &lc){
110
  castor::server::BlockingQueue<DiskReadTask*>::valueRemainingPair 
111
112
113
114
115
116
117
118
119
120
  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)");
121
  }
122
123
  return vrp.value;
}
124
125
126
//------------------------------------------------------------------------------
//addThreadStats
//------------------------------------------------------------------------------
127
void DiskReadThreadPool::addThreadStats(const DiskStats& other){
128
  castor::server::MutexLocker lock(&m_statAddingProtection);
129
130
  m_pooldStat+=other;
}
131
//------------------------------------------------------------------------------
132
133
134
//logWithStat
//------------------------------------------------------------------------------
void DiskReadThreadPool::logWithStat(int level, const std::string& message){
135
  m_pooldStat.totalTime = m_totalTime.secs();
136
  log::ScopedParamContainer params(m_lc);
137
138
139
140
141
142
  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)
143
        .add("poolFileCount",m_pooldStat.filesCount)
Eric Cano's avatar
Eric Cano committed
144
        .add("poolDataVolume", m_pooldStat.dataVolume)
145
        .add("poolGlobalPayloadTransferSpeedMBps",
146
           m_pooldStat.totalTime?1.0*m_pooldStat.dataVolume/1000/1000/m_pooldStat.totalTime:0)
147
        .add("poolAverageDiskPerformanceMBps",
148
           m_pooldStat.transferTime?1.0*m_pooldStat.dataVolume/1000/1000/m_pooldStat.transferTime:0.0)
149
        .add("poolOpenRWCloseToTransferTimeRatio",
150
           m_pooldStat.transferTime?(m_pooldStat.openingTime+m_pooldStat.readWriteTime+m_pooldStat.closingTime)/m_pooldStat.transferTime:0.0);
151
  m_lc.log(level,message);
152
153
}
//------------------------------------------------------------------------------
154
// DiskReadWorkerThread::run
155
156
//------------------------------------------------------------------------------
void DiskReadThreadPool::DiskReadWorkerThread::run() {
157
158
159
160
  castor::log::ScopedParamContainer logParams(m_lc);
  logParams.add("thread", "DiskRead")
           .add("threadID", m_threadID);
  m_lc.log(LOG_DEBUG, "Starting DiskReadWorkerThread");
161
  
162
  std::unique_ptr<DiskReadTask> task;
163
  castor::utils::Timer localTime;
164
  castor::utils::Timer totalTime;
165
  
166
167
  while(1) {
    task.reset( m_parent.popAndRequestMore(m_lc));
168
    m_threadStat.waitInstructionsTime += localTime.secs(castor::utils::Timer::resetCounter);
169
    if (NULL!=task.get()) {
170
      task->execute(m_lc, m_parent.m_diskFileFactory,m_parent.m_watchdog);
171
      m_threadStat += task->getTaskStats();
172
    }
173
174
    else {
      break;
175
    }
176
  } //end of while(1)
177
178
179
  m_threadStat.totalTime = totalTime.secs();
  m_parent.addThreadStats(m_threadStat);
  logWithStat(LOG_INFO, "Finishing of DiskReadWorkerThread");
180
181
  // 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)
182
183
  int remainingThreads = --m_parent.m_nbActiveThread;
  if (!remainingThreads) {
184
    m_parent.m_injector->finish();
185
186
    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");
187
188
189
190
  } 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");
191
  }
192
193
194
195
196
197
198
199
}

//------------------------------------------------------------------------------
// DiskReadWorkerThread::logWithStat
//------------------------------------------------------------------------------
void DiskReadThreadPool::DiskReadWorkerThread::
logWithStat(int level, const std::string& message){
  log::ScopedParamContainer params(m_lc);
200
201
202
203
204
205
     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)
206
207
           .add("threadDataVolume",m_threadStat.dataVolume)
           .add("threadFileCount",m_threadStat.filesCount)
208
           .add("threadGlobalPayloadTransferSpeedMBps",
209
              m_threadStat.totalTime?1.0*m_threadStat.dataVolume/1000/1000/m_threadStat.totalTime:0)
210
           .add("threadAverageDiskPerformanceMBps",
211
              m_threadStat.transferTime?1.0*m_threadStat.dataVolume/1000/1000/m_threadStat.transferTime:0.0)
212
           .add("threadOpenRWCloseToTransferTimeRatio",
213
              m_threadStat.transferTime?(m_threadStat.openingTime+m_threadStat.readWriteTime+m_threadStat.closingTime)/m_threadStat.transferTime:0.0);
214
    m_lc.log(level,message);
215
}
216
}}}}
217