Skip to content

Instantly share code, notes, and snippets.

@folkengine
Created February 20, 2024 19:39
Show Gist options
  • Save folkengine/d2f97199e85ac865e58aa128a7dac04e to your computer and use it in GitHub Desktop.
Save folkengine/d2f97199e85ac865e58aa128a7dac04e to your computer and use it in GitHub Desktop.
Start testing: Feb 20 19:30 UTC
----------------------------------------------------------
1/5 Testing: database_tests
1/5 Test: database_tests
Command: "/workspaces/libocpp/build/tests/database_tests"
Directory: /workspaces/libocpp/build/tests
"database_tests" start time: Feb 20 19:30 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 15 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 15 tests from DatabaseTest
[ RUN ] DatabaseTest.test_init_connector_table
[2024-02-20 19:30:02.553973] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:02.553992] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:02.634278] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_init_connector_table (80 ms)
[ RUN ] DatabaseTest.test_list_version
[2024-02-20 19:30:02.634400] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:02.634406] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:02.726714] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_list_version (92 ms)
[ RUN ] DatabaseTest.test_local_authorization_list_entry_1
[2024-02-20 19:30:02.726819] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:02.726825] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:02.810655] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_local_authorization_list_entry_1 (83 ms)
[ RUN ] DatabaseTest.test_local_authorization_list_entry_2
[2024-02-20 19:30:02.810787] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:02.810796] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:02.905516] [0x00007dfb64aea740] [info] IdTag DEADBEEF in auth list has expiry date in the past, setting entry to expired.
[2024-02-20 19:30:02.910416] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_local_authorization_list_entry_2 (99 ms)
[ RUN ] DatabaseTest.test_local_authorization_list
[2024-02-20 19:30:02.910523] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:02.910529] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.003562] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_local_authorization_list (93 ms)
[ RUN ] DatabaseTest.test_clear_authorization_list
[2024-02-20 19:30:03.003659] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.003665] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.097312] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_clear_authorization_list (93 ms)
[ RUN ] DatabaseTest.test_authorization_cache_entry
[2024-02-20 19:30:03.097411] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.097417] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.182669] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_authorization_cache_entry (85 ms)
[ RUN ] DatabaseTest.test_authorization_cache_entry_2
[2024-02-20 19:30:03.182767] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.182773] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.272133] [0x00007dfb64aea740] [info] IdTag DEADBEEF in auth cache has expiry date in the past, setting entry to expired.
[2024-02-20 19:30:03.277345] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_authorization_cache_entry_2 (94 ms)
[ RUN ] DatabaseTest.test_clear_authorization_cache
[2024-02-20 19:30:03.277507] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.277513] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.367342] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_clear_authorization_cache (89 ms)
[ RUN ] DatabaseTest.test_connector_availability
[2024-02-20 19:30:03.367490] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.367496] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.457498] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_connector_availability (90 ms)
[ RUN ] DatabaseTest.test_insert_and_get_transaction
[2024-02-20 19:30:03.457593] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.457599] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.561694] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_insert_and_get_transaction (104 ms)
[ RUN ] DatabaseTest.test_insert_and_get_transaction_without_id_tag
[2024-02-20 19:30:03.561822] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.561831] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.665365] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_insert_and_get_transaction_without_id_tag (103 ms)
[ RUN ] DatabaseTest.test_insert_and_get_profiles
[2024-02-20 19:30:03.665465] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.665471] [0x00007dfb64aea740] [info] Running SQL initialization script.
/workspaces/libocpp/tests/database_tests.cpp:312: Skipped
validFrom/validTo checks are failing. See https://github.com/EVerest/libocpp/issues/384
[2024-02-20 19:30:03.745865] [0x00007dfb64aea740] [info] Successfully closed database file
[ SKIPPED ] DatabaseTest.test_insert_and_get_profiles (80 ms)
[ RUN ] DatabaseTest.test_update_profile_same_profile_id
[2024-02-20 19:30:03.745958] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.745964] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.862066] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_update_profile_same_profile_id (116 ms)
[ RUN ] DatabaseTest.test_delete_profile
[2024-02-20 19:30:03.862168] [0x00007dfb64aea740] [info] Established connection to Database.
[2024-02-20 19:30:03.862174] [0x00007dfb64aea740] [info] Running SQL initialization script.
[2024-02-20 19:30:03.960480] [0x00007dfb64aea740] [info] Successfully closed database file
[ OK ] DatabaseTest.test_delete_profile (98 ms)
[----------] 15 tests from DatabaseTest (1407 ms total)
[----------] Global test environment tear-down
[==========] 15 tests from 1 test suite ran. (1407 ms total)
[ PASSED ] 14 tests.
[ SKIPPED ] 1 test, listed below:
[ SKIPPED ] DatabaseTest.test_insert_and_get_profiles
<end of output>
Test time = 1.41 sec
----------------------------------------------------------
Test Passed.
"database_tests" end time: Feb 20 19:30 UTC
"database_tests" time elapsed: 00:00:01
----------------------------------------------------------
2/5 Testing: libocpp_common_unit_tests
2/5 Test: libocpp_common_unit_tests
Command: "/workspaces/libocpp/build/tests/libocpp_common_unit_tests"
Directory: /workspaces/libocpp/build/tests
"libocpp_common_unit_tests" start time: Feb 20 19:30 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 11 tests from 3 test suites.
[----------] Global test environment set-up.
[----------] 2 tests from ControlMessageV16Test
[ RUN ] ControlMessageV16Test.test_is_transactional
[ OK ] ControlMessageV16Test.test_is_transactional (5 ms)
[ RUN ] ControlMessageV16Test.test_is_transactional_update
[ OK ] ControlMessageV16Test.test_is_transactional_update (0 ms)
[----------] 2 tests from ControlMessageV16Test (5 ms total)
[----------] 2 tests from ControlMessageV201Test
[ RUN ] ControlMessageV201Test.test_is_transactional
[ OK ] ControlMessageV201Test.test_is_transactional (0 ms)
[ RUN ] ControlMessageV201Test.test_is_transactional_update
[ OK ] ControlMessageV201Test.test_is_transactional_update (0 ms)
[----------] 2 tests from ControlMessageV201Test (0 ms total)
[----------] 7 tests from MessageQueueTest
[ RUN ] MessageQueueTest.test_transactional_message_is_sent
[2024-02-20 19:30:03.970845] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:03.970856] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.970871] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:03.970980] [0x000071523bcaf740] [info] [2,"0","transactional",{"data":"test_data"}]
[2024-02-20 19:30:03.970992] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:03.971025] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.971063] [0x0000715239ca76c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:03.971070] [0x0000715239ca76c0] [info] There are 1 messages in the transaction message queue.
[2024-02-20 19:30:03.971074] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.971085] [0x0000715239ca76c0] [info] transaction msg timestamp: 2024-02-20T19:30:03.970Z
[2024-02-20 19:30:03.971089] [0x0000715239ca76c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:03.971094] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: 0 attempt#: 0
[2024-02-20 19:30:03.971126] [0x0000715239ca76c0] [info] Successfully sent message. UID: 0
[2024-02-20 19:30:03.971130] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:03.971139] [0x0000715239ca76c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:03.971170] [0x000071523bcaf740] [info] stop() notified message queue
[ OK ] MessageQueueTest.test_transactional_message_is_sent (0 ms)
[ RUN ] MessageQueueTest.test_non_transactional_message_is_sent
[2024-02-20 19:30:03.971620] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:03.971626] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.971640] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:03.971699] [0x000071523bcaf740] [info] [2,"0","non_transactional",{"data":"test_data"}]
[2024-02-20 19:30:03.971710] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:03.971739] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.971799] [0x0000715239ca76c0] [info] There are 1 messages in the normal message queue.
[2024-02-20 19:30:03.971807] [0x0000715239ca76c0] [info] There are 0 messages in the transaction message queue.
[2024-02-20 19:30:03.971811] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.971822] [0x0000715239ca76c0] [info] normal msg timestamp: 2024-02-20T19:30:03.971Z
[2024-02-20 19:30:03.971826] [0x0000715239ca76c0] [info] normal message timestamp <= now
[2024-02-20 19:30:03.971831] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: 0 attempt#: 0
[2024-02-20 19:30:03.971861] [0x0000715239ca76c0] [info] Successfully sent message. UID: 0
[2024-02-20 19:30:03.971868] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:03.971874] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.971877] [0x0000715239ca76c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:03.971894] [0x000071523bcaf740] [info] stop() notified message queue
[ OK ] MessageQueueTest.test_non_transactional_message_is_sent (0 ms)
[ RUN ] MessageQueueTest.test_queuing_up_of_transactional_messages
[2024-02-20 19:30:03.972339] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:03.972349] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:03.972400] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.972453] [0x000071523bcaf740] [info] [2,"test_call_0","transactional",{"data":"test_call_0"}]
[2024-02-20 19:30:03.972464] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:03.972494] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.972498] [0x0000715239ca76c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:03.972502] [0x0000715239ca76c0] [info] There are 1 messages in the transaction message queue.
[2024-02-20 19:30:03.972505] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.972516] [0x0000715239ca76c0] [info] transaction msg timestamp: 2024-02-20T19:30:03.972Z
[2024-02-20 19:30:03.972520] [0x0000715239ca76c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:03.972525] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: test_call_0 attempt#: 0
[2024-02-20 19:30:03.972551] [0x0000715239ca76c0] [info] Could not send message, this is most likely because the charge point is offline.
[2024-02-20 19:30:03.972556] [0x0000715239ca76c0] [info] The message in flight is transaction related and will be sent again once the connection can be established again.
[2024-02-20 19:30:03.972561] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.972584] [0x000071523bcaf740] [info] [2,"test_call_1","transactional",{"data":"test_call_1"}]
[2024-02-20 19:30:03.972595] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:03.972625] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.972664] [0x000071523bcaf740] [info] [2,"test_call_2","transactional",{"data":"test_call_2"}]
[2024-02-20 19:30:03.972674] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:03.972698] [0x000071523bcaf740] [info] Queue sizes exceed threshold (2) with 3 transaction and 0 normal messages in queue
[2024-02-20 19:30:03.972708] [0x000071523bcaf740] [info] There are no further transaction update messages to drop!
[2024-02-20 19:30:03.972722] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.972761] [0x000071523bcaf740] [info] [2,"test_call_3","transactional",{"data":"test_call_3"}]
[2024-02-20 19:30:03.972771] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:03.972795] [0x000071523bcaf740] [info] Queue sizes exceed threshold (2) with 4 transaction and 0 normal messages in queue
[2024-02-20 19:30:03.972805] [0x000071523bcaf740] [info] There are no further transaction update messages to drop!
[2024-02-20 19:30:03.972821] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.972860] [0x000071523bcaf740] [info] [2,"test_call_4","transactional",{"data":"test_call_4"}]
[2024-02-20 19:30:03.972870] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:03.972893] [0x000071523bcaf740] [info] Queue sizes exceed threshold (2) with 5 transaction and 0 normal messages in queue
[2024-02-20 19:30:03.972904] [0x000071523bcaf740] [info] There are no further transaction update messages to drop!
[2024-02-20 19:30:03.972917] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.972931] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:03.972940] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:03.972955] [0x0000715239ca76c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:03.972963] [0x0000715239ca76c0] [info] There are 5 messages in the transaction message queue.
[2024-02-20 19:30:03.972966] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.972977] [0x0000715239ca76c0] [info] transaction msg timestamp: 2024-02-20T19:30:03.972Z
[2024-02-20 19:30:03.972981] [0x0000715239ca76c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:03.972985] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: test_call_0 attempt#: 1
[2024-02-20 19:30:03.973027] [0x0000715239ca76c0] [info] Successfully sent message. UID: test_call_0
[2024-02-20 19:30:03.973038] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.973197] [0x0000715239ca76c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:03.973205] [0x0000715239ca76c0] [info] There are 4 messages in the transaction message queue.
[2024-02-20 19:30:03.973208] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.973259] [0x0000715239ca76c0] [info] transaction msg timestamp: 2024-02-20T19:30:03.972Z
[2024-02-20 19:30:03.973264] [0x0000715239ca76c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:03.973269] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: test_call_1 attempt#: 0
[2024-02-20 19:30:03.973311] [0x0000715239ca76c0] [info] Successfully sent message. UID: test_call_1
[2024-02-20 19:30:03.973323] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.973458] [0x0000715239ca76c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:03.973466] [0x0000715239ca76c0] [info] There are 3 messages in the transaction message queue.
[2024-02-20 19:30:03.973469] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.973480] [0x0000715239ca76c0] [info] transaction msg timestamp: 2024-02-20T19:30:03.972Z
[2024-02-20 19:30:03.973486] [0x0000715239ca76c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:03.973491] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: test_call_2 attempt#: 0
[2024-02-20 19:30:03.973546] [0x0000715239ca76c0] [info] Successfully sent message. UID: test_call_2
[2024-02-20 19:30:03.973563] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.973704] [0x0000715239ca76c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:03.973713] [0x0000715239ca76c0] [info] There are 2 messages in the transaction message queue.
[2024-02-20 19:30:03.973718] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.973753] [0x0000715239ca76c0] [info] transaction msg timestamp: 2024-02-20T19:30:03.972Z
[2024-02-20 19:30:03.973760] [0x0000715239ca76c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:03.973767] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: test_call_3 attempt#: 0
[2024-02-20 19:30:03.973834] [0x0000715239ca76c0] [info] Successfully sent message. UID: test_call_3
[2024-02-20 19:30:03.973848] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.973958] [0x0000715239ca76c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:03.973967] [0x0000715239ca76c0] [info] There are 1 messages in the transaction message queue.
[2024-02-20 19:30:03.973973] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.973991] [0x0000715239ca76c0] [info] transaction msg timestamp: 2024-02-20T19:30:03.972Z
[2024-02-20 19:30:03.973999] [0x0000715239ca76c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:03.974007] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: test_call_4 attempt#: 0
[2024-02-20 19:30:03.974104] [0x0000715239ca76c0] [info] Successfully sent message. UID: test_call_4
[2024-02-20 19:30:03.974108] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:03.974117] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.974123] [0x0000715239ca76c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:03.974146] [0x000071523bcaf740] [info] stop() notified message queue
[ OK ] MessageQueueTest.test_queuing_up_of_transactional_messages (2 ms)
[ RUN ] MessageQueueTest.test_non_queuing_up_of_non_transactional_messages
[2024-02-20 19:30:03.974659] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:03.974666] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.974678] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:03.974734] [0x000071523bcaf740] [info] [2,"test_call_0","non_transactional",{"data":"test_call_0"}]
[2024-02-20 19:30:03.974745] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:03.974754] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:03.974759] [0x0000715239ca76c0] [info] There are 1 messages in the normal message queue.
[2024-02-20 19:30:03.974765] [0x0000715239ca76c0] [info] There are 0 messages in the transaction message queue.
[2024-02-20 19:30:03.974772] [0x0000715239ca76c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:03.974789] [0x0000715239ca76c0] [info] normal msg timestamp: 2024-02-20T19:30:03.974Z
[2024-02-20 19:30:03.974796] [0x0000715239ca76c0] [info] normal message timestamp <= now
[2024-02-20 19:30:03.974803] [0x0000715239ca76c0] [info] Attempting to send message to central system. UID: test_call_0 attempt#: 0
[2024-02-20 19:30:03.974838] [0x0000715239ca76c0] [info] Could not send message, this is most likely because the charge point is offline.
[2024-02-20 19:30:03.974845] [0x0000715239ca76c0] [info] The message in flight is not transaction related and will be dropped
[2024-02-20 19:30:03.974857] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:03.974871] [0x000071523bcaf740] [info] [2,"test_call_1","non_transactional",{"data":"test_call_1"}]
[2024-02-20 19:30:03.974918] [0x000071523bcaf740] [info] [2,"test_call_2","non_transactional",{"data":"test_call_2"}]
[2024-02-20 19:30:03.974962] [0x000071523bcaf740] [info] [2,"test_call_3","non_transactional",{"data":"test_call_3"}]
[2024-02-20 19:30:03.975008] [0x000071523bcaf740] [info] [2,"test_call_4","non_transactional",{"data":"test_call_4"}]
[2024-02-20 19:30:03.975027] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:03.975037] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.025208] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:04.025301] [0x0000715239ca76c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:04.025378] [0x000071523bcaf740] [info] stop() notified message queue
[ OK ] MessageQueueTest.test_non_queuing_up_of_non_transactional_messages (51 ms)
[ RUN ] MessageQueueTest.test_queuing_up_of_non_transactional_messages
[2024-02-20 19:30:04.026397] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.026423] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.026446] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:04.026470] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.026505] [0x0000715239ca76c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:04.026570] [0x000071523bcaf740] [info] stop() notified message queue
[2024-02-20 19:30:04.027076] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.027293] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.027328] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.027534] [0x000071523bcaf740] [info] [2,"test_call_0","non_transactional",{"data":"test_call_0"}]
[2024-02-20 19:30:04.027575] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.027604] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.027644] [0x000071522bfff6c0] [info] There are 1 messages in the normal message queue.
[2024-02-20 19:30:04.027682] [0x000071522bfff6c0] [info] There are 0 messages in the transaction message queue.
[2024-02-20 19:30:04.027706] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.027768] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.027Z
[2024-02-20 19:30:04.027796] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.027815] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_0 attempt#: 0
[2024-02-20 19:30:04.027959] [0x000071522bfff6c0] [info] Could not send message, this is most likely because the charge point is offline.
[2024-02-20 19:30:04.027978] [0x000071522bfff6c0] [info] The message in flight will be sent again once the connection can be established again since QueueAllMessages is set to 'true'.
[2024-02-20 19:30:04.028007] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.028084] [0x000071523bcaf740] [info] [2,"test_call_1","non_transactional",{"data":"test_call_1"}]
[2024-02-20 19:30:04.028118] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.028144] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.028191] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.028223] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.028261] [0x000071522bfff6c0] [info] There are 2 messages in the normal message queue.
[2024-02-20 19:30:04.028277] [0x000071522bfff6c0] [info] There are 0 messages in the transaction message queue.
[2024-02-20 19:30:04.028286] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.028316] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.027Z
[2024-02-20 19:30:04.028328] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.028341] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_0 attempt#: 1
[2024-02-20 19:30:04.028472] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_0
[2024-02-20 19:30:04.028505] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.028674] [0x000071522bfff6c0] [info] There are 1 messages in the normal message queue.
[2024-02-20 19:30:04.028684] [0x000071522bfff6c0] [info] There are 0 messages in the transaction message queue.
[2024-02-20 19:30:04.028692] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.028720] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.028Z
[2024-02-20 19:30:04.028731] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.028744] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_1 attempt#: 0
[2024-02-20 19:30:04.028843] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_1
[2024-02-20 19:30:04.028859] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:04.028889] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.028903] [0x000071522bfff6c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:04.028980] [0x000071523bcaf740] [info] stop() notified message queue
[ OK ] MessageQueueTest.test_queuing_up_of_non_transactional_messages (3 ms)
[ RUN ] MessageQueueTest.test_clean_up_non_transactional_queue
[2024-02-20 19:30:04.029978] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.029999] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.030012] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:04.030044] [0x000071523b4aa6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.030079] [0x000071523b4aa6c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:04.030150] [0x000071523bcaf740] [info] stop() notified message queue
[2024-02-20 19:30:04.030664] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.030909] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.030933] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.031007] [0x000071523bcaf740] [info] pause()
[2024-02-20 19:30:04.031024] [0x000071523bcaf740] [info] pause() notified message queue
[2024-02-20 19:30:04.031103] [0x000071523bcaf740] [info] [2,"test_call_0","non_transactional",{"data":"test_call_0"}]
[2024-02-20 19:30:04.031123] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.031138] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.031209] [0x000071523bcaf740] [info] [2,"test_call_1","non_transactional",{"data":"test_call_1"}]
[2024-02-20 19:30:04.031226] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.031239] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.031303] [0x000071523bcaf740] [info] [2,"test_call_2","non_transactional",{"data":"test_call_2"}]
[2024-02-20 19:30:04.031320] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.031333] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.031460] [0x000071523bcaf740] [info] [2,"test_call_3","non_transactional",{"data":"test_call_3"}]
[2024-02-20 19:30:04.031482] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.031496] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.031576] [0x000071523bcaf740] [info] [2,"test_call_4","non_transactional",{"data":"test_call_4"}]
[2024-02-20 19:30:04.031598] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.031615] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.031704] [0x000071523bcaf740] [info] [2,"test_call_5","non_transactional",{"data":"test_call_5"}]
[2024-02-20 19:30:04.031728] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.031746] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.031829] [0x000071523bcaf740] [info] [2,"test_call_6","non_transactional",{"data":"test_call_6"}]
[2024-02-20 19:30:04.031851] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.031869] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.032065] [0x000071523bcaf740] [info] [2,"test_call_7","non_transactional",{"data":"test_call_7"}]
[2024-02-20 19:30:04.032092] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.032110] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.032277] [0x000071523bcaf740] [info] [2,"test_call_8","non_transactional",{"data":"test_call_8"}]
[2024-02-20 19:30:04.032302] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.032319] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.032479] [0x000071523bcaf740] [info] [2,"test_call_9","non_transactional",{"data":"test_call_9"}]
[2024-02-20 19:30:04.032504] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.032522] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.032682] [0x000071523bcaf740] [info] [2,"test_call_10","non_transactional",{"data":"test_call_10"}]
[2024-02-20 19:30:04.032707] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.032724] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.032885] [0x000071523bcaf740] [info] [2,"test_call_11","non_transactional",{"data":"test_call_11"}]
[2024-02-20 19:30:04.032910] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.032929] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.033152] [0x000071523bcaf740] [info] [2,"test_call_12","non_transactional",{"data":"test_call_12"}]
[2024-02-20 19:30:04.033181] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.033200] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.033365] [0x000071523bcaf740] [info] [2,"test_call_13","non_transactional",{"data":"test_call_13"}]
[2024-02-20 19:30:04.033391] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.033409] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.033568] [0x000071523bcaf740] [info] [2,"test_call_14","non_transactional",{"data":"test_call_14"}]
[2024-02-20 19:30:04.033593] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.033611] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.033773] [0x000071523bcaf740] [info] [2,"test_call_15","transactional",{"data":"test_call_15"}]
[2024-02-20 19:30:04.033800] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.033869] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.034039] [0x000071523bcaf740] [info] [2,"test_call_16","transactional",{"data":"test_call_16"}]
[2024-02-20 19:30:04.034069] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.034171] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.034350] [0x000071523bcaf740] [info] [2,"test_call_17","transactional",{"data":"test_call_17"}]
[2024-02-20 19:30:04.034377] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.034441] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.034610] [0x000071523bcaf740] [info] [2,"test_call_18","transactional",{"data":"test_call_18"}]
[2024-02-20 19:30:04.034636] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.034695] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.034861] [0x000071523bcaf740] [info] [2,"test_call_19","transactional",{"data":"test_call_19"}]
[2024-02-20 19:30:04.034886] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.034945] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.035111] [0x000071523bcaf740] [info] [2,"test_call_20","transactional",{"data":"test_call_20"}]
[2024-02-20 19:30:04.035136] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.035194] [0x000071523bcaf740] [info] Queue sizes exceed threshold (20) with 6 transaction and 15 normal messages in queue
[2024-02-20 19:30:04.035226] [0x000071523bcaf740] [info] Dropping 2 messages from normal message queue.
[2024-02-20 19:30:04.035296] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.035523] [0x000071523bcaf740] [info] [2,"test_call_21","transactional",{"data":"test_call_21"}]
[2024-02-20 19:30:04.035554] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.035617] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.035789] [0x000071523bcaf740] [info] [2,"test_call_22","transactional",{"data":"test_call_22"}]
[2024-02-20 19:30:04.035815] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.035867] [0x000071523bcaf740] [info] Queue sizes exceed threshold (20) with 8 transaction and 13 normal messages in queue
[2024-02-20 19:30:04.035883] [0x000071523bcaf740] [info] Dropping 2 messages from normal message queue.
[2024-02-20 19:30:04.035921] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.036095] [0x000071523bcaf740] [info] [2,"test_call_23","transactional",{"data":"test_call_23"}]
[2024-02-20 19:30:04.036122] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.036182] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.036385] [0x000071523bcaf740] [info] [2,"test_call_24","transactional",{"data":"test_call_24"}]
[2024-02-20 19:30:04.036415] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.036467] [0x000071523bcaf740] [info] Queue sizes exceed threshold (20) with 10 transaction and 11 normal messages in queue
[2024-02-20 19:30:04.036483] [0x000071523bcaf740] [info] Dropping 2 messages from normal message queue.
[2024-02-20 19:30:04.036521] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.036631] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.036657] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.036729] [0x000071522bfff6c0] [info] There are 9 messages in the normal message queue.
[2024-02-20 19:30:04.036763] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.036779] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.036822] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.031Z
[2024-02-20 19:30:04.036839] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.036865] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.036879] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.036897] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_6 attempt#: 0
[2024-02-20 19:30:04.037109] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_6
[2024-02-20 19:30:04.037150] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.037370] [0x000071522bfff6c0] [info] There are 8 messages in the normal message queue.
[2024-02-20 19:30:04.037396] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.037409] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.037447] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.032Z
[2024-02-20 19:30:04.037462] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.037486] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.037500] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.037515] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_7 attempt#: 0
[2024-02-20 19:30:04.037678] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_7
[2024-02-20 19:30:04.037722] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.037944] [0x000071522bfff6c0] [info] There are 7 messages in the normal message queue.
[2024-02-20 19:30:04.037978] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.038000] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.038066] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.032Z
[2024-02-20 19:30:04.038092] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.038146] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.038170] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.038200] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_8 attempt#: 0
[2024-02-20 19:30:04.038458] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_8
[2024-02-20 19:30:04.038511] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.038733] [0x000071522bfff6c0] [info] There are 6 messages in the normal message queue.
[2024-02-20 19:30:04.038764] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.038777] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.038818] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.032Z
[2024-02-20 19:30:04.038834] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.038860] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.038873] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.038891] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_9 attempt#: 0
[2024-02-20 19:30:04.039068] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_9
[2024-02-20 19:30:04.039108] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.039397] [0x000071522bfff6c0] [info] There are 5 messages in the normal message queue.
[2024-02-20 19:30:04.039416] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.039427] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.039462] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.032Z
[2024-02-20 19:30:04.039475] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.039498] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.039511] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.039526] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_10 attempt#: 0
[2024-02-20 19:30:04.039685] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_10
[2024-02-20 19:30:04.039721] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.040072] [0x000071522bfff6c0] [info] There are 4 messages in the normal message queue.
[2024-02-20 19:30:04.040103] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.040115] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.040156] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.032Z
[2024-02-20 19:30:04.040172] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.040197] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.040211] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.040228] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_11 attempt#: 0
[2024-02-20 19:30:04.040400] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_11
[2024-02-20 19:30:04.040439] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.040637] [0x000071522bfff6c0] [info] There are 3 messages in the normal message queue.
[2024-02-20 19:30:04.040661] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.040672] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.040711] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.040726] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.040751] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.040767] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.040784] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_12 attempt#: 0
[2024-02-20 19:30:04.040947] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_12
[2024-02-20 19:30:04.040985] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.041228] [0x000071522bfff6c0] [info] There are 2 messages in the normal message queue.
[2024-02-20 19:30:04.041259] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.041272] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.041316] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.041333] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.041358] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.041372] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.041390] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_13 attempt#: 0
[2024-02-20 19:30:04.041566] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_13
[2024-02-20 19:30:04.041606] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.041810] [0x000071522bfff6c0] [info] There are 1 messages in the normal message queue.
[2024-02-20 19:30:04.041841] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.041853] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.041895] [0x000071522bfff6c0] [info] normal msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.041911] [0x000071522bfff6c0] [info] normal message timestamp <= now
[2024-02-20 19:30:04.041936] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.041949] [0x000071522bfff6c0] [info] Prioritizing newer normal message over older transaction message
[2024-02-20 19:30:04.041967] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_14 attempt#: 0
[2024-02-20 19:30:04.042172] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_14
[2024-02-20 19:30:04.042227] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.042457] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.042487] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.042500] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.042544] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.042560] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.042577] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_15 attempt#: 0
[2024-02-20 19:30:04.042743] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_15
[2024-02-20 19:30:04.042781] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.043078] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.043095] [0x000071522bfff6c0] [info] There are 9 messages in the transaction message queue.
[2024-02-20 19:30:04.043105] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.043141] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.033Z
[2024-02-20 19:30:04.043154] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.043169] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_16 attempt#: 0
[2024-02-20 19:30:04.043320] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_16
[2024-02-20 19:30:04.043355] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.043725] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.043755] [0x000071522bfff6c0] [info] There are 8 messages in the transaction message queue.
[2024-02-20 19:30:04.043767] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.043807] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.034Z
[2024-02-20 19:30:04.043821] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.043839] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_17 attempt#: 0
[2024-02-20 19:30:04.044010] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_17
[2024-02-20 19:30:04.044052] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.044354] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.044386] [0x000071522bfff6c0] [info] There are 7 messages in the transaction message queue.
[2024-02-20 19:30:04.044397] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.044436] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.034Z
[2024-02-20 19:30:04.044455] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.044471] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_18 attempt#: 0
[2024-02-20 19:30:04.044626] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_18
[2024-02-20 19:30:04.044662] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.045044] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.045082] [0x000071522bfff6c0] [info] There are 6 messages in the transaction message queue.
[2024-02-20 19:30:04.045095] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.045137] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.034Z
[2024-02-20 19:30:04.045151] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.045168] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_19 attempt#: 0
[2024-02-20 19:30:04.045330] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_19
[2024-02-20 19:30:04.045368] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.045641] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.045671] [0x000071522bfff6c0] [info] There are 5 messages in the transaction message queue.
[2024-02-20 19:30:04.045683] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.045724] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.035Z
[2024-02-20 19:30:04.045740] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.045758] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_20 attempt#: 0
[2024-02-20 19:30:04.045918] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_20
[2024-02-20 19:30:04.045956] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.046239] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.046256] [0x000071522bfff6c0] [info] There are 4 messages in the transaction message queue.
[2024-02-20 19:30:04.046266] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.046302] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.035Z
[2024-02-20 19:30:04.046318] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.046333] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_21 attempt#: 0
[2024-02-20 19:30:04.046484] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_21
[2024-02-20 19:30:04.046519] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.046804] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.046833] [0x000071522bfff6c0] [info] There are 3 messages in the transaction message queue.
[2024-02-20 19:30:04.046845] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.046885] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.035Z
[2024-02-20 19:30:04.046899] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.046916] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_22 attempt#: 0
[2024-02-20 19:30:04.047086] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_22
[2024-02-20 19:30:04.047123] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.047499] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.047549] [0x000071522bfff6c0] [info] There are 2 messages in the transaction message queue.
[2024-02-20 19:30:04.047587] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.047674] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.036Z
[2024-02-20 19:30:04.047699] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.047718] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_23 attempt#: 0
[2024-02-20 19:30:04.047884] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_23
[2024-02-20 19:30:04.047923] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.048158] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.048201] [0x000071522bfff6c0] [info] There are 1 messages in the transaction message queue.
[2024-02-20 19:30:04.048222] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.048296] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.036Z
[2024-02-20 19:30:04.048329] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.048364] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_24 attempt#: 0
[2024-02-20 19:30:04.048569] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_24
[2024-02-20 19:30:04.048626] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.098828] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:04.098971] [0x000071522bfff6c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:04.099145] [0x000071523bcaf740] [info] stop() notified message queue
[ OK ] MessageQueueTest.test_clean_up_non_transactional_queue (70 ms)
[ RUN ] MessageQueueTest.test_clean_up_transactional_queue
[2024-02-20 19:30:04.101273] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.101306] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.101347] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:04.101357] [0x0000715239ca76c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.101408] [0x0000715239ca76c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:04.101549] [0x000071523bcaf740] [info] stop() notified message queue
[2024-02-20 19:30:04.102264] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.102596] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.102632] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.102746] [0x000071523bcaf740] [info] pause()
[2024-02-20 19:30:04.102774] [0x000071523bcaf740] [info] pause() notified message queue
[2024-02-20 19:30:04.102901] [0x000071523bcaf740] [info] [2,"test_call_0","non_transactional",{"data":"test_call_0"}]
[2024-02-20 19:30:04.102932] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.102954] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.103053] [0x000071523bcaf740] [info] [2,"test_call_1","non_transactional",{"data":"test_call_1"}]
[2024-02-20 19:30:04.103078] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.103098] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.103190] [0x000071523bcaf740] [info] [2,"test_call_2","non_transactional",{"data":"test_call_2"}]
[2024-02-20 19:30:04.103214] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.103234] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.103325] [0x000071523bcaf740] [info] [2,"test_call_3","non_transactional",{"data":"test_call_3"}]
[2024-02-20 19:30:04.103349] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.103412] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.103521] [0x000071523bcaf740] [info] [2,"test_call_4","non_transactional",{"data":"test_call_4"}]
[2024-02-20 19:30:04.103546] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.103565] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.103656] [0x000071523bcaf740] [info] [2,"test_call_5","non_transactional",{"data":"test_call_5"}]
[2024-02-20 19:30:04.103679] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.103702] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.103792] [0x000071523bcaf740] [info] [2,"test_call_6","non_transactional",{"data":"test_call_6"}]
[2024-02-20 19:30:04.103817] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.103836] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.103926] [0x000071523bcaf740] [info] [2,"test_call_7","non_transactional",{"data":"test_call_7"}]
[2024-02-20 19:30:04.103948] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.103969] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.104061] [0x000071523bcaf740] [info] [2,"test_call_8","non_transactional",{"data":"test_call_8"}]
[2024-02-20 19:30:04.104085] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.104105] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.104196] [0x000071523bcaf740] [info] [2,"test_call_9","non_transactional",{"data":"test_call_9"}]
[2024-02-20 19:30:04.104220] [0x000071523bcaf740] [info] Adding message to normal message queue
[2024-02-20 19:30:04.104241] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.104331] [0x000071523bcaf740] [info] [2,"test_call_10","transactional",{"data":"test_call_10"}]
[2024-02-20 19:30:04.104356] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.104434] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.104651] [0x000071523bcaf740] [info] [2,"test_call_11","transactional",{"data":"test_call_11"}]
[2024-02-20 19:30:04.104682] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.104753] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.104958] [0x000071523bcaf740] [info] [2,"test_call_12","transactional",{"data":"test_call_12"}]
[2024-02-20 19:30:04.104987] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.105057] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.105259] [0x000071523bcaf740] [info] [2,"test_call_13","transactional_update",{"data":"test_call_13"}]
[2024-02-20 19:30:04.105292] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.105351] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 4 transaction and 10 normal messages in queue
[2024-02-20 19:30:04.105370] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.105411] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.105513] [0x000071523bcaf740] [info] [2,"test_call_14","transactional_update",{"data":"test_call_14"}]
[2024-02-20 19:30:04.105538] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.105591] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 5 transaction and 9 normal messages in queue
[2024-02-20 19:30:04.105609] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.105646] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.105746] [0x000071523bcaf740] [info] [2,"test_call_15","transactional_update",{"data":"test_call_15"}]
[2024-02-20 19:30:04.105770] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.105822] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 6 transaction and 8 normal messages in queue
[2024-02-20 19:30:04.105840] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.105877] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.105976] [0x000071523bcaf740] [info] [2,"test_call_16","transactional_update",{"data":"test_call_16"}]
[2024-02-20 19:30:04.106000] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.106052] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 7 transaction and 7 normal messages in queue
[2024-02-20 19:30:04.106070] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.106106] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.106301] [0x000071523bcaf740] [info] [2,"test_call_17","transactional",{"data":"test_call_17"}]
[2024-02-20 19:30:04.106331] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.106385] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 8 transaction and 6 normal messages in queue
[2024-02-20 19:30:04.106403] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.106441] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.106636] [0x000071523bcaf740] [info] [2,"test_call_18","transactional",{"data":"test_call_18"}]
[2024-02-20 19:30:04.106664] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.106719] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 9 transaction and 5 normal messages in queue
[2024-02-20 19:30:04.106737] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.106775] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.106969] [0x000071523bcaf740] [info] [2,"test_call_19","transactional_update",{"data":"test_call_19"}]
[2024-02-20 19:30:04.106999] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.107055] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 10 transaction and 4 normal messages in queue
[2024-02-20 19:30:04.107072] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.107110] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.107242] [0x000071523bcaf740] [info] [2,"test_call_20","transactional_update",{"data":"test_call_20"}]
[2024-02-20 19:30:04.107283] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.107400] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 11 transaction and 3 normal messages in queue
[2024-02-20 19:30:04.107437] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.107504] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.107621] [0x000071523bcaf740] [info] [2,"test_call_21","transactional_update",{"data":"test_call_21"}]
[2024-02-20 19:30:04.107648] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.107706] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 12 transaction and 2 normal messages in queue
[2024-02-20 19:30:04.107725] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.107764] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.107863] [0x000071523bcaf740] [info] [2,"test_call_22","transactional_update",{"data":"test_call_22"}]
[2024-02-20 19:30:04.107888] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.107940] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 13 transaction and 1 normal messages in queue
[2024-02-20 19:30:04.107957] [0x000071523bcaf740] [info] Dropping 1 messages from normal message queue.
[2024-02-20 19:30:04.107993] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.108197] [0x000071523bcaf740] [info] [2,"test_call_23","transactional_update",{"data":"test_call_23"}]
[2024-02-20 19:30:04.108227] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.108283] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 14 transaction and 0 normal messages in queue
[2024-02-20 19:30:04.108311] [0x000071523bcaf740] [info] Drop transactional message test_call_13
[2024-02-20 19:30:04.108404] [0x000071523bcaf740] [info] Drop transactional message test_call_15
[2024-02-20 19:30:04.108503] [0x000071523bcaf740] [info] Drop transactional message test_call_19
[2024-02-20 19:30:04.108604] [0x000071523bcaf740] [info] Drop transactional message test_call_21
[2024-02-20 19:30:04.108692] [0x000071523bcaf740] [info] Dropped 4 transactional update messages to reduce queue size.
[2024-02-20 19:30:04.108737] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.108850] [0x000071523bcaf740] [info] [2,"test_call_24","transactional_update",{"data":"test_call_24"}]
[2024-02-20 19:30:04.108877] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.108948] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.109155] [0x000071523bcaf740] [info] [2,"test_call_25","transactional",{"data":"test_call_25"}]
[2024-02-20 19:30:04.109186] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.109256] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.109459] [0x000071523bcaf740] [info] [2,"test_call_26","transactional",{"data":"test_call_26"}]
[2024-02-20 19:30:04.109488] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.109559] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.109756] [0x000071523bcaf740] [info] [2,"test_call_27","transactional_update",{"data":"test_call_27"}]
[2024-02-20 19:30:04.109785] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.109841] [0x000071523bcaf740] [info] Queue sizes exceed threshold (13) with 14 transaction and 0 normal messages in queue
[2024-02-20 19:30:04.109868] [0x000071523bcaf740] [info] Drop transactional message test_call_14
[2024-02-20 19:30:04.109919] [0x000071523bcaf740] [info] Drop transactional message test_call_20
[2024-02-20 19:30:04.109962] [0x000071523bcaf740] [info] Drop transactional message test_call_23
[2024-02-20 19:30:04.110003] [0x000071523bcaf740] [info] Dropped 3 transactional update messages to reduce queue size.
[2024-02-20 19:30:04.110037] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.110238] [0x000071523bcaf740] [info] [2,"test_call_28","transactional_update",{"data":"test_call_28"}]
[2024-02-20 19:30:04.110269] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.110341] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.110538] [0x000071523bcaf740] [info] [2,"test_call_29","transactional",{"data":"test_call_29"}]
[2024-02-20 19:30:04.110568] [0x000071523bcaf740] [info] Adding message to transaction message queue
[2024-02-20 19:30:04.110643] [0x000071523bcaf740] [info] Notified message queue worker
[2024-02-20 19:30:04.110826] [0x000071523bcaf740] [info] resume() called
[2024-02-20 19:30:04.110883] [0x000071523bcaf740] [info] resume() notified message queue
[2024-02-20 19:30:04.110956] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.111004] [0x000071522bfff6c0] [info] There are 13 messages in the transaction message queue.
[2024-02-20 19:30:04.111038] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.111145] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.104Z
[2024-02-20 19:30:04.111188] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.111223] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_10 attempt#: 0
[2024-02-20 19:30:04.111506] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_10
[2024-02-20 19:30:04.111557] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.111899] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.111949] [0x000071522bfff6c0] [info] There are 12 messages in the transaction message queue.
[2024-02-20 19:30:04.111980] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.112058] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.104Z
[2024-02-20 19:30:04.112093] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.112128] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_11 attempt#: 0
[2024-02-20 19:30:04.112408] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_11
[2024-02-20 19:30:04.112477] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.112723] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.112761] [0x000071522bfff6c0] [info] There are 11 messages in the transaction message queue.
[2024-02-20 19:30:04.112785] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.112855] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.104Z
[2024-02-20 19:30:04.112889] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.112943] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_12 attempt#: 0
[2024-02-20 19:30:04.113234] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_12
[2024-02-20 19:30:04.113303] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.113549] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.113586] [0x000071522bfff6c0] [info] There are 10 messages in the transaction message queue.
[2024-02-20 19:30:04.113613] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.113685] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.105Z
[2024-02-20 19:30:04.113721] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.113755] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_16 attempt#: 0
[2024-02-20 19:30:04.114036] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_16
[2024-02-20 19:30:04.114105] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.114372] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.114406] [0x000071522bfff6c0] [info] There are 9 messages in the transaction message queue.
[2024-02-20 19:30:04.114433] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.114498] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.106Z
[2024-02-20 19:30:04.114519] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.114538] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_17 attempt#: 0
[2024-02-20 19:30:04.114719] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_17
[2024-02-20 19:30:04.114765] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.115091] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.115125] [0x000071522bfff6c0] [info] There are 8 messages in the transaction message queue.
[2024-02-20 19:30:04.115140] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.115188] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.106Z
[2024-02-20 19:30:04.115207] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.115229] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_18 attempt#: 0
[2024-02-20 19:30:04.115476] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_18
[2024-02-20 19:30:04.115543] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.115815] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.115851] [0x000071522bfff6c0] [info] There are 7 messages in the transaction message queue.
[2024-02-20 19:30:04.115876] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.115953] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.107Z
[2024-02-20 19:30:04.115983] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.116016] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_22 attempt#: 0
[2024-02-20 19:30:04.116275] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_22
[2024-02-20 19:30:04.116333] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.116597] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.116630] [0x000071522bfff6c0] [info] There are 6 messages in the transaction message queue.
[2024-02-20 19:30:04.116654] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.116730] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.108Z
[2024-02-20 19:30:04.116760] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.116792] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_24 attempt#: 0
[2024-02-20 19:30:04.117047] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_24
[2024-02-20 19:30:04.117104] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.117333] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.117365] [0x000071522bfff6c0] [info] There are 5 messages in the transaction message queue.
[2024-02-20 19:30:04.117390] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.117465] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.109Z
[2024-02-20 19:30:04.117495] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.117527] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_25 attempt#: 0
[2024-02-20 19:30:04.117776] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_25
[2024-02-20 19:30:04.117833] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.118059] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.118090] [0x000071522bfff6c0] [info] There are 4 messages in the transaction message queue.
[2024-02-20 19:30:04.118114] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.118187] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.109Z
[2024-02-20 19:30:04.118217] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.118249] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_26 attempt#: 0
[2024-02-20 19:30:04.118493] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_26
[2024-02-20 19:30:04.118549] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.118784] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.118817] [0x000071522bfff6c0] [info] There are 3 messages in the transaction message queue.
[2024-02-20 19:30:04.118842] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.118917] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.109Z
[2024-02-20 19:30:04.118947] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.118979] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_27 attempt#: 0
[2024-02-20 19:30:04.119226] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_27
[2024-02-20 19:30:04.119282] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.119635] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.119670] [0x000071522bfff6c0] [info] There are 2 messages in the transaction message queue.
[2024-02-20 19:30:04.119685] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.119733] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.110Z
[2024-02-20 19:30:04.119750] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.119770] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_28 attempt#: 0
[2024-02-20 19:30:04.119956] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_28
[2024-02-20 19:30:04.120000] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.120295] [0x000071522bfff6c0] [info] There are 0 messages in the normal message queue.
[2024-02-20 19:30:04.120315] [0x000071522bfff6c0] [info] There are 1 messages in the transaction message queue.
[2024-02-20 19:30:04.120327] [0x000071522bfff6c0] [info] There is no message in flight, checking message queue for a new message.
[2024-02-20 19:30:04.120369] [0x000071522bfff6c0] [info] transaction msg timestamp: 2024-02-20T19:30:04.110Z
[2024-02-20 19:30:04.120384] [0x000071522bfff6c0] [info] transaction message timestamp <= now
[2024-02-20 19:30:04.120403] [0x000071522bfff6c0] [info] Attempting to send message to central system. UID: test_call_29 attempt#: 0
[2024-02-20 19:30:04.120570] [0x000071522bfff6c0] [info] Successfully sent message. UID: test_call_29
[2024-02-20 19:30:04.120610] [0x000071522bfff6c0] [info] Waiting for a message from the message queue
[2024-02-20 19:30:04.120664] [0x000071523bcaf740] [info] stop()
[2024-02-20 19:30:04.120850] [0x000071522bfff6c0] [info] Message queue stopped processing messages
[2024-02-20 19:30:04.120955] [0x000071523bcaf740] [info] stop() notified message queue
[ OK ] MessageQueueTest.test_clean_up_transactional_queue (21 ms)
[----------] 7 tests from MessageQueueTest (151 ms total)
[----------] Global test environment tear-down
[==========] 11 tests from 3 test suites ran. (157 ms total)
[ PASSED ] 11 tests.
<end of output>
Test time = 0.16 sec
----------------------------------------------------------
Test Passed.
"libocpp_common_unit_tests" end time: Feb 20 19:30 UTC
"libocpp_common_unit_tests" time elapsed: 00:00:00
----------------------------------------------------------
3/5 Testing: libocpp_v201_unit_tests
3/5 Test: libocpp_v201_unit_tests
Command: "/workspaces/libocpp/build/tests/libocpp_v201_unit_tests"
Directory: /workspaces/libocpp/build/tests
"libocpp_v201_unit_tests" start time: Feb 20 19:30 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 23 tests from 4 test suites.
[----------] Global test environment set-up.
[----------] 12 tests from ComponentStateManagerTest
[ RUN ] ComponentStateManagerTest.test_boot_empty_db
[2024-02-20 19:30:04.141564] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[2024-02-20 19:30:04.141614] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[2024-02-20 19:30:04.141636] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_boot_empty_db (0 ms)
[ RUN ] ComponentStateManagerTest.test_boot_recover_persisted_states
[2024-02-20 19:30:04.141855] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_boot_recover_persisted_states (0 ms)
[ RUN ] ComponentStateManagerTest.test_check_evse_and_connector_ids
[2024-02-20 19:30:04.142435] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_check_evse_and_connector_ids (0 ms)
[ RUN ] ComponentStateManagerTest.test_boot_missing_states_are_operative
[2024-02-20 19:30:04.142636] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_boot_missing_states_are_operative (0 ms)
[ RUN ] ComponentStateManagerTest.test_persist_operative_states
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: cs_op_state_update("Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: evse_op_state_update(1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: evse_op_state_update(2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(2, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(2, 2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-20 19:30:04.143004] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_persist_operative_states (0 ms)
[ RUN ] ComponentStateManagerTest.test_effective_state_getters_cs_inoperative
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: cs_op_state_update("Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: evse_op_state_update(1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: evse_op_state_update(2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(2, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(2, 2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Occupied")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(2, 1, "Reserved")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(2, 2, "Faulted")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-20 19:30:04.143430] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_effective_state_getters_cs_inoperative (0 ms)
[ RUN ] ComponentStateManagerTest.test_effective_state_getters_evse_inoperative
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: evse_op_state_update(1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Occupied")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(2, 1, "Reserved")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(2, 2, "Faulted")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-20 19:30:04.143752] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_effective_state_getters_evse_inoperative (0 ms)
[ RUN ] ComponentStateManagerTest.test_connector_state_machine
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Reserved")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Occupied")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Faulted")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Faulted")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Unavailable")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: connector_op_state_update(1, 1, "Operative")
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Occupied")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
GMOCK WARNING:
Uninteresting mock function call - returning default value.
Function call: connector_status_update(1, 1, "Reserved")
Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-20 19:30:04.144063] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_connector_state_machine (0 ms)
[ RUN ] ComponentStateManagerTest.test_effective_state_changed_callbacks
[2024-02-20 19:30:04.144907] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_effective_state_changed_callbacks (0 ms)
[ RUN ] ComponentStateManagerTest.test_trigger_boot_callbacks
[2024-02-20 19:30:04.145595] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_trigger_boot_callbacks (0 ms)
[ RUN ] ComponentStateManagerTest.test_send_status_notification_changed_connectors
[2024-02-20 19:30:04.146170] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_send_status_notification_changed_connectors (0 ms)
[ RUN ] ComponentStateManagerTest.test_send_status_notification_single_connector
[2024-02-20 19:30:04.146708] [0x000072e72d1c5740] [info] Successfully closed database: "/dev/null/cp.db"
[ OK ] ComponentStateManagerTest.test_send_status_notification_single_connector (0 ms)
[----------] 12 tests from ComponentStateManagerTest (5 ms total)
[----------] 2 tests from DeviceModelStorageSQLiteTest
[ RUN ] DeviceModelStorageSQLiteTest.test_check_integrity_valid
[2024-02-20 19:30:04.147568] [0x000072e72d1c5740] [info] Established connection to device model database successfully: "./resources/unittest_device_model.db"
[ OK ] DeviceModelStorageSQLiteTest.test_check_integrity_valid (1 ms)
[ RUN ] DeviceModelStorageSQLiteTest.test_check_integrity_invalid
[2024-02-20 19:30:04.148887] [0x000072e72d1c5740] [info] Established connection to device model database successfully: "./resources/unittest_device_model_missing_required.db"
[ OK ] DeviceModelStorageSQLiteTest.test_check_integrity_invalid (1 ms)
[----------] 2 tests from DeviceModelStorageSQLiteTest (3 ms total)
[----------] 4 tests from NotifyReportRequestsSplitterTest
[ RUN ] NotifyReportRequestsSplitterTest.test_create_single_request_no_report_data
[ OK ] NotifyReportRequestsSplitterTest.test_create_single_request_no_report_data (14 ms)
[ RUN ] NotifyReportRequestsSplitterTest.test_create_single_request
[ OK ] NotifyReportRequestsSplitterTest.test_create_single_request (1 ms)
[ RUN ] NotifyReportRequestsSplitterTest.test_create_split_request
[2024-02-20 19:30:04.166367] [0x000072e72d1c5740] [info] Split NotifyReportRequest '42' into 2 messages.
[ OK ] NotifyReportRequestsSplitterTest.test_create_split_request (1 ms)
[ RUN ] NotifyReportRequestsSplitterTest.test_splits_contains_at_least_one_report
[2024-02-20 19:30:04.167075] [0x000072e72d1c5740] [info] Split NotifyReportRequest '42' into 2 messages.
[ OK ] NotifyReportRequestsSplitterTest.test_splits_contains_at_least_one_report (0 ms)
[----------] 4 tests from NotifyReportRequestsSplitterTest (17 ms total)
[----------] 5 tests from OcspUpdaterTest
[ RUN ] OcspUpdaterTest.test_success_boot_many
[2024-02-20 19:30:04.167924] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.168052] [0x000072e72d1c16c0] [info] libocpp: Done updating OCSP cache
[ OK ] OcspUpdaterTest.test_success_boot_many (0 ms)
[ RUN ] OcspUpdaterTest.test_retry_boot_many
[2024-02-20 19:30:04.168757] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.168817] [0x000072e72d1c16c0] [info] libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry.
[2024-02-20 19:30:04.168897] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.168956] [0x000072e72d1c16c0] [info] libocpp: OCSP status update failed: CSMS sent an Accepted GetCertificateStatusResponse with no ocspResult, will retry.
[2024-02-20 19:30:04.169035] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.169141] [0x000072e72d1c16c0] [info] libocpp: Done updating OCSP cache
[ OK ] OcspUpdaterTest.test_retry_boot_many (0 ms)
[ RUN ] OcspUpdaterTest.test_reverify_logic
[2024-02-20 19:30:04.169642] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.169718] [0x000072e72d1c16c0] [info] libocpp: Done updating OCSP cache
[2024-02-20 19:30:04.169796] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.169860] [0x000072e72d1c16c0] [info] libocpp: Done updating OCSP cache
[ OK ] OcspUpdaterTest.test_reverify_logic (0 ms)
[ RUN ] OcspUpdaterTest.test_trigger
[2024-02-20 19:30:04.170329] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.170412] [0x000072e72d1c16c0] [info] libocpp: Done updating OCSP cache
[2024-02-20 19:30:04.170447] [0x000072e72d1c16c0] [info] libocpp: Updating OCSP cache on 3 certificates
[2024-02-20 19:30:04.170514] [0x000072e72d1c16c0] [info] libocpp: Done updating OCSP cache
[ OK ] OcspUpdaterTest.test_trigger (0 ms)
[ RUN ] OcspUpdaterTest.test_exception_trigger_when_not_running
[ OK ] OcspUpdaterTest.test_exception_trigger_when_not_running (0 ms)
[----------] 5 tests from OcspUpdaterTest (3 ms total)
[----------] Global test environment tear-down
[==========] 23 tests from 4 test suites ran. (30 ms total)
[ PASSED ] 23 tests.
<end of output>
Test time = 0.05 sec
----------------------------------------------------------
Test Passed.
"libocpp_v201_unit_tests" end time: Feb 20 19:30 UTC
"libocpp_v201_unit_tests" time elapsed: 00:00:00
----------------------------------------------------------
4/5 Testing: libocpp_v16_unit_tests
4/5 Test: libocpp_v16_unit_tests
Command: "/workspaces/libocpp/build/tests/libocpp_v16_unit_tests"
Directory: /workspaces/libocpp/build/tests
"libocpp_v16_unit_tests" start time: Feb 20 19:30 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 30 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 30 tests from ChargepointTestFixture
[ RUN ] ChargepointTestFixture.ValidateProfile
[2024-02-20 19:30:04.182575] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.182588] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[ OK ] ChargepointTestFixture.ValidateProfile (6 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__example1
[ OK ] ChargepointTestFixture.ValidateProfile__example1 (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ConnectorIdGreaterThanConnectorsSize__ReturnsFalse
[2024-02-20 19:30:04.182825] [0x00007a5e56592740] [info] INVALID PROFILE - connector_id invalid or invalid stack level
[ OK ] ChargepointTestFixture.ValidateProfile__ConnectorIdGreaterThanConnectorsSize__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_NegativeConnectorIdTest__ReturnsFalse
[2024-02-20 19:30:04.182918] [0x00007a5e56592740] [info] INVALID PROFILE - connector_id invalid or invalid stack level
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NegativeConnectorIdTest__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_ConnectorIdZero__ReturnsFalse
[2024-02-20 19:30:04.183012] [0x00007a5e56592740] [info] INVALID PROFILE - connector_id invalid or invalid stack level
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ConnectorIdZero__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_StackLevelGreaterThanMaxStackLevel__ReturnsFalse
[2024-02-20 19:30:04.183100] [0x00007a5e56592740] [info] INVALID PROFILE - connector_id invalid or invalid stack level
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_StackLevelGreaterThanMaxStackLevel__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindAbsoluteNoStartSchedule__ReturnsFalse
[2024-02-20 19:30:04.183181] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindAbsoluteNoStartSchedule__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_AllowsNoStartSchedule__ReturnsTrue
[2024-02-20 19:30:04.183268] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.183274] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_AllowsNoStartSchedule__ReturnsTrue (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_InstalledProfilesGreaterThanMaxInstalledProfiles__ReturnsFalse
[2024-02-20 19:30:04.183370] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.183377] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-20 19:30:04.183382] [0x00007a5e56592740] [info] Maximum amount of profiles are installed at the charging point
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_InstalledProfilesGreaterThanMaxInstalledProfiles__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_InvalidChargingSchedule__ReturnsFalse
[2024-02-20 19:30:04.183467] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.183473] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-20 19:30:04.183487] [0x00007a5e56592740] [info] INVALID SCHEDULE - ChargingRateUnit not supported: A
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_InvalidChargingSchedule__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoRecurrencyKind__ReturnsFalse
[2024-02-20 19:30:04.183572] [0x00007a5e56592740] [info] INVALID PROFILE - Recurring Profile Kind with no given RecurrencyKind
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoRecurrencyKind__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoStartSchedule__ReturnsFalse
[2024-02-20 19:30:04.183655] [0x00007a5e56592740] [info] INVALID PROFILE - Recurring Profile Kind with no startSchedule
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoStartSchedule__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_NoStartScheduleAllowedRelative__ReturnsTrue
[2024-02-20 19:30:04.183747] [0x00007a5e56592740] [info] INVALID PROFILE - Recurring Profile Kind with no startSchedule
[2024-02-20 19:30:04.183753] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NoStartScheduleAllowedRelative__ReturnsTrue (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__RecurringNoStartScheduleNotAllowed__ReturnsFalse
[2024-02-20 19:30:04.183828] [0x00007a5e56592740] [info] INVALID PROFILE - Recurring Profile Kind with no startSchedule
[ OK ] ChargepointTestFixture.ValidateProfile__RecurringNoStartScheduleNotAllowed__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorId0__ReturnsTrue
[2024-02-20 19:30:04.183918] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.183924] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorId0__ReturnsTrue (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorIdNot0__ReturnsFalse
[2024-02-20 19:30:04.184012] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.184018] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-20 19:30:04.184024] [0x00007a5e56592740] [info] INVALID PROFILE - connector_id != 0 with purpose ChargePointMaxProfile or kind is Relative
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorIdNot0__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__ValidProfileTxDefaultProfile__ReturnsTrue
[2024-02-20 19:30:04.184108] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.184114] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[ OK ] ChargepointTestFixture.ValidateProfile__ValidProfileTxDefaultProfile__ReturnsTrue (0 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileIgnoreNoTransaction__ReturnsTrue
[2024-02-20 19:30:04.235696] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.235719] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[ OK ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileIgnoreNoTransaction__ReturnsTrue (51 ms)
[ RUN ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileConnectorId0__ReturnsFalse
[2024-02-20 19:30:04.235923] [0x00007a5e56592740] [info] INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-20 19:30:04.235935] [0x00007a5e56592740] [info] Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-20 19:30:04.235945] [0x00007a5e56592740] [info] INVALID PROFILE - connector_id != 0 or no active transaction at this connector
[ OK ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileConnectorId0__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_DoNotCheckIdOnly__ReturnsFalse
[ OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_DoNotCheckIdOnly__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_CheckIdOnly__ReturnsFalse
[ OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_CheckIdOnly__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.ClearAllProfilesWithFilter__OnlyOneMatchingProfileId_CheckIdOnly__ReturnsTrue
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: insert_or_update_charging_profile(0, @0x7ffd77fcbab0 {
"chargingProfileId": 1,
"chargingProfileKind": "Absolute",
"chargingProfilePurpose": "TxDefaultProfile",
"chargingSchedule": {
"chargingRateUnit": "A",
"chargingSchedulePeriod": []
},
"recurrencyKind": "Daily",
"stackLevel": 1
})
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-20 19:30:04.236566] [0x00007a5e56592740] [info] Clearing ChargingProfile with id: 1
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: delete_charging_profile(1)
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[ OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__OnlyOneMatchingProfileId_CheckIdOnly__ReturnsTrue (0 ms)
[ RUN ] ChargepointTestFixture.ClearAllProfilesWithFilter__NoMatchingProfileId_CheckIdOnly__ReturnsFalse
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: insert_or_update_charging_profile(0, @0x7ffd77fcbab0 {
"chargingProfileId": 1,
"chargingProfileKind": "Absolute",
"chargingProfilePurpose": "TxDefaultProfile",
"chargingSchedule": {
"chargingRateUnit": "A",
"chargingSchedulePeriod": []
},
"recurrencyKind": "Daily",
"stackLevel": 1
})
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[ OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__NoMatchingProfileId_CheckIdOnly__ReturnsFalse (0 ms)
[ RUN ] ChargepointTestFixture.AddChargePointMaxProfile
GMOCK WARNING:
Uninteresting mock function call - returning directly.
Function call: insert_or_update_charging_profile(0, @0x7ffd77fcb990 {
"chargingProfileId": 1,
"chargingProfileKind": "Absolute",
"chargingProfilePurpose": "ChargePointMaxProfile",
"chargingSchedule": {
"chargingRateUnit": "A",
"chargingSchedulePeriod": []
},
"recurrencyKind": "Daily",
"stackLevel": 1
})
NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
<end of output>
Test time = 0.17 sec
----------------------------------------------------------
Test Failed.
"libocpp_v16_unit_tests" end time: Feb 20 19:30 UTC
"libocpp_v16_unit_tests" time elapsed: 00:00:00
----------------------------------------------------------
5/5 Testing: utils_tests
5/5 Test: utils_tests
Command: "/workspaces/libocpp/build/tests/utils_tests"
Directory: /workspaces/libocpp/build/tests
"utils_tests" start time: Feb 20 19:30 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from UtilsTest
[ RUN ] UtilsTest.test_valid_datetime
[ OK ] UtilsTest.test_valid_datetime (1 ms)
[ RUN ] UtilsTest.test_invalid_datetime
[ OK ] UtilsTest.test_invalid_datetime (0 ms)
[----------] 2 tests from UtilsTest (1 ms total)
[----------] Global test environment tear-down
[==========] 2 tests from 1 test suite ran. (1 ms total)
[ PASSED ] 2 tests.
<end of output>
Test time = 0.00 sec
----------------------------------------------------------
Test Passed.
"utils_tests" end time: Feb 20 19:30 UTC
"utils_tests" time elapsed: 00:00:00
----------------------------------------------------------
End testing: Feb 20 19:30 UTC
@folkengine
Copy link
Author

[0/1] Running tests...
Test project /workspaces/libocpp/build
Start 1: database_tests
1/5 Test #1: database_tests ................... Passed 1.41 sec
Start 2: libocpp_common_unit_tests
2/5 Test #2: libocpp_common_unit_tests ........ Passed 0.16 sec
Start 3: libocpp_v201_unit_tests
3/5 Test #3: libocpp_v201_unit_tests .......... Passed 0.05 sec
Start 4: libocpp_v16_unit_tests
4/5 Test #4: libocpp_v16_unit_tests ...........***Exception: SegFault 0.17 sec
Start 5: utils_tests
5/5 Test #5: utils_tests ...................... Passed 0.00 sec

80% tests passed, 1 tests failed out of 5

@folkengine
Copy link
Author

Linux 22dc33651c94 6.6.10-76060610-generic #202401051437170472813122.04~24d69e2 SMP PREEMPT_DYNAMIC Mon J x86_64 GNU/Linux

@folkengine
Copy link
Author

folkengine commented Feb 21, 2024

----------------------------------------------------------
1/5 Testing: database_tests
1/5 Test: database_tests
Command: "/workspaces/libocpp/build/tests/database_tests"
Directory: /workspaces/libocpp/build/tests
"database_tests" start time: Feb 21 17:58 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 15 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 15 tests from DatabaseTest
[ RUN      ] DatabaseTest.test_init_connector_table
[2024-02-21 17:58:26.641100] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:26.641113] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:26.733120] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_init_connector_table (92 ms)
[ RUN      ] DatabaseTest.test_list_version
[2024-02-21 17:58:26.733243] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:26.733250] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:26.827530] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_list_version (94 ms)
[ RUN      ] DatabaseTest.test_local_authorization_list_entry_1
[2024-02-21 17:58:26.827634] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:26.827640] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:26.915102] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_local_authorization_list_entry_1 (87 ms)
[ RUN      ] DatabaseTest.test_local_authorization_list_entry_2
[2024-02-21 17:58:26.915196] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:26.915202] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.005610] [0x00007e9158f4c740] [info]    IdTag DEADBEEF in auth list has expiry date in the past, setting entry to expired.
[2024-02-21 17:58:27.009760] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_local_authorization_list_entry_2 (94 ms)
[ RUN      ] DatabaseTest.test_local_authorization_list
[2024-02-21 17:58:27.009853] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.009859] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.110420] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_local_authorization_list (100 ms)
[ RUN      ] DatabaseTest.test_clear_authorization_list
[2024-02-21 17:58:27.110518] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.110524] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.199999] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_clear_authorization_list (89 ms)
[ RUN      ] DatabaseTest.test_authorization_cache_entry
[2024-02-21 17:58:27.200096] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.200102] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.286045] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_authorization_cache_entry (86 ms)
[ RUN      ] DatabaseTest.test_authorization_cache_entry_2
[2024-02-21 17:58:27.286153] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.286159] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.373673] [0x00007e9158f4c740] [info]    IdTag DEADBEEF in auth cache has expiry date in the past, setting entry to expired.
[2024-02-21 17:58:27.378471] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_authorization_cache_entry_2 (92 ms)
[ RUN      ] DatabaseTest.test_clear_authorization_cache
[2024-02-21 17:58:27.378567] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.378573] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.470455] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_clear_authorization_cache (91 ms)
[ RUN      ] DatabaseTest.test_connector_availability
[2024-02-21 17:58:27.470553] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.470559] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.562356] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_connector_availability (91 ms)
[ RUN      ] DatabaseTest.test_insert_and_get_transaction
[2024-02-21 17:58:27.562454] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.562460] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.665989] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_insert_and_get_transaction (103 ms)
[ RUN      ] DatabaseTest.test_insert_and_get_transaction_without_id_tag
[2024-02-21 17:58:27.666231] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.666260] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.769150] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_insert_and_get_transaction_without_id_tag (103 ms)
[ RUN      ] DatabaseTest.test_insert_and_get_profiles
[2024-02-21 17:58:27.769333] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.769344] [0x00007e9158f4c740] [info]    Running SQL initialization script.
/workspaces/libocpp/tests/database_tests.cpp:312: Skipped
validFrom/validTo checks are failing. See https://github.com/EVerest/libocpp/issues/384
[2024-02-21 17:58:27.855199] [0x00007e9158f4c740] [info]    Successfully closed database file
[  SKIPPED ] DatabaseTest.test_insert_and_get_profiles (86 ms)
[ RUN      ] DatabaseTest.test_update_profile_same_profile_id
[2024-02-21 17:58:27.855292] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.855299] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:27.964244] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_update_profile_same_profile_id (109 ms)
[ RUN      ] DatabaseTest.test_delete_profile
[2024-02-21 17:58:27.964347] [0x00007e9158f4c740] [info]    Established connection to Database.
[2024-02-21 17:58:27.964353] [0x00007e9158f4c740] [info]    Running SQL initialization script.
[2024-02-21 17:58:28.075126] [0x00007e9158f4c740] [info]    Successfully closed database file
[       OK ] DatabaseTest.test_delete_profile (110 ms)
[----------] 15 tests from DatabaseTest (1434 ms total)

[----------] Global test environment tear-down
[==========] 15 tests from 1 test suite ran. (1434 ms total)
[  PASSED  ] 14 tests.
[  SKIPPED ] 1 test, listed below:
[  SKIPPED ] DatabaseTest.test_insert_and_get_profiles
<end of output>
Test time =   1.44 sec
----------------------------------------------------------
Test Passed.
"database_tests" end time: Feb 21 17:58 UTC
"database_tests" time elapsed: 00:00:01
----------------------------------------------------------

2/5 Testing: libocpp_common_unit_tests
2/5 Test: libocpp_common_unit_tests
Command: "/workspaces/libocpp/build/tests/libocpp_common_unit_tests"
Directory: /workspaces/libocpp/build/tests
"libocpp_common_unit_tests" start time: Feb 21 17:58 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 6 tests from 3 test suites.
[----------] Global test environment set-up.
[----------] 2 tests from ControlMessageV16Test
[ RUN      ] ControlMessageV16Test.test_is_transactional
[       OK ] ControlMessageV16Test.test_is_transactional (5 ms)
[ RUN      ] ControlMessageV16Test.test_is_transactional_update
[       OK ] ControlMessageV16Test.test_is_transactional_update (0 ms)
[----------] 2 tests from ControlMessageV16Test (5 ms total)

[----------] 2 tests from ControlMessageV201Test
[ RUN      ] ControlMessageV201Test.test_is_transactional
[       OK ] ControlMessageV201Test.test_is_transactional (0 ms)
[ RUN      ] ControlMessageV201Test.test_is_transactional_update
[       OK ] ControlMessageV201Test.test_is_transactional_update (0 ms)
[----------] 2 tests from ControlMessageV201Test (0 ms total)

[----------] 2 tests from MessageQueueTest
[ RUN      ] MessageQueueTest.test_queuing_up_of_transactional_messages_REDUX
[2024-02-21 17:58:28.084901] [0x000074d48623b740] [info]    resume() called
[2024-02-21 17:58:28.084913] [0x000074d48623b740] [info]    resume() notified message queue
[2024-02-21 17:58:28.084946] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.084991] [0x000074d48623b740] [info]    [2,"test_call_0","transactional",{"data":"test_call_0"}]
[2024-02-21 17:58:28.084999] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.085022] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.085071] [0x000074d4842336c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.085079] [0x000074d4842336c0] [info]    There are 1 messages in the transaction message queue.
[2024-02-21 17:58:28.085082] [0x000074d4842336c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.085093] [0x000074d4842336c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.084Z
[2024-02-21 17:58:28.085097] [0x000074d4842336c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.085102] [0x000074d4842336c0] [info]    Attempting to send message to central system. UID: test_call_0 attempt#: 0
[2024-02-21 17:58:28.085130] [0x000074d4842336c0] [info]    Could not send message, this is most likely because the charge point is offline.
[2024-02-21 17:58:28.085134] [0x000074d4842336c0] [info]    The message in flight is transaction related and will be sent again once the connection can be established again.
[2024-02-21 17:58:28.085140] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.085196] [0x000074d48623b740] [info]    [2,"test_call_1","transactional",{"data":"test_call_1"}]
[2024-02-21 17:58:28.085205] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.085224] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.085249] [0x000074d48623b740] [info]    [2,"test_call_2","transactional",{"data":"test_call_2"}]
[2024-02-21 17:58:28.085254] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.085267] [0x000074d48623b740] [info]    Queue sizes exceed threshold (2) with 3 transaction and 0 normal messages in queue
[2024-02-21 17:58:28.085272] [0x000074d48623b740] [info]    There are no further transaction update messages to drop!
[2024-02-21 17:58:28.085279] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.085302] [0x000074d48623b740] [info]    [2,"test_call_3","transactional",{"data":"test_call_3"}]
[2024-02-21 17:58:28.085307] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.085319] [0x000074d48623b740] [info]    Queue sizes exceed threshold (2) with 4 transaction and 0 normal messages in queue
[2024-02-21 17:58:28.085324] [0x000074d48623b740] [info]    There are no further transaction update messages to drop!
[2024-02-21 17:58:28.085331] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.085353] [0x000074d48623b740] [info]    [2,"test_call_4","transactional",{"data":"test_call_4"}]
[2024-02-21 17:58:28.085358] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.085370] [0x000074d48623b740] [info]    Queue sizes exceed threshold (2) with 5 transaction and 0 normal messages in queue
[2024-02-21 17:58:28.085375] [0x000074d48623b740] [info]    There are no further transaction update messages to drop!
[2024-02-21 17:58:28.085381] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.085388] [0x000074d48623b740] [info]    resume() called
[2024-02-21 17:58:28.085393] [0x000074d48623b740] [info]    resume() notified message queue
[2024-02-21 17:58:28.085401] [0x000074d4842336c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.085405] [0x000074d4842336c0] [info]    There are 5 messages in the transaction message queue.
[2024-02-21 17:58:28.085407] [0x000074d4842336c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.085417] [0x000074d4842336c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.084Z
[2024-02-21 17:58:28.085420] [0x000074d4842336c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.085425] [0x000074d4842336c0] [info]    Attempting to send message to central system. UID: test_call_0 attempt#: 1
[2024-02-21 17:58:28.085465] [0x000074d4842336c0] [info]    Successfully sent message. UID: test_call_0
[2024-02-21 17:58:28.085477] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.085599] [0x000074d4842336c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.085602] [0x000074d4842336c0] [info]    There are 4 messages in the transaction message queue.
[2024-02-21 17:58:28.085605] [0x000074d4842336c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.085614] [0x000074d4842336c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.085Z
[2024-02-21 17:58:28.085618] [0x000074d4842336c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.085622] [0x000074d4842336c0] [info]    Attempting to send message to central system. UID: test_call_1 attempt#: 0
[2024-02-21 17:58:28.085654] [0x000074d4842336c0] [info]    Successfully sent message. UID: test_call_1
[2024-02-21 17:58:28.085663] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.085744] [0x000074d4842336c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.085748] [0x000074d4842336c0] [info]    There are 3 messages in the transaction message queue.
[2024-02-21 17:58:28.085750] [0x000074d4842336c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.085759] [0x000074d4842336c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.085Z
[2024-02-21 17:58:28.085762] [0x000074d4842336c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.085765] [0x000074d4842336c0] [info]    Attempting to send message to central system. UID: test_call_2 attempt#: 0
[2024-02-21 17:58:28.085796] [0x000074d4842336c0] [info]    Successfully sent message. UID: test_call_2
[2024-02-21 17:58:28.085805] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.085839] [0x000074d4842336c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.085842] [0x000074d4842336c0] [info]    There are 2 messages in the transaction message queue.
[2024-02-21 17:58:28.085845] [0x000074d4842336c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.085853] [0x000074d4842336c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.085Z
[2024-02-21 17:58:28.085857] [0x000074d4842336c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.085861] [0x000074d4842336c0] [info]    Attempting to send message to central system. UID: test_call_3 attempt#: 0
[2024-02-21 17:58:28.085891] [0x000074d4842336c0] [info]    Successfully sent message. UID: test_call_3
[2024-02-21 17:58:28.085900] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.085931] [0x000074d4842336c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.085935] [0x000074d4842336c0] [info]    There are 1 messages in the transaction message queue.
[2024-02-21 17:58:28.085937] [0x000074d4842336c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.085946] [0x000074d4842336c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.085Z
[2024-02-21 17:58:28.085949] [0x000074d4842336c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.085953] [0x000074d4842336c0] [info]    Attempting to send message to central system. UID: test_call_4 attempt#: 0
[2024-02-21 17:58:28.085984] [0x000074d4842336c0] [info]    Successfully sent message. UID: test_call_4
[2024-02-21 17:58:28.085990] [0x000074d48623b740] [info]    stop()
[2024-02-21 17:58:28.086000] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.086023] [0x000074d4842336c0] [info]    Message queue stopped processing messages
[2024-02-21 17:58:28.086057] [0x000074d48623b740] [info]    stop() notified message queue
[       OK ] MessageQueueTest.test_queuing_up_of_transactional_messages_REDUX (1 ms)
[ RUN      ] MessageQueueTest.test_clean_up_transactional_queue_REDUX
[2024-02-21 17:58:28.086434] [0x000074d48623b740] [info]    resume() called
[2024-02-21 17:58:28.086441] [0x000074d48623b740] [info]    resume() notified message queue
[2024-02-21 17:58:28.086463] [0x000074d4842336c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.086475] [0x000074d48623b740] [info]    stop()
[2024-02-21 17:58:28.086487] [0x000074d4842336c0] [info]    Message queue stopped processing messages
[2024-02-21 17:58:28.086504] [0x000074d48623b740] [info]    stop() notified message queue
[2024-02-21 17:58:28.086703] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.086816] [0x000074d48623b740] [info]    resume() called
[2024-02-21 17:58:28.086827] [0x000074d48623b740] [info]    resume() notified message queue
[2024-02-21 17:58:28.086868] [0x000074d48623b740] [info]    pause()
[2024-02-21 17:58:28.086878] [0x000074d48623b740] [info]    pause() notified message queue
[2024-02-21 17:58:28.086916] [0x000074d48623b740] [info]    [2,"test_call_0","non_transactional",{"data":"test_call_0"}]
[2024-02-21 17:58:28.086930] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.086940] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.086982] [0x000074d48623b740] [info]    [2,"test_call_1","non_transactional",{"data":"test_call_1"}]
[2024-02-21 17:58:28.086992] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087001] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087041] [0x000074d48623b740] [info]    [2,"test_call_2","non_transactional",{"data":"test_call_2"}]
[2024-02-21 17:58:28.087052] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087061] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087102] [0x000074d48623b740] [info]    [2,"test_call_3","non_transactional",{"data":"test_call_3"}]
[2024-02-21 17:58:28.087112] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087121] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087164] [0x000074d48623b740] [info]    [2,"test_call_4","non_transactional",{"data":"test_call_4"}]
[2024-02-21 17:58:28.087174] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087184] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087225] [0x000074d48623b740] [info]    [2,"test_call_5","non_transactional",{"data":"test_call_5"}]
[2024-02-21 17:58:28.087235] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087248] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087288] [0x000074d48623b740] [info]    [2,"test_call_6","non_transactional",{"data":"test_call_6"}]
[2024-02-21 17:58:28.087299] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087307] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087348] [0x000074d48623b740] [info]    [2,"test_call_7","non_transactional",{"data":"test_call_7"}]
[2024-02-21 17:58:28.087385] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087396] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087455] [0x000074d48623b740] [info]    [2,"test_call_8","non_transactional",{"data":"test_call_8"}]
[2024-02-21 17:58:28.087466] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087474] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087516] [0x000074d48623b740] [info]    [2,"test_call_9","non_transactional",{"data":"test_call_9"}]
[2024-02-21 17:58:28.087528] [0x000074d48623b740] [info]    Adding message to normal message queue
[2024-02-21 17:58:28.087538] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087579] [0x000074d48623b740] [info]    [2,"test_call_10","transactional",{"data":"test_call_10"}]
[2024-02-21 17:58:28.087589] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.087618] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087695] [0x000074d48623b740] [info]    [2,"test_call_11","transactional",{"data":"test_call_11"}]
[2024-02-21 17:58:28.087706] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.087735] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087810] [0x000074d48623b740] [info]    [2,"test_call_12","transactional",{"data":"test_call_12"}]
[2024-02-21 17:58:28.087820] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.087849] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.087925] [0x000074d48623b740] [info]    [2,"test_call_13","transactional_update",{"data":"test_call_13"}]
[2024-02-21 17:58:28.087936] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.087962] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 4 transaction and 10 normal messages in queue
[2024-02-21 17:58:28.087971] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.087987] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088029] [0x000074d48623b740] [info]    [2,"test_call_14","transactional_update",{"data":"test_call_14"}]
[2024-02-21 17:58:28.088039] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088063] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 5 transaction and 9 normal messages in queue
[2024-02-21 17:58:28.088073] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088089] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088130] [0x000074d48623b740] [info]    [2,"test_call_15","transactional_update",{"data":"test_call_15"}]
[2024-02-21 17:58:28.088140] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088166] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 6 transaction and 8 normal messages in queue
[2024-02-21 17:58:28.088175] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088191] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088232] [0x000074d48623b740] [info]    [2,"test_call_16","transactional_update",{"data":"test_call_16"}]
[2024-02-21 17:58:28.088242] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088267] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 7 transaction and 7 normal messages in queue
[2024-02-21 17:58:28.088276] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088292] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088365] [0x000074d48623b740] [info]    [2,"test_call_17","transactional",{"data":"test_call_17"}]
[2024-02-21 17:58:28.088376] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088401] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 8 transaction and 6 normal messages in queue
[2024-02-21 17:58:28.088410] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088426] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088500] [0x000074d48623b740] [info]    [2,"test_call_18","transactional",{"data":"test_call_18"}]
[2024-02-21 17:58:28.088511] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088536] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 9 transaction and 5 normal messages in queue
[2024-02-21 17:58:28.088545] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088561] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088635] [0x000074d48623b740] [info]    [2,"test_call_19","transactional_update",{"data":"test_call_19"}]
[2024-02-21 17:58:28.088646] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088671] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 10 transaction and 4 normal messages in queue
[2024-02-21 17:58:28.088680] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088696] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088736] [0x000074d48623b740] [info]    [2,"test_call_20","transactional_update",{"data":"test_call_20"}]
[2024-02-21 17:58:28.088747] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088772] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 11 transaction and 3 normal messages in queue
[2024-02-21 17:58:28.088781] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088796] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088836] [0x000074d48623b740] [info]    [2,"test_call_21","transactional_update",{"data":"test_call_21"}]
[2024-02-21 17:58:28.088847] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088871] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 12 transaction and 2 normal messages in queue
[2024-02-21 17:58:28.088880] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088896] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.088936] [0x000074d48623b740] [info]    [2,"test_call_22","transactional_update",{"data":"test_call_22"}]
[2024-02-21 17:58:28.088947] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.088971] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 13 transaction and 1 normal messages in queue
[2024-02-21 17:58:28.088981] [0x000074d48623b740] [info]    Dropping 1 messages from normal message queue.
[2024-02-21 17:58:28.088996] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.089070] [0x000074d48623b740] [info]    [2,"test_call_23","transactional_update",{"data":"test_call_23"}]
[2024-02-21 17:58:28.089081] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.089106] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 14 transaction and 0 normal messages in queue
[2024-02-21 17:58:28.089118] [0x000074d48623b740] [info]    Drop transactional message test_call_13
[2024-02-21 17:58:28.089142] [0x000074d48623b740] [info]    Drop transactional message test_call_15
[2024-02-21 17:58:28.089165] [0x000074d48623b740] [info]    Drop transactional message test_call_19
[2024-02-21 17:58:28.089188] [0x000074d48623b740] [info]    Drop transactional message test_call_21
[2024-02-21 17:58:28.089210] [0x000074d48623b740] [info]    Dropped 4 transactional update messages to reduce queue size.
[2024-02-21 17:58:28.089225] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.089268] [0x000074d48623b740] [info]    [2,"test_call_24","transactional_update",{"data":"test_call_24"}]
[2024-02-21 17:58:28.089278] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.089327] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.089404] [0x000074d48623b740] [info]    [2,"test_call_25","transactional",{"data":"test_call_25"}]
[2024-02-21 17:58:28.089416] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.089445] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.089524] [0x000074d48623b740] [info]    [2,"test_call_26","transactional",{"data":"test_call_26"}]
[2024-02-21 17:58:28.089536] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.089567] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.089658] [0x000074d48623b740] [info]    [2,"test_call_27","transactional_update",{"data":"test_call_27"}]
[2024-02-21 17:58:28.089669] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.089729] [0x000074d48623b740] [info]    Queue sizes exceed threshold (13) with 14 transaction and 0 normal messages in queue
[2024-02-21 17:58:28.089740] [0x000074d48623b740] [info]    Drop transactional message test_call_14
[2024-02-21 17:58:28.089765] [0x000074d48623b740] [info]    Drop transactional message test_call_20
[2024-02-21 17:58:28.089789] [0x000074d48623b740] [info]    Drop transactional message test_call_23
[2024-02-21 17:58:28.089814] [0x000074d48623b740] [info]    Dropped 3 transactional update messages to reduce queue size.
[2024-02-21 17:58:28.089831] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.089943] [0x000074d48623b740] [info]    [2,"test_call_28","transactional_update",{"data":"test_call_28"}]
[2024-02-21 17:58:28.089954] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.090014] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.090157] [0x000074d48623b740] [info]    [2,"test_call_29","transactional",{"data":"test_call_29"}]
[2024-02-21 17:58:28.090186] [0x000074d48623b740] [info]    Adding message to transaction message queue
[2024-02-21 17:58:28.090235] [0x000074d48623b740] [info]    Notified message queue worker
[2024-02-21 17:58:28.090324] [0x000074d48623b740] [info]    resume() called
[2024-02-21 17:58:28.090353] [0x000074d48623b740] [info]    resume() notified message queue
[2024-02-21 17:58:28.090369] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.090379] [0x000074d482a306c0] [info]    There are 13 messages in the transaction message queue.
[2024-02-21 17:58:28.090385] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.090403] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.087Z
[2024-02-21 17:58:28.090410] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.090417] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_10 attempt#: 0
[2024-02-21 17:58:28.090484] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_10
[2024-02-21 17:58:28.090537] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.090655] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.090680] [0x000074d482a306c0] [info]    There are 12 messages in the transaction message queue.
[2024-02-21 17:58:28.090683] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.090694] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.087Z
[2024-02-21 17:58:28.090698] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.090702] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_11 attempt#: 0
[2024-02-21 17:58:28.090768] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_11
[2024-02-21 17:58:28.090778] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.090829] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.090832] [0x000074d482a306c0] [info]    There are 11 messages in the transaction message queue.
[2024-02-21 17:58:28.090835] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.090844] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.087Z
[2024-02-21 17:58:28.090866] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.090870] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_12 attempt#: 0
[2024-02-21 17:58:28.090939] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_12
[2024-02-21 17:58:28.090955] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.090999] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.091006] [0x000074d482a306c0] [info]    There are 10 messages in the transaction message queue.
[2024-02-21 17:58:28.091011] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.091047] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.088Z
[2024-02-21 17:58:28.091053] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.091060] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_16 attempt#: 0
[2024-02-21 17:58:28.091155] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_16
[2024-02-21 17:58:28.091167] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.091224] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.091233] [0x000074d482a306c0] [info]    There are 9 messages in the transaction message queue.
[2024-02-21 17:58:28.091239] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.091257] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.088Z
[2024-02-21 17:58:28.091265] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.091274] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_17 attempt#: 0
[2024-02-21 17:58:28.091331] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_17
[2024-02-21 17:58:28.091344] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.091408] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.091413] [0x000074d482a306c0] [info]    There are 8 messages in the transaction message queue.
[2024-02-21 17:58:28.091416] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.091425] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.088Z
[2024-02-21 17:58:28.091429] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.091433] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_18 attempt#: 0
[2024-02-21 17:58:28.091471] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_18
[2024-02-21 17:58:28.091481] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.091537] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.091540] [0x000074d482a306c0] [info]    There are 7 messages in the transaction message queue.
[2024-02-21 17:58:28.091543] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.091553] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.088Z
[2024-02-21 17:58:28.091557] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.091560] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_22 attempt#: 0
[2024-02-21 17:58:28.091598] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_22
[2024-02-21 17:58:28.091607] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.091666] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.091674] [0x000074d482a306c0] [info]    There are 6 messages in the transaction message queue.
[2024-02-21 17:58:28.091682] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.091695] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.089Z
[2024-02-21 17:58:28.091699] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.091703] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_24 attempt#: 0
[2024-02-21 17:58:28.091742] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_24
[2024-02-21 17:58:28.091752] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.091862] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.091875] [0x000074d482a306c0] [info]    There are 5 messages in the transaction message queue.
[2024-02-21 17:58:28.091883] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.091901] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.089Z
[2024-02-21 17:58:28.091908] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.091916] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_25 attempt#: 0
[2024-02-21 17:58:28.091973] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_25
[2024-02-21 17:58:28.092007] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.092064] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.092072] [0x000074d482a306c0] [info]    There are 4 messages in the transaction message queue.
[2024-02-21 17:58:28.092078] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.092094] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.089Z
[2024-02-21 17:58:28.092104] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.092113] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_26 attempt#: 0
[2024-02-21 17:58:28.092192] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_26
[2024-02-21 17:58:28.092231] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.092281] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.092289] [0x000074d482a306c0] [info]    There are 3 messages in the transaction message queue.
[2024-02-21 17:58:28.092293] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.092306] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.089Z
[2024-02-21 17:58:28.092312] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.092317] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_27 attempt#: 0
[2024-02-21 17:58:28.092372] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_27
[2024-02-21 17:58:28.092431] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.092478] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.092486] [0x000074d482a306c0] [info]    There are 2 messages in the transaction message queue.
[2024-02-21 17:58:28.092490] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.092503] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.089Z
[2024-02-21 17:58:28.092510] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.092517] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_28 attempt#: 0
[2024-02-21 17:58:28.092582] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_28
[2024-02-21 17:58:28.092598] [0x000074d482a306c0] [info]    Waiting for a message from the message queue
[2024-02-21 17:58:28.092646] [0x000074d482a306c0] [info]    There are 0 messages in the normal message queue.
[2024-02-21 17:58:28.092654] [0x000074d482a306c0] [info]    There are 1 messages in the transaction message queue.
[2024-02-21 17:58:28.092663] [0x000074d482a306c0] [info]    There is no message in flight, checking message queue for a new message.
[2024-02-21 17:58:28.092684] [0x000074d482a306c0] [info]    transaction msg timestamp: 2024-02-21T17:58:28.090Z
[2024-02-21 17:58:28.092693] [0x000074d482a306c0] [info]    transaction message timestamp <= now
[2024-02-21 17:58:28.092703] [0x000074d482a306c0] [info]    Attempting to send message to central system. UID: test_call_29 attempt#: 0
[2024-02-21 17:58:28.092765] [0x000074d482a306c0] [info]    Successfully sent message. UID: test_call_29
[2024-02-21 17:58:28.092771] [0x000074d48623b740] [info]    stop()
[2024-02-21 17:58:28.092797] [0x000074d482a306c0] [info]    Message queue stopped processing messages
[2024-02-21 17:58:28.092824] [0x000074d48623b740] [info]    stop() notified message queue
[       OK ] MessageQueueTest.test_clean_up_transactional_queue_REDUX (6 ms)
[----------] 2 tests from MessageQueueTest (8 ms total)

[----------] Global test environment tear-down
[==========] 6 tests from 3 test suites ran. (14 ms total)
[  PASSED  ] 6 tests.
<end of output>
Test time =   0.02 sec
----------------------------------------------------------
Test Passed.
"libocpp_common_unit_tests" end time: Feb 21 17:58 UTC
"libocpp_common_unit_tests" time elapsed: 00:00:00
----------------------------------------------------------

3/5 Testing: libocpp_v201_unit_tests
3/5 Test: libocpp_v201_unit_tests
Command: "/workspaces/libocpp/build/tests/libocpp_v201_unit_tests"
Directory: /workspaces/libocpp/build/tests
"libocpp_v201_unit_tests" start time: Feb 21 17:58 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 23 tests from 4 test suites.
[----------] Global test environment set-up.
[----------] 12 tests from ComponentStateManagerTest
[ RUN      ] ComponentStateManagerTest.test_boot_empty_db
[2024-02-21 17:58:28.098555] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[2024-02-21 17:58:28.098565] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[2024-02-21 17:58:28.098570] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_boot_empty_db (0 ms)
[ RUN      ] ComponentStateManagerTest.test_boot_recover_persisted_states
[2024-02-21 17:58:28.098617] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_boot_recover_persisted_states (0 ms)
[ RUN      ] ComponentStateManagerTest.test_check_evse_and_connector_ids
[2024-02-21 17:58:28.098754] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_check_evse_and_connector_ids (0 ms)
[ RUN      ] ComponentStateManagerTest.test_boot_missing_states_are_operative
[2024-02-21 17:58:28.098797] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_boot_missing_states_are_operative (0 ms)
[ RUN      ] ComponentStateManagerTest.test_persist_operative_states

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: cs_op_state_update("Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: evse_op_state_update(1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: evse_op_state_update(2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(2, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(2, 2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-21 17:58:28.098881] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_persist_operative_states (0 ms)
[ RUN      ] ComponentStateManagerTest.test_effective_state_getters_cs_inoperative

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: cs_op_state_update("Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: evse_op_state_update(1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: evse_op_state_update(2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(2, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(2, 2, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Occupied")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(2, 1, "Reserved")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(2, 2, "Faulted")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-21 17:58:28.098958] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_effective_state_getters_cs_inoperative (0 ms)
[ RUN      ] ComponentStateManagerTest.test_effective_state_getters_evse_inoperative

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: evse_op_state_update(1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Occupied")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(2, 1, "Reserved")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(2, 2, "Faulted")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-21 17:58:28.099017] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_effective_state_getters_evse_inoperative (0 ms)
[ RUN      ] ComponentStateManagerTest.test_connector_state_machine

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Reserved")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Occupied")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Faulted")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(1, 1, "Inoperative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Faulted")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Unavailable")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: connector_op_state_update(1, 1, "Operative")
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Occupied")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.

GMOCK WARNING:
Uninteresting mock function call - returning default value.
    Function call: connector_status_update(1, 1, "Reserved")
          Returns: false
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-21 17:58:28.099082] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_connector_state_machine (0 ms)
[ RUN      ] ComponentStateManagerTest.test_effective_state_changed_callbacks
[2024-02-21 17:58:28.099241] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_effective_state_changed_callbacks (0 ms)
[ RUN      ] ComponentStateManagerTest.test_trigger_boot_callbacks
[2024-02-21 17:58:28.099404] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_trigger_boot_callbacks (0 ms)
[ RUN      ] ComponentStateManagerTest.test_send_status_notification_changed_connectors
[2024-02-21 17:58:28.099547] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_send_status_notification_changed_connectors (0 ms)
[ RUN      ] ComponentStateManagerTest.test_send_status_notification_single_connector
[2024-02-21 17:58:28.099642] [0x00007036da523740] [info]    Successfully closed database: "/dev/null/cp.db"
[       OK ] ComponentStateManagerTest.test_send_status_notification_single_connector (0 ms)
[----------] 12 tests from ComponentStateManagerTest (1 ms total)

[----------] 2 tests from DeviceModelStorageSQLiteTest
[ RUN      ] DeviceModelStorageSQLiteTest.test_check_integrity_valid
[2024-02-21 17:58:28.099815] [0x00007036da523740] [info]    Established connection to device model database successfully: "./resources/unittest_device_model.db"
[       OK ] DeviceModelStorageSQLiteTest.test_check_integrity_valid (0 ms)
[ RUN      ] DeviceModelStorageSQLiteTest.test_check_integrity_invalid
[2024-02-21 17:58:28.100144] [0x00007036da523740] [info]    Established connection to device model database successfully: "./resources/unittest_device_model_missing_required.db"
[       OK ] DeviceModelStorageSQLiteTest.test_check_integrity_invalid (0 ms)
[----------] 2 tests from DeviceModelStorageSQLiteTest (0 ms total)

[----------] 4 tests from NotifyReportRequestsSplitterTest
[ RUN      ] NotifyReportRequestsSplitterTest.test_create_single_request_no_report_data
[       OK ] NotifyReportRequestsSplitterTest.test_create_single_request_no_report_data (5 ms)
[ RUN      ] NotifyReportRequestsSplitterTest.test_create_single_request
[       OK ] NotifyReportRequestsSplitterTest.test_create_single_request (0 ms)
[ RUN      ] NotifyReportRequestsSplitterTest.test_create_split_request
[2024-02-21 17:58:28.106422] [0x00007036da523740] [info]    Split NotifyReportRequest '42' into 2 messages.
[       OK ] NotifyReportRequestsSplitterTest.test_create_split_request (0 ms)
[ RUN      ] NotifyReportRequestsSplitterTest.test_splits_contains_at_least_one_report
[2024-02-21 17:58:28.106814] [0x00007036da523740] [info]    Split NotifyReportRequest '42' into 2 messages.
[       OK ] NotifyReportRequestsSplitterTest.test_splits_contains_at_least_one_report (0 ms)
[----------] 4 tests from NotifyReportRequestsSplitterTest (6 ms total)

[----------] 5 tests from OcspUpdaterTest
[ RUN      ] OcspUpdaterTest.test_success_boot_many
[2024-02-21 17:58:28.107331] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.107468] [0x00007036da51f6c0] [info]    libocpp: Done updating OCSP cache
[       OK ] OcspUpdaterTest.test_success_boot_many (0 ms)
[ RUN      ] OcspUpdaterTest.test_retry_boot_many
[2024-02-21 17:58:28.107791] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.107830] [0x00007036da51f6c0] [info]    libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry.
[2024-02-21 17:58:28.107898] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.107936] [0x00007036da51f6c0] [info]    libocpp: OCSP status update failed: CSMS sent an Accepted GetCertificateStatusResponse with no ocspResult, will retry.
[2024-02-21 17:58:28.108003] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.108076] [0x00007036da51f6c0] [info]    libocpp: Done updating OCSP cache
[       OK ] OcspUpdaterTest.test_retry_boot_many (0 ms)
[ RUN      ] OcspUpdaterTest.test_reverify_logic
[2024-02-21 17:58:28.108382] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.108431] [0x00007036da51f6c0] [info]    libocpp: Done updating OCSP cache
[2024-02-21 17:58:28.108499] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.108542] [0x00007036da51f6c0] [info]    libocpp: Done updating OCSP cache
[       OK ] OcspUpdaterTest.test_reverify_logic (0 ms)
[ RUN      ] OcspUpdaterTest.test_trigger
[2024-02-21 17:58:28.108799] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.108853] [0x00007036da51f6c0] [info]    libocpp: Done updating OCSP cache
[2024-02-21 17:58:28.108910] [0x00007036da51f6c0] [info]    libocpp: Updating OCSP cache on 3 certificates
[2024-02-21 17:58:28.108996] [0x00007036da51f6c0] [info]    libocpp: Done updating OCSP cache
[       OK ] OcspUpdaterTest.test_trigger (0 ms)
[ RUN      ] OcspUpdaterTest.test_exception_trigger_when_not_running
[       OK ] OcspUpdaterTest.test_exception_trigger_when_not_running (0 ms)
[----------] 5 tests from OcspUpdaterTest (2 ms total)

[----------] Global test environment tear-down
[==========] 23 tests from 4 test suites ran. (11 ms total)
[  PASSED  ] 23 tests.
<end of output>
Test time =   0.02 sec
----------------------------------------------------------
Test Passed.
"libocpp_v201_unit_tests" end time: Feb 21 17:58 UTC
"libocpp_v201_unit_tests" time elapsed: 00:00:00
----------------------------------------------------------

4/5 Testing: libocpp_v16_unit_tests
4/5 Test: libocpp_v16_unit_tests
Command: "/workspaces/libocpp/build/tests/libocpp_v16_unit_tests"
Directory: /workspaces/libocpp/build/tests
"libocpp_v16_unit_tests" start time: Feb 21 17:58 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 30 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 30 tests from ChargepointTestFixture
[ RUN      ] ChargepointTestFixture.ValidateProfile
[2024-02-21 17:58:28.119349] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.119392] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[       OK ] ChargepointTestFixture.ValidateProfile (5 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__example1
[       OK ] ChargepointTestFixture.ValidateProfile__example1 (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ConnectorIdGreaterThanConnectorsSize__ReturnsFalse
[2024-02-21 17:58:28.119610] [0x000070c788a6f740] [info]    INVALID PROFILE - connector_id invalid or invalid stack level
[       OK ] ChargepointTestFixture.ValidateProfile__ConnectorIdGreaterThanConnectorsSize__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_NegativeConnectorIdTest__ReturnsFalse
[2024-02-21 17:58:28.119774] [0x000070c788a6f740] [info]    INVALID PROFILE - connector_id invalid or invalid stack level
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NegativeConnectorIdTest__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_ConnectorIdZero__ReturnsFalse
[2024-02-21 17:58:28.119889] [0x000070c788a6f740] [info]    INVALID PROFILE - connector_id invalid or invalid stack level
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ConnectorIdZero__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_StackLevelGreaterThanMaxStackLevel__ReturnsFalse
[2024-02-21 17:58:28.119984] [0x000070c788a6f740] [info]    INVALID PROFILE - connector_id invalid or invalid stack level
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_StackLevelGreaterThanMaxStackLevel__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindAbsoluteNoStartSchedule__ReturnsFalse
[2024-02-21 17:58:28.120088] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindAbsoluteNoStartSchedule__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_AllowsNoStartSchedule__ReturnsTrue
[2024-02-21 17:58:28.120229] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.120241] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_AllowsNoStartSchedule__ReturnsTrue (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_InstalledProfilesGreaterThanMaxInstalledProfiles__ReturnsFalse
[2024-02-21 17:58:28.120384] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.120393] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-21 17:58:28.120400] [0x000070c788a6f740] [info]    Maximum amount of profiles are installed at the charging point
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_InstalledProfilesGreaterThanMaxInstalledProfiles__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_InvalidChargingSchedule__ReturnsFalse
[2024-02-21 17:58:28.120488] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.120496] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-21 17:58:28.120508] [0x000070c788a6f740] [info]    INVALID SCHEDULE - ChargingRateUnit not supported: A
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_InvalidChargingSchedule__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoRecurrencyKind__ReturnsFalse
[2024-02-21 17:58:28.120609] [0x000070c788a6f740] [info]    INVALID PROFILE - Recurring Profile Kind with no given RecurrencyKind
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoRecurrencyKind__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoStartSchedule__ReturnsFalse
[2024-02-21 17:58:28.120790] [0x000070c788a6f740] [info]    INVALID PROFILE - Recurring Profile Kind with no startSchedule
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_ChargingProfileKindRecurringNoStartSchedule__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_NoStartScheduleAllowedRelative__ReturnsTrue
[2024-02-21 17:58:28.120933] [0x000070c788a6f740] [info]    INVALID PROFILE - Recurring Profile Kind with no startSchedule
[2024-02-21 17:58:28.120985] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NoStartScheduleAllowedRelative__ReturnsTrue (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__RecurringNoStartScheduleNotAllowed__ReturnsFalse
[2024-02-21 17:58:28.121089] [0x000070c788a6f740] [info]    INVALID PROFILE - Recurring Profile Kind with no startSchedule
[       OK ] ChargepointTestFixture.ValidateProfile__RecurringNoStartScheduleNotAllowed__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorId0__ReturnsTrue
[2024-02-21 17:58:28.121173] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.121183] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorId0__ReturnsTrue (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorIdNot0__ReturnsFalse
[2024-02-21 17:58:28.121276] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.121285] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-21 17:58:28.121294] [0x000070c788a6f740] [info]    INVALID PROFILE - connector_id != 0 with purpose ChargePointMaxProfile or kind is Relative
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfile_NotRecurrencyKindConnectorIdNot0__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__ValidProfileTxDefaultProfile__ReturnsTrue
[2024-02-21 17:58:28.121453] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.121464] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[       OK ] ChargepointTestFixture.ValidateProfile__ValidProfileTxDefaultProfile__ReturnsTrue (0 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileIgnoreNoTransaction__ReturnsTrue
[2024-02-21 17:58:28.147624] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.147642] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[       OK ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileIgnoreNoTransaction__ReturnsTrue (26 ms)
[ RUN      ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileConnectorId0__ReturnsFalse
[2024-02-21 17:58:28.147757] [0x000070c788a6f740] [info]    INVALID PROFILE - Absolute Profile Kind with no given startSchedule
[2024-02-21 17:58:28.147766] [0x000070c788a6f740] [info]    Allowing profile because AllowChargingProfileWithoutStartSchedule is configured
[2024-02-21 17:58:28.147774] [0x000070c788a6f740] [info]    INVALID PROFILE - connector_id != 0 or no active transaction at this connector
[       OK ] ChargepointTestFixture.ValidateProfile__AbsoluteTxProfileConnectorId0__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_DoNotCheckIdOnly__ReturnsFalse
[       OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_DoNotCheckIdOnly__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_CheckIdOnly__ReturnsFalse
[       OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__AllOptionalsEmpty_CheckIdOnly__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.ClearAllProfilesWithFilter__OnlyOneMatchingProfileId_CheckIdOnly__ReturnsTrue

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: insert_or_update_charging_profile(0, @0x7ffe46b02760 {
    "chargingProfileId": 1,
    "chargingProfileKind": "Absolute",
    "chargingProfilePurpose": "TxDefaultProfile",
    "chargingSchedule": {
        "chargingRateUnit": "A",
        "chargingSchedulePeriod": []
    },
    "recurrencyKind": "Daily",
    "stackLevel": 1
})
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[2024-02-21 17:58:28.148104] [0x000070c788a6f740] [info]    Clearing ChargingProfile with id: 1

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: delete_charging_profile(1)
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[       OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__OnlyOneMatchingProfileId_CheckIdOnly__ReturnsTrue (0 ms)
[ RUN      ] ChargepointTestFixture.ClearAllProfilesWithFilter__NoMatchingProfileId_CheckIdOnly__ReturnsFalse

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: insert_or_update_charging_profile(0, @0x7ffe46b02760 {
    "chargingProfileId": 1,
    "chargingProfileKind": "Absolute",
    "chargingProfilePurpose": "TxDefaultProfile",
    "chargingSchedule": {
        "chargingRateUnit": "A",
        "chargingSchedulePeriod": []
    },
    "recurrencyKind": "Daily",
    "stackLevel": 1
})
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
[       OK ] ChargepointTestFixture.ClearAllProfilesWithFilter__NoMatchingProfileId_CheckIdOnly__ReturnsFalse (0 ms)
[ RUN      ] ChargepointTestFixture.AddChargePointMaxProfile

GMOCK WARNING:
Uninteresting mock function call - returning directly.
    Function call: insert_or_update_charging_profile(0, @0x7ffe46b02640 {
    "chargingProfileId": 1,
    "chargingProfileKind": "Absolute",
    "chargingProfilePurpose": "ChargePointMaxProfile",
    "chargingSchedule": {
        "chargingRateUnit": "A",
        "chargingSchedulePeriod": []
    },
    "recurrencyKind": "Daily",
    "stackLevel": 1
})
NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/docs/gmock_cook_book.md#knowing-when-to-expect for details.
<end of output>
Test time =   0.13 sec
----------------------------------------------------------
Test Failed.
"libocpp_v16_unit_tests" end time: Feb 21 17:58 UTC
"libocpp_v16_unit_tests" time elapsed: 00:00:00
----------------------------------------------------------

5/5 Testing: utils_tests
5/5 Test: utils_tests
Command: "/workspaces/libocpp/build/tests/utils_tests"
Directory: /workspaces/libocpp/build/tests
"utils_tests" start time: Feb 21 17:58 UTC
Output:
----------------------------------------------------------
Running main() from /workspaces/libocpp/build/_deps/gtest-src/googletest/src/gtest_main.cc
[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from UtilsTest
[ RUN      ] UtilsTest.test_valid_datetime
[       OK ] UtilsTest.test_valid_datetime (1 ms)
[ RUN      ] UtilsTest.test_invalid_datetime
[       OK ] UtilsTest.test_invalid_datetime (0 ms)
[----------] 2 tests from UtilsTest (1 ms total)

[----------] Global test environment tear-down
[==========] 2 tests from 1 test suite ran. (1 ms total)
[  PASSED  ] 2 tests.
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"utils_tests" end time: Feb 21 17:58 UTC
"utils_tests" time elapsed: 00:00:00
----------------------------------------------------------

End testing: Feb 21 17:58 UTC

@folkengine
Copy link
Author

folkengine commented Feb 21, 2024

    Start 1: database_tests
1/5 Test #1: database_tests ...................   Passed    1.44 sec
    Start 2: libocpp_common_unit_tests
2/5 Test #2: libocpp_common_unit_tests ........   Passed    0.02 sec
    Start 3: libocpp_v201_unit_tests
3/5 Test #3: libocpp_v201_unit_tests ..........   Passed    0.02 sec
    Start 4: libocpp_v16_unit_tests
4/5 Test #4: libocpp_v16_unit_tests ...........***Exception: SegFault  0.13 sec
    Start 5: utils_tests
5/5 Test #5: utils_tests ......................   Passed    0.00 sec

80% tests passed, 1 tests failed out of 5

Total Test time (real) =   1.61 sec

The following tests FAILED:
          4 - libocpp_v16_unit_tests (SEGFAULT)
Errors while running CTest
Output from these tests are in: /workspaces/libocpp/build/Testing/Temporary/LastTest.log
Use "--rerun-failed --output-on-failure" to re-run the failed cases verbosely.
FAILED: CMakeFiles/test.util 
cd /workspaces/libocpp/build && /usr/bin/ctest --force-new-ctest-process
ninja: build stopped: subcommand failed.```

@folkengine
Copy link
Author

  • AddChargePointMaxProfile
  • AddChargePointMaxProfile__InvalidProfileType__ShouldNotWorkButDoes
  • AddTxDefaultProfile_ConnectorId_eq_0
  • AddTxDefaultProfile_ConnectorId_eq_0_Retrieved_at_0__NoProfilesReturned
  • AddTxDefaultProfile__ConnectorId_gt_0
  • AddTxDefaultProfile__ConnectorIdOverMax__ThrowsException
  • AddTxProfile

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment