Back to list

info: starting build process on Thu Oct 30 05:34:32 AM UTC 2025
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.1 [745859]:unittest: seed is 1761802473
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: 3196459698
SECTION: cluck_client: verify timeouts
2025/10/30 05:34:33 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: verify timeouts}
2025/10/30 05:34:33 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: verify timeouts}
SECTION: cluck_client: successful LOCK (simple)
2025/10/30 05:34:33 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_READY". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: configuration: add_match() with command expression "NO_LOCK". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745865]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #745865. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745865]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #745865 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (simple)}
--- message: cluckd/LOCK object_name=lock-name;pid=745859;serial=1;tag=10001;timeout=1761802551.746402017;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/10/30 05:34:33 unittester [745859/745859]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/LOCK_READY cache=no". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:33 unittester [745859/745859]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/LOCKED object_name=lock-name;tag=10001;timeout_date=1761802478.965557416;unlocked_date=1761802483.965557416". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: successful LOCK (simple)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=745859;serial=1;tag=10001
2025/10/30 05:34:34 unittester [745859/745859]: 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:438) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (simple)}
2025/10/30 05:34:34 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: successful LOCK (simple)}
SECTION: cluck_client: successful LOCK (extended)
2025/10/30 05:34:34 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: notice: this cluck object is not currently locked. (in function "void cluck::cluck::unlock()()") (cluck.cpp:996) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745907]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #745907. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745907]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #745907 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: successful LOCK (extended)}
--- message: cluckd/LOCK duration=3600;object_name=lock-name;pid=745859;serial=2;tag=10002;timeout=1761802484.891258745;transmission_report=failure;type=1;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/10/30 05:34:34 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10002;timeout_date=1761806074.403429292;unlocked_date=1761806077.403429292". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: successful LOCK (extended)}
--- message: tester/READ size=219
2025/10/30 05:34:34 unittester [745859/745859]: 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:438) {cluck_client: successful LOCK (extended)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=745859;serial=2;tag=10002
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:438) {cluck_client: successful LOCK (extended)}
2025/10/30 05:34:34 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: successful LOCK (extended)}
SECTION: cluck_client: failing UNLOCKED (invalid object name)
2025/10/30 05:34:34 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745949]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #745949. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745949]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #745949 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:260) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: 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:227) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:34 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: failing UNLOCKED (invalid object name)}
--- message: cluckd/LOCK object_name=lock-name;pid=745859;serial=3;tag=10003;timeout=1761802552.608407031;transmission_report=failure
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/10/30 05:34:34 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10003;timeout_date=1761802479.82782142;unlocked_date=1761802484.82782142". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: failing UNLOCKED (invalid object name)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=745859;serial=3;tag=10003
2025/10/30 05:34:35 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/UNLOCKED object_name=invalid-name;tag=10003;timeout_date=1761802479.82782142;unlocked_date=1761802484.82782142". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {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/10/30 05:34:35 unittester [745859/745859]: 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:260) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:35 unittester [745859/745859]: 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:260) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:35 unittester [745859/745859]: 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:260) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:35 unittester [745859/745859]: 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:260) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:35 unittester [745859/745859]: 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:260) {cluck_client: failing UNLOCKED (invalid object name)}
2025/10/30 05:34:35 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: failing UNLOCKED (invalid object name)}
SECTION: cluck_client: UNLOCKING--cluckd server safe timeout
2025/10/30 05:34:35 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745991]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #745991. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745991]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #745991 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:35 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server safe timeout}
--- message: cluckd/LOCK object_name=unlocking-lock;pid=745859;serial=4;tag=10004;timeout=1761802478.247089868;transmission_report=failure
2025/10/30 05:34:35 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=unlocking-lock;tag=10004;timeout_date=1761802476.256073979;unlocked_date=1761802481.256073979". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:438) {cluck_client: UNLOCKING--cluckd server safe timeout}
--- message: cluckd/UNLOCK object_name=unlocking-lock;pid=745859;serial=4;tag=10004
2025/10/30 05:34:37 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:438) {cluck_client: UNLOCKING--cluckd server safe timeout}
2025/10/30 05:34:37 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: UNLOCKING--cluckd server safe timeout}
SECTION: cluck_client: UNLOCKING--cluckd server late timeout
2025/10/30 05:34:37 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/746033]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746033. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/746033]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746033 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:37 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--cluckd server late timeout}
--- message: cluckd/LOCK duration=3;object_name=unlocking-lock;pid=745859;serial=5;tag=10005;timeout=1761802480.282716864;transmission_report=failure;unlock_duration=3
2025/10/30 05:34:37 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=unlocking-lock;tag=10005;timeout_date=1761802480.295069709;unlocked_date=1761802483.295069709". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:44 unittester [745859/745859]: 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:438) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:44 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:44 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:44 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:44 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--cluckd server late timeout}
2025/10/30 05:34:44 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: UNLOCKING--cluckd server late timeout}
SECTION: cluck_client: UNLOCKING--invalid object name
2025/10/30 05:34:44 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/746075]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746075. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/746075]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746075 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: 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:227) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:44 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: UNLOCKING--invalid object name}
--- message: cluckd/LOCK duration=3;object_name=unlocking-lock;pid=745859;serial=6;tag=10006;timeout=1761802487.323178854;transmission_report=failure;unlock_duration=3
2025/10/30 05:34:44 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=unlocking-lock;tag=10006;timeout_date=1761802485.332264527;unlocked_date=1761802490.332264527". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:46 unittester [745859/745859]: 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:438) {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/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: UNLOCKING--invalid object name}
2025/10/30 05:34:46 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: UNLOCKING--invalid object name}
SECTION: cluck_client: spurious TRANSMISSION_REPORT
2025/10/30 05:34:46 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/746117]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746117. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/746117]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746117 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: spurious TRANSMISSION_REPORT}
--- message: cluckd/LOCK duration=3600;object_name=lock-name;pid=745859;serial=7;tag=10007;timeout=1761802496.859759573;transmission_report=failure;type=1;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/10/30 05:34:46 unittester [745859/745859]: 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:438) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10007;timeout_date=1761806086.372148665;unlocked_date=1761806089.372148665". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client: spurious TRANSMISSION_REPORT}
--- message: tester/READ size=219
2025/10/30 05:34:46 unittester [745859/745859]: 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:438) {cluck_client: spurious TRANSMISSION_REPORT}
--- message: cluckd/UNLOCK object_name=lock-name;pid=745859;serial=7;tag=10007
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: 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:438) {cluck_client: spurious TRANSMISSION_REPORT}
2025/10/30 05:34:46 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: spurious TRANSMISSION_REPORT}
SECTION: cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon
2025/10/30 05:34:46 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/746159]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746159. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/746159]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746159 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:227) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
--- message: cluckd/LOCK duration=3;object_name=lock-not-received;pid=745859;serial=8;tag=10008;timeout=1761802489.793488117;transmission_report=failure;unlock_duration=3
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:260) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:438) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: 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:1453) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
2025/10/30 05:34:46 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client: TRANSMISSION_REPORT--could not send message to a cluck daemon}
SECTION: cluck_client_error: messenger required
2025/10/30 05:34:46 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: messenger required}
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: messenger required}
SECTION: cluck_client_error: dispatcher required
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: dispatcher required}
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: dispatcher required}
SECTION: cluck_client_error: LOCK_FAILED--pretend the LOCK times out
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/746241]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746241. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/746241]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746241 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
--- message: cluckd/LOCK duration=3;object_name=lock-timeout;pid=745859;serial=9;tag=10011;timeout=1761802490.602038969;transmission_report=failure;unlock_duration=3
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: 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:438) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: LOCK_FAILED--pretend the LOCK times out}
SECTION: cluck_client_error: LOCK_FAILED--invalid tag
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/746243]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746243. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/746243]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746243 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--invalid tag}
--- message: cluckd/LOCK duration=3;object_name=lock-timeout;pid=745859;serial=10;tag=10012;timeout=1761802490.632543436;transmission_report=failure;unlock_duration=3
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: 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:438) {cluck_client_error: LOCK_FAILED--invalid tag}
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: LOCK_FAILED--invalid tag}
SECTION: cluck_client_error: LOCK_FAILED--other error (not timeout)
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/746245]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746245. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/746245]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746245 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
--- message: cluckd/LOCK duration=3;object_name=lock-timeout;pid=745859;serial=11;tag=10013;timeout=1761802490.660473252;transmission_report=failure;unlock_duration=3
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: 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:438) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: warning: communicatord did not like our LOCK message: other-error. (in function "void cluck::cluck::msg_lock_failed(ed::message&)()") (cluck.cpp:1411) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: LOCK_FAILED--other error (not timeout)}
SECTION: cluck_client_error: LOCK times out locally
2025/10/30 05:34:47 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/746247]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746247. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/746247]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746247 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: 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:227) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:47 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCK times out locally}
--- message: cluckd/LOCK duration=3;object_name=lock-name;pid=745859;serial=12;tag=10014;timeout=1761802490.688574079;transmission_report=failure;unlock_duration=3
2025/10/30 05:34:57 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:57 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:57 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:57 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:57 unittester [745859/745859]: 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:260) {cluck_client_error: LOCK times out locally}
2025/10/30 05:34:57 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: LOCK times out locally}
SECTION: cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)
2025/10/30 05:34:57 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/746250]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746250. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/746250]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746250 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:34:57 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
--- message: cluckd/LOCK duration=3;object_name=lock-name;pid=745859;serial=13;tag=10015;timeout=1761802500.719717104;transmission_report=failure;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/10/30 05:34:57 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10015;timeout_date=1761802500.731845587;unlocked_date=1761802503.731845587". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {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/10/30 05:34:58 unittester [745859/745859]: 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:438) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=745859;serial=13;tag=10015
--- message: not_lock
--- message: not_read
--- message: drain UNLOCKED
2025/10/30 05:35:03 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:438) {cluck_client_error: LOCKED timing out locally (LOCK works, get replay, never UNLOCK...)}
2025/10/30 05:35:03 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {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/10/30 05:35:03 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/746295]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746295. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/746295]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746295 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:03 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
--- message: cluckd/LOCK duration=3600;object_name=lock-name;pid=745859;serial=14;tag=10016;timeout=1761802506.122766673;transmission_report=failure;unlock_duration=3
--- message: .:communicatord/COMMANDS list=DATA,LOCKED,LOCK_FAILED,TRANSMISSION_REPORT,UNLOCKED,UNLOCKING
2025/10/30 05:35:03 unittester [745859/745859]: trace: dispatch message "<my_server:cluckd lock_server:cluck_test/LOCKED object_name=lock-name;tag=10016;timeout_date=1761806103.134756489;unlocked_date=1761806106.134756489". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
--- message: tester/READ size=219
2025/10/30 05:35:03 unittester [745859/745859]: 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:438) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
--- message: cluckd/UNLOCK object_name=lock-name;pid=745859;serial=14;tag=10016
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
2025/10/30 05:35:23 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: LOCKING timing out locally (LOCK+UNLOCK+UNLOCKING+sleep)}
SECTION: cluck_client_error: LOCKED with invalid tag
2025/10/30 05:35:23 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_READY". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: configuration: add_match() with command expression "NO_LOCK". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/746337]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746337. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/746337]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746337 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:227) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: configuration: add_match() with command expression "LOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: configuration: add_match() with command expression "LOCK_FAILED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKED". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: configuration: add_match() with command expression "UNLOCKING". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: configuration: add_match() with command expression "TRANSMISSION_REPORT". (in function "void ed::dispatcher::add_match(const ed::dispatcher_match&)()") (dispatcher.cpp:321) {cluck_client_error: LOCKED with invalid tag}
--- message: cluckd/LOCK object_name=lock-name;pid=745859;serial=15;tag=10017;timeout=1761802601.346991654;transmission_report=failure
--- message: --- test_case = [invalid_tag]....
--- message: --- test_case = EQUAL?! invalid tag....
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: 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:260) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/NO_LOCK cache=no". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: trace: dispatch message "<lock_server:cluckd my_server:cluck_test/LOCKED object_name=lock-name;tag=bad_tag;timeout_date=1761802523.567351773;unlocked_date=1761802523.567351773". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_client_error: LOCKED with invalid tag}
2025/10/30 05:35:23 unittester [745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_daemon_one_computer: verify cluckd}
SECTION: cluck_daemon_one_computer: verify cluckd
2025/10/30 05:35:23 unittester cluckd[745859/745859]: information: -------------------------------------------------- (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:774) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:23 unittester cluckd[745859/745859]: information: cluckd v1.0.1 started. (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:780) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:23 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:23 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:23 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:23 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: information: --------------------------------- cluckd started. (in function "void cluck_daemon::cluckd::run()()") (cluckd.cpp:423) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_one_computer: verify cluckd}
--- message: --- expect REGISTER ---
2025/10/30 05:35:24 unittester cluckd[745859/746379]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746379. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/746379]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746379 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_one_computer: verify cluckd}
--- message: REGISTER service=cluckd;version=1
--- message: --- expect COMMANDS ---
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "HELP". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:586) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:586) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:586) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "READY my_address=127.0.0.1". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd COMMANDS list=ABSOLUTELY,ACTIVATE_LOCK,ADD_TICKET,ALIVE,CLOCK_STABLE,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 CLOCK_STATUS ---
--- message: <:cluckd CLOCK_STATUS cache=no
--- message: --- expect FLUID_SETTINGS_LISTEN ---
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "STATUS cache=no;server=unittester;service=fluid_settings;status=up;up_since=1761802524.050689662". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=15;object_name=early_lock;pid=10203;tag=15045;timeout=1761802584.051343088". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:2865) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "UNLOCK object_name=too_soon;pid=431;tag=11104". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:4233) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "unittester:cluckd/CLOCK_STABLE cache=no;clock_resolution=verified". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {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/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "unittester:cluckd/FLUID_SETTINGS_REGISTERED". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "unittester:cluckd/FLUID_SETTINGS_READY errcnt=31". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK_STATUS". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:499) {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/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "unittester:cluckd/CLUSTER_UP neighbors_count=1". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:2550) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: warning: election status = add leader(s)... 1 computers and 1 leaders. (in function "void cluck_daemon::cluckd::election_status()()") (cluckd.cpp:1170) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: Attempting to lock "early_lock" (15045) on "unittester/10203" for "unittester/website" (timeout: 1761802584.051343088). (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:384) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=info". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd */LOCK_LEADERS election_date=1761802524.092205474;leader0=14|2217727805|127.0.0.1|745859|unittester
--- message: --- wait for message LOCK_STARTED ---
--- message: <:cluckd */LOCK_STARTED election_date=1761802524.092205474;leader0=14|2217727805|127.0.0.1|745859|unittester;lock_id=14|2217727805|127.0.0.1|745859|unittester;server_name=unittester;start_time=1761802523.986279041
--- 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=1761802539.094766275;unlocked_date=1761802554.094766275
--- message: --- wait for message CLUCKD_STATUS (without tickets)....
--- message: <:cluckd unittester:website/CLUCKD_STATUS status={"computers":[{"connected":true,"id":"14|2217727805|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|2217727805|127.0.0.1|745859|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|2217727805|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|2217727805|127.0.0.1|745859|unittester","ip":"127.0.0.1","leaders_count":1,"neighbors_count":1,"neighbors_quorum":1}
--- message: --- wait for message LOCK_FAILED ---
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:2780) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "UNLOCK object_name=bad_pid;pid=-431;tag=11104". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:2053) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: Unlock on "early_lock" (15045) with key "unittester/10203". (in function "void cluck_daemon::ticket::drop_ticket()()") (ticket.cpp:815) {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=1761802524.125233541
--- message: --- expect LOCKED ---
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=-9123;tag=505;timeout=1761802584.127490759". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:2053) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1761802584.127490759". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: Attempting to lock "lock1" (505) on "unittester/123" for "unittester/website" (timeout: 1761802584.127490759). (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:384) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1761802584.127490759". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:2995) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd unittester:website/LOCKED object_name=lock1;tag=505;timeout_date=1761802534.135991216;unlocked_date=1761802544.135991216
--- 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/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<rc1:cluckd unittester:cluckd/LOCK_LEADERS election_date=1761802524.092205474;leader0=14|2217727805|127.0.0.1|745859|unittester". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<rc1:cluckd unittester:cluckd/ADD_TICKET key=00000001/unittester/123;object_name=lock1;tag=505;timeout=1761802584.127490759". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:2273) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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/10/30 05:35:24 unittester cluckd[745859/745859]: 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&):2295: 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/10/30 05:35:24 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=debug". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {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|2217727805|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|2217727805|127.0.0.1|745859|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=1761802534.135991216|locked=true|object_name=lock1|obtention_timeout=1761802584.127490759|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|2217727805|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"14|2217727805|127.0.0.1|745859|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=1761802534.135991216|locked=true|object_name=lock1|obtention_timeout=1761802584.127490759|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: 1761802584.127490759
--- message: --- expect UNLOCKING ---
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: major: UNLOCK could not find key "another_server/435" in object "lock1". (in function "void cluck_daemon::cluckd::msg_unlock(ed::message&)()") (cluckd.cpp:4302) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:24 unittester cluckd[745859/745859]: warning: UNLOCK could not find object "unknown_lock". (in function "void cluck_daemon::cluckd::msg_unlock(ed::message&)()") (cluckd.cpp:4313) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:35 unittester cluckd[745859/745859]: 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:976) {cluck_daemon_one_computer: verify cluckd}
--- message: <:cluckd unittester:website/UNLOCKING error=timedout;object_name=lock1;tag=505
--- message: --- expect UNLOCKED ---
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:1009) {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/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/QUITTING". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: removing 1 connection, "messenger", of 4 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:260) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: removing 1 connection, "interrupt", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:260) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: removing 1 connection, "timer", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:260) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_one_computer: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: unimportant: exiting nested diagnostic (in function "virtual snaplogger::nested_diagnostic::~nested_diagnostic()()") (nested_diagnostic.cpp:66) {cluck_daemon_one_computer: verify cluckd}
SECTION: cluck_daemon_two_computers: verify cluckd
2025/10/30 05:35:45 unittester cluckd[745859/745859]: unimportant: entering nested diagnostic (in function "snaplogger::nested_diagnostic::nested_diagnostic(const std::string&, bool)()") (nested_diagnostic.cpp:53) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: information: -------------------------------------------------- (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:774) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: information: cluckd v1.0.1 started. (in function "bool snaplogger::process_logger_options(advgetopt::getopt&, const std::string&, std::basic_ostream<char>&, bool)()") (options.cpp:780) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: information: --------------------------------- cluckd started. (in function "void cluck_daemon::cluckd::run()()") (cluckd.cpp:423) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_two_computers: verify cluckd}
--- message: --- expect REGISTER ---
2025/10/30 05:35:45 unittester cluckd[745859/746381]: information: entering thread "background tcp_client_permanent_message_connection for asynchronous connections" #746381. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/746381]: information: leaving thread "background tcp_client_permanent_message_connection for asynchronous connections" #746381 with status 0. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:227) {cluck_daemon_two_computers: verify cluckd}
--- message: REGISTER service=cluckd;version=1
--- message: --- expect COMMANDS ---
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "HELP". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:586) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:586) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:586) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "READY my_address=127.0.0.1". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd COMMANDS list=ABSOLUTELY,ACTIVATE_LOCK,ADD_TICKET,ALIVE,CLOCK_STABLE,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
--- message: --- expect CLOCK_STATUS ---
--- message: <:cluckd CLOCK_STATUS cache=no
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "STATUS cache=no;server=two;server_name=two;service=remote communicator (in);status=up;up_since=1761802545.224548436". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "STATUS cache=no;server=unittester;service=fluid_settings;status=up;up_since=1761802545.225936408". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK_STATUS". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "unittester:cluckd/CLOCK_STABLE cache=no;clock_resolution=verified". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {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/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "unittester:cluckd/CLUSTER_UP neighbors_count=2". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:2550) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:499) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=info". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: not considered ready: no leaders. (in function "bool cluck_daemon::cluckd::is_daemon_ready() const()") (cluckd.cpp:499) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd */LOCK_STARTED lock_id=10|1949153621|127.0.0.1|745859|unittester;server_name=unittester;start_time=1761802545.160268945
--- 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|1949153621|127.0.0.1|745859|unittester","ip":"127.0.0.1","name":"unittester"}],"daemon_ready":false,"id":"10|1949153621|127.0.0.1|745859|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|1949153621|127.0.0.1|745859|unittester","ip":"127.0.0.1","name":"unittester"}],"daemon_ready":false,"id":"10|1949153621|127.0.0.1|745859|unittester","ip":"127.0.0.1","leaders_count":0,"neighbors_count":2,"neighbors_quorum":2}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<two:cluckd unittester:cluckd/LOCK_STARTED lock_id=ignored;server_name=unittester;start_time=1761802545.246654591". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<two:cluckd unittester:cluckd/LOCK_STARTED lock_id=invalid;server_name=two;start_time=1761802545.247366976". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<two:cluckd unittester:cluckd/LOCK_STARTED lock_id=14|570061893|172.1.2.3|2943806|two;server_name=two;start_time=1761802545.247998643". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: warning: election status = add leader(s)... 2 computers and 2 leaders. (in function "void cluck_daemon::cluckd::election_status()()") (cluckd.cpp:1170) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd */LOCK_LEADERS election_date=1761802545.256336675;leader0=10|1949153621|127.0.0.1|745859|unittester;leader1=14|570061893|172.1.2.3|2943806|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=1761802545.256336675;leader0=10|1949153621|127.0.0.1|745859|unittester;leader1=14|570061893|172.1.2.3|2943806|two;lock_id=10|1949153621|127.0.0.1|745859|unittester;server_name=unittester;start_time=1761802545.160268945
--- message: --- wait for message LOCK_FAILED (timedout)....
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:2780) {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/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1761802605.272398551". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: Attempting to lock "lock1" (505) on "unittester/123" for "unittester/website" (timeout: 1761802605.272398551). (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:384) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;tag=505;timeout=1761802605.272398551". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:2921) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/LOCK duration=10;object_name=lock1;pid=123;serial=1;tag=505;timeout=1761802605.272398551". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {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=1761802605.272398551
--- 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/10/30 05:35:45 unittester cluckd[745859/745859]: 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:438) {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/10/30 05:35:45 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd two:cluckd/ADD_TICKET key=00000070/unittester/123;object_name=lock1;tag=505;timeout=1761802605.272398551
--- message: --- wait for message LOCK_EXITING....
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:438) {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/10/30 05:35:45 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:438) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd unittester:website/LOCKED object_name=lock1;tag=505;timeout_date=1761802555.314379557;unlocked_date=1761802565.314379557
--- 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/10/30 05:35:45 unittester cluckd[745859/745859]: trace: dispatch message "<unittester:website unittester:cluckd/INFO mode=debug". (in function "bool ed::dispatcher::dispatch(ed::message&)()") (dispatcher.cpp:438) {cluck_daemon_two_computers: verify cluckd}
--- message: <:cluckd unittester:website/CLUCKD_STATUS status={"computers":[{"connected":true,"id":"14|570061893|172.1.2.3|2943806|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|1949153621|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|1949153621|127.0.0.1|745859|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=1761802555.314379557|locked=true|object_name=lock1|obtention_timeout=1761802605.272398551|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|570061893|172.1.2.3|2943806|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|1949153621|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|1949153621|127.0.0.1|745859|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=1761802555.314379557|locked=true|object_name=lock1|obtention_timeout=1761802605.272398551|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/10/30 05:35:45 unittester cluckd[745859/746380]: fatal: thread internal_run() got exception: "event_dispatcher_exception: /home/alexis/snapwebsites/snapcpp/contrib/cluck/tests/rprtr/cluck_daemon_test_two_computers.rprtr:771: message expected parameter "status", set to "{"computers":[{"connected":true,"id":"14|570061893|172.1.2.3|2943806|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|1949153621|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|1949153621|127.0.0.1|745859|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=1761802555.314379557|locked=true|object_name=lock1|obtention_timeout=1761802605.272398551|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\\|1949153621\\|127\\.0\\.0\\.1\\|745859\\|unittester","ip":"127\\.0\\.0\\.1","leader":0,"name":"unittester"\\},\\{"connected":true,"id":"14\\|570061893\\|172\\.1\\.2\\.3\\|2943806\\|two","ip":"172\\.1\\.2\\.3","leader":1,"name":"two"\\}\\],"daemon_ready":true,"id":"10\\|1949153621\\|127\\.0\\.0\\.1\\|745859\\|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"\\}".", exiting thread now. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: 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:260) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: removing 1 connection, "messenger", of 3 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:260) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/746380]: fatal: thread internal_leave() got exception: "event_dispatcher_exception: thread failed with leave status: 2", exiting thread now. (in function "void snaplogger::{anonymous}::cppthread_logs(cppthread::log_level_t, const std::string&)()") (private_logger.cpp:118) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: removing 1 connection, "interrupt", of 2 connections (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:260) {cluck_daemon_two_computers: verify cluckd}
2025/10/30 05:35:45 unittester cluckd[745859/745859]: trace: removing 1 connection, "timer", of 1 connection (including this one). (in function "bool ed::communicator::remove_connection(ed::connection::pointer_t)()") (communicator.cpp:260) {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:771: message expected parameter "status", set to "{"computers":[{"connected":true,"id":"14|570061893|172.1.2.3|2943806|two","ip":"172.1.2.3","leader":1,"name":"two"},{"connected":true,"id":"10|1949153621|127.0.0.1|745859|unittester","ip":"127.0.0.1","leader":0,"name":"unittester"}],"daemon_ready":true,"id":"10|1949153621|127.0.0.1|745859|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=1761802555.314379557|locked=true|object_name=lock1|obtention_timeout=1761802605.272398551|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\|1949153621\|127\.0\.0\.1\|745859\|unittester","ip":"127\.0\.0\.1","leader":0,"name":"unittester"\},\{"connected":true,"id":"14\|570061893\|172\.1\.2\.3\|2943806\|two","ip":"172\.1\.2\.3","leader":1,"name":"two"\}\],"daemon_ready":true,"id":"10\|1949153621\|127\.0\.0\.1\|745859\|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.70user 2.36system 1:12.07elapsed 8%CPU (0avgtext+0avgdata 31588maxresident)k
0inputs+304outputs (0major+141415minor)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 on Thu Oct 30 05:35:45 AM UTC 2025.

Back to list