47630f826e
chrono::system_clock can go back in time. For time interval measurements, where we don't care about the local time, a monotonic clock should be used. This avoids the server uptime jumping around with daylight saving (or indeed by regular synchronization with an atomic clock), among other cases. Change-Id: I09f9b24c82d19439348a2e66cad9e9de7d755208 Signed-off-by: Ashod Nakashian <ashod.nakashian@collabora.co.uk>
1126 lines
33 KiB
C++
1126 lines
33 KiB
C++
/* -*- Mode: C++; tab-width: 4; indent-tabs-mode: nil; c-basic-offset: 4; fill-column: 100 -*- */
|
|
/*
|
|
* This Source Code Form is subject to the terms of the Mozilla Public
|
|
* License, v. 2.0. If a copy of the MPL was not distributed with this
|
|
* file, You can obtain one at http://mozilla.org/MPL/2.0/.
|
|
*/
|
|
|
|
#include <config.h>
|
|
|
|
#include "AdminModel.hpp"
|
|
|
|
#include <chrono>
|
|
#include <memory>
|
|
#include <set>
|
|
#include <sstream>
|
|
#include <string>
|
|
|
|
#include <Poco/URI.h>
|
|
|
|
#include <Protocol.hpp>
|
|
#include <net/WebSocketHandler.hpp>
|
|
#include <Log.hpp>
|
|
#include <Unit.hpp>
|
|
#include <Util.hpp>
|
|
#include <wsd/LOOLWSD.hpp>
|
|
|
|
#include <fnmatch.h>
|
|
#include <dirent.h>
|
|
|
|
void Document::addView(const std::string& sessionId, const std::string& userName, const std::string& userId)
|
|
{
|
|
const auto ret = _views.emplace(sessionId, View(sessionId, userName, userId));
|
|
if (!ret.second)
|
|
{
|
|
LOG_WRN("View with SessionID [" << sessionId << "] already exists.");
|
|
}
|
|
else
|
|
{
|
|
++_activeViews;
|
|
}
|
|
}
|
|
|
|
int Document::expireView(const std::string& sessionId)
|
|
{
|
|
auto it = _views.find(sessionId);
|
|
if (it != _views.end())
|
|
{
|
|
it->second.expire();
|
|
|
|
// If last view, expire the Document also
|
|
if (--_activeViews == 0)
|
|
_end = std::time(nullptr);
|
|
}
|
|
takeSnapshot();
|
|
|
|
return _activeViews;
|
|
}
|
|
|
|
void Document::setViewLoadDuration(const std::string& sessionId, std::chrono::milliseconds viewLoadDuration)
|
|
{
|
|
std::map<std::string, View>::iterator it = _views.find(sessionId);
|
|
if (it != _views.end())
|
|
it->second.setLoadDuration(viewLoadDuration);
|
|
}
|
|
|
|
std::pair<std::time_t, std::string> Document::getSnapshot() const
|
|
{
|
|
std::time_t ct = std::time(nullptr);
|
|
std::ostringstream oss;
|
|
oss << '{';
|
|
oss << "\"creationTime\"" << ':' << ct << ',';
|
|
oss << "\"memoryDirty\"" << ':' << getMemoryDirty() << ',';
|
|
oss << "\"activeViews\"" << ':' << getActiveViews() << ',';
|
|
|
|
oss << "\"views\"" << ":[";
|
|
std::string separator;
|
|
for (const auto& view : getViews())
|
|
{
|
|
oss << separator << '"';
|
|
if(view.second.isExpired())
|
|
{
|
|
oss << '-';
|
|
}
|
|
oss << view.first << '"';
|
|
separator = ",";
|
|
}
|
|
oss << "],";
|
|
|
|
oss << "\"lastActivity\"" << ':' << _lastActivity;
|
|
oss << '}';
|
|
return std::make_pair(ct, oss.str());
|
|
}
|
|
|
|
const std::string Document::getHistory() const
|
|
{
|
|
std::ostringstream oss;
|
|
oss << "{";
|
|
oss << "\"docKey\"" << ":\"" << _docKey << "\",";
|
|
oss << "\"filename\"" << ":\"" << LOOLWSD::anonymizeUrl(getFilename()) << "\",";
|
|
oss << "\"start\"" << ':' << _start << ',';
|
|
oss << "\"end\"" << ':' << _end << ',';
|
|
oss << "\"pid\"" << ':' << getPid() << ',';
|
|
oss << "\"snapshots\"" << ":[";
|
|
std::string separator;
|
|
for (const auto& s : _snapshots)
|
|
{
|
|
oss << separator << s.second;
|
|
separator = ",";
|
|
}
|
|
oss << "]}";
|
|
return oss.str();
|
|
}
|
|
|
|
void Document::takeSnapshot()
|
|
{
|
|
std::pair<std::time_t, std::string> p = getSnapshot();
|
|
auto insPoint = _snapshots.upper_bound(p.first);
|
|
_snapshots.insert(insPoint, p);
|
|
}
|
|
|
|
std::string Document::to_string() const
|
|
{
|
|
std::ostringstream oss;
|
|
std::string encodedFilename;
|
|
Poco::URI::encode(getFilename(), " ", encodedFilename);
|
|
oss << getPid() << ' '
|
|
<< encodedFilename << ' '
|
|
<< getActiveViews() << ' '
|
|
<< getMemoryDirty() << ' '
|
|
<< getElapsedTime() << ' '
|
|
<< getIdleTime() << ' ';
|
|
return oss.str();
|
|
}
|
|
|
|
void Document::updateMemoryDirty()
|
|
{
|
|
// Avoid accessing smaps too often
|
|
const time_t now = std::time(nullptr);
|
|
if (now - _lastTimeSMapsRead >= 5)
|
|
{
|
|
size_t lastMemDirty = _memoryDirty;
|
|
_memoryDirty = _procSMaps ? Util::getPssAndDirtyFromSMaps(_procSMaps).second : 0;
|
|
_lastTimeSMapsRead = now;
|
|
if (lastMemDirty != _memoryDirty)
|
|
_hasMemDirtyChanged = true;
|
|
}
|
|
}
|
|
|
|
void Document::setLastJiffies(size_t newJ)
|
|
{
|
|
const auto now = std::chrono::steady_clock::now();
|
|
if (_lastJiffy)
|
|
_lastCpuPercentage = (100 * 1000 * (newJ - _lastJiffy) / ::sysconf(_SC_CLK_TCK))
|
|
/ std::chrono::duration_cast<std::chrono::milliseconds>(now - _lastJiffyTime).count();
|
|
_lastJiffy = newJ;
|
|
_lastJiffyTime = now;
|
|
}
|
|
|
|
bool Subscriber::notify(const std::string& message)
|
|
{
|
|
// If there is no socket, then return false to
|
|
// signify we're disconnected.
|
|
std::shared_ptr<WebSocketHandler> webSocket = _ws.lock();
|
|
if (webSocket)
|
|
{
|
|
if (_subscriptions.find(LOOLProtocol::getFirstToken(message)) == _subscriptions.end())
|
|
{
|
|
// No subscribers for the given message.
|
|
return true;
|
|
}
|
|
|
|
try
|
|
{
|
|
UnitWSD::get().onAdminNotifyMessage(message);
|
|
webSocket->sendMessage(message);
|
|
return true;
|
|
}
|
|
catch (const std::exception& ex)
|
|
{
|
|
LOG_ERR("Failed to notify Admin subscriber with message [" <<
|
|
message << "] due to [" << ex.what() << "].");
|
|
}
|
|
}
|
|
|
|
return false;
|
|
}
|
|
|
|
bool Subscriber::subscribe(const std::string& command)
|
|
{
|
|
auto ret = _subscriptions.insert(command);
|
|
return ret.second;
|
|
}
|
|
|
|
void Subscriber::unsubscribe(const std::string& command)
|
|
{
|
|
_subscriptions.erase(command);
|
|
}
|
|
|
|
void AdminModel::assertCorrectThread() const
|
|
{
|
|
// FIXME: share this code [!]
|
|
const bool sameThread = std::this_thread::get_id() == _owner;
|
|
if (!sameThread)
|
|
LOG_ERR("Admin command invoked from foreign thread. Expected: " <<
|
|
Log::to_string(_owner) << " but called from " <<
|
|
std::this_thread::get_id() << " (" << Util::getThreadId() << ").");
|
|
|
|
assert(sameThread);
|
|
}
|
|
|
|
AdminModel::~AdminModel()
|
|
{
|
|
LOG_TRC("History:\n\n" << getAllHistory() << '\n');
|
|
LOG_INF("AdminModel dtor.");
|
|
}
|
|
|
|
std::string AdminModel::getAllHistory() const
|
|
{
|
|
std::ostringstream oss;
|
|
oss << "{ \"documents\" : [";
|
|
std::string separator1;
|
|
for (const auto& d : _documents)
|
|
{
|
|
oss << separator1;
|
|
oss << d.second->getHistory();
|
|
separator1 = ",";
|
|
}
|
|
oss << "], \"expiredDocuments\" : [";
|
|
separator1 = "";
|
|
for (const auto& ed : _expiredDocuments)
|
|
{
|
|
oss << separator1;
|
|
oss << ed.second->getHistory();
|
|
separator1 = ",";
|
|
}
|
|
oss << "]}";
|
|
return oss.str();
|
|
}
|
|
|
|
std::string AdminModel::query(const std::string& command)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
const auto token = LOOLProtocol::getFirstToken(command);
|
|
if (token == "documents")
|
|
{
|
|
return getDocuments();
|
|
}
|
|
else if (token == "active_users_count")
|
|
{
|
|
return std::to_string(getTotalActiveViews());
|
|
}
|
|
else if (token == "active_docs_count")
|
|
{
|
|
return std::to_string(_documents.size());
|
|
}
|
|
else if (token == "mem_stats")
|
|
{
|
|
return getMemStats();
|
|
}
|
|
else if (token == "mem_stats_size")
|
|
{
|
|
return std::to_string(_memStatsSize);
|
|
}
|
|
else if (token == "cpu_stats")
|
|
{
|
|
return getCpuStats();
|
|
}
|
|
else if (token == "cpu_stats_size")
|
|
{
|
|
return std::to_string(_cpuStatsSize);
|
|
}
|
|
else if (token == "sent_activity")
|
|
{
|
|
return getSentActivity();
|
|
}
|
|
else if (token == "recv_activity")
|
|
{
|
|
return getRecvActivity();
|
|
}
|
|
else if (token == "net_stats_size")
|
|
{
|
|
return std::to_string(std::max(_sentStatsSize, _recvStatsSize));
|
|
}
|
|
|
|
return std::string("");
|
|
}
|
|
|
|
/// Returns memory consumed by all active loolkit processes
|
|
unsigned AdminModel::getKitsMemoryUsage()
|
|
{
|
|
assertCorrectThread();
|
|
|
|
unsigned totalMem = 0;
|
|
unsigned docs = 0;
|
|
for (const auto& it : _documents)
|
|
{
|
|
if (!it.second->isExpired())
|
|
{
|
|
const int bytes = it.second->getMemoryDirty();
|
|
if (bytes > 0)
|
|
{
|
|
totalMem += bytes;
|
|
++docs;
|
|
}
|
|
}
|
|
}
|
|
|
|
if (docs > 0)
|
|
{
|
|
LOG_TRC("Got total Kits memory of " << totalMem << " bytes for " << docs <<
|
|
" docs, avg: " << static_cast<double>(totalMem) / docs << " bytes / doc.");
|
|
}
|
|
|
|
return totalMem;
|
|
}
|
|
|
|
size_t AdminModel::getKitsJiffies()
|
|
{
|
|
assertCorrectThread();
|
|
|
|
size_t totalJ = 0;
|
|
for (auto& it : _documents)
|
|
{
|
|
if (!it.second->isExpired())
|
|
{
|
|
const int pid = it.second->getPid();
|
|
if (pid > 0)
|
|
{
|
|
unsigned newJ = Util::getCpuUsage(pid);
|
|
unsigned prevJ = it.second->getLastJiffies();
|
|
if(newJ >= prevJ)
|
|
{
|
|
totalJ += (newJ - prevJ);
|
|
it.second->setLastJiffies(newJ);
|
|
}
|
|
}
|
|
}
|
|
}
|
|
return totalJ;
|
|
}
|
|
|
|
void AdminModel::subscribe(int sessionId, const std::weak_ptr<WebSocketHandler>& ws)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
const auto ret = _subscribers.emplace(sessionId, Subscriber(ws));
|
|
if (!ret.second)
|
|
{
|
|
LOG_WRN("Subscriber already exists");
|
|
}
|
|
}
|
|
|
|
void AdminModel::subscribe(int sessionId, const std::string& command)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
auto subscriber = _subscribers.find(sessionId);
|
|
if (subscriber != _subscribers.end())
|
|
{
|
|
subscriber->second.subscribe(command);
|
|
}
|
|
}
|
|
|
|
void AdminModel::unsubscribe(int sessionId, const std::string& command)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
auto subscriber = _subscribers.find(sessionId);
|
|
if (subscriber != _subscribers.end())
|
|
subscriber->second.unsubscribe(command);
|
|
}
|
|
|
|
void AdminModel::addMemStats(unsigned memUsage)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
_memStats.push_back(memUsage);
|
|
if (_memStats.size() > _memStatsSize)
|
|
_memStats.pop_front();
|
|
|
|
notify("mem_stats " + std::to_string(memUsage));
|
|
}
|
|
|
|
void AdminModel::addCpuStats(unsigned cpuUsage)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
_cpuStats.push_back(cpuUsage);
|
|
if (_cpuStats.size() > _cpuStatsSize)
|
|
_cpuStats.pop_front();
|
|
|
|
notify("cpu_stats " + std::to_string(cpuUsage));
|
|
}
|
|
|
|
void AdminModel::addSentStats(uint64_t sent)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
_sentStats.push_back(sent);
|
|
if (_sentStats.size() > _sentStatsSize)
|
|
_sentStats.pop_front();
|
|
|
|
notify("sent_activity " + std::to_string(sent));
|
|
}
|
|
|
|
void AdminModel::addRecvStats(uint64_t recv)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
_recvStats.push_back(recv);
|
|
if (_recvStats.size() > _recvStatsSize)
|
|
_recvStats.pop_front();
|
|
|
|
notify("recv_activity " + std::to_string(recv));
|
|
}
|
|
|
|
void AdminModel::setCpuStatsSize(unsigned size)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
int wasteValuesLen = _cpuStats.size() - size;
|
|
while (wasteValuesLen-- > 0)
|
|
{
|
|
if (_cpuStats.empty())
|
|
{
|
|
break;
|
|
}
|
|
|
|
_cpuStats.pop_front();
|
|
}
|
|
_cpuStatsSize = size;
|
|
|
|
notify("settings cpu_stats_size=" + std::to_string(_cpuStatsSize));
|
|
}
|
|
|
|
void AdminModel::setMemStatsSize(unsigned size)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
int wasteValuesLen = _memStats.size() - size;
|
|
while (wasteValuesLen-- > 0)
|
|
{
|
|
if (_memStats.empty())
|
|
{
|
|
break;
|
|
}
|
|
|
|
_memStats.pop_front();
|
|
}
|
|
_memStatsSize = size;
|
|
|
|
notify("settings mem_stats_size=" + std::to_string(_memStatsSize));
|
|
}
|
|
|
|
void AdminModel::notify(const std::string& message)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
if (!_subscribers.empty())
|
|
{
|
|
LOG_TRC("Message to admin console: " << message);
|
|
for (auto it = std::begin(_subscribers); it != std::end(_subscribers); )
|
|
{
|
|
if (!it->second.notify(message))
|
|
{
|
|
it = _subscribers.erase(it);
|
|
}
|
|
else
|
|
{
|
|
++it;
|
|
}
|
|
}
|
|
}
|
|
}
|
|
|
|
void AdminModel::addBytes(const std::string& docKey, uint64_t sent, uint64_t recv)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
auto doc = _documents.find(docKey);
|
|
if(doc != _documents.end())
|
|
doc->second->addBytes(sent, recv);
|
|
|
|
_sentBytesTotal += sent;
|
|
_recvBytesTotal += recv;
|
|
}
|
|
|
|
void AdminModel::modificationAlert(const std::string& docKey, pid_t pid, bool value)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
auto doc = _documents.find(docKey);
|
|
if (doc != _documents.end())
|
|
doc->second->setModified(value);
|
|
|
|
std::ostringstream oss;
|
|
oss << "modifications "
|
|
<< pid << ' '
|
|
<< (value?"Yes":"No");
|
|
|
|
notify(oss.str());
|
|
}
|
|
|
|
void AdminModel::addDocument(const std::string& docKey, pid_t pid,
|
|
const std::string& filename, const std::string& sessionId,
|
|
const std::string& userName, const std::string& userId,
|
|
const int smapsFD)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
const auto ret = _documents.emplace(docKey, std::unique_ptr<Document>(new Document(docKey, pid, filename)));
|
|
ret.first->second->setProcSMapsFD(smapsFD);
|
|
ret.first->second->takeSnapshot();
|
|
ret.first->second->addView(sessionId, userName, userId);
|
|
LOG_DBG("Added admin document [" << docKey << "].");
|
|
|
|
std::string encodedUsername;
|
|
std::string encodedFilename;
|
|
std::string encodedUserId;
|
|
Poco::URI::encode(userId, " ", encodedUserId);
|
|
Poco::URI::encode(filename, " ", encodedFilename);
|
|
Poco::URI::encode(userName, " ", encodedUsername);
|
|
|
|
// Notify the subscribers
|
|
std::ostringstream oss;
|
|
oss << "adddoc "
|
|
<< pid << ' '
|
|
<< encodedFilename << ' '
|
|
<< sessionId << ' '
|
|
<< encodedUsername << ' '
|
|
<< encodedUserId << ' ';
|
|
|
|
// We have to wait until the kit sends us its PSS.
|
|
// Here we guestimate until we get an update.
|
|
if (_documents.size() < 2) // If we aren't the only one.
|
|
{
|
|
if (_memStats.empty())
|
|
{
|
|
oss << 0;
|
|
}
|
|
else
|
|
{
|
|
// Estimate half as much as wsd+forkit.
|
|
oss << _memStats.front() / 2;
|
|
}
|
|
}
|
|
else
|
|
{
|
|
oss << _documents.begin()->second->getMemoryDirty();
|
|
}
|
|
|
|
notify(oss.str());
|
|
}
|
|
|
|
void AdminModel::doRemove(std::map<std::string, std::unique_ptr<Document>>::iterator &docIt)
|
|
{
|
|
std::unique_ptr<Document> doc;
|
|
std::swap(doc, docIt->second);
|
|
std::string docItKey = docIt->first;
|
|
_documents.erase(docIt);
|
|
_expiredDocuments.emplace(docItKey + std::to_string(std::chrono::duration_cast<std::chrono::nanoseconds>(
|
|
std::chrono::steady_clock::now().time_since_epoch()).count()),
|
|
std::move(doc));
|
|
}
|
|
|
|
void AdminModel::removeDocument(const std::string& docKey, const std::string& sessionId)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
auto docIt = _documents.find(docKey);
|
|
if (docIt != _documents.end() && !docIt->second->isExpired())
|
|
{
|
|
// Notify the subscribers
|
|
std::ostringstream oss;
|
|
oss << "rmdoc "
|
|
<< docIt->second->getPid() << ' '
|
|
<< sessionId;
|
|
notify(oss.str());
|
|
|
|
// The idea is to only expire the document and keep the history
|
|
// of documents open and close, to be able to give a detailed summary
|
|
// to the admin console with views.
|
|
if (docIt->second->expireView(sessionId) == 0)
|
|
doRemove(docIt);
|
|
}
|
|
}
|
|
|
|
void AdminModel::removeDocument(const std::string& docKey)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
auto docIt = _documents.find(docKey);
|
|
if (docIt != _documents.end())
|
|
{
|
|
std::ostringstream oss;
|
|
oss << "rmdoc "
|
|
<< docIt->second->getPid() << ' ';
|
|
const std::string msg = oss.str();
|
|
|
|
for (const auto& pair : docIt->second->getViews())
|
|
{
|
|
// Notify the subscribers
|
|
notify(msg + pair.first);
|
|
docIt->second->expireView(pair.first);
|
|
}
|
|
|
|
LOG_DBG("Removed admin document [" << docKey << "].");
|
|
doRemove(docIt);
|
|
}
|
|
}
|
|
|
|
std::string AdminModel::getMemStats()
|
|
{
|
|
assertCorrectThread();
|
|
|
|
std::ostringstream oss;
|
|
for (const auto& i: _memStats)
|
|
{
|
|
oss << i << ',';
|
|
}
|
|
|
|
return oss.str();
|
|
}
|
|
|
|
std::string AdminModel::getCpuStats()
|
|
{
|
|
assertCorrectThread();
|
|
|
|
std::ostringstream oss;
|
|
for (const auto& i: _cpuStats)
|
|
{
|
|
oss << i << ',';
|
|
}
|
|
|
|
return oss.str();
|
|
}
|
|
|
|
std::string AdminModel::getSentActivity()
|
|
{
|
|
assertCorrectThread();
|
|
|
|
std::ostringstream oss;
|
|
for (const auto& i: _sentStats)
|
|
{
|
|
oss << i << ',';
|
|
}
|
|
|
|
return oss.str();
|
|
}
|
|
|
|
std::string AdminModel::getRecvActivity()
|
|
{
|
|
assertCorrectThread();
|
|
|
|
std::ostringstream oss;
|
|
for (const auto& i: _recvStats)
|
|
{
|
|
oss << i << ',';
|
|
}
|
|
|
|
return oss.str();
|
|
}
|
|
|
|
unsigned AdminModel::getTotalActiveViews()
|
|
{
|
|
assertCorrectThread();
|
|
|
|
unsigned numTotalViews = 0;
|
|
for (const auto& it: _documents)
|
|
{
|
|
if (!it.second->isExpired())
|
|
{
|
|
numTotalViews += it.second->getActiveViews();
|
|
}
|
|
}
|
|
|
|
return numTotalViews;
|
|
}
|
|
|
|
std::vector<DocBasicInfo> AdminModel::getDocumentsSortedByIdle() const
|
|
{
|
|
std::vector<DocBasicInfo> docs;
|
|
docs.reserve(_documents.size());
|
|
for (const auto& it: _documents)
|
|
{
|
|
docs.emplace_back(it.second->getDocKey(),
|
|
it.second->getIdleTime(),
|
|
it.second->getMemoryDirty(),
|
|
!it.second->getModifiedStatus());
|
|
}
|
|
|
|
// Sort the list by idle times;
|
|
std::sort(std::begin(docs), std::end(docs),
|
|
[](const DocBasicInfo& a, const DocBasicInfo& b)
|
|
{
|
|
return a.getIdleTime() >= b.getIdleTime();
|
|
});
|
|
|
|
return docs;
|
|
}
|
|
|
|
void AdminModel::cleanupResourceConsumingDocs()
|
|
{
|
|
DocCleanupSettings& settings = _defDocProcSettings.getCleanupSettings();
|
|
|
|
for (const auto& it: _documents)
|
|
{
|
|
Document *doc = it.second.get();
|
|
if (!doc->isExpired())
|
|
{
|
|
size_t idleTime = doc->getIdleTime();
|
|
size_t memDirty = doc->getMemoryDirty();
|
|
unsigned cpuPercentage = doc->getLastCpuPercentage();
|
|
|
|
if (idleTime >= settings.getIdleTime() &&
|
|
(memDirty >= settings.getLimitDirtyMem() * 1024 ||
|
|
cpuPercentage >= settings.getLimitCpu()))
|
|
{
|
|
time_t now = std::time(nullptr);
|
|
const size_t badBehaviorDuration = now - doc->getBadBehaviorDetectionTime();
|
|
if (!doc->getBadBehaviorDetectionTime())
|
|
{
|
|
LOG_WRN("Detected resource consuming doc [" << doc->getDocKey() << "]: idle="
|
|
<< idleTime << " s, memory=" << memDirty << " KB, CPU=" << cpuPercentage << "%.");
|
|
doc->setBadBehaviorDetectionTime(now);
|
|
}
|
|
else if (badBehaviorDuration >= settings.getBadBehaviorPeriod())
|
|
{
|
|
// We should not try to close it nicely (closeDocument) because
|
|
// we could lose it: it will be removed from our internal lists
|
|
// but the process itself can hang and continue to exist and
|
|
// consume resources.
|
|
// Also, try first to SIGABRT the kit process so that a stack trace
|
|
// could be dumped. If the process is still alive then, at next
|
|
// iteration, try to SIGKILL it.
|
|
if (SigUtil::killChild(doc->getPid(), doc->getAbortTime() ? SIGKILL : SIGABRT))
|
|
LOG_ERR((doc->getAbortTime() ? "Killed" : "Aborted") << " resource consuming doc [" << doc->getDocKey() << "]");
|
|
else
|
|
LOG_ERR("Cannot " << (doc->getAbortTime() ? "kill" : "abort") << " resource consuming doc [" << doc->getDocKey() << "]");
|
|
if (!doc->getAbortTime())
|
|
doc->setAbortTime(time_t(nullptr));
|
|
}
|
|
}
|
|
else if (doc->getBadBehaviorDetectionTime())
|
|
{
|
|
doc->setBadBehaviorDetectionTime(0);
|
|
LOG_WRN("Removed doc [" << doc->getDocKey() << "] from resource consuming monitoring list");
|
|
}
|
|
}
|
|
}
|
|
}
|
|
|
|
std::string AdminModel::getDocuments() const
|
|
{
|
|
assertCorrectThread();
|
|
|
|
std::ostringstream oss;
|
|
oss << '{' << "\"documents\"" << ':' << '[';
|
|
std::string separator1;
|
|
for (const auto& it: _documents)
|
|
{
|
|
if (!it.second->isExpired())
|
|
{
|
|
std::string encodedFilename;
|
|
Poco::URI::encode(it.second->getFilename(), " ", encodedFilename); // Is encoded name needed?
|
|
oss << separator1 << '{' << ' '
|
|
<< "\"pid\"" << ':' << it.second->getPid() << ','
|
|
<< "\"docKey\"" << ':' << '"' << it.second->getDocKey() << '"' << ','
|
|
<< "\"fileName\"" << ':' << '"' << encodedFilename << '"' << ','
|
|
<< "\"activeViews\"" << ':' << it.second->getActiveViews() << ','
|
|
<< "\"memory\"" << ':' << it.second->getMemoryDirty() << ','
|
|
<< "\"elapsedTime\"" << ':' << it.second->getElapsedTime() << ','
|
|
<< "\"idleTime\"" << ':' << it.second->getIdleTime() << ','
|
|
<< "\"modified\"" << ':' << '"' << (it.second->getModifiedStatus() ? "Yes" : "No") << '"' << ','
|
|
<< "\"views\"" << ':' << '[';
|
|
std::map<std::string, View> viewers = it.second->getViews();
|
|
std::string separator;
|
|
for(const auto& viewIt: viewers)
|
|
{
|
|
if(!viewIt.second.isExpired()) {
|
|
oss << separator << '{'
|
|
<< "\"userName\"" << ':' << '"' << viewIt.second.getUserName() << '"' << ','
|
|
<< "\"userId\"" << ':' << '"' << viewIt.second.getUserId() << '"' << ','
|
|
<< "\"sessionid\"" << ':' << '"' << viewIt.second.getSessionId() << '"' << '}';
|
|
separator = ',';
|
|
}
|
|
}
|
|
oss << ']'
|
|
<< '}';
|
|
separator1 = ',';
|
|
}
|
|
}
|
|
oss << ']' << '}';
|
|
|
|
return oss.str();
|
|
}
|
|
|
|
void AdminModel::updateLastActivityTime(const std::string& docKey)
|
|
{
|
|
assertCorrectThread();
|
|
|
|
auto docIt = _documents.find(docKey);
|
|
if (docIt != _documents.end())
|
|
{
|
|
if (docIt->second->getIdleTime() >= 10)
|
|
{
|
|
docIt->second->takeSnapshot(); // I would like to keep the idle time
|
|
docIt->second->updateLastActivityTime();
|
|
notify("resetidle " + std::to_string(docIt->second->getPid()));
|
|
}
|
|
}
|
|
}
|
|
|
|
double AdminModel::getServerUptimeSecs()
|
|
{
|
|
const auto currentTime = std::chrono::steady_clock::now();
|
|
const std::chrono::milliseconds uptime
|
|
= std::chrono::duration_cast<std::chrono::milliseconds>(currentTime - LOOLWSD::StartTime);
|
|
return uptime.count() / 1000.0; // Convert to seconds and fractions.
|
|
}
|
|
|
|
void AdminModel::setViewLoadDuration(const std::string& docKey, const std::string& sessionId, std::chrono::milliseconds viewLoadDuration)
|
|
{
|
|
auto it = _documents.find(docKey);
|
|
if (it != _documents.end())
|
|
it->second->setViewLoadDuration(sessionId, viewLoadDuration);
|
|
}
|
|
|
|
void AdminModel::setDocWopiDownloadDuration(const std::string& docKey, std::chrono::milliseconds wopiDownloadDuration)
|
|
{
|
|
auto it = _documents.find(docKey);
|
|
if (it != _documents.end())
|
|
it->second->setWopiDownloadDuration(wopiDownloadDuration);
|
|
}
|
|
|
|
void AdminModel::setDocWopiUploadDuration(const std::string& docKey, const std::chrono::milliseconds wopiUploadDuration)
|
|
{
|
|
auto it = _documents.find(docKey);
|
|
if (it != _documents.end())
|
|
it->second->setWopiUploadDuration(wopiUploadDuration);
|
|
}
|
|
|
|
void AdminModel::addSegFaultCount(unsigned segFaultCount)
|
|
{
|
|
_segFaultCount += segFaultCount;
|
|
}
|
|
|
|
int filterNumberName(const struct dirent *dir)
|
|
{
|
|
return !fnmatch("[0-9]*", dir->d_name, 0);
|
|
}
|
|
|
|
int AdminModel::getPidsFromProcName(const std::regex& procNameRegEx, std::vector<int> *pids)
|
|
{
|
|
struct dirent **namelist = NULL;
|
|
int n = scandir("/proc", &namelist, filterNumberName, 0);
|
|
int pidCount = 0;
|
|
|
|
if (n < 0)
|
|
return n;
|
|
|
|
std::string comm;
|
|
char line[256] = { 0 }; //Here we need only 16 bytes but for safety reasons we use file name max length
|
|
|
|
if (pids != NULL)
|
|
pids->clear();
|
|
|
|
while (n--)
|
|
{
|
|
comm = "/proc/";
|
|
comm += namelist[n]->d_name;
|
|
comm += "/comm";
|
|
FILE* fp = fopen(comm.c_str(), "r");
|
|
if (fp != nullptr)
|
|
{
|
|
if (fgets(line, sizeof (line), fp))
|
|
{
|
|
char *nl = strchr(line, '\n');
|
|
if (nl != NULL)
|
|
*nl = 0;
|
|
if (regex_match(line, procNameRegEx))
|
|
{
|
|
pidCount ++;
|
|
if (pids)
|
|
pids->push_back(strtol(namelist[n]->d_name, NULL, 10));
|
|
}
|
|
}
|
|
fclose(fp);
|
|
}
|
|
free(namelist[n]);
|
|
}
|
|
free(namelist);
|
|
|
|
return pidCount;
|
|
}
|
|
|
|
class AggregateStats
|
|
{
|
|
public:
|
|
AggregateStats()
|
|
: _total(0), _min(0xFFFFFFFFFFFFFFFF), _max(0), _count(0)
|
|
{}
|
|
|
|
void Update(uint64_t value)
|
|
{
|
|
_total += value;
|
|
_min = (_min > value ? value : _min);
|
|
_max = (_max < value ? value : _max);
|
|
_count ++;
|
|
}
|
|
|
|
uint64_t getIntAverage() const { return _count ? std::round(_total / (double)_count) : 0; }
|
|
double getDoubleAverage() const { return _count ? _total / (double) _count : 0; }
|
|
uint64_t getMin() const { return _min == 0xFFFFFFFFFFFFFFFF ? 0 : _min; }
|
|
uint64_t getMax() const { return _max; }
|
|
uint64_t getTotal() const { return _total; }
|
|
uint64_t getCount() const { return _count; }
|
|
|
|
void Print(std::ostringstream &oss, const char *prefix, const char* unit) const
|
|
{
|
|
std::string newUnit = std::string(unit && unit[0] ? "_" : "") + unit;
|
|
std::string newPrefix = prefix + std::string(prefix && prefix[0] ? "_" : "");
|
|
|
|
oss << newPrefix << "total" << newUnit << ' ' << _total << std::endl;
|
|
oss << newPrefix << "average" << newUnit << ' ' << getIntAverage() << std::endl;
|
|
oss << newPrefix << "min" << newUnit << ' ' << getMin() << std::endl;
|
|
oss << newPrefix << "max" << newUnit << ' ' << _max << std::endl;
|
|
}
|
|
|
|
private:
|
|
uint64_t _total;
|
|
uint64_t _min;
|
|
uint64_t _max;
|
|
uint32_t _count;
|
|
};
|
|
|
|
struct ActiveExpiredStats
|
|
{
|
|
public:
|
|
|
|
void Update(uint64_t value, bool active)
|
|
{
|
|
_all.Update(value);
|
|
if (active)
|
|
_active.Update(value);
|
|
else
|
|
_expired.Update(value);
|
|
}
|
|
|
|
void Print(std::ostringstream &oss, const char *prefix, const char* name, const char* unit) const
|
|
{
|
|
std::ostringstream ossTmp;
|
|
std::string newName = std::string(name && name[0] ? "_" : "") + name;
|
|
std::string newPrefix = prefix + std::string(prefix && prefix[0] ? "_" : "");
|
|
|
|
ossTmp << newPrefix << "all" << newName;
|
|
_all.Print(oss, ossTmp.str().c_str(), unit);
|
|
ossTmp.str(std::string());
|
|
ossTmp << newPrefix << "active" << newName;
|
|
_active.Print(oss, ossTmp.str().c_str(), unit);
|
|
ossTmp.str(std::string());
|
|
ossTmp << newPrefix << "expired" << newName;
|
|
_expired.Print(oss, ossTmp.str().c_str(), unit);
|
|
}
|
|
|
|
AggregateStats _all;
|
|
AggregateStats _active;
|
|
AggregateStats _expired;
|
|
};
|
|
|
|
struct DocumentAggregateStats
|
|
{
|
|
void Update(const Document &d, bool active)
|
|
{
|
|
_kitUsedMemory.Update(d.getMemoryDirty() * 1024, active);
|
|
_viewsCount.Update(d.getViews().size(), active);
|
|
_activeViewsCount.Update(d.getActiveViews(), active);
|
|
_expiredViewsCount.Update(d.getViews().size() - d.getActiveViews(), active);
|
|
_openedTime.Update(d.getOpenTime(), active);
|
|
_bytesSentToClients.Update(d.getSentBytes(), active);
|
|
_bytesRecvFromClients.Update(d.getRecvBytes(), active);
|
|
_wopiDownloadDuration.Update(d.getWopiDownloadDuration().count(), active);
|
|
_wopiUploadDuration.Update(d.getWopiUploadDuration().count(), active);
|
|
|
|
//View load duration
|
|
for (const auto& v : d.getViews())
|
|
_viewLoadDuration.Update(v.second.getLoadDuration().count(), active);
|
|
}
|
|
|
|
ActiveExpiredStats _kitUsedMemory;
|
|
ActiveExpiredStats _viewsCount;
|
|
ActiveExpiredStats _activeViewsCount;
|
|
ActiveExpiredStats _expiredViewsCount;
|
|
ActiveExpiredStats _openedTime;
|
|
ActiveExpiredStats _bytesSentToClients;
|
|
ActiveExpiredStats _bytesRecvFromClients;
|
|
ActiveExpiredStats _wopiDownloadDuration;
|
|
ActiveExpiredStats _wopiUploadDuration;
|
|
ActiveExpiredStats _viewLoadDuration;
|
|
};
|
|
|
|
struct KitProcStats
|
|
{
|
|
void UpdateAggregateStats(int pid)
|
|
{
|
|
_threadCount.Update(Util::getStatFromPid(pid, 19));
|
|
_cpuTime.Update(Util::getCpuUsage(pid) / sysconf (_SC_CLK_TCK));
|
|
}
|
|
|
|
int unassignedCount;
|
|
int assignedCount;
|
|
AggregateStats _threadCount;
|
|
AggregateStats _cpuTime;
|
|
};
|
|
|
|
void AdminModel::CalcDocAggregateStats(DocumentAggregateStats& stats)
|
|
{
|
|
for (auto& d : _documents)
|
|
stats.Update(*d.second, true);
|
|
|
|
for (auto& d : _expiredDocuments)
|
|
stats.Update(*d.second, false);
|
|
}
|
|
|
|
void CalcKitStats(KitProcStats& stats)
|
|
{
|
|
std::vector<int> childProcs;
|
|
stats.unassignedCount = AdminModel::getPidsFromProcName(std::regex("kit_spare_[0-9]*"), &childProcs);
|
|
stats.assignedCount = AdminModel::getPidsFromProcName(std::regex("kit_[0-9]*"), &childProcs);
|
|
for (int& pid : childProcs)
|
|
{
|
|
stats.UpdateAggregateStats(pid);
|
|
}
|
|
}
|
|
|
|
void PrintDocActExpMetrics(std::ostringstream &oss, const char* name, const char* unit, const ActiveExpiredStats &values)
|
|
{
|
|
values.Print(oss, "document", name, unit);
|
|
}
|
|
|
|
void PrintKitAggregateMetrics(std::ostringstream &oss, const char* name, const char* unit, const AggregateStats &values)
|
|
{
|
|
std::string prefix = std::string("kit_") + name;
|
|
values.Print(oss, prefix.c_str(), unit);
|
|
}
|
|
|
|
void AdminModel::getMetrics(std::ostringstream &oss)
|
|
{
|
|
oss << "loolwsd_count " << getPidsFromProcName(std::regex("loolwsd"), nullptr) << std::endl;
|
|
oss << "loolwsd_thread_count " << Util::getStatFromPid(getpid(), 19) << std::endl;
|
|
oss << "loolwsd_cpu_time_seconds " << Util::getCpuUsage(getpid()) / sysconf (_SC_CLK_TCK) << std::endl;
|
|
oss << "loolwsd_memory_used_bytes " << Util::getMemoryUsagePSS(getpid()) * 1024 << std::endl;
|
|
oss << std::endl;
|
|
|
|
oss << "forkit_count " << getPidsFromProcName(std::regex("forkit"), nullptr) << std::endl;
|
|
oss << "forkit_thread_count " << Util::getStatFromPid(_forKitPid, 19) << std::endl;
|
|
oss << "forkit_cpu_time_seconds " << Util::getCpuUsage(_forKitPid) / sysconf (_SC_CLK_TCK) << std::endl;
|
|
oss << "forkit_memory_used_bytes " << Util::getMemoryUsageRSS(_forKitPid) * 1024 << std::endl;
|
|
oss << std::endl;
|
|
|
|
DocumentAggregateStats docStats;
|
|
KitProcStats kitStats;
|
|
|
|
CalcDocAggregateStats(docStats);
|
|
CalcKitStats(kitStats);
|
|
|
|
oss << "kit_count " << kitStats.unassignedCount + kitStats.assignedCount << std::endl;
|
|
oss << "kit_unassigned_count " << kitStats.unassignedCount << std::endl;
|
|
oss << "kit_assigned_count " << kitStats.assignedCount << std::endl;
|
|
oss << "kit_segfault_count " << _segFaultCount << std::endl;
|
|
PrintKitAggregateMetrics(oss, "thread_count", "", kitStats._threadCount);
|
|
PrintKitAggregateMetrics(oss, "memory_used", "bytes", docStats._kitUsedMemory._active);
|
|
PrintKitAggregateMetrics(oss, "cpu_time", "seconds", kitStats._cpuTime);
|
|
oss << std::endl;
|
|
|
|
PrintDocActExpMetrics(oss, "views_all_count", "", docStats._viewsCount);
|
|
docStats._activeViewsCount._active.Print(oss, "document_active_views_active_count", "");
|
|
docStats._expiredViewsCount._active.Print(oss, "document_active_views_expired_count", "");
|
|
oss << std::endl;
|
|
|
|
PrintDocActExpMetrics(oss, "opened_time", "seconds", docStats._openedTime);
|
|
oss << std::endl;
|
|
PrintDocActExpMetrics(oss, "sent_to_clients", "bytes", docStats._bytesSentToClients);
|
|
oss << std::endl;
|
|
PrintDocActExpMetrics(oss, "received_from_client", "bytes", docStats._bytesRecvFromClients);
|
|
oss << std::endl;
|
|
PrintDocActExpMetrics(oss, "wopi_upload_duration", "milliseconds", docStats._wopiUploadDuration);
|
|
oss << std::endl;
|
|
PrintDocActExpMetrics(oss, "wopi_download_duration", "milliseconds", docStats._wopiDownloadDuration);
|
|
oss << std::endl;
|
|
PrintDocActExpMetrics(oss, "view_load_duration", "milliseconds", docStats._viewLoadDuration);
|
|
}
|
|
|
|
std::set<pid_t> AdminModel::getDocumentPids() const
|
|
{
|
|
std::set<pid_t> pids;
|
|
|
|
for (const auto& it : _documents)
|
|
pids.insert(it.second->getPid());
|
|
|
|
return pids;
|
|
}
|
|
|
|
void AdminModel::UpdateMemoryDirty()
|
|
{
|
|
for (const auto& it: _documents)
|
|
{
|
|
it.second->updateMemoryDirty();
|
|
}
|
|
}
|
|
|
|
void AdminModel::notifyDocsMemDirtyChanged()
|
|
{
|
|
for (const auto& it: _documents)
|
|
{
|
|
int memoryDirty = it.second->getMemoryDirty();
|
|
if (it.second->hasMemDirtyChanged())
|
|
{
|
|
notify("propchange " + std::to_string(it.second->getPid()) + " mem " + std::to_string(memoryDirty));
|
|
it.second->setMemDirtyChanged(false);
|
|
}
|
|
}
|
|
}
|
|
|
|
/* vim:set shiftwidth=4 softtabstop=4 expandtab: */
|