Back to list

make: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[1]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[ 16%] Built target cluck
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[ 38%] Built target cluckd
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[ 41%] Built target daemon_message-definitions_VerifyMessageDefinitions
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[ 52%] Built target cluck-status
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[ 75%] Built target cluckdaemon
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[ 94%] Built target unittest
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make[2]: Entering directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[ 97%] Generating API documentation with Doxygen
make[2]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
[100%] Built target cluck_Documentation
make[1]: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
make: Leaving directory '/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck'
../../BUILD/Debug/contrib/cluck/tests/unittest --tmp-dir "../../BUILD/Debug/contrib/cluck/tmp" --binary-dir "/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck" --dist-dir "/home/alexis/snapwebsites/snapcpp/BUILD/Debug/dist" --source-dir "/home/alexis/snapwebsites/snapcpp/contrib/cluck"   --progress "" 
cluck v1.0.0 [382293]:unittest: seed is 1752386103
source directory: "/home/alexis/snapwebsites/snapcpp/contrib/cluck"
binary directory: "/home/alexis/snapwebsites/snapcpp/BUILD/Debug/contrib/cluck"
dist directory: "/home/alexis/snapwebsites/snapcpp/BUILD/Debug/dist"
temporary directory: "../../BUILD/Debug/contrib/cluck/tmp"
Randomness seeded to: 2771418716
SECTION: cluck_client: verify timeouts
2025/07/13 05:55:03 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: verify timeouts}
2025/07/13 05:55:03 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: verify timeouts}
SECTION: cluck_client: successful LOCK (simple)
2025/07/13 05:55:03 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_READY". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "NO_LOCK". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "cluck::lock-name", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382299]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382299. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382299]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382299 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (simple)}
--- message: cluckd/LOCK object_name=lock-name;pid=382293;serial=1;tag=10001;timeout=1752389703.420190133;transmission_report=failure
--- message: --- test_case = [valid]....
--- message: --- test_case = not equal invalid tag....
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,LOCK_READY,NO_LOCK,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/07/13 05:55:03 unittester [382293/382293]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/LOCK_READY cache=no". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/LOCKED object_name=lock-name;tag=10001;timeout_date=1752386108.429192713;unlocked_date=1752386113.429192713". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: successful LOCK (simple)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=382293;serial=1;tag=10001
2025/07/13 05:55:03 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: removing 1 connection, "cluck::lock-name", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/UNLOCKED object_name=lock-name;tag=10001". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: successful LOCK (simple)}
2025/07/13 05:55:03 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: successful LOCK (simple)}
SECTION: cluck_client: successful LOCK (extended)
2025/07/13 05:55:03 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "cluck::lock-name", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: notice: this cluck object is not currently locked. (in function "void cluck::cluck::unlock()()") (cluck.cpp:996) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382341]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382341. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382341]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382341 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:03 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: successful LOCK (extended)}
--- message: cluckd/LOCK duration=3600;object_name=lock-name;pid=382293;serial=2;tag=10002;timeout=1752386114.345228834;transmission_report=failure;type=1;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/07/13 05:55:03 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10002;timeout_date=1752389703.85751183;unlocked_date=1752389706.85751183". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: successful LOCK (extended)}
--- message: tester/READ size=219
2025/07/13 05:55:04 unittester [382293/382293]: trace: dispatch message "<my_server:tester other_server:cluck_test/DATA data=the-data (the size received is the max.--the size returned is this buffer's size);size=81". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: successful LOCK (extended)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=382293;serial=2;tag=10002
2025/07/13 05:55:04 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKED object_name=lock-name;tag=10002". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "cluck::lock-name", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: successful LOCK (extended)}
2025/07/13 05:55:04 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: successful LOCK (extended)}
SECTION: cluck_client: failing UNLOCKED (invalid object name)
2025/07/13 05:55:04 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "cluck::lock-name", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382383]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382383. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382383]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382383 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: failing UNLOCKED (invalid object name)}
--- message: cluckd/LOCK object_name=lock-name;pid=382293;serial=3;tag=10003;timeout=1752389704.276771099;transmission_report=failure
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/07/13 05:55:04 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10003;timeout_date=1752386109.285689693;unlocked_date=1752386114.285689693". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: failing UNLOCKED (invalid object name)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=382293;serial=3;tag=10003
2025/07/13 05:55:04 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKED object_name=invalid-name;tag=10003;timeout_date=1752386109.285689693;unlocked_date=1752386114.285689693". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: failing UNLOCKED (invalid object name)}
--- message: my_server:cluckd/INVALID command=UNLOCKED;message=the "object_name" parameter does not match this cluck object. Got "invalid-name", expected "lock-name".
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "cluck::lock-name", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: failing UNLOCKED (invalid object name)}
2025/07/13 05:55:04 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: failing UNLOCKED (invalid object name)}
SECTION: cluck_client: UNLOCKING--cluckd server safe timeout
2025/07/13 05:55:04 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382425]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382425. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382425]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382425 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:04 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server safe timeout}
--- message: cluckd/LOCK object_name=unlocking-lock;pid=382293;serial=4;tag=10004;timeout=1752386107.694034546;transmission_report=failure
2025/07/13 05:55:04 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=unlocking-lock;tag=10004;timeout_date=1752386105.703024899;unlocked_date=1752386110.703024899". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKING object_name=unlocking-lock;tag=10004". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: UNLOCKING--cluckd server safe timeout}
--- message: cluckd/UNLOCK object_name=unlocking-lock;pid=382293;serial=4;tag=10004
2025/07/13 05:55:06 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKED object_name=unlocking-lock;tag=10004". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/07/13 05:55:06 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: UNLOCKING--cluckd server safe timeout}
SECTION: cluck_client: UNLOCKING--cluckd server late timeout
2025/07/13 05:55:06 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382467]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382467. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382467]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382467 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:06 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--cluckd server late timeout}
--- message: cluckd/LOCK duration=3;object_name=unlocking-lock;pid=382293;serial=5;tag=10005;timeout=1752386109.730280398;transmission_report=failure;unlock_duration=3
2025/07/13 05:55:06 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=unlocking-lock;tag=10005;timeout_date=1752386109.742354537;unlocked_date=1752386112.742354537". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:13 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:13 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:13 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:13 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:13 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKING object_name=unlocking-lock;tag=10005". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/07/13 05:55:13 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: UNLOCKING--cluckd server late timeout}
SECTION: cluck_client: UNLOCKING--invalid object name
2025/07/13 05:55:13 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382509]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382509. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382509]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382509 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:13 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: UNLOCKING--invalid object name}
--- message: cluckd/LOCK duration=3;object_name=unlocking-lock;pid=382293;serial=6;tag=10006;timeout=1752386116.770030307;transmission_report=failure;unlock_duration=3
2025/07/13 05:55:13 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=unlocking-lock;tag=10006;timeout_date=1752386114.779080864;unlocked_date=1752386119.779080864". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:15 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKING object_name=unknown-lock;tag=10006". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: UNLOCKING--invalid object name}
--- message: my_server:cluckd/INVALID command=UNLOCKING;message=the "object_name" parameter does not match this cluck object. Got "unknown-lock", expected "unlocking-lock".
2025/07/13 05:55:15 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:15 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:15 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:15 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: UNLOCKING--invalid object name}
2025/07/13 05:55:15 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: UNLOCKING--invalid object name}
SECTION: cluck_client: spurious TRANSMISSION_REPORT
2025/07/13 05:55:15 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382551]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382551. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382551]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382551 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: spurious TRANSMISSION_REPORT}
--- message: cluckd/LOCK duration=3600;object_name=lock-name;pid=382293;serial=7;tag=10007;timeout=1752386126.30499119;transmission_report=failure;type=1;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/07/13 05:55:15 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/TRANSMISSION_REPORT command=NOT_OUR_MESSAGE;status=10007". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:15 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10007;timeout_date=1752389715.817112288;unlocked_date=1752389718.817112288". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: spurious TRANSMISSION_REPORT}
--- message: tester/READ size=219
2025/07/13 05:55:16 unittester [382293/382293]: trace: dispatch message "<my_server:tester other_server:cluck_test/DATA data=the-data (the size received is the max.--the size returned is this buffer's size);size=81". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: spurious TRANSMISSION_REPORT}
--- message: cluckd/UNLOCK object_name=lock-name;pid=382293;serial=7;tag=10007
2025/07/13 05:55:16 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKED object_name=lock-name;tag=10007". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: spurious TRANSMISSION_REPORT}
2025/07/13 05:55:16 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: spurious TRANSMISSION_REPORT}
SECTION: cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon
2025/07/13 05:55:16 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382593]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382593. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382593]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382593 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
--- message: cluckd/LOCK duration=3;object_name=lock-not-received;pid=382293;serial=8;tag=10008;timeout=1752386119.238640862;transmission_report=failure;unlock_duration=3
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/TRANSMISSION_REPORT command=LOCK;status=failed". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: recoverable error: the transmission of our "LOCK" message failed to travel to a cluckd service. (in function "void cluck::cluck::msg_transmission_report(ed::message&)()") (cluck.cpp:1441) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/07/13 05:55:16 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
SECTION: cluck_client_error: messenger required
2025/07/13 05:55:16 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: messenger required}
2025/07/13 05:55:16 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: messenger required}
SECTION: cluck_client_error: dispatcher required
2025/07/13 05:55:16 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: dispatcher required}
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: dispatcher required}
SECTION: cluck_client_error: LOCK_FAILED--pretend the LOCK times out
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382675]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382675. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382675]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382675 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
--- message: cluckd/LOCK duration=3;object_name=lock-timeout;pid=382293;serial=9;tag=10011;timeout=1752386120.045723205;transmission_report=failure;unlock_duration=3
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCK_FAILED error=timedout;key=server2/service2;object_name=lock-timeout;tag=10011". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
SECTION: cluck_client_error: LOCK_FAILED--invalid tag
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382677]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382677. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382677]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382677 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--invalid tag}
--- message: cluckd/LOCK duration=3;object_name=lock-timeout;pid=382293;serial=10;tag=10012;timeout=1752386120.07673568;transmission_report=failure;unlock_duration=3
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCK_FAILED error=timedout;key=server1/service1;object_name=wrong-name;tag=10012". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: LOCK_FAILED--invalid tag}
SECTION: cluck_client_error: LOCK_FAILED--other error (not timeout)
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382679]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382679. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382679]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382679 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
--- message: cluckd/LOCK duration=3;object_name=lock-timeout;pid=382293;serial=11;tag=10013;timeout=1752386120.104715876;transmission_report=failure;unlock_duration=3
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCK_FAILED error=other-error;key=do we always send a key?;object_name=lock-timeout;tag=10013". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: warning: communicatord did not like our LOCK message: other-error. (in function "void cluck::cluck::msg_lock_failed(ed::message&)()") (cluck.cpp:1399) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
SECTION: cluck_client_error: LOCK times out locally
2025/07/13 05:55:17 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "cluck::lock-name", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382681]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382681. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382681]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382681 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:17 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCK times out locally}
--- message: cluckd/LOCK duration=3;object_name=lock-name;pid=382293;serial=12;tag=10014;timeout=1752386120.132871122;transmission_report=failure;unlock_duration=3
2025/07/13 05:55:27 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:27 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:27 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:27 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:27 unittester [382293/382293]: trace: removing 1 connection, "cluck::lock-name", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCK times out locally}
2025/07/13 05:55:27 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: LOCK times out locally}
SECTION: cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)
2025/07/13 05:55:27 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: trace: added 1 connection, "cluck::lock-name", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382684]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382684. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382684]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382684 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:27 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
--- message: cluckd/LOCK duration=3;object_name=lock-name;pid=382293;serial=13;tag=10015;timeout=1752386130.163999921;transmission_report=failure;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/07/13 05:55:27 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10015;timeout_date=1752386130.176126993;unlocked_date=1752386133.176126993". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
--- message: tester/READ size=219
--- message: not_lock
--- message: drain DATA
--- message: sleep 5s
2025/07/13 05:55:27 unittester [382293/382293]: trace: dispatch message "<my_server:tester other_server:cluck_test/DATA data=the-data (the size received is the max.--the size returned is this buffer's size);size=81;unlock=false". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=382293;serial=13;tag=10015
--- message: not_lock
--- message: not_read
--- message: drain UNLOCKED
2025/07/13 05:55:32 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: removing 1 connection, "cluck::lock-name", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKED object_name=lock-name;tag=10015". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/07/13 05:55:32 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
SECTION: cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)
2025/07/13 05:55:32 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: added 1 connection, "cluck::lock-name", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382727]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382727. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382727]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382727 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:32 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
--- message: cluckd/LOCK duration=3600;object_name=lock-name;pid=382293;serial=14;tag=10016;timeout=1752386135.588075298;transmission_report=failure;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/07/13 05:55:32 unittester [382293/382293]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10016;timeout_date=1752389732.600128545;unlocked_date=1752389735.600128545". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
--- message: tester/READ size=219
2025/07/13 05:55:32 unittester [382293/382293]: trace: dispatch message "<my_server:tester other_server:cluck_test/DATA data=the-data (the size received is the max.--the size returned is this buffer's size);size=81". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=382293;serial=14;tag=10016
2025/07/13 05:55:52 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:52 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "cluck::lock-name", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/07/13 05:55:53 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
SECTION: cluck_client_error: LOCKED with invalid tag
2025/07/13 05:55:53 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: added 1 connection, "thread_done", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: added 1 connection, "test_messenger", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: added 1 connection, "test_timer", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_READY". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: configuration: add_match() with command expression "NO_LOCK". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: added 1 connection, "cluck::lock-name", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382772]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382772. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382772]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382772 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:323) {cluck_client_error: LOCKED with invalid tag}
--- message: cluckd/LOCK object_name=lock-name;pid=382293;serial=15;tag=10017;timeout=1752389753.019419495;transmission_report=failure
--- message: --- test_case = [invalid_tag]....
--- message: --- test_case = EQUAL?! invalid tag....
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "thread_done", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "test_messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "test_timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: removing 1 connection, "cluck::lock-name", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/NO_LOCK cache=no". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/LOCKED object_name=lock-name;tag=bad_tag;timeout_date=1752386153.02920792;unlocked_date=1752386153.02920792". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_client_error: LOCKED with invalid tag}
2025/07/13 05:55:53 unittester [382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_client_error: LOCKED with invalid tag}
SECTION: cluck_daemon_one_computer: verify cluckd
2025/07/13 05:55:53 unittester [382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: information: -------------------------------------------------- (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:764) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: information: cluckd v1.0.0 started. (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:770) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: added 1 connection, "interrupt", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: added 1 connection, "timer", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: added 1 connection, "messenger", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: added 1 connection, "communicator_tcp_stream", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: added 1 connection, "thread_done", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: information: --------------------------------- cluckd started. (in function "void cluck_daemon::cluckd::run()()") (cluckd.cpp:423) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 6 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_one_computer: verify cluckd}
--- message: --- expect REGISTER ---
2025/07/13 05:55:53 unittester cluckd[382293/382814]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382814. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382814]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382814 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 6 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 6 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_one_computer: verify cluckd}
--- message: REGISTER service=cluckd;version=1
--- message: --- expect COMMANDS ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "HELP". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: configuration warning: command "STATUS" was already inserted. Is it a "match_t::MATCH_CALLBACK"? If so then it is fine. If not, some of your callback functions may not get called. (in function "bool ed::dispatcher::get_commands(advgetopt::string_set_t&)()") (dispatcher.cpp:588) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: configuration warning: command "INVALID" was already inserted. Is it a "match_t::MATCH_CALLBACK"? If so then it is fine. If not, some of your callback functions may not get called. (in function "bool ed::dispatcher::get_commands(advgetopt::string_set_t&)()") (dispatcher.cpp:588) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: configuration warning: command "UNKNOWN" was already inserted. Is it a "match_t::MATCH_CALLBACK"? If so then it is fine. If not, some of your callback functions may not get called. (in function "bool ed::dispatcher::get_commands(advgetopt::string_set_t&)()") (dispatcher.cpp:588) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "READY my_address=127.0.0.1". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd COMMANDS list=ABSOLUTELY,ACTIVATE_LOCK,ADD_TICKET,ALIVE,CLUSTER_DOWN,CLUSTER_UP,DISCONNECTED,DROP_TICKET,FLUID_SETTINGS_DEFAULT_VALUE,FLUID_SETTINGS_DELETED,FLUID_SETTINGS_OPTIONS,FLUID_SETTINGS_READY,FLUID_SETTINGS_REGISTERED,FLUID_SETTINGS_UPDATED,FLUID_SETTINGS_VALUE,FLUID_SETTINGS_VALUE_UPDATED,GET_MAX_TICKET,HANGUP,HELP,INFO,INVALID,LEAK,LIST_TICKETS,LOCK,LOCK_ACTIVATED,LOCK_ENTERED,LOCK_ENTERING,LOCK_EXITING,LOCK_FAILED,LOCK_LEADERS,LOCK_STARTED,LOCK_STATUS,LOCK_TICKETS,LOG_ROTATE,MAX_TICKET,QUITTING,READY,RESTART,SERVICE_UNAVAILABLE,STATUS,STOP,TICKET_ADDED,TICKET_READY,UNKNOWN,UNLOCK
--- message: --- expect SERVICE_STATUS ---
--- message: <:cluckd SERVICE_STATUS service=fluid_settings
--- message: --- expect FLUID_SETTINGS_LISTEN ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "STATUS cache=no;server=unittester;service=fluid_settings;status=up;up_since=1752386153.530296905". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=15;object_name=early_lock;pid=10203;tag=15045;timeout=1752386213.530957329". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:491) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: caching LOCK message for "early_lock" (15045) as the cluck system is not yet considered ready. (in function "void cluck_daemon::cluckd::msg_lock(ed::message&)()") (cluckd.cpp:2838) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "UNLOCK object_name=too_soon;pid=431;tag=11104". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:491) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: error: received an UNLOCK when cluckd is not ready to receive lock related messages. (in function "void cluck_daemon::cluckd::msg_unlock(ed::message&)()") (cluckd.cpp:4206) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd fluid_settings/FLUID_SETTINGS_LISTEN cache="no;reply";names=cluckd::server-name
--- message: --- expect CLUSTER_STATUS ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "unittester:cluckd/FLUID_SETTINGS_REGISTERED". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "unittester:cluckd/FLUID_SETTINGS_VALUE_UPDATED message=current value;name=cluckd::server-name;value=this_very_server". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "unittester:cluckd/FLUID_SETTINGS_READY errcnt=31". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK_STATUS". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:491) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd communicatord/CLUSTER_STATUS
--- message: --- wait for message NO_LOCK ---
--- message: <:cluckd unittester:website/NO_LOCK cache=no
--- message: --- wait for message LOCK_LEADERS ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "unittester:cluckd/CLUSTER_UP neighbors_count=1". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: information: cluster is up with 1 neighbors, attempt an election then check for leaders by sending a LOCK_STARTED message. (in function "void cluck_daemon::cluckd::msg_cluster_up(ed::message&)()") (cluckd.cpp:2518) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: warning: election status = add leader(s)... 1 computers and 1 leaders. (in function "void cluck_daemon::cluckd::election_status()()") (cluckd.cpp:1162) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: Attempting to lock "early_lock" (15045) on "unittester/10203" for "unittester/website" (timeout: 1752386213.530957329). (in function "cluck_daemon::ticket::ticket(cluck_daemon::cluckd*, cluck_daemon::messenger::pointer_t, const std::string&, ed::dispatcher_match::tag_t, const std::string&, cluck::timeout_t, cluck::timeout_t, const std::string&, const std::string&)()") (ticket.cpp:383) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=info". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd */LOCK_LEADERS election_date=1752386153.570834825;leader0=14|3349617668|127.0.0.1|382293|unittester
--- message: --- wait for message LOCK_STARTED ---
--- message: <:cluckd */LOCK_STARTED election_date=1752386153.570834825;leader0=14|3349617668|127.0.0.1|382293|unittester;lock_id=14|3349617668|127.0.0.1|382293|unittester;server_name=unittester;start_time=1752386153.467899038
--- message: --- wait for message LOCK_READY ---
--- message: <:cluckd ./LOCK_READY cache=no
--- message: --- expect LOCKED (early) ---
--- message: <:cluckd unittester:website/LOCKED object_name=early_lock;tag=15045;timeout_date=1752386168.573513364;unlocked_date=1752386183.573513364
--- message: --- wait for message CLUCKD_STATUS (without tickets)....
--- message: <:cluckd unittester:website/CLUCKD_STATUS status={"computers":[{"connected":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":1,"neighbors_count":1,"neighbors_quorum":1}
--- message: --- verify message CLUCKD_STATUS: {"computers":[{"connected":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":1,"neighbors_count":1,"neighbors_quorum":1}
--- message: --- wait for message LOCK_FAILED ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1000". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: warning: lock on "lock1" (505)/ "123" timed out before we could start the locking process. (in function "void cluck_daemon::cluckd::msg_lock(ed::message&)()") (cluckd.cpp:2753) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "UNLOCK object_name=bad_pid;pid=-431;tag=11104". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: noisy error: cluckd::get_parameters(): invalid pid specified for a lock (-431); it must be a positive decimal number. (in function "bool cluck_daemon::cluckd::get_parameters(const ed::message&, std::string*, ed::dispatcher_match::tag_t*, pid_t*, cluck::timeout_t*, std::string*, std::string*)()") (cluckd.cpp:2045) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/UNLOCK object_name=early_lock;pid=10203;tag=15045". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: Unlock on "early_lock" (15045) with key "unittester/10203". (in function "void cluck_daemon::ticket::drop_ticket()()") (ticket.cpp:814) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd unittester:website/LOCK_FAILED description=LOCK timeout date is already in the past;error=timedout;key=unittester/123;object_name=lock1;tag=505
--- message: --- expect UNLOCKED (early) ---
--- message: <:cluckd unittester:website/UNLOCKED object_name=early_lock;tag=15045;unlocked_date=1752386153.603505063
--- message: --- expect LOCKED ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=-9123;tag=505;timeout=1752386213.60590374". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: noisy error: cluckd::get_parameters(): invalid pid specified for a lock (-9123); it must be a positive decimal number. (in function "bool cluck_daemon::cluckd::get_parameters(const ed::message&, std::string*, ed::dispatcher_match::tag_t*, pid_t*, cluck::timeout_t*, std::string*, std::string*)()") (cluckd.cpp:2045) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1752386213.60590374". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: Attempting to lock "lock1" (505) on "unittester/123" for "unittester/website" (timeout: 1752386213.60590374). (in function "cluck_daemon::ticket::ticket(cluck_daemon::cluckd*, cluck_daemon::messenger::pointer_t, const std::string&, ed::dispatcher_match::tag_t, const std::string&, cluck::timeout_t, cluck::timeout_t, const std::string&, const std::string&)()") (ticket.cpp:383) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1752386213.60590374". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: error: a ticket has the same object name "lock1" (505) and entering key "unittester/123". (in function "void cluck_daemon::cluckd::msg_lock(ed::message&)()") (cluckd.cpp:2968) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd unittester:website/LOCKED object_name=lock1;tag=505;timeout_date=1752386163.614360331;unlocked_date=1752386173.614360331
--- message: send LOCK_LEADERS
--- message: --- wait for message LOCK_FAILED (duplicate) ---
--- message: <:cluckd unittester:website/LOCK_FAILED description=LOCK called with the same ticket object_name and entering_key;error=duplicate;key=unittester/123;object_name=lock1;tag=505
--- message: --- wait for message LOCK_FAILED (duplicate ADD_TICKET) ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<rc1:cluckd unittester:cluckd/LOCK_LEADERS election_date=1752386153.570834825;leader0=14|3349617668|127.0.0.1|382293|unittester". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<rc1:cluckd unittester:cluckd/ADD_TICKET key=00000001/unittester/123;object_name=lock1;tag=505;timeout=1752386213.60590374". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: error: an existing ticket has the same object name "lock1" (505) and key "00000001/unittester/123". (in function "void cluck_daemon::cluckd::msg_add_ticket(ed::message&)()") (cluckd.cpp:2265) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: error: could not create the flags directory "/var/lib/communicatord/flags"; errno: 13, Permission denied (in function "std::string communicatord::{anonymous}::get_path_to_flag_files()()") (flags.cpp:156) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: error: flag output filename could not be generated -- 1969/12/31 23:59:59: flag(cluckd/ticket/invalid-algorithm):/home/alexis/snapwebsites/snapcpp/contrib/cluck/daemon/cluckd.cpp:void cluck_daemon::cluckd::msg_add_ticket(ed::message&):2287: msg_add_ticket() received a second call to add the same ticket. This either means there is a bug in our algorithm or there is a hacker sending us messages trying to create invalid tickets. (priority: 25, manual-down, count: 1) (in function "bool communicatord::flag::save()()") (flags.cpp:995) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=debug". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd rc1:cluckd/LOCK_FAILED description=ADD_TICKET called with an existing ticket name and key;error=duplicate;key=00000001/unittester/123;object_name=lock1;tag=505
--- message: --- wait for message CLUCKD_STATUS (with ticket)....
--- message: <:cluckd unittester:website/CLUCKD_STATUS status={"computers":[{"connected":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":1,"neighbors_count":1,"neighbors_quorum":1,"tickets":"added_ticket=true|added_ticket_quorum=true|entering_key=unittester/123|get_max_ticket=true|lock_duration=10|lock_failed=none|lock_timeout_date=1752386163.614360331|locked=true|object_name=lock1|obtention_timeout=1752386213.60590374|our_ticket=1|owner=unittester|serial=2|server_name=unittester|service_name=website|tag=505|ticket_key=00000001/unittester/123|ticket_ready=true|unlock_duration=10\\n"}
--- message: --- verify message CLUCKD_STATUS: {"computers":[{"connected":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|3349617668|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":1,"neighbors_count":1,"neighbors_quorum":1,"tickets":"added_ticket=true|added_ticket_quorum=true|entering_key=unittester/123|get_max_ticket=true|lock_duration=10|lock_failed=none|lock_timeout_date=1752386163.614360331|locked=true|object_name=lock1|obtention_timeout=1752386213.60590374|our_ticket=1|owner=unittester|serial=2|server_name=unittester|service_name=website|tag=505|ticket_key=00000001/unittester/123|ticket_ready=true|unlock_duration=10\n"}
--- message: --- timeout: 1752386213.605903740
--- message: --- expect UNLOCKING ---
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<another_server:website unittester:cluckd/UNLOCK object_name=lock1;pid=435;tag=11104". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: major: UNLOCK could not find key "another_server/435" in object "lock1". (in function "void cluck_daemon::cluckd::msg_unlock(ed::message&)()") (cluckd.cpp:4275) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/UNLOCK object_name=unknown_lock;pid=435;tag=11104". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:55:53 unittester cluckd[382293/382293]: warning: UNLOCK could not find object "unknown_lock". (in function "void cluck_daemon::cluckd::msg_unlock(ed::message&)()") (cluckd.cpp:4286) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:04 unittester cluckd[382293/382293]: important: Lock on "lock1" (505) with key "unittester/123" timed out its lock allowed time. (in function "void cluck_daemon::ticket::lock_failed(const std::string&)()") (ticket.cpp:975) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd unittester:website/UNLOCKING error=timedout;object_name=lock1;tag=505
--- message: --- expect UNLOCKED ---
2025/07/13 05:56:14 unittester cluckd[382293/382293]: important: Lock on "lock1" (505) with key "unittester/123" timed out its unlocking allowed time. (in function "void cluck_daemon::ticket::lock_failed(const std::string&)()") (ticket.cpp:1008) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd unittester:website/UNLOCKED error=timedout;object_name=lock1;tag=505
--- message: --- Sending QUITTING and draining messages...
--- message: --- Wait while draining messages...
--- message: --- Script is done...
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/QUITTING". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "communicator_tcp_stream", of 6 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "interrupt", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "thread_done", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_one_computer: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:67) {cluck_daemon_one_computer: verify cluckd}
SECTION: cluck_daemon_two_computers: verify cluckd
2025/07/13 05:56:14 unittester cluckd[382293/382293]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:54) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: information: -------------------------------------------------- (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:764) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: information: cluckd v1.0.0 started. (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:770) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: added 1 connection, "interrupt", there is now 1 connection (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: added 1 connection, "timer", there are now 2 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: added 1 connection, "messenger", there are now 3 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: added 1 connection, "communicator_tcp_stream", there are now 4 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: added 1 connection, "thread_done", there are now 5 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: information: --------------------------------- cluckd started. (in function "void cluck_daemon::cluckd::run()()") (cluckd.cpp:423) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", there are now 6 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_two_computers: verify cluckd}
--- message: --- expect REGISTER ---
2025/07/13 05:56:14 unittester cluckd[382293/382816]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #382816. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382816]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #382816 with status 0. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::thread_signal_handler", of 6 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: added 1 connection, "tcp_client_permanent_message_connection_impl::messenger", there are now 6 connections (including this one). (in function "bool ed::communicator::add_connection(ed::connection::pointer_t)()") (communicator.cpp:228) {cluck_daemon_two_computers: verify cluckd}
--- message: REGISTER service=cluckd;version=1
--- message: --- expect COMMANDS ---
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "HELP". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: configuration warning: command "STATUS" was already inserted. Is it a "match_t::MATCH_CALLBACK"? If so then it is fine. If not, some of your callback functions may not get called. (in function "bool ed::dispatcher::get_commands(advgetopt::string_set_t&)()") (dispatcher.cpp:588) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: configuration warning: command "INVALID" was already inserted. Is it a "match_t::MATCH_CALLBACK"? If so then it is fine. If not, some of your callback functions may not get called. (in function "bool ed::dispatcher::get_commands(advgetopt::string_set_t&)()") (dispatcher.cpp:588) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: configuration warning: command "UNKNOWN" was already inserted. Is it a "match_t::MATCH_CALLBACK"? If so then it is fine. If not, some of your callback functions may not get called. (in function "bool ed::dispatcher::get_commands(advgetopt::string_set_t&)()") (dispatcher.cpp:588) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "READY my_address=127.0.0.1". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd COMMANDS list=ABSOLUTELY,ACTIVATE_LOCK,ADD_TICKET,ALIVE,CLUSTER_DOWN,CLUSTER_UP,DISCONNECTED,DROP_TICKET,FLUID_SETTINGS_DEFAULT_VALUE,FLUID_SETTINGS_DELETED,FLUID_SETTINGS_OPTIONS,FLUID_SETTINGS_READY,FLUID_SETTINGS_REGISTERED,FLUID_SETTINGS_UPDATED,FLUID_SETTINGS_VALUE,FLUID_SETTINGS_VALUE_UPDATED,GET_MAX_TICKET,HANGUP,HELP,INFO,INVALID,LEAK,LIST_TICKETS,LOCK,LOCK_ACTIVATED,LOCK_ENTERED,LOCK_ENTERING,LOCK_EXITING,LOCK_FAILED,LOCK_LEADERS,LOCK_STARTED,LOCK_STATUS,LOCK_TICKETS,LOG_ROTATE,MAX_TICKET,QUITTING,READY,RESTART,SERVICE_UNAVAILABLE,STATUS,STOP,TICKET_ADDED,TICKET_READY,UNKNOWN,UNLOCK
--- message: --- expect SERVICE_STATUS ---
--- message: <:cluckd SERVICE_STATUS service=fluid_settings
--- message: --- service name in STATUS message is: fluid_settings
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "STATUS cache=no;server=two;server_name=two;service=remote communicator (in);status=up;up_since=1752386174.706753401". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "STATUS cache=no;server=unittester;service=fluid_settings;status=up;up_since=1752386174.708246153". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK_STATUS". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:491) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd communicatord/CLUSTER_STATUS
--- message: --- wait for message NO_LOCK....
--- message: <:cluckd unittester:website/NO_LOCK cache=no
--- message: --- wait for message LOCK_STARTED (initial)....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "unittester:cluckd/CLUSTER_UP neighbors_count=2". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: information: cluster is up with 2 neighbors, attempt an election then check for leaders by sending a LOCK_STARTED message. (in function "void cluck_daemon::cluckd::msg_cluster_up(ed::message&)()") (cluckd.cpp:2518) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:491) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=info". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:491) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd */LOCK_STARTED lock_id=10|2704737503|127.0.0.1|382293|unittester;server_name=unittester;start_time=1752386174.641896881
--- message: --- verify message LOCK_STARTED (initial)....
--- message: --- wait for message CLUCKD_STATUS (without tickets)....
--- message: <:cluckd unittester:website/CLUCKD_STATUS status={"computers":[{"connected":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","name":"unittester"}],"daemon_ready":false,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":0,"neighbors_count":2,"neighbors_quorum":2}
--- message: --- verify message CLUCKD_STATUS: {"computers":[{"connected":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","name":"unittester"}],"daemon_ready":false,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":0,"neighbors_count":2,"neighbors_quorum":2}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<two:cluckd unittester:cluckd/LOCK_STARTED lock_id=ignored;server_name=unittester;start_time=1752386174.727999386". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<two:cluckd unittester:cluckd/LOCK_STARTED lock_id=invalid;server_name=two;start_time=1752386174.728756253". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: error: received a computer id which does not have exactly 5 parts: "invalid". (in function "bool cluck_daemon::computer::set_id(const std::string&)()") (computer.cpp:167) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<two:cluckd unittester:cluckd/LOCK_STARTED lock_id=14|558050946|172.1.2.3|2343681|two;server_name=two;start_time=1752386174.729392077". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: warning: election status = add leader(s)... 2 computers and 2 leaders. (in function "void cluck_daemon::cluckd::election_status()()") (cluckd.cpp:1162) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd */LOCK_LEADERS election_date=1752386174.737905958;leader0=10|2704737503|127.0.0.1|382293|unittester;leader1=14|558050946|172.1.2.3|2343681|two
--- message: --- wait for message LOCK_READY....
--- message: <:cluckd ./LOCK_READY cache=no
--- message: --- wait for message LOCK_STARTED (reply_a)....
--- message: <:cluckd two:cluckd/LOCK_STARTED election_date=1752386174.737905958;leader0=10|2704737503|127.0.0.1|382293|unittester;leader1=14|558050946|172.1.2.3|2343681|two;lock_id=10|2704737503|127.0.0.1|382293|unittester;server_name=unittester;start_time=1752386174.641896881
--- message: --- wait for message LOCK_FAILED (timedout)....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1000". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: warning: lock on "lock1" (505)/ "123" timed out before we could start the locking process. (in function "void cluck_daemon::cluckd::msg_lock(ed::message&)()") (cluckd.cpp:2753) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd unittester:website/LOCK_FAILED description=LOCK timeout date is already in the past;error=timedout;key=unittester/123;object_name=lock1;tag=505
--- message: --- wait for message LOCK_ENTERING....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1752386234.754724849". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: Attempting to lock "lock1" (505) on "unittester/123" for "unittester/website" (timeout: 1752386234.754724849). (in function "cluck_daemon::ticket::ticket(cluck_daemon::cluckd*, cluck_daemon::messenger::pointer_t, const std::string&, ed::dispatcher_match::tag_t, const std::string&, cluck::timeout_t, cluck::timeout_t, const std::string&, const std::string&)()") (ticket.cpp:383) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1752386234.754724849". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: error: an entering ticket has the same object name "lock1" (505) and entering key "unittester/123". (in function "void cluck_daemon::cluckd::msg_lock(ed::message&)()") (cluckd.cpp:2894) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;serial=1;tag=505;timeout=1752386234.754724849". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd two:cluckd/LOCK_ENTERING duration=10;key=unittester/123;object_name=lock1;serial=1;source=unittester/website;tag=505;timeout=1752386234.754724849
--- message: --- wait for message LOCK_FAILED (duplicate)....
--- message: <:cluckd unittester:website/LOCK_FAILED description=LOCK called with the same entering ticket object_name and entering_key;error=duplicate;key=unittester/123;object_name=lock1;tag=505
--- message: --- wait for message GET_MAX_TICKET....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:cluckd unittester:cluckd/LOCK_ENTERED key=unittester/123;object_name=lock1;tag=505". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd two:cluckd/GET_MAX_TICKET key=unittester/123;object_name=lock1;tag=505
--- message: --- wait for message ADD_TICKET....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<two:cluckd unittester:cluckd/MAX_TICKET key=unittester/123;object_name=lock1;tag=505;ticket_id=111". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd two:cluckd/ADD_TICKET key=00000070/unittester/123;object_name=lock1;tag=505;timeout=1752386234.754724849
--- message: --- wait for message LOCK_EXITING....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<two:cluckd unittester:cluckd/TICKET_ADDED key=00000070/unittester/123;object_name=lock1;tag=505". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd two:cluckd/LOCK_EXITING key=unittester/123;object_name=lock1;tag=505
--- message: --- wait for message TICKET_READY....
--- message: <:cluckd two:cluckd/TICKET_READY key=00000070/unittester/123;object_name=lock1;tag=505
--- message: --- wait for message ACTIVATE_LOCK....
--- message: <:cluckd two:cluckd/ACTIVATE_LOCK key=00000070/unittester/123;object_name=lock1;tag=505
--- message: --- wait for message LOCKED....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<two:cluckd unittester:cluckd/TICKET_READY key=00000070/unittester/123;object_name=lock1;tag=505". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<two:cluckd unittester:cluckd/ACTIVATE_LOCK key=00000070/unittester/123;object_name=lock1;tag=505". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd unittester:website/LOCKED object_name=lock1;tag=505;timeout_date=1752386184.79757701;unlocked_date=1752386194.79757701
--- message: --- wait for message LOCK_ACTIVATED....
--- message: <:cluckd two:cluckd/LOCK_ACTIVATED key=00000070/unittester/123;object_name=lock1;other_key=00000070/unittester/123;tag=505
--- message: --- wait for message CLUCKD_STATUS (with ticket)....
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=debug". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:440) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd unittester:website/CLUCKD_STATUS status={"computers":[{"connected":true,"id":"14|558050946|172.1.2.3|2343681|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":2,"neighbors_count":2,"neighbors_quorum":2,"tickets":"added_ticket=true|added_ticket_quorum=true|entering_key=unittester/123|get_max_ticket=true|lock_duration=10|lock_failed=none|lock_timeout_date=1752386184.79757701|locked=true|object_name=lock1|obtention_timeout=1752386234.754724849|our_ticket=112|owner=unittester|serial=1|server_name=unittester|service_name=website|tag=505|ticket_key=00000070/unittester/123|ticket_ready=true|unlock_duration=10\\n"}
--- message: --- verify message CLUCKD_STATUS: {"computers":[{"connected":true,"id":"14|558050946|172.1.2.3|2343681|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":2,"neighbors_count":2,"neighbors_quorum":2,"tickets":"added_ticket=true|added_ticket_quorum=true|entering_key=unittester/123|get_max_ticket=true|lock_duration=10|lock_failed=none|lock_timeout_date=1752386184.79757701|locked=true|object_name=lock1|obtention_timeout=1752386234.754724849|our_ticket=112|owner=unittester|serial=1|server_name=unittester|service_name=website|tag=505|ticket_key=00000070/unittester/123|ticket_ready=true|unlock_duration=10\n"}
2025/07/13 05:56:14 unittester cluckd[382293/382815]: fatal: thread internal_run() got exception: "event_dispatcher_exception: /home/alexis/snapwebsites/snapcpp/contrib/cluck/tests/rprtr/cluck_daemon_test_two_computers.rprtr:753: message expected parameter "status", set to "{"computers":[{"connected":true,"id":"14|558050946|172.1.2.3|2343681|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":2,"neighbors_count":2,"neighbors_quorum":2,"tickets":"added_ticket=true|added_ticket_quorum=true|entering_key=unittester/123|get_max_ticket=true|lock_duration=10|lock_failed=none|lock_timeout_date=1752386184.79757701|locked=true|object_name=lock1|obtention_timeout=1752386234.754724849|our_ticket=112|owner=unittester|serial=1|server_name=unittester|service_name=website|tag=505|ticket_key=00000070/unittester/123|ticket_ready=true|unlock_duration=10\ (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "thread_done", of 6 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "communicator_tcp_stream", of 5 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "tcp_client_permanent_message_connection_impl::messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382815]: fatal: thread internal_leave() got exception: "event_dispatcher_exception: thread failed with leave status: 2", exiting thread now. (in function "void snaplogger::{anonymous}::getopt_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:87) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "interrupt", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_two_computers: verify cluckd}
2025/07/13 05:56:14 unittester cluckd[382293/382293]: trace: removing 1 connection, "timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:261) {cluck_daemon_two_computers: verify cluckd}

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
unittest is a Catch2 v3.8.0 host application.
Run with -? for options

-------------------------------------------------------------------------------
cluck_daemon_two_computers
  cluck_daemon_two_computers: verify cluckd
-------------------------------------------------------------------------------
/home/alexis/snapwebsites/snapcpp/contrib/cluck/tests/catch_daemon.cpp:179
...............................................................................

/home/alexis/snapwebsites/snapcpp/contrib/cluck/tests/catch_daemon.cpp:269: FAILED:
  CATCH_REQUIRE( s->get_exit_code() == 0 )
with expansion:
  -1 == 0

terminate called after throwing an instance of 'ed::runtime_error'
  what():  event_dispatcher_exception: /home/alexis/snapwebsites/snapcpp/contrib/cluck/tests/rprtr/cluck_daemon_test_two_computers.rprtr:753: message expected parameter "status", set to "{"computers":[{"connected":true,"id":"14|558050946|172.1.2.3|2343681|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|2704737503|127.0.0.1|382293|unittester","ip":"127.0.0.1","leaders_count":2,"neighbors_count":2,"neighbors_quorum":2,"tickets":"added_ticket=true|added_ticket_quorum=true|entering_key=unittester/123|get_max_ticket=true|lock_duration=10|lock_failed=none|lock_timeout_date=1752386184.79757701|locked=true|object_name=lock1|obtention_timeout=1752386234.754724849|our_ticket=112|owner=unittester|serial=1|server_name=unittester|service_name=website|tag=505|ticket_key=00000070/unittester/123|ticket_ready=true|unlock_duration=10\n"}", to match regex "\{"computers":\[\{"connected":true,"id":"10\|2704737503\|127\.0\.0\.1\|382293\|unittester","ip":"127\.0\.0\.1","leader":0,"name":"unittester"\},\{"connected":true,"id":"14\|558050946\|172\.1\.2\.3\|2343681\|two","ip":"172\.1\.2\.3","leader":1,"name":"two"\}\],"daemon_ready":true,"id":"10\|2704737503\|127\.0\.0\.1\|382293\|unittester","ip":"127\.0\.0\.1","leaders_count":2,"neighbors_count":2,"neighbors_quorum":2,"tickets":"added_ticket=true\|added_ticket_quorum=true\|entering_key=monster\/123\|get_max_ticket=true\|lock_duration=10\|lock_failed=none\|lock_timeout_date=[0-9]+(\.[0-9]+)?\|locked=true\|object_name=lock1\|obtention_timeout=[0-9]+(\.[0-9]+)?\|our_ticket=112\|owner=monster\|serial=1\|server_name=monster\|service_name=website\|tag=505\|ticket_key=00000070\/monster\/123\|ticket_ready=true\|unlock_duration=10\\n"\}".
Command terminated by signal 6
3.75user 2.33system 1:12.13elapsed 8%CPU (0avgtext+0avgdata 31336maxresident)k
0inputs+344outputs (0major+141248minor)pagefaults 0swaps
info: test logs were saved in: "../../BUILD/Debug/contrib/cluck/tests/tests.log".
error: test(s) failed.

Build, Tests/Coverage, or Upload failed.

Back to list