Add DEBUG prints to follow configuration path

Author: pennam

src/ArduinoIoTCloudTCP.cpp

@@ -71,6 +71,7 @@ extern "C" void updateTimezoneInfo()
 
 extern "C" void setThingIdOutdated()
 {
+  DEBUG_VERBOSE("Set thing_id outdated flag");
   ArduinoCloud.setThingIdOutdatedFlag();
 }
 
@@ -327,6 +328,7 @@ void ArduinoIoTCloudTCP::update()
   /* Check if the state has changed in message handler */
   if(_next_state != State::Invalid)
   {
+    DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s State changed in message handler...", __FUNCTION__);
     _state = _next_state;
     _next_state = State::Invalid;
   }
@@ -403,6 +405,7 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_ConnectMqttBroker()
   if (_mqttClient.connect(_brokerAddress.c_str(), _brokerPort))
   {
     _last_connection_attempt_cnt = 0;
+    DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s connected to %s:%d", __FUNCTION__, _brokerAddress.c_str(), _brokerPort);
     return State::SendDeviceProperties;
   }
 
@@ -423,6 +426,8 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_SendDeviceProperties()
     return State::Disconnect;
   }
 
+  DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s", __FUNCTION__);
+
 #if OTA_ENABLED
   sendOTAPropertiesToCloud();
 #endif
@@ -441,6 +446,8 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_SubscribeDeviceTopic()
     return State::SubscribeDeviceTopic;
   }
 
+  DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s subscribed to %s", __FUNCTION__, _deviceTopicIn.c_str());
+
   if (_last_device_subscribe_cnt > AIOT_CONFIG_LASTVALUES_SYNC_MAX_RETRY_CNT)
   {
     _last_device_subscribe_cnt = 0;
@@ -456,6 +463,7 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_SubscribeDeviceTopic()
   _next_device_subscribe_attempt_tick = millis() + subscribe_retry_delay;
   _last_device_subscribe_cnt++;
 
+  DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s waiting device configuration from %s", __FUNCTION__, _deviceTopicIn.c_str());
   return State::WaitDeviceConfig;
 }
 
@@ -474,6 +482,8 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_WaitDeviceConfig()
   if (millis() > _next_device_subscribe_attempt_tick)
   {
     /* Configuration not received or device not attached to a valid thing. Try to resubscribe */
+    DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s configuration not received or device not attached to a valid thing", __FUNCTION__);
+    DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s new subscription attempt at tick time %d", __FUNCTION__, _next_device_subscribe_attempt_tick);
     if (_mqttClient.unsubscribe(_deviceTopicIn))
     {
       return State::SubscribeDeviceTopic;
@@ -492,6 +502,7 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_CheckDeviceConfig()
   if(_deviceSubscribedToThing == true)
   {
     /* Unsubscribe from old things topics and go on with a new subsctiption */
+    DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s Unsubscribing from old thing topics", __FUNCTION__);
     _mqttClient.unsubscribe(_shadowTopicIn);
     _mqttClient.unsubscribe(_dataTopicIn);
     _deviceSubscribedToThing = false;
@@ -506,6 +517,7 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_CheckDeviceConfig()
     attach_retry_delay = min(attach_retry_delay, static_cast<unsigned long>(AIOT_CONFIG_MAX_DEVICE_TOPIC_ATTACH_RETRY_DELAY_ms));
     _next_device_subscribe_attempt_tick = millis() + attach_retry_delay;
     _last_device_attach_cnt++;
+    DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s Device not attached to a valid Thing. New Request delay: %d", __FUNCTION__, attach_retry_delay);
     return State::WaitDeviceConfig;
   }
 
@@ -517,6 +529,8 @@ ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_CheckDeviceConfig()
 
 ArduinoIoTCloudTCP::State ArduinoIoTCloudTCP::handle_SubscribeThingTopics()
 {
+  DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s", __FUNCTION__);
+
   if (!_mqttClient.connected())
   {
     return State::Disconnect;
@@ -687,6 +701,7 @@ void ArduinoIoTCloudTCP::handleMessage(int length)
   /* Topic for OTA properties and device configuration */
   if (_deviceTopicIn == topic) {
     CBORDecoder::decode(_device_property_container, (uint8_t*)bytes, length);
+    DEBUG_VERBOSE("ArduinoIoTCloudTCP::%s [%d] device configuration or OTA message received", __FUNCTION__, millis());
     _last_device_subscribe_cnt = 0;
     _next_device_subscribe_attempt_tick = 0;
   }