Commit b8c92977 authored by Ulrich Sibiller's avatar Ulrich Sibiller

nxcomp: use lowercase abbreviations for seconds and milliseconds

parent 3bd7e03e
...@@ -1238,7 +1238,7 @@ int Channel::handleWait(int timeout) ...@@ -1238,7 +1238,7 @@ int Channel::handleWait(int timeout)
} }
#ifdef TEST #ifdef TEST
*logofs << "handleWait: Waiting " << remaining << " Ms " *logofs << "handleWait: Waiting " << remaining << " ms "
<< "for a new message on FD#" << fd_ << "for a new message on FD#" << fd_
<< ".\n" << logofs_flush; << ".\n" << logofs_flush;
#endif #endif
...@@ -1306,7 +1306,7 @@ int Channel::handleDrain(int limit, int timeout) ...@@ -1306,7 +1306,7 @@ int Channel::handleDrain(int limit, int timeout)
#ifdef TEST #ifdef TEST
*logofs << "handleDrain: Trying to write to FD#" *logofs << "handleDrain: Trying to write to FD#"
<< fd_ << " with " << remaining << " Ms " << fd_ << " with " << remaining << " ms "
<< "remaining.\n" << logofs_flush; << "remaining.\n" << logofs_flush;
#endif #endif
......
...@@ -628,7 +628,7 @@ int NXTransWatchdog(int timeout) ...@@ -628,7 +628,7 @@ int NXTransWatchdog(int timeout)
{ {
#ifdef TEST #ifdef TEST
*logofs << "NXTransWatchdog: Timeout of " << timeout *logofs << "NXTransWatchdog: Timeout of " << timeout
<< " Ms raised in watchdog.\n" << logofs_flush; << " ms raised in watchdog.\n" << logofs_flush;
#endif #endif
// //
...@@ -645,7 +645,7 @@ int NXTransWatchdog(int timeout) ...@@ -645,7 +645,7 @@ int NXTransWatchdog(int timeout)
{ {
#ifdef TEST #ifdef TEST
*logofs << "NXTransWatchdog: Waiting for the timeout " *logofs << "NXTransWatchdog: Waiting for the timeout "
<< "with " << timeout - diffTs << " Ms to run.\n" << "with " << timeout - diffTs << " ms to run.\n"
<< logofs_flush; << logofs_flush;
#endif #endif
...@@ -847,7 +847,7 @@ int NXTransKeeper(int caches, int images, const char *root) ...@@ -847,7 +847,7 @@ int NXTransKeeper(int caches, int images, const char *root)
#ifdef TEST #ifdef TEST
*logofs << "NXTransKeeper: Going to sleep for " *logofs << "NXTransKeeper: Going to sleep for "
<< timeout / 20 << " Ms.\n" << logofs_flush; << timeout / 20 << " ms.\n" << logofs_flush;
#endif #endif
usleep(timeout / 20 * 1000); usleep(timeout / 20 * 1000);
...@@ -937,7 +937,7 @@ int NXTransKeeper(int caches, int images, const char *root) ...@@ -937,7 +937,7 @@ int NXTransKeeper(int caches, int images, const char *root)
#ifdef TEST #ifdef TEST
*logofs << "NXTransKeeper: Going to sleep for " << timeout *logofs << "NXTransKeeper: Going to sleep for " << timeout
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
#endif #endif
usleep(timeout * 1000); usleep(timeout * 1000);
......
...@@ -5782,7 +5782,7 @@ int ClientChannel::handleSplitEvent(DecodeBuffer &decodeBuffer) ...@@ -5782,7 +5782,7 @@ int ClientChannel::handleSplitEvent(DecodeBuffer &decodeBuffer)
#if defined(TEST) || defined(SPLIT) #if defined(TEST) || defined(SPLIT)
*logofs << "handleSplitEvent: SPLIT! Spent " *logofs << "handleSplitEvent: SPLIT! Spent "
<< diffTimestamp(startTs, getTimestamp()) << " Ms " << diffTimestamp(startTs, getTimestamp()) << " ms "
<< "handling abort split events for FD#" << fd_ << "handling abort split events for FD#" << fd_
<< ".\n" << logofs_flush; << ".\n" << logofs_flush;
#endif #endif
......
...@@ -2578,15 +2578,15 @@ int NXTransPrepare(int *setFDs, fd_set *readSet, ...@@ -2578,15 +2578,15 @@ int NXTransPrepare(int *setFDs, fd_set *readSet,
if (isTimestamp(*selectTs) == 0) if (isTimestamp(*selectTs) == 0)
{ {
nxinfo << "Loop: WARNING! Preparing the select with requested " nxinfo << "Loop: WARNING! Preparing the select with requested "
<< "timeout of " << selectTs -> tv_sec << " S and " << "timeout of " << selectTs -> tv_sec << " s and "
<< (double) selectTs -> tv_usec / 1000 << " Ms.\n" << (double) selectTs -> tv_usec / 1000 << " ms.\n"
<< std::flush; << std::flush;
} }
else else
{ {
nxinfo << "Loop: Preparing the select with requested " nxinfo << "Loop: Preparing the select with requested "
<< "timeout of " << selectTs -> tv_sec << " S and " << "timeout of " << selectTs -> tv_sec << " s and "
<< (double) selectTs -> tv_usec / 1000 << " Ms.\n" << (double) selectTs -> tv_usec / 1000 << " ms.\n"
<< std::flush; << std::flush;
} }
...@@ -2632,7 +2632,7 @@ int NXTransPrepare(int *setFDs, fd_set *readSet, ...@@ -2632,7 +2632,7 @@ int NXTransPrepare(int *setFDs, fd_set *readSet,
int diffTs = diffTimestamp(startTs, nowTs); int diffTs = diffTimestamp(startTs, nowTs);
nxinfo << "Loop: Mark - 0 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 0 - at " << strMsTimestamp()
<< " with " << diffTs << " Ms elapsed.\n" << " with " << diffTs << " ms elapsed.\n"
<< std::flush; << std::flush;
// //
...@@ -2701,7 +2701,7 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2701,7 +2701,7 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
if (diffTs > 20) if (diffTs > 20)
{ {
nxdbg << "Loop: TIME! Spent " << diffTs nxdbg << "Loop: TIME! Spent " << diffTs
<< " Ms handling messages for proxy FD#" << " ms handling messages for proxy FD#"
<< proxyFD << ".\n" << std::flush; << proxyFD << ".\n" << std::flush;
} }
...@@ -2711,8 +2711,8 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2711,8 +2711,8 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
if (isTimestamp(*selectTs) == 0) if (isTimestamp(*selectTs) == 0)
{ {
nxinfo << "Loop: WARNING! Executing the select with requested " nxinfo << "Loop: WARNING! Executing the select with requested "
<< "timeout of " << selectTs -> tv_sec << " S and " << "timeout of " << selectTs -> tv_sec << " s and "
<< (double) selectTs -> tv_usec / 1000 << " Ms.\n" << (double) selectTs -> tv_usec / 1000 << " ms.\n"
<< std::flush; << std::flush;
} }
else if (proxy != NULL && proxy -> getFlushable(proxyFD) > 0) else if (proxy != NULL && proxy -> getFlushable(proxyFD) > 0)
...@@ -2720,8 +2720,8 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2720,8 +2720,8 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: WARNING! Proxy FD#" << proxyFD nxinfo << "Loop: WARNING! Proxy FD#" << proxyFD
<< " has " << proxy -> getFlushable(proxyFD) << " has " << proxy -> getFlushable(proxyFD)
<< " bytes to write but timeout is " << " bytes to write but timeout is "
<< selectTs -> tv_sec << " S and " << selectTs -> tv_sec << " s and "
<< selectTs -> tv_usec / 1000 << " Ms.\n" << selectTs -> tv_usec / 1000 << " ms.\n"
<< std::flush; << std::flush;
} }
...@@ -2742,7 +2742,7 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2742,7 +2742,7 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
if (diffTs > 100) if (diffTs > 100)
{ {
nxdbg << "Loop: TIME! Spent " << diffTs nxdbg << "Loop: TIME! Spent " << diffTs
<< " Ms waiting for new data for proxy FD#" << " ms waiting for new data for proxy FD#"
<< proxyFD << ".\n" << std::flush; << proxyFD << ".\n" << std::flush;
} }
...@@ -2767,7 +2767,7 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2767,7 +2767,7 @@ int NXTransSelect(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
diffTs = diffTimestamp(startTs, nowTs); diffTs = diffTimestamp(startTs, nowTs);
nxinfo << "Loop: Out of select after " << diffTs << " Ms " nxinfo << "Loop: Out of select after " << diffTs << " ms "
<< "at " << strMsTimestamp(nowTs) << " with result " << "at " << strMsTimestamp(nowTs) << " with result "
<< *resultFDs << ".\n" << std::flush; << *resultFDs << ".\n" << std::flush;
...@@ -2874,7 +2874,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2874,7 +2874,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: Mark - 1 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 1 - at " << strMsTimestamp()
<< " with " << diffTimestamp(startTs, getTimestamp()) << " with " << diffTimestamp(startTs, getTimestamp())
<< " Ms elapsed.\n" << std::flush; << " ms elapsed.\n" << std::flush;
// //
// Rotate the channel that will be handled // Rotate the channel that will be handled
...@@ -2891,7 +2891,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2891,7 +2891,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: Mark - 2 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 2 - at " << strMsTimestamp()
<< " with " << diffTimestamp(startTs, getTimestamp()) << " with " << diffTimestamp(startTs, getTimestamp())
<< " Ms elapsed.\n" << std::flush; << " ms elapsed.\n" << std::flush;
// //
// Check if any socket has become readable. // Check if any socket has become readable.
...@@ -2901,7 +2901,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2901,7 +2901,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: Mark - 3 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 3 - at " << strMsTimestamp()
<< " with " << diffTimestamp(startTs, getTimestamp()) << " with " << diffTimestamp(startTs, getTimestamp())
<< " Ms elapsed.\n" << std::flush; << " ms elapsed.\n" << std::flush;
// //
// Handle the scheduled events on channels. // Handle the scheduled events on channels.
...@@ -2923,7 +2923,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2923,7 +2923,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: Mark - 4 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 4 - at " << strMsTimestamp()
<< " with " << diffTimestamp(startTs, getTimestamp()) << " with " << diffTimestamp(startTs, getTimestamp())
<< " Ms elapsed.\n" << std::flush; << " ms elapsed.\n" << std::flush;
// //
// Check if user sent a signal to produce // Check if user sent a signal to produce
...@@ -2946,7 +2946,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2946,7 +2946,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: Mark - 5 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 5 - at " << strMsTimestamp()
<< " with " << diffTimestamp(startTs, getTimestamp()) << " with " << diffTimestamp(startTs, getTimestamp())
<< " Ms elapsed.\n" << std::flush; << " ms elapsed.\n" << std::flush;
// //
// Check if there is any data to flush. // Check if there is any data to flush.
...@@ -2958,7 +2958,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2958,7 +2958,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: Mark - 6 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 6 - at " << strMsTimestamp()
<< " with " << diffTimestamp(startTs, getTimestamp()) << " with " << diffTimestamp(startTs, getTimestamp())
<< " Ms elapsed.\n" << std::flush; << " ms elapsed.\n" << std::flush;
} }
// //
...@@ -2991,7 +2991,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet, ...@@ -2991,7 +2991,7 @@ int NXTransExecute(int *resultFDs, int *errorFDs, int *setFDs, fd_set *readSet,
nxinfo << "Loop: Mark - 7 - at " << strMsTimestamp() nxinfo << "Loop: Mark - 7 - at " << strMsTimestamp()
<< " with " << diffTimestamp(startTs, getTimestamp()) << " with " << diffTimestamp(startTs, getTimestamp())
<< " Ms elapsed.\n" << std::flush; << " ms elapsed.\n" << std::flush;
} }
// //
...@@ -3360,7 +3360,7 @@ int InitAfterNegotiation() ...@@ -3360,7 +3360,7 @@ int InitAfterNegotiation()
nxinfo << "Loop: INIT! Completed initialization at " nxinfo << "Loop: INIT! Completed initialization at "
<< strMsTimestamp(nowTs) << " with " << strMsTimestamp(nowTs) << " with "
<< diffTimestamp(initTs, nowTs) << " Ms " << diffTimestamp(initTs, nowTs) << " ms "
<< "since the init mark.\n" << std::flush; << "since the init mark.\n" << std::flush;
initTs = getNewTimestamp(); initTs = getNewTimestamp();
...@@ -6077,8 +6077,8 @@ void SetTimer(int value) ...@@ -6077,8 +6077,8 @@ void SetTimer(int value)
timer.it_value = lastTimer.next; timer.it_value = lastTimer.next;
nxinfo << "Loop: Timer set to " << lastTimer.next.tv_sec nxinfo << "Loop: Timer set to " << lastTimer.next.tv_sec
<< " S and " << lastTimer.next.tv_usec / 1000 << " s and " << lastTimer.next.tv_usec / 1000
<< " Ms at " << strMsTimestamp() << " in process " << " ms at " << strMsTimestamp() << " in process "
<< "with pid '" << getpid() << "'.\n" << "with pid '" << getpid() << "'.\n"
<< std::flush; << std::flush;
...@@ -6600,7 +6600,7 @@ int ConnectToRemote(ChannelEndPoint &socketAddress) ...@@ -6600,7 +6600,7 @@ int ConnectToRemote(ChannelEndPoint &socketAddress)
{ {
nxdbg << "Loop: Timer set to " << connectTimeout / 1000 nxdbg << "Loop: Timer set to " << connectTimeout / 1000
<< " S " << "with retry set to " << retryConnect << " s " << "with retry set to " << retryConnect
<< " in process with pid '" << getpid() << " in process with pid '" << getpid()
<< "'.\n" << std::flush; << "'.\n" << std::flush;
...@@ -13799,7 +13799,7 @@ static void handleCheckSessionInLoop() ...@@ -13799,7 +13799,7 @@ static void handleCheckSessionInLoop()
} }
nxinfo << "Loop: Starting watchdog process with timeout " nxinfo << "Loop: Starting watchdog process with timeout "
<< "of " << timeout << " Ms.\n" << "of " << timeout << " ms.\n"
<< std::flush; << std::flush;
} }
else else
...@@ -14064,9 +14064,9 @@ static void handleCheckSelectInLoop(int &setFDs, fd_set &readSet, ...@@ -14064,9 +14064,9 @@ static void handleCheckSelectInLoop(int &setFDs, fd_set &readSet,
} }
nxinfo << "Loop: Select timeout is " nxinfo << "Loop: Select timeout is "
<< selectTs.tv_sec << " S and " << selectTs.tv_sec << " s and "
<< (double) selectTs.tv_usec / 1000 << (double) selectTs.tv_usec / 1000
<< " Ms.\n" << std::flush; << " ms.\n" << std::flush;
} }
static void handleCheckResultInLoop(int &resultFDs, int &errorFDs, int &setFDs, fd_set &readSet, static void handleCheckResultInLoop(int &resultFDs, int &errorFDs, int &setFDs, fd_set &readSet,
...@@ -14082,14 +14082,14 @@ static void handleCheckResultInLoop(int &resultFDs, int &errorFDs, int &setFDs, ...@@ -14082,14 +14082,14 @@ static void handleCheckResultInLoop(int &resultFDs, int &errorFDs, int &setFDs,
nxinfo << "Loop: Select result is [" << resultFDs nxinfo << "Loop: Select result is [" << resultFDs
<< "] at " << strMsTimestamp() << " with no " << "] at " << strMsTimestamp() << " with no "
<< "communication within " << diffTs << "communication within " << diffTs
<< " Ms.\n" << std::flush; << " ms.\n" << std::flush;
} }
else else
{ {
nxinfo << "Loop: Select result is [" << resultFDs nxinfo << "Loop: Select result is [" << resultFDs
<< "] error is [" << errorFDs << "] at " << "] error is [" << errorFDs << "] at "
<< strMsTimestamp() << " after " << diffTs << strMsTimestamp() << " after " << diffTs
<< " Ms.\n" << std::flush; << " ms.\n" << std::flush;
} }
...@@ -14524,8 +14524,8 @@ static void handleNegotiationInLoop(int &setFDs, fd_set &readSet, ...@@ -14524,8 +14524,8 @@ static void handleNegotiationInLoop(int &setFDs, fd_set &readSet,
setMinTimestamp(selectTs, control -> PingTimeout); setMinTimestamp(selectTs, control -> PingTimeout);
nxinfo << "Loop: Selected proxy FD#" << proxyFD << " in negotiation " nxinfo << "Loop: Selected proxy FD#" << proxyFD << " in negotiation "
<< "phase with timeout of " << selectTs.tv_sec << " S and " << "phase with timeout of " << selectTs.tv_sec << " s and "
<< selectTs.tv_usec << " Ms.\n" << std::flush; << selectTs.tv_usec << " ms.\n" << std::flush;
return; return;
......
...@@ -410,8 +410,8 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax) ...@@ -410,8 +410,8 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax)
#ifdef TEST #ifdef TEST
*logofs << "Proxy: Initial timeout is " << tsMax.tv_sec *logofs << "Proxy: Initial timeout is " << tsMax.tv_sec
<< " S and " << (double) tsMax.tv_usec / << " s and " << (double) tsMax.tv_usec /
1000 << " Ms with congestion " 1000 << " ms with congestion "
<< statistics -> getCongestionInFrame() << statistics -> getCongestionInFrame()
<< ".\n" << logofs_flush; << ".\n" << logofs_flush;
#endif #endif
...@@ -422,8 +422,8 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax) ...@@ -422,8 +422,8 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax)
#ifdef TEST #ifdef TEST
*logofs << "Proxy: Initial timeout is " << tsMax.tv_sec *logofs << "Proxy: Initial timeout is " << tsMax.tv_sec
<< " S and " << (double) tsMax.tv_usec / << " s and " << (double) tsMax.tv_usec /
1000 << " Ms.\n" << logofs_flush; 1000 << " ms.\n" << logofs_flush;
#endif #endif
} }
...@@ -449,12 +449,12 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax) ...@@ -449,12 +449,12 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax)
{ {
*logofs << "Proxy: FLUSH! SPLIT! WARNING! Running with " *logofs << "Proxy: FLUSH! SPLIT! WARNING! Running with "
<< diffTimestamp(timeouts_.splitTs, getTimestamp()) << diffTimestamp(timeouts_.splitTs, getTimestamp())
<< " Ms elapsed since the last split.\n" << " ms elapsed since the last split.\n"
<< logofs_flush; << logofs_flush;
} }
*logofs << "Proxy: FLUSH! SPLIT! Requesting timeout of " *logofs << "Proxy: FLUSH! SPLIT! Requesting timeout of "
<< diffTs << " Ms as there are splits to send.\n" << diffTs << " ms as there are splits to send.\n"
<< logofs_flush; << logofs_flush;
#endif #endif
...@@ -525,12 +525,12 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax) ...@@ -525,12 +525,12 @@ int Proxy::setReadDescriptors(fd_set *fdSet, int &fdMax, T_timestamp &tsMax)
{ {
*logofs << "Proxy: FLUSH! WARNING! Running with " *logofs << "Proxy: FLUSH! WARNING! Running with "
<< diffTimestamp(timeouts_.motionTs, getTimestamp()) << diffTimestamp(timeouts_.motionTs, getTimestamp())
<< " Ms elapsed since the last motion.\n" << " ms elapsed since the last motion.\n"
<< logofs_flush; << logofs_flush;
} }
*logofs << "Proxy: FLUSH! Requesting timeout of " *logofs << "Proxy: FLUSH! Requesting timeout of "
<< diffTs << " Ms as FD#" << fd << " has motion " << diffTs << " ms as FD#" << fd << " has motion "
<< "events to send.\n" << logofs_flush; << "events to send.\n" << logofs_flush;
#endif #endif
...@@ -1946,7 +1946,7 @@ int Proxy::handleEvents() ...@@ -1946,7 +1946,7 @@ int Proxy::handleEvents()
*logofs << "Proxy: FLUSH! Motion timeout expired after " *logofs << "Proxy: FLUSH! Motion timeout expired after "
<< diffTimestamp(timeouts_.motionTs, getTimestamp()) << diffTimestamp(timeouts_.motionTs, getTimestamp())
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
#endif #endif
...@@ -1972,7 +1972,7 @@ int Proxy::handleEvents() ...@@ -1972,7 +1972,7 @@ int Proxy::handleEvents()
{ {
*logofs << "Proxy: Running with " *logofs << "Proxy: Running with "
<< diffTimestamp(timeouts_.motionTs, getTimestamp()) << diffTimestamp(timeouts_.motionTs, getTimestamp())
<< " Ms elapsed since the last motion.\n" << " ms elapsed since the last motion.\n"
<< logofs_flush; << logofs_flush;
} }
#endif #endif
...@@ -1990,7 +1990,7 @@ int Proxy::handleEvents() ...@@ -1990,7 +1990,7 @@ int Proxy::handleEvents()
#if defined(TEST) || defined(INFO) || defined(SPLIT) #if defined(TEST) || defined(INFO) || defined(SPLIT)
*logofs << "Proxy: SPLIT! Split timeout expired after " *logofs << "Proxy: SPLIT! Split timeout expired after "
<< diffTimestamp(timeouts_.splitTs, getTimestamp()) << diffTimestamp(timeouts_.splitTs, getTimestamp())
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
#endif #endif
#if defined(TEST) || defined(INFO) || defined(SPLIT) #if defined(TEST) || defined(INFO) || defined(SPLIT)
...@@ -2033,7 +2033,7 @@ int Proxy::handleEvents() ...@@ -2033,7 +2033,7 @@ int Proxy::handleEvents()
{ {
*logofs << "Proxy: SPLIT! Running with " *logofs << "Proxy: SPLIT! Running with "
<< diffTimestamp(timeouts_.splitTs, getTimestamp()) << diffTimestamp(timeouts_.splitTs, getTimestamp())
<< " Ms elapsed since the last split.\n" << " ms elapsed since the last split.\n"
<< logofs_flush; << logofs_flush;
} }
#endif #endif
...@@ -2252,7 +2252,7 @@ int Proxy::handleFrame(T_frame_type type) ...@@ -2252,7 +2252,7 @@ int Proxy::handleFrame(T_frame_type type)
<< " new " << outputLength << " flushable " << transport_ -> << " new " << outputLength << " flushable " << transport_ ->
flushable() << " tokens " << tokens_[token_control].remaining flushable() << " tokens " << tokens_[token_control].remaining
<< " after " << diffTimestamp(timeouts_.writeTs, nowTs) << " after " << diffTimestamp(timeouts_.writeTs, nowTs)
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
*logofs << "Proxy: FLUSH! Immediate flush to proxy FD#" << fd_ *logofs << "Proxy: FLUSH! Immediate flush to proxy FD#" << fd_
<< " of " << outputLength << " bytes at " << strMsTimestamp() << " of " << outputLength << " bytes at " << strMsTimestamp()
...@@ -2276,7 +2276,7 @@ int Proxy::handleFrame(T_frame_type type) ...@@ -2276,7 +2276,7 @@ int Proxy::handleFrame(T_frame_type type)
*logofs << "Proxy: WARNING! TIME! Data written to proxy FD#" *logofs << "Proxy: WARNING! TIME! Data written to proxy FD#"
<< fd_ << " at " << strMsTimestamp() << " after " << fd_ << " at " << strMsTimestamp() << " after "
<< diffTimestamp(timeouts_.writeTs, nowTs) << diffTimestamp(timeouts_.writeTs, nowTs)
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
} }
#endif #endif
...@@ -2533,7 +2533,7 @@ int Proxy::handleDrain() ...@@ -2533,7 +2533,7 @@ int Proxy::handleDrain()
*logofs << "Proxy: Timeout raised while draining " *logofs << "Proxy: Timeout raised while draining "
<< "FD#" << fd_ << " at " << strMsTimestamp() << "FD#" << fd_ << " at " << strMsTimestamp()
<< " after " << diffTimestamp(startTs, nowTs) << " after " << diffTimestamp(startTs, nowTs)
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
#endif #endif
result = 0; result = 0;
...@@ -2547,7 +2547,7 @@ int Proxy::handleDrain() ...@@ -2547,7 +2547,7 @@ int Proxy::handleDrain()
*logofs << "Proxy: Trying to write to FD#" << fd_ *logofs << "Proxy: Trying to write to FD#" << fd_
<< " at " << strMsTimestamp() << " with length " << " at " << strMsTimestamp() << " with length "
<< transport_ -> length() << " and " << transport_ -> length() << " and "
<< remaining << " Ms remaining.\n" << remaining << " ms remaining.\n"
<< logofs_flush; << logofs_flush;
#endif #endif
...@@ -2590,7 +2590,7 @@ int Proxy::handleDrain() ...@@ -2590,7 +2590,7 @@ int Proxy::handleDrain()
#if defined(TEST) || defined(INFO) #if defined(TEST) || defined(INFO)
*logofs << "Proxy: Waiting for more data from proxy " *logofs << "Proxy: Waiting for more data from proxy "
<< "FD#" << fd_ << " at " << strMsTimestamp() << "FD#" << fd_ << " at " << strMsTimestamp()
<< " with " << remaining << " Ms remaining.\n" << " with " << remaining << " ms remaining.\n"
<< logofs_flush; << logofs_flush;
#endif #endif
...@@ -2633,7 +2633,7 @@ int Proxy::handleDrain() ...@@ -2633,7 +2633,7 @@ int Proxy::handleDrain()
*logofs << "Proxy: Got decongestion for proxy FD#" *logofs << "Proxy: Got decongestion for proxy FD#"
<< fd_ << " at " << strMsTimestamp() << " after " << fd_ << " at " << strMsTimestamp() << " after "
<< diffTimestamp(startTs, getTimestamp()) << diffTimestamp(startTs, getTimestamp())
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
#endif #endif
result = 1; result = 1;
...@@ -3599,7 +3599,7 @@ int Proxy::handlePing() ...@@ -3599,7 +3599,7 @@ int Proxy::handlePing()
#if defined(TEST) || defined(INFO) #if defined(TEST) || defined(INFO)
*logofs << "Proxy: WARNING! No data received from " *logofs << "Proxy: WARNING! No data received from "
<< "remote proxy on FD#" << fd_ << " since " << "remote proxy on FD#" << fd_ << " since "
<< diffIn << " Ms.\n" << logofs_flush; << diffIn << " ms.\n" << logofs_flush;
#endif #endif
if (control -> ProxyTimeout > 0 && if (control -> ProxyTimeout > 0 &&
......
...@@ -4628,7 +4628,7 @@ int ServerChannel::handleSplit(DecodeBuffer &decodeBuffer, MessageStore *store, ...@@ -4628,7 +4628,7 @@ int ServerChannel::handleSplit(DecodeBuffer &decodeBuffer, MessageStore *store,
#if defined(TEST) || defined(SPLIT) #if defined(TEST) || defined(SPLIT)
*logofs << "handleSplit: SPLIT! Spent " *logofs << "handleSplit: SPLIT! Spent "
<< diffTimestamp(startTs, getTimestamp()) << " Ms " << diffTimestamp(startTs, getTimestamp()) << " ms "
<< "handling abort split events for FD#" << fd_ << "handling abort split events for FD#" << fd_
<< ".\n" << logofs_flush; << ".\n" << logofs_flush;
...@@ -4790,7 +4790,7 @@ int ServerChannel::handleSplit(DecodeBuffer &decodeBuffer) ...@@ -4790,7 +4790,7 @@ int ServerChannel::handleSplit(DecodeBuffer &decodeBuffer)
#if defined(TEST) || defined(SPLIT) #if defined(TEST) || defined(SPLIT)
*logofs << "handleSplit: WARNING! SPLIT! Spent " *logofs << "handleSplit: WARNING! SPLIT! Spent "
<< diffTimestamp(startTs, getTimestamp()) << " Ms " << diffTimestamp(startTs, getTimestamp()) << " ms "
<< "handling asynchronous abort split events for " << "handling asynchronous abort split events for "
<< "FD#" << fd_ << ".\n" << logofs_flush; << "FD#" << fd_ << ".\n" << logofs_flush;
...@@ -5835,7 +5835,7 @@ int ServerChannel::handleAsyncEvents() ...@@ -5835,7 +5835,7 @@ int ServerChannel::handleAsyncEvents()
#if defined(TEST) || defined(INFO) #if defined(TEST) || defined(INFO)
*logofs << "handleAsyncEvents: Spent " << diffTimestamp(startTs, *logofs << "handleAsyncEvents: Spent " << diffTimestamp(startTs,
getTimestamp()) << " Ms handling events for FD#" getTimestamp()) << " ms handling events for FD#"
<< fd_ << ".\n" << logofs_flush; << fd_ << ".\n" << logofs_flush;
#endif #endif
...@@ -7268,7 +7268,7 @@ int ServerChannel::handleShmem(unsigned char &opcode, unsigned char *&buffer, ...@@ -7268,7 +7268,7 @@ int ServerChannel::handleShmem(unsigned char &opcode, unsigned char *&buffer,
#if defined(TEST) || defined(INFO) #if defined(TEST) || defined(INFO)
*logofs << "handleShmem: WARNING! Missing completion " *logofs << "handleShmem: WARNING! Missing completion "
<< "after " << diffTimestamp(shmemState_ -> last, << "after " << diffTimestamp(shmemState_ -> last,
getTimestamp()) << " Ms for shared memory " getTimestamp()) << " ms for shared memory "
<< "for FD#" << fd_ << ".\n" << logofs_flush; << "for FD#" << fd_ << ".\n" << logofs_flush;
#endif #endif
...@@ -7402,7 +7402,7 @@ int ServerChannel::handleShmemEvent() ...@@ -7402,7 +7402,7 @@ int ServerChannel::handleShmemEvent()
{ {
#if defined(TEST) || defined(INFO) #if defined(TEST) || defined(INFO)
*logofs << "handleShmemEvent: Spent " *logofs << "handleShmemEvent: Spent "
<< diffTimestamp(startTs, getTimestamp()) << " Ms " << diffTimestamp(startTs, getTimestamp()) << " ms "
<< "waiting for shared memory sequence for FD#" << "waiting for shared memory sequence for FD#"
<< fd_ << ".\n" << logofs_flush; << fd_ << ".\n" << logofs_flush;
#endif #endif
...@@ -7414,7 +7414,7 @@ int ServerChannel::handleShmemEvent() ...@@ -7414,7 +7414,7 @@ int ServerChannel::handleShmemEvent()
*logofs << "handleShmemEvent: WARNING! Can't reset shared " *logofs << "handleShmemEvent: WARNING! Can't reset shared "
<< "memory sequence for FD#" << fd_ << " after " << "memory sequence for FD#" << fd_ << " after "
<< diffTimestamp(shmemState_ -> last, getTimestamp()) << diffTimestamp(shmemState_ -> last, getTimestamp())
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
#endif #endif
return 0; return 0;
...@@ -7430,7 +7430,7 @@ int ServerChannel::checkShmemEvent(unsigned char event, unsigned short sequence, ...@@ -7430,7 +7430,7 @@ int ServerChannel::checkShmemEvent(unsigned char event, unsigned short sequence,
*logofs << "checkShmemEvent: Reset shared memory sequence " *logofs << "checkShmemEvent: Reset shared memory sequence "
<< shmemState_ -> sequence << " for FD#" << fd_ << shmemState_ -> sequence << " for FD#" << fd_
<< " after " << diffTimestamp(shmemState_ -> last, << " after " << diffTimestamp(shmemState_ -> last,
getTimestamp()) << " Ms.\n" << logofs_flush; getTimestamp()) << " ms.\n" << logofs_flush;
#endif #endif
shmemState_ -> sequence = 0; shmemState_ -> sequence = 0;
......
...@@ -361,14 +361,14 @@ void Statistics::updateBitrate(int bytes) ...@@ -361,14 +361,14 @@ void Statistics::updateBitrate(int bytes)
#ifdef DEBUG #ifdef DEBUG
*logofs << "Statistics: Difference since previous timestamp is " *logofs << "Statistics: Difference since previous timestamp is "
<< diffFramesInMs << " Ms.\n" << logofs_flush; << diffFramesInMs << " ms.\n" << logofs_flush;
#endif #endif
if (diffFramesInMs > 0) if (diffFramesInMs > 0)
{ {
#ifdef DEBUG #ifdef DEBUG
*logofs << "Statistics: Removing " << diffFramesInMs *logofs << "Statistics: Removing " << diffFramesInMs
<< " Ms in short and long time frame.\n" << " ms in short and long time frame.\n"
<< logofs_flush; << logofs_flush;
#endif #endif
...@@ -1751,7 +1751,7 @@ int Statistics::getTimeStats(int type, char *&buffer) ...@@ -1751,7 +1751,7 @@ int Statistics::getTimeStats(int type, char *&buffer)
char format[FORMAT_LENGTH]; char format[FORMAT_LENGTH];
sprintf(format, "\ntime: %.0f Ms idle, %.0f Ms (%.0f Ms in read, %.0f Ms in write) running.\n\n", sprintf(format, "\ntime: %.0f ms idle, %.0fms (%.0f ms in read, %.0f ms in write) running.\n\n",
transportData -> idleTime_, transportData -> readTime_, transportData -> idleTime_, transportData -> readTime_,
transportData -> readTime_ - transportData -> writeTime_, transportData -> readTime_ - transportData -> writeTime_,
transportData -> writeTime_); transportData -> writeTime_);
......
...@@ -135,7 +135,7 @@ inline T_timestamp getTimestamp() ...@@ -135,7 +135,7 @@ inline T_timestamp getTimestamp()
if (diffTs > DRIFT_TIMESTAMP) if (diffTs > DRIFT_TIMESTAMP)
{ {
*logofs << "Timestamp: WARNING! Time difference since the " *logofs << "Timestamp: WARNING! Time difference since the "
<< "current timestamp is " << diffTs << " Ms.\n" << "current timestamp is " << diffTs << "ms.\n"
<< logofs_flush; << logofs_flush;
} }
...@@ -293,7 +293,7 @@ inline T_timestamp getNewTimestamp() ...@@ -293,7 +293,7 @@ inline T_timestamp getNewTimestamp()
if (diffTs > DRIFT_TIMESTAMP) if (diffTs > DRIFT_TIMESTAMP)
{ {
*logofs << "Timestamp: WARNING! Time difference since the " *logofs << "Timestamp: WARNING! Time difference since the "
<< "old timestamp is " << diffTs << " Ms.\n" << "old timestamp is " << diffTs << "ms.\n"
<< logofs_flush; << logofs_flush;
} }
......
...@@ -868,7 +868,7 @@ int Transport::wait(int timeout) const ...@@ -868,7 +868,7 @@ int Transport::wait(int timeout) const
#ifdef TEST #ifdef TEST
*logofs << "Transport: There are " << available *logofs << "Transport: There are " << available
<< " bytes on FD#" << fd_ << " after " << " bytes on FD#" << fd_ << " after "
<< diffTs << " Ms.\n" << logofs_flush; << diffTs << " ms.\n" << logofs_flush;
#endif #endif
return available; return available;
...@@ -933,13 +933,13 @@ int Transport::wait(int timeout) const ...@@ -933,13 +933,13 @@ int Transport::wait(int timeout) const
{ {
*logofs << "Transport: No data available on FD#" << fd_ *logofs << "Transport: No data available on FD#" << fd_
<< " after " << diffTimestamp(startTs, nowTs) << " after " << diffTimestamp(startTs, nowTs)
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
} }
else else
{ {
*logofs << "Transport: Data became available on FD#" << fd_ *logofs << "Transport: Data became available on FD#" << fd_
<< " after " << diffTimestamp(startTs, nowTs) << " after " << diffTimestamp(startTs, nowTs)
<< " Ms.\n" << logofs_flush; << " ms.\n" << logofs_flush;
} }
#endif #endif
} }
......
Markdown is supported
0% or
You are about to add 0 people to the discussion. Proceed with caution.
Finish editing this message first!
Please register or to comment