Merge branch 'bugfix/StopResponding_#58' of github.com:F4FXL/DStarGateway into bugfix/StopResponding_#58

develop^2
Geoffrey Merck 1 month ago
commit 23a95d46cd

@ -16,7 +16,6 @@
* Foundation, Inc., 675 Mass Ave, Cambridge, MA 02139, USA.
*/
#pragma once
#include <ctime>
@ -39,10 +38,10 @@ private:
static uint m_prevMsgCount;
static uint m_repeatThreshold;
static void getTimeStamp(std::string& s);
static void getTimeStamp(std::string &s);
template<typename... Args>
static void formatLogMessage(std::string& output, LOG_SEVERITY severity, const std::string & f, Args... args)
template <typename... Args>
static void formatLogMessage(std::string &output, LOG_SEVERITY severity, const std::string &f, Args... args)
{
assert(severity != LOG_NONE);
@ -58,7 +57,7 @@ private:
case LOG_FATAL:
severityStr.assign("FATAL ");
break;
case LOG_INFO :
case LOG_INFO:
severityStr.assign("INFO ");
break;
case LOG_WARNING:
@ -74,84 +73,113 @@ private:
std::string f2("[%s] ");
f2.append(f);
CStringUtils::string_format_in_place(output, f2, severityStr.c_str(), args...);
boost::trim_if(output, [](char c){ return c == '\n' || c == '\r' || c == ' ' || c == '\t'; });
boost::trim_if(output, [](char c)
{ return c == '\n' || c == '\r' || c == ' ' || c == '\t'; });
output.push_back('\n');
}
public:
static void addTarget(CLogTarget * target);
static void addTarget(CLogTarget *target);
static void finalise();
static uint& getRepeatThreshold();
static uint &getRepeatThreshold();
template<typename... Args> static void logTrace(const std::string & f, Args... args)
template <typename... Args>
static void logTrace(const std::string &f, Args... args)
{
log(LOG_TRACE, f, args...);
}
template<typename... Args> static void logDebug(const std::string & f, Args... args)
template <typename... Args>
static void logDebug(const std::string &f, Args... args)
{
log(LOG_DEBUG, f, args...);
}
template<typename... Args> static void logInfo(const std::string & f, Args... args)
template <typename... Args>
static void logInfo(const std::string &f, Args... args)
{
log(LOG_INFO, f, args...);
}
template<typename... Args> static void logWarning(const std::string & f, Args... args)
template <typename... Args>
static void logWarning(const std::string &f, Args... args)
{
log(LOG_WARNING, f, args...);
}
template<typename... Args> static void logError(const std::string & f, Args... args)
template <typename... Args>
static void logError(const std::string &f, Args... args)
{
log(LOG_ERROR, f, args...);
}
template<typename... Args> static void logFatal(const std::string & f, Args... args)
template <typename... Args>
static void logFatal(const std::string &f, Args... args)
{
log(LOG_FATAL, f, args...);
}
template<typename... Args> static void log(LOG_SEVERITY severity, const std::string & f, Args... args)
template <typename... Args>
static void log(LOG_SEVERITY severity, const std::string &f, Args... args)
{
// Protect against concurrent access to log targets
std::lock_guard lockTarget(m_targetsMutex);
if(m_targets.empty())
if (m_targets.empty())
return;
// Format the message with the given arguments
std::string msg;
formatLogMessage(msg, severity, f, args...);
bool repeatedMsg = (msg.compare(m_prevMsg) == 0);
bool repeatedMsg = (msg == m_prevMsg);
if(repeatedMsg && m_repeatThreshold > 0U) {
// Handle repeated messages
if (repeatedMsg && m_repeatThreshold > 0U) {
m_prevMsgCount++;
if(m_prevMsgCount >= m_repeatThreshold)
if (m_prevMsgCount >= m_repeatThreshold)
{
// If threshold reached, skip logging this duplicate
return;
}
}
m_prevMsg.assign(msg);
// If we are leaving a repetition sequence, log a summary first
if (!repeatedMsg && m_repeatThreshold > 0U && m_prevMsgCount >= m_repeatThreshold) {
std::string summary;
formatLogMessage(summary, severity,
"Previous message repeated %d times",
m_prevMsgCount - m_repeatThreshold + 1);
if(m_prevMsgCount >= m_repeatThreshold && !repeatedMsg && m_repeatThreshold > 0U) {
formatLogMessage(msg, severity, "Previous message repeated %d times", m_prevMsgCount - m_repeatThreshold + 1);
m_prevMsg.clear();
}
std::string ts;
getTimeStamp(ts);
std::string timestamp;
getTimeStamp(timestamp);
std::string msgts;
CStringUtils::string_format_in_place(msgts, "[%s] %s", timestamp.c_str(), msg.c_str());
std::string summaryLine;
CStringUtils::string_format_in_place(summaryLine, "[%s] %s", ts.c_str(), summary.c_str());
for(auto target : m_targets) {
if(severity >= target->getLevel()) {
target->printLog(msgts);
}
for (auto target : m_targets)
{
if (severity >= target->getLevel())
target->printLog(summaryLine);
}
if(m_prevMsgCount != 0 && !repeatedMsg) {
// Reset repetition counter after summary
m_prevMsgCount = 0;
log(severity, f, args ...);
}
// Always log the current message
std::string ts;
getTimeStamp(ts);
std::string msgLine;
CStringUtils::string_format_in_place(msgLine, "[%s] %s", ts.c_str(), msg.c_str());
for (auto target : m_targets) {
if (severity >= target->getLevel())
target->printLog(msgLine);
}
// Save current message for repetition detection
m_prevMsg = msg;
}
};

@ -118,6 +118,7 @@ int main(int argc, char *argv[])
TLog logConf;
config->getLog(logConf);
CLog::finalise();
CLog::getRepeatThreshold() = logConf.repeatThreshold;
if(logConf.displayLevel != LOG_NONE && !daemon.daemon) CLog::addTarget(new CLogConsoleTarget(logConf.displayLevel));
if(logConf.fileLevel != LOG_NONE) CLog::addTarget(new CLogFileTarget(logConf.fileLevel, logConf.logDir, logConf.fileRoot, logConf.fileRotate));

@ -58,19 +58,24 @@ IRCClient::IRCClient(IRCApplication *app, const std::string& update_channel, con
IRCClient::~IRCClient()
{
stopWork();
delete m_proto;
}
void IRCClient::startWork()
{
m_terminateThread = false;
m_future = std::async(std::launch::async, &IRCClient::Entry, this);
if (m_thread.joinable())
return;
m_terminateThread.store(false, std::memory_order_relaxed);
m_thread = std::thread(&IRCClient::Entry, this);
}
void IRCClient::stopWork()
{
m_terminateThread = true;
m_future.get();
m_terminateThread.store(true, std::memory_order_relaxed);
if (m_thread.joinable())
m_thread.join();
}
void IRCClient::Entry()
@ -98,7 +103,7 @@ void IRCClient::Entry()
switch (state) {
case 0:
if (m_terminateThread) {
if (m_terminateThread.load(std::memory_order_relaxed)) {
CLog::logInfo("IRCClient::Entry: thread terminated at state=%d\n", state);
return;
}
@ -118,7 +123,7 @@ void IRCClient::Entry()
break;
case 1:
if (m_terminateThread) {
if (m_terminateThread.load(std::memory_order_relaxed)) {
CLog::logInfo("IRCClient::Entry: thread terminated at state=%d\n", state);
return;
}
@ -261,7 +266,7 @@ void IRCClient::Entry()
case 5:
if (m_terminateThread)
if (m_terminateThread.load(std::memory_order_relaxed))
state = 6;
else {
if (m_recvQ->isEOF()) {

@ -22,7 +22,8 @@ along with this program. If not, see <http://www.gnu.org/licenses/>.
#pragma once
#include <string>
#include <future>
#include <thread>
#include <atomic>
#include "IRCReceiver.h"
#include "IRCMessageQueue.h"
@ -47,13 +48,13 @@ private:
unsigned int m_port;
std::string m_callsign;
std::string m_password;
bool m_terminateThread;
std::atomic<bool> m_terminateThread{false};
IRCReceiver *m_recv;
IRCMessageQueue *m_recvQ;
IRCMessageQueue *m_sendQ;
IRCProtocol *m_proto;
IRCApplication *m_app;
std::future<void> m_future;
};
std::thread m_thread;
};

@ -112,7 +112,7 @@ public:
std::regex m_fromPattern;
bool m_initReady;
bool m_terminateThread;
std::atomic<bool> m_terminateThread{false};
std::map<std::string, IRCDDBAppUserObject> m_userMap;
std::mutex m_userMapMutex;
@ -151,6 +151,8 @@ IRCDDBApp::IRCDDBApp(const std::string& u_chan)
IRCDDBApp::~IRCDDBApp()
{
stopWork();
delete m_d->m_sendQ;
delete m_d;
}
@ -279,14 +281,18 @@ IRCMessage *IRCDDBApp::getReplyMessage()
void IRCDDBApp::startWork()
{
m_d->m_terminateThread = false;
m_future = std::async(std::launch::async, &IRCDDBApp::Entry, this);
if (m_thread.joinable())
return;
m_d->m_terminateThread.store(false, std::memory_order_relaxed);
m_thread = std::thread(&IRCDDBApp::Entry, this);
}
void IRCDDBApp::stopWork()
{
m_d->m_terminateThread = true;
m_future.get();
m_d->m_terminateThread.store(true, std::memory_order_relaxed);
if (m_thread.joinable())
m_thread.join();
}
unsigned int IRCDDBApp::calculateUsn(const std::string& nick)
@ -996,7 +1002,7 @@ static bool needsDatabaseUpdate(int tableID)
void IRCDDBApp::Entry()
{
int sendlistTableID = 0;
while (!m_d->m_terminateThread) {
while (!m_d->m_terminateThread.load(std::memory_order_relaxed)) {
if (m_d->m_timer > 0)
m_d->m_timer--;
switch(m_d->m_state) {

@ -25,7 +25,8 @@ along with this program. If not, see <http://www.gnu.org/licenses/>.
#include "IRCApplication.h"
#include <string>
#include <future>
#include <thread>
#include <atomic>
#include <ctime>
#include <vector>
@ -99,6 +100,7 @@ private:
IRCDDBAppPrivate *m_d;
time_t m_maxTime;
std::future<void> m_future;
std::thread m_thread;
};

@ -52,6 +52,18 @@ namespace LogRepeatTests
EXPECT_THAT(m_logTarget->m_messages[1].c_str(), EndsWith("[ERROR ] Two Message\n"));
}
TEST_F(LogRepeat, ThreeIdenticalMessageThreshold0) {
CLog::getRepeatThreshold() = 0U;
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
EXPECT_EQ(3, m_logTarget->m_messages.size()) << "There should be 3 messages in the log.";
EXPECT_THAT(m_logTarget->m_messages[0].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[1].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[2].c_str(), EndsWith("[ERROR ] One Message\n"));
}
TEST_F(LogRepeat, ThreeIdenticalMessageThreshold1) {
CLog::getRepeatThreshold() = 1U;
CLog::logError("One Message");
@ -62,6 +74,34 @@ namespace LogRepeatTests
EXPECT_THAT(m_logTarget->m_messages[0].c_str(), EndsWith("[ERROR ] One Message\n"));
}
TEST_F(LogRepeat, NineIdenticalMessageTwoDifferentThreshold0) {
CLog::getRepeatThreshold() = 0U;
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("One Message");
CLog::logError("Another Message");
CLog::logError("And here is another Message");
EXPECT_EQ(11, m_logTarget->m_messages.size()) << "There should be two message in the log.";
EXPECT_THAT(m_logTarget->m_messages[0].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[1].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[2].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[3].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[4].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[5].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[6].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[7].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[8].c_str(), EndsWith("[ERROR ] One Message\n"));
EXPECT_THAT(m_logTarget->m_messages[9].c_str(), EndsWith("[ERROR ] Another Message\n"));
EXPECT_THAT(m_logTarget->m_messages[10].c_str(), EndsWith("[ERROR ] And here is another Message\n"));
}
TEST_F(LogRepeat, NineIdenticalMessageTwoDifferentThreshold1) {
CLog::getRepeatThreshold() = 1U;
CLog::logError("One Message");

Loading…
Cancel
Save

Powered by TurnKey Linux.