ev-pop.log

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

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