Skip to content
This repository has been archived by the owner on Oct 12, 2023. It is now read-only.

[V2] Problems connecting and sending data #545

Closed
termocontrole opened this issue Mar 15, 2018 · 12 comments
Closed

[V2] Problems connecting and sending data #545

termocontrole opened this issue Mar 15, 2018 · 12 comments

Comments

@termocontrole
Copy link

Hi,

I´m using a Raspberry Pi with Azure IoT Edge V2 and the modbus module, in the beginning the modbus module had some issues, but we were abble to track and solve it.

Now the messages are not correctly sent to IoT Hub, a lot of messages seems to be missed: some times it connects and send data to Hub, other times doesn´t connect and there are times that seems to be connected but doesn´t send any data to the endpoint.

I don´t know how to debug the IoT Edge V2 or turn the debug flags on to better understand whats heppening.

Here is my logs from 13-03 to 15-03:

13-03 - IoT Edge connected in portal but sending no data.

14-03 - IoT Edge connected in portal but sending no data.

15-03 - Warning: Your device is disconnected from this IoT Hub. The information displayed below is not up to date. You can go to the device twin to see the last update time. If you have not set up your IoT Edge device, please follow IoT Edge QuickStart instructions. If you have already set up your device, please check device connectivity.

edgeAgent:

2018-03-13 19:29:59.193 +00:00 [INF] - Starting module management agent.
2018-03-13 19:30:01.625 +00:00 [INF] - Version - 1.0.0-preview021.10543704 (c87b52c93b13f03bf34da8d9ae650d55368ccecb)
2018-03-13 19:30:01.626 +00:00 [INF] -
        █████╗ ███████╗██╗   ██╗██████╗ ███████╗
       ██╔══██╗╚══███╔╝██║   ██║██╔══██╗██╔════╝
       ███████║  ███╔╝ ██║   ██║██████╔╝█████╗
       ██╔══██║ ███╔╝  ██║   ██║██╔══██╗██╔══╝
       ██║  ██║███████╗╚██████╔╝██║  ██║███████╗
       ╚═╝  ╚═╝╚══════╝ ╚═════╝ ╚═╝  ╚═╝╚══════╝

 ██╗ ██████╗ ████████╗    ███████╗██████╗  ██████╗ ███████╗
 ██║██╔═══██╗╚══██╔══╝    ██╔════╝██╔══██╗██╔════╝ ██╔════╝
 ██║██║   ██║   ██║       █████╗  ██║  ██║██║  ███╗█████╗
 ██║██║   ██║   ██║       ██╔══╝  ██║  ██║██║   ██║██╔══╝
 ██║╚██████╔╝   ██║       ███████╗██████╔╝╚██████╔╝███████╗
 ╚═╝ ╚═════╝    ╚═╝       ╚══════╝╚═════╝  ╚═════╝ ╚══════╝

2018-03-13 19:30:02.817 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via AMQP...
2018-03-13 19:30:08.904 +00:00 [INF] - Created persistent store at /tmp/edgeAgent
2018-03-13 19:30:10.188 +00:00 [INF] - Edge agent connected to IoT Hub via AMQP.
2018-03-13 20:16:35.771 +00:00 [INF] - Plan execution started for deployment 9
2018-03-13 20:16:35.860 +00:00 [INF] - Executing command: "Command Group: (
  [docker stop -t 10 modbus]
  [docker start modbus]
  [Update health stats for module modbus]
)"
2018-03-13 20:16:35.870 +00:00 [INF] - Executing command: "docker stop -t 10 modbus"
2018-03-13 20:16:35.902 +00:00 [INF] - Executing command: "docker start modbus"
2018-03-13 20:16:36.925 +00:00 [INF] - Executing command: "Update health stats for module modbus"
2018-03-13 20:16:36.998 +00:00 [INF] - Executing command: "Command Group: (
  [docker stop -t 10 edgeHub]
  [docker start edgeHub]
  [Update health stats for module edgeHub]
)"
2018-03-13 20:16:36.999 +00:00 [INF] - Executing command: "docker stop -t 10 edgeHub"
2018-03-13 20:16:37.003 +00:00 [INF] - Executing command: "docker start edgeHub"
2018-03-13 20:16:38.140 +00:00 [INF] - Executing command: "Update health stats for module edgeHub"
2018-03-13 20:16:38.146 +00:00 [INF] - Plan execution ended for deployment 9
2018-03-13 20:16:39.436 +00:00 [INF] - Updated reported properties
2018-03-13 20:16:44.555 +00:00 [INF] - HealthRestartPlanner is clearing restart stats for module 'modbus' as it has been running healthy for 00:10:00.
2018-03-13 20:16:44.556 +00:00 [INF] - HealthRestartPlanner is clearing restart stats for module 'edgeHub' as it has been running healthy for 00:10:00.
2018-03-13 20:16:44.557 +00:00 [INF] - Plan execution started for deployment 9
2018-03-13 20:16:44.561 +00:00 [INF] - Executing command: "Reset health stats for module modbus"
2018-03-13 20:16:44.570 +00:00 [INF] - Executing command: "Reset health stats for module edgeHub"
2018-03-13 20:16:44.571 +00:00 [INF] - Plan execution ended for deployment 9
2018-03-13 20:16:44.761 +00:00 [INF] - Updated reported properties
2018-03-13 22:16:31.060 +00:00 [INF] - Starting compaction of stores
2018-03-13 22:16:31.064 +00:00 [INF] - Starting compaction of store default
2018-03-13 22:16:31.074 +00:00 [INF] - Starting compaction of store mma
2018-03-14 00:16:31.055 +00:00 [INF] - Starting compaction of stores
2018-03-14 00:16:31.056 +00:00 [INF] - Starting compaction of store default
2018-03-14 00:16:31.057 +00:00 [INF] - Starting compaction of store mma
2018-03-14 02:16:31.054 +00:00 [INF] - Starting compaction of stores
2018-03-14 02:16:31.055 +00:00 [INF] - Starting compaction of store default
2018-03-14 02:16:31.055 +00:00 [INF] - Starting compaction of store mma
2018-03-14 04:16:31.057 +00:00 [INF] - Starting compaction of stores
2018-03-14 04:16:31.058 +00:00 [INF] - Starting compaction of store default
2018-03-14 04:16:31.059 +00:00 [INF] - Starting compaction of store mma
2018-03-14 06:16:31.054 +00:00 [INF] - Starting compaction of stores
2018-03-14 06:16:31.054 +00:00 [INF] - Starting compaction of store default
2018-03-14 06:16:31.055 +00:00 [INF] - Starting compaction of store mma
2018-03-14 08:16:31.063 +00:00 [INF] - Starting compaction of stores
2018-03-14 08:16:31.064 +00:00 [INF] - Starting compaction of store default
2018-03-14 08:16:31.064 +00:00 [INF] - Starting compaction of store mma
2018-03-14 10:16:31.055 +00:00 [INF] - Starting compaction of stores
2018-03-14 10:16:31.055 +00:00 [INF] - Starting compaction of store default
2018-03-14 10:16:31.055 +00:00 [INF] - Starting compaction of store mma
2018-03-14 12:16:31.060 +00:00 [INF] - Starting compaction of stores
2018-03-14 12:16:31.060 +00:00 [INF] - Starting compaction of store default
2018-03-14 12:16:31.061 +00:00 [INF] - Starting compaction of store mma
2018-03-14 14:16:31.055 +00:00 [INF] - Starting compaction of stores
2018-03-14 14:16:31.056 +00:00 [INF] - Starting compaction of store default
2018-03-14 14:16:31.056 +00:00 [INF] - Starting compaction of store mma
2018-03-14 16:16:31.063 +00:00 [INF] - Starting compaction of stores
2018-03-14 16:16:31.064 +00:00 [INF] - Starting compaction of store default
2018-03-14 16:16:31.064 +00:00 [INF] - Starting compaction of store mma
2018-03-14 18:16:31.061 +00:00 [INF] - Starting compaction of stores
2018-03-14 18:16:31.062 +00:00 [INF] - Starting compaction of store default
2018-03-14 18:16:31.062 +00:00 [INF] - Starting compaction of store mma
2018-03-14 20:16:31.061 +00:00 [INF] - Starting compaction of stores
2018-03-14 20:16:31.062 +00:00 [INF] - Starting compaction of store default
2018-03-14 20:16:31.062 +00:00 [INF] - Starting compaction of store mma
2018-03-14 22:16:31.057 +00:00 [INF] - Starting compaction of stores
2018-03-14 22:16:31.059 +00:00 [INF] - Starting compaction of store default
2018-03-14 22:16:31.059 +00:00 [INF] - Starting compaction of store mma
2018-03-15 00:16:31.052 +00:00 [INF] - Starting compaction of stores
2018-03-15 00:16:31.053 +00:00 [INF] - Starting compaction of store default
2018-03-15 00:16:31.053 +00:00 [INF] - Starting compaction of store mma
2018-03-15 02:16:31.062 +00:00 [INF] - Starting compaction of stores
2018-03-15 02:16:31.062 +00:00 [INF] - Starting compaction of store default
2018-03-15 02:16:31.063 +00:00 [INF] - Starting compaction of store mma
2018-03-15 04:16:31.063 +00:00 [INF] - Starting compaction of stores
2018-03-15 04:16:31.063 +00:00 [INF] - Starting compaction of store default
2018-03-15 04:16:31.064 +00:00 [INF] - Starting compaction of store mma
2018-03-15 06:16:31.054 +00:00 [INF] - Starting compaction of stores
2018-03-15 06:16:31.055 +00:00 [INF] - Starting compaction of store default
2018-03-15 06:16:31.056 +00:00 [INF] - Starting compaction of store mma
2018-03-15 08:16:31.057 +00:00 [INF] - Starting compaction of stores
2018-03-15 08:16:31.058 +00:00 [INF] - Starting compaction of store default
2018-03-15 08:16:31.058 +00:00 [INF] - Starting compaction of store mma
2018-03-15 10:16:31.064 +00:00 [INF] - Starting compaction of stores
2018-03-15 10:16:31.064 +00:00 [INF] - Starting compaction of store default
2018-03-15 10:16:31.064 +00:00 [INF] - Starting compaction of store mma
2018-03-15 11:00:42.829 +00:00 [INF] - Module 'edgeHub' scheduled to restart after 10s (07s left).
2018-03-15 11:00:43.832 +00:00 [INF] - Updated reported properties
2018-03-15 11:00:48.885 +00:00 [INF] - Module 'edgeHub' scheduled to restart after 10s (01s left).
2018-03-15 11:00:53.949 +00:00 [INF] - Plan execution started for deployment 9
2018-03-15 11:00:53.950 +00:00 [INF] - Executing command: "Command Group: (
  [docker stop -t 10 edgeHub]
  [docker start edgeHub]
  [Update health stats for module edgeHub]
)"
2018-03-15 11:00:53.951 +00:00 [INF] - Executing command: "docker stop -t 10 edgeHub"
2018-03-15 11:00:53.958 +00:00 [INF] - Executing command: "docker start edgeHub"
2018-03-15 11:00:55.094 +00:00 [INF] - Executing command: "Update health stats for module edgeHub"
2018-03-15 11:00:55.094 +00:00 [INF] - Plan execution ended for deployment 9
2018-03-15 11:00:55.272 +00:00 [INF] - Updated reported properties
2018-03-15 11:10:41.857 +00:00 [INF] - HealthRestartPlanner is clearing restart stats for module 'edgeHub' as it has been running healthy for 00:10:00.
2018-03-15 11:10:41.858 +00:00 [INF] - Plan execution started for deployment 9
2018-03-15 11:10:41.858 +00:00 [INF] - Executing command: "Reset health stats for module edgeHub"
2018-03-15 11:10:41.859 +00:00 [INF] - Plan execution ended for deployment 9
2018-03-15 11:10:42.078 +00:00 [INF] - Updated reported properties
2018-03-15 12:16:31.057 +00:00 [INF] - Starting compaction of stores
2018-03-15 12:16:31.057 +00:00 [INF] - Starting compaction of store default
2018-03-15 12:16:31.058 +00:00 [INF] - Starting compaction of store mma

edgeHub:

2018-03-13 20:16:51.725 +00:00 [INF] - User profile is available. Using '"/home/edgehubuser/.aspnet/DataProtection-Keys"' as key repository; keys will not be encrypted at rest.
2018-03-13 20:16:54.702 +00:00 [INF] - Starting Edge Hub.
2018-03-13 20:16:54.710 +00:00 [INF] - Version - 1.0.0-preview021.10543704 (c87b52c93b13f03bf34da8d9ae650d55368ccecb)
2018-03-13 20:16:54.711 +00:00 [INF] -
        █████╗ ███████╗██╗   ██╗██████╗ ███████╗
       ██╔══██╗╚══███╔╝██║   ██║██╔══██╗██╔════╝
       ███████║  ███╔╝ ██║   ██║██████╔╝█████╗
       ██╔══██║ ███╔╝  ██║   ██║██╔══██╗██╔══╝
       ██║  ██║███████╗╚██████╔╝██║  ██║███████╗
       ╚═╝  ╚═╝╚══════╝ ╚═════╝ ╚═╝  ╚═╝╚══════╝

 ██╗ ██████╗ ████████╗    ███████╗██████╗  ██████╗ ███████╗
 ██║██╔═══██╗╚══██╔══╝    ██╔════╝██╔══██╗██╔════╝ ██╔════╝
 ██║██║   ██║   ██║       █████╗  ██║  ██║██║  ███╗█████╗
 ██║██║   ██║   ██║       ██╔══╝  ██║  ██║██║   ██║██╔══╝
 ██║╚██████╔╝   ██║       ███████╗██████╔╝╚██████╔╝███████╗
 ╚═╝ ╚═════╝    ╚═╝       ╚══════╝╚═════╝  ╚═════╝ ╚══════╝

2018-03-13 20:16:54.737 +00:00 [INF] - Initializing configuration
2018-03-13 20:16:58.943 +00:00 [INF] - Created persistent store at /tmp/edgeHub
2018-03-13 20:16:59.299 +00:00 [INF] - Attempting to connect to IoT Hub for client TC-IoT-Edge/$edgeHub via AMQP...
2018-03-13 20:17:04.691 +00:00 [INF] - Connected to IoT Hub for client TC-IoT-Edge/$edgeHub via AMQP, with client operation timeout 60000.
2018-03-13 20:17:04.769 +00:00 [INF] - Created cloud connection for client TC-IoT-Edge/$edgeHub
2018-03-13 20:17:04.827 +00:00 [INF] - New device connection for device TC-IoT-Edge/$edgeHub
2018-03-13 20:17:06.007 +00:00 [INF] - Created new message store
2018-03-13 20:17:06.036 +00:00 [INF] - Started task to cleanup processed and stale messages
2018-03-13 20:17:07.914 +00:00 [INF] - Obtained edge hub config from module twin
2018-03-13 20:17:08.770 +00:00 [INF] - Set the following 1 route(s) in edge hub
2018-03-13 20:17:08.775 +00:00 [INF] - modbusToIoTHub: FROM /messages/modules/modbus/outputs/modbusOutput INTO $upstream
2018-03-13 20:17:08.781 +00:00 [INF] - Updated message store TTL to 7200 seconds
2018-03-13 20:17:08.783 +00:00 [INF] - Updated the edge hub store and forward configuration
2018-03-13 20:17:08.785 +00:00 [INF] - Initialized edge hub configuration
2018-03-13 20:17:08.785 +00:00 [INF] - Starting Http Server
2018-03-13 20:17:09.313 +00:00 [INF] - Starting MQTT Server
2018-03-13 20:17:09.436 +00:00 [INF] - Starting
2018-03-13 20:17:09.735 +00:00 [INF] - Initializing TLS endpoint on port 8883.
2018-03-13 20:17:10.270 +00:00 [INF] - Started
2018-03-13 20:17:20.200 +00:00 [INF] - Attempting to connect to IoT Hub for client TC-IoT-Edge/modbus via AMQP...
2018-03-13 20:17:20.305 +00:00 [INF] - New token requested by client TC-IoT-Edge/modbus, but using existing token as it is usable.
2018-03-13 20:17:20.484 +00:00 [INF] - Connected to IoT Hub for client TC-IoT-Edge/modbus via AMQP, with client operation timeout 60000.
2018-03-13 20:17:20.621 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus on cloud reconnect
2018-03-13 20:17:20.621 +00:00 [INF] - Created cloud connection for client TC-IoT-Edge/modbus
2018-03-13 20:17:20.625 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-13 20:17:20.631 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-13 20:17:20.636 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-13 20:17:20.677 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 0a0f3ad1
2018-03-13 20:17:20.834 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-13 20:17:20.915 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-13 20:17:20.920 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-13 20:17:21.321 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-13 20:17:27.432 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-13 20:17:27.533 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-13 20:47:06.077 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-13 20:47:06.306 +00:00 [INF] - Cleaned up 27 messages from queue for endpoint iothub and 27 messages from message store.
2018-03-13 21:15:17.012 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-13 21:15:17.097 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-13 21:15:17.117 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-13 21:15:17.162 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-13 21:15:17.165 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-13 21:15:17.664 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 01:00:00.3482854
2018-03-13 21:15:17.665 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-13 21:15:17.666 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-13 21:15:17.666 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-13 21:15:17.666 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 20b5afdb
2018-03-13 21:15:17.668 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-13 21:15:17.671 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-13 21:15:17.672 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-13 21:15:17.675 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-13 21:17:36.310 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-13 21:17:36.424 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-13 21:48:06.422 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-13 21:48:06.535 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-13 22:13:17.991 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-13 22:13:18.094 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-13 22:13:18.095 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-13 22:13:18.095 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-13 22:13:18.095 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-13 22:13:18.300 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6992274
2018-03-13 22:13:18.301 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-13 22:13:18.301 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-13 22:13:18.301 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-13 22:13:18.301 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 67464470
2018-03-13 22:13:18.303 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-13 22:13:18.306 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-13 22:13:18.306 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-13 22:13:18.308 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-13 22:16:58.950 +00:00 [INF] - Starting compaction of stores
2018-03-13 22:16:58.954 +00:00 [INF] - Starting compaction of store twins
2018-03-13 22:16:58.988 +00:00 [INF] - Starting compaction of store iothub
2018-03-13 22:16:59.005 +00:00 [INF] - Starting compaction of store messages
2018-03-13 22:16:59.023 +00:00 [INF] - Starting compaction of store default
2018-03-13 22:16:59.023 +00:00 [INF] - Starting compaction of store sessions
2018-03-13 22:16:59.045 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-13 22:18:36.540 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-13 22:18:36.630 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-13 22:49:06.642 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-13 22:49:06.801 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-13 23:11:18.000 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-13 23:11:18.001 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-13 23:11:18.097 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-13 23:11:18.097 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-13 23:11:18.098 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-13 23:11:18.372 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 664ad086
2018-03-13 23:11:18.702 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 015adade
2018-03-13 23:11:19.286 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 5112454c
2018-03-13 23:11:20.136 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 37388837
2018-03-13 23:11:22.055 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 63fbc10f
2018-03-13 23:11:25.287 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7141074
2018-03-13 23:11:25.287 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-13 23:11:25.288 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-13 23:11:25.288 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-13 23:11:25.289 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 5d62c721
2018-03-13 23:11:25.291 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-13 23:11:25.293 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-13 23:11:25.294 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-13 23:11:25.296 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-13 23:19:36.822 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-13 23:19:36.935 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-13 23:50:06.935 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-13 23:50:07.118 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 00:09:25.002 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 00:09:25.003 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 00:09:25.004 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 00:09:25.004 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 00:09:25.004 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 00:09:25.214 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7861853
2018-03-14 00:09:25.214 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 00:09:25.214 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 00:09:25.214 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 00:09:25.215 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 0dffacd6
2018-03-14 00:09:25.217 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 00:09:25.226 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 00:09:25.226 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 00:09:25.227 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 00:16:58.940 +00:00 [INF] - Starting compaction of stores
2018-03-14 00:16:58.940 +00:00 [INF] - Starting compaction of store twins
2018-03-14 00:16:58.963 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 00:16:58.980 +00:00 [INF] - Starting compaction of store messages
2018-03-14 00:16:59.028 +00:00 [INF] - Starting compaction of store default
2018-03-14 00:16:59.029 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 00:16:59.046 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 00:20:37.120 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 00:20:37.232 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 00:51:07.235 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 00:51:07.343 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 01:07:25.013 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 01:07:25.014 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 01:07:25.015 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 01:07:25.016 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 01:07:25.016 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 01:07:25.209 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7905289
2018-03-14 01:07:25.209 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 01:07:25.210 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 01:07:25.210 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 01:07:25.210 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 3248bba3
2018-03-14 01:07:25.212 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 01:07:25.213 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 01:07:25.213 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 01:07:25.215 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 01:21:37.347 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 01:21:37.459 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 01:52:07.470 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 01:52:07.691 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 02:05:24.999 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 02:05:25.000 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 02:05:25.000 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 02:05:25.001 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 02:05:25.093 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 02:05:25.362 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 63344866
2018-03-14 02:05:25.707 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 45e983aa
2018-03-14 02:05:26.262 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 2a62e7cc
2018-03-14 02:05:27.100 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 3243218d
2018-03-14 02:05:28.891 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 0c23618a
2018-03-14 02:05:32.489 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.5109583
2018-03-14 02:05:32.489 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 02:05:32.489 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 02:05:32.490 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 02:05:32.490 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 2280eca7
2018-03-14 02:05:32.491 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 02:05:32.494 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 02:05:32.494 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 02:05:32.496 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 02:16:58.939 +00:00 [INF] - Starting compaction of stores
2018-03-14 02:16:58.940 +00:00 [INF] - Starting compaction of store twins
2018-03-14 02:16:59.055 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 02:16:59.096 +00:00 [INF] - Starting compaction of store messages
2018-03-14 02:16:59.110 +00:00 [INF] - Starting compaction of store default
2018-03-14 02:16:59.111 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 02:16:59.123 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 02:22:37.698 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 02:22:37.787 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-14 02:53:07.790 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 02:53:08.034 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 03:03:31.992 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 03:03:31.993 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 03:03:31.994 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 03:03:31.994 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 03:03:31.994 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 03:03:32.274 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7260320
2018-03-14 03:03:32.274 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 03:03:32.275 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 03:03:32.275 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 03:03:32.276 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 117adc30
2018-03-14 03:03:32.277 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 03:03:32.280 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 03:03:32.280 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 03:03:32.283 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 03:23:38.036 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 03:23:38.136 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 03:54:08.132 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 03:54:08.333 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 04:01:31.991 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 04:01:31.992 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 04:01:31.992 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 04:01:31.993 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 04:01:31.993 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 04:01:32.284 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7152791
2018-03-14 04:01:32.285 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 04:01:32.285 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 04:01:32.286 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 04:01:32.286 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 5d6302d8
2018-03-14 04:01:32.288 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 04:01:32.290 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 04:01:32.291 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 04:01:32.293 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 04:16:58.940 +00:00 [INF] - Starting compaction of stores
2018-03-14 04:16:58.941 +00:00 [INF] - Starting compaction of store twins
2018-03-14 04:16:58.968 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 04:16:58.995 +00:00 [INF] - Starting compaction of store messages
2018-03-14 04:16:59.013 +00:00 [INF] - Starting compaction of store default
2018-03-14 04:16:59.014 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 04:16:59.030 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 04:24:38.330 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 04:24:38.449 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 04:55:08.455 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 04:55:08.678 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 04:59:32.008 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 04:59:32.010 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 04:59:32.010 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 04:59:32.011 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 04:59:32.012 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 04:59:32.236 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 16dfda06
2018-03-14 04:59:32.576 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 1631764a
2018-03-14 04:59:33.159 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 68228132
2018-03-14 04:59:34.102 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 66c911fa
2018-03-14 04:59:35.792 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 010549fc
2018-03-14 04:59:39.324 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6760497
2018-03-14 04:59:39.324 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 04:59:39.324 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 04:59:39.325 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 04:59:39.325 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 52851f0d
2018-03-14 04:59:39.327 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 04:59:39.330 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 04:59:39.330 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 04:59:39.332 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 05:25:38.685 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 05:25:38.800 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 05:56:08.816 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 05:56:08.981 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 05:57:39.015 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 05:57:39.016 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 05:57:39.017 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 05:57:39.017 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 05:57:39.018 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 05:57:39.226 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7738326
2018-03-14 05:57:39.226 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 05:57:39.226 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 05:57:39.227 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 05:57:39.227 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 25a130bb
2018-03-14 05:57:39.228 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 05:57:39.230 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 05:57:39.231 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 05:57:39.232 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 06:16:58.941 +00:00 [INF] - Starting compaction of stores
2018-03-14 06:16:58.941 +00:00 [INF] - Starting compaction of store twins
2018-03-14 06:16:58.964 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 06:16:58.983 +00:00 [INF] - Starting compaction of store messages
2018-03-14 06:16:59.000 +00:00 [INF] - Starting compaction of store default
2018-03-14 06:16:59.000 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 06:16:59.022 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 06:26:38.991 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 06:26:39.101 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-14 06:55:39.008 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 06:55:39.009 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 06:55:39.010 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 06:55:39.010 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 06:55:39.011 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 06:55:39.219 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7805888
2018-03-14 06:55:39.220 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 06:55:39.220 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 06:55:39.220 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 06:55:39.220 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 72973458
2018-03-14 06:55:39.222 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 06:55:39.224 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 06:55:39.224 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 06:55:39.226 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 06:57:09.122 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 06:57:09.235 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 07:27:39.240 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 07:27:39.355 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 07:53:38.990 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 07:53:38.991 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 07:53:38.991 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 07:53:38.992 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 07:53:38.992 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 07:53:39.227 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 6e098714
2018-03-14 07:53:39.546 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 53d16ef3
2018-03-14 07:53:40.164 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 3a2efb53
2018-03-14 07:53:41.200 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 391328f7
2018-03-14 07:53:42.860 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 2e25570f
2018-03-14 07:53:46.805 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 01:00:00.1941036
2018-03-14 07:53:46.806 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 07:53:46.806 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 07:53:46.807 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 07:53:46.807 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 27a29e29
2018-03-14 07:53:46.809 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 07:53:46.812 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 07:53:46.812 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 07:53:46.814 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 07:58:09.357 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 07:58:09.471 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 08:16:58.945 +00:00 [INF] - Starting compaction of stores
2018-03-14 08:16:58.946 +00:00 [INF] - Starting compaction of store twins
2018-03-14 08:16:58.967 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 08:16:58.984 +00:00 [INF] - Starting compaction of store messages
2018-03-14 08:16:59.008 +00:00 [INF] - Starting compaction of store default
2018-03-14 08:16:59.032 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 08:16:59.049 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 08:28:39.480 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 08:28:39.633 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 08:51:47.008 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 08:51:47.009 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 08:51:47.010 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 08:51:47.011 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 08:51:47.011 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 08:51:47.309 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6908725
2018-03-14 08:51:47.309 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 08:51:47.309 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 08:51:47.310 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 08:51:47.311 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 0237969e
2018-03-14 08:51:47.312 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 08:51:47.315 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 08:51:47.315 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 08:51:47.318 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 08:59:09.634 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 08:59:09.745 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 09:29:39.755 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 09:29:39.967 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 09:49:46.992 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 09:49:46.993 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 09:49:46.993 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 09:49:46.994 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 09:49:46.995 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 09:49:47.267 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7324999
2018-03-14 09:49:47.269 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 09:49:47.270 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 09:49:47.271 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 09:49:47.271 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 614695f6
2018-03-14 09:49:47.273 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 09:49:47.276 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 09:49:47.276 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 09:49:47.278 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 10:00:09.960 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 10:00:10.072 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 10:16:58.940 +00:00 [INF] - Starting compaction of stores
2018-03-14 10:16:58.940 +00:00 [INF] - Starting compaction of store twins
2018-03-14 10:16:58.962 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 10:16:58.986 +00:00 [INF] - Starting compaction of store messages
2018-03-14 10:16:59.012 +00:00 [INF] - Starting compaction of store default
2018-03-14 10:16:59.013 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 10:16:59.030 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 10:30:40.075 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 10:30:40.168 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 10:47:47.007 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 10:47:47.008 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 10:47:47.008 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 10:47:47.104 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 10:47:47.104 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 10:47:47.369 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 05611a9e
2018-03-14 10:47:47.705 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 2abe0238
2018-03-14 10:47:48.354 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 2779a1d8
2018-03-14 10:47:49.318 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 6b4c48c2
2018-03-14 10:47:51.068 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 110686ba
2018-03-14 10:47:54.824 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 01:00:00.1758582
2018-03-14 10:47:54.825 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 10:47:54.825 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 10:47:54.826 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 10:47:54.826 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 6ccf146a
2018-03-14 10:47:54.828 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 10:47:54.831 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 10:47:54.831 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 10:47:54.833 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 11:01:10.180 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 11:01:10.303 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-14 11:31:40.315 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 11:31:40.539 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 11:45:55.029 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 11:45:55.031 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 11:45:55.032 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 11:45:55.032 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 11:45:55.033 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 11:45:55.237 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7624133
2018-03-14 11:45:55.266 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 11:45:55.266 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 11:45:55.266 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 11:45:55.267 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 2a309b0b
2018-03-14 11:45:55.270 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 11:45:55.271 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 11:45:55.271 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 11:45:55.273 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 12:02:10.542 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 12:02:10.653 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 12:16:58.943 +00:00 [INF] - Starting compaction of stores
2018-03-14 12:16:58.944 +00:00 [INF] - Starting compaction of store twins
2018-03-14 12:16:58.973 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 12:16:58.997 +00:00 [INF] - Starting compaction of store messages
2018-03-14 12:16:59.015 +00:00 [INF] - Starting compaction of store default
2018-03-14 12:16:59.015 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 12:16:59.031 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 12:32:40.662 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 12:32:40.764 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 12:43:55.092 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 12:43:55.093 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 12:43:55.093 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 12:43:55.094 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 12:43:55.095 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 12:43:55.327 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6722854
2018-03-14 12:43:55.328 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 12:43:55.328 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 12:43:55.329 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 12:43:55.329 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 5e3f5a0b
2018-03-14 12:43:55.331 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 12:43:55.333 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 12:43:55.333 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 12:43:55.336 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 13:03:10.761 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 13:03:10.872 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 13:33:40.880 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 13:33:41.034 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 13:41:55.006 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 13:41:55.007 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 13:41:55.007 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 13:41:55.008 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 13:41:55.008 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 13:41:55.358 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 53cc06fc
2018-03-14 13:41:55.692 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 17d512a5
2018-03-14 13:41:56.267 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 30feaf5c
2018-03-14 13:41:57.284 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 321630c7
2018-03-14 13:41:59.118 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 198c6542
2018-03-14 13:42:02.477 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.5225373
2018-03-14 13:42:02.478 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 13:42:02.478 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 13:42:02.478 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 13:42:02.478 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 08ef899c
2018-03-14 13:42:02.480 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 13:42:02.482 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 13:42:02.483 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 13:42:02.485 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 14:04:11.051 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 14:04:11.164 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 14:16:58.947 +00:00 [INF] - Starting compaction of stores
2018-03-14 14:16:58.948 +00:00 [INF] - Starting compaction of store twins
2018-03-14 14:16:58.969 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 14:16:58.986 +00:00 [INF] - Starting compaction of store messages
2018-03-14 14:16:59.003 +00:00 [INF] - Starting compaction of store default
2018-03-14 14:16:59.004 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 14:16:59.026 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 14:34:41.162 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 14:34:41.309 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 14:40:02.006 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 14:40:02.007 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 14:40:02.008 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 14:40:02.010 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 14:40:02.011 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 14:40:02.271 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7284735
2018-03-14 14:40:02.272 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 14:40:02.272 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 14:40:02.273 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 14:40:02.273 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 0598443a
2018-03-14 14:40:02.275 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 14:40:02.327 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 14:40:02.328 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 14:40:02.330 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 15:05:11.307 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 15:05:11.396 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-14 15:35:41.392 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 15:35:41.550 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 15:38:01.992 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 15:38:01.993 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 15:38:01.993 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 15:38:01.994 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 15:38:01.994 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 15:38:02.278 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7215457
2018-03-14 15:38:02.279 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 15:38:02.280 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 15:38:02.280 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 15:38:02.281 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 0421b3af
2018-03-14 15:38:02.334 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 15:38:02.336 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 15:38:02.336 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 15:38:02.338 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 16:06:11.550 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 16:06:11.655 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 16:16:58.940 +00:00 [INF] - Starting compaction of stores
2018-03-14 16:16:58.940 +00:00 [INF] - Starting compaction of store twins
2018-03-14 16:16:58.963 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 16:16:58.979 +00:00 [INF] - Starting compaction of store messages
2018-03-14 16:16:59.005 +00:00 [INF] - Starting compaction of store default
2018-03-14 16:16:59.027 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 16:16:59.045 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 16:36:02.003 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 16:36:02.003 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 16:36:02.004 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 16:36:02.004 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 16:36:02.004 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 16:36:02.261 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 169e1500
2018-03-14 16:36:02.623 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 6b7b93fb
2018-03-14 16:36:03.277 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 05986e0f
2018-03-14 16:36:04.206 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 22fe156a
2018-03-14 16:36:06.182 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 21ecb99e
2018-03-14 16:36:09.365 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6356097
2018-03-14 16:36:09.365 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 16:36:09.365 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 16:36:09.365 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 16:36:09.366 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 39ec9de0
2018-03-14 16:36:09.368 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 16:36:09.370 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 16:36:09.371 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 16:36:09.373 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 16:36:41.651 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 16:36:41.747 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 17:07:11.757 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 17:07:11.896 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 17:34:09.001 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 17:34:09.002 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 17:34:09.002 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 17:34:09.003 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 17:34:09.003 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 17:34:09.285 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7145506
2018-03-14 17:34:09.285 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 17:34:09.285 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 17:34:09.286 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 17:34:09.286 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 6640fa74
2018-03-14 17:34:09.288 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 17:34:09.291 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 17:34:09.291 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 17:34:09.293 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 17:37:41.892 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 17:37:42.004 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 18:08:12.002 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 18:08:12.110 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 18:16:58.944 +00:00 [INF] - Starting compaction of stores
2018-03-14 18:16:58.945 +00:00 [INF] - Starting compaction of store twins
2018-03-14 18:16:58.967 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 18:16:58.989 +00:00 [INF] - Starting compaction of store messages
2018-03-14 18:16:59.018 +00:00 [INF] - Starting compaction of store default
2018-03-14 18:16:59.018 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 18:16:59.034 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 18:32:09.005 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 18:32:09.006 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 18:32:09.006 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 18:32:09.006 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 18:32:09.007 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 18:32:09.214 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7851969
2018-03-14 18:32:09.215 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 18:32:09.215 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 18:32:09.215 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 18:32:09.216 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 13c5b179
2018-03-14 18:32:09.218 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 18:32:09.218 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 18:32:09.219 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 18:32:09.220 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 18:38:42.124 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 18:38:42.235 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 19:09:12.236 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 19:09:12.454 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 19:30:09.006 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 19:30:09.007 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 19:30:09.008 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 19:30:09.010 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 19:30:09.010 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 19:30:09.369 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 5bb9aa22
2018-03-14 19:30:09.715 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 5e7b10f3
2018-03-14 19:30:10.332 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 492f0122
2018-03-14 19:30:11.290 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 102a521c
2018-03-14 19:30:13.113 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 2333cbce
2018-03-14 19:30:15.951 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 01:00:00.0489560
2018-03-14 19:30:15.951 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 19:30:15.952 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 19:30:15.952 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 19:30:15.953 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 54ec83de
2018-03-14 19:30:15.954 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 19:30:15.957 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 19:30:15.957 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 19:30:15.959 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 19:39:42.471 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 19:39:42.578 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-14 20:10:12.571 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 20:10:12.756 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 20:16:58.943 +00:00 [INF] - Starting compaction of stores
2018-03-14 20:16:58.943 +00:00 [INF] - Starting compaction of store twins
2018-03-14 20:16:58.970 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 20:16:58.999 +00:00 [INF] - Starting compaction of store messages
2018-03-14 20:16:59.017 +00:00 [INF] - Starting compaction of store default
2018-03-14 20:16:59.017 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 20:16:59.034 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 20:28:16.104 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 20:28:16.105 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 20:28:16.105 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 20:28:16.107 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 20:28:16.107 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 20:28:16.336 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6631256
2018-03-14 20:28:16.337 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 20:28:16.337 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 20:28:16.337 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 20:28:16.338 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 04236494
2018-03-14 20:28:16.340 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 20:28:16.342 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 20:28:16.342 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 20:28:16.344 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 20:40:42.764 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 20:40:42.877 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 21:11:12.884 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 21:11:13.085 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 21:26:15.992 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 21:26:15.992 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 21:26:15.993 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 21:26:15.993 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 21:26:15.993 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 21:26:16.209 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7912399
2018-03-14 21:26:16.209 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 21:26:16.210 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 21:26:16.210 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 21:26:16.211 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 3b91aa3d
2018-03-14 21:26:16.213 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 21:26:16.214 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 21:26:16.214 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 21:26:16.216 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 21:41:43.084 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 21:41:43.200 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 22:12:13.200 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 22:12:13.427 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 22:16:58.941 +00:00 [INF] - Starting compaction of stores
2018-03-14 22:16:58.941 +00:00 [INF] - Starting compaction of store twins
2018-03-14 22:16:58.964 +00:00 [INF] - Starting compaction of store iothub
2018-03-14 22:16:58.980 +00:00 [INF] - Starting compaction of store messages
2018-03-14 22:16:59.086 +00:00 [INF] - Starting compaction of store default
2018-03-14 22:16:59.086 +00:00 [INF] - Starting compaction of store sessions
2018-03-14 22:16:59.104 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-14 22:24:15.993 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 22:24:15.994 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 22:24:15.994 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 22:24:15.996 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 22:24:15.997 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 22:24:16.356 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 43b1ed0c
2018-03-14 22:24:16.729 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 491c51d5
2018-03-14 22:24:17.353 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 2ce206ef
2018-03-14 22:24:18.520 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 61655f51
2018-03-14 22:24:20.615 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 7b074ace
2018-03-14 22:24:24.196 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.8039468
2018-03-14 22:24:24.196 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 22:24:24.196 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 22:24:24.196 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 22:24:24.197 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 262e936d
2018-03-14 22:24:24.198 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 22:24:24.201 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 22:24:24.201 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 22:24:24.204 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 22:42:43.433 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 22:42:43.541 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 23:13:13.543 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 23:13:13.700 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-14 23:22:24.005 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-14 23:22:24.005 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-14 23:22:24.006 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-14 23:22:24.006 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-14 23:22:24.006 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-14 23:22:24.270 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7299827
2018-03-14 23:22:24.270 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-14 23:22:24.270 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-14 23:22:24.271 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-14 23:22:24.271 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 4c5c7c1e
2018-03-14 23:22:24.272 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-14 23:22:24.275 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-14 23:22:24.275 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-14 23:22:24.326 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-14 23:43:43.720 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-14 23:43:43.824 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-15 00:14:13.820 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 00:14:13.962 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 00:16:58.940 +00:00 [INF] - Starting compaction of stores
2018-03-15 00:16:58.940 +00:00 [INF] - Starting compaction of store twins
2018-03-15 00:16:58.961 +00:00 [INF] - Starting compaction of store iothub
2018-03-15 00:16:58.978 +00:00 [INF] - Starting compaction of store messages
2018-03-15 00:16:59.000 +00:00 [INF] - Starting compaction of store default
2018-03-15 00:16:59.022 +00:00 [INF] - Starting compaction of store sessions
2018-03-15 00:16:59.039 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-15 00:20:23.996 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 00:20:24.095 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 00:20:24.095 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 00:20:24.095 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 00:20:24.095 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 00:20:24.322 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6781691
2018-03-15 00:20:24.322 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 00:20:24.322 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 00:20:24.323 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 00:20:24.323 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 2a10c59b
2018-03-15 00:20:24.325 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 00:20:24.327 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 00:20:24.327 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 00:20:24.330 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 00:44:43.971 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 00:44:44.080 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 01:15:14.095 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 01:15:14.300 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 01:18:24.004 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 01:18:24.005 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 01:18:24.005 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 01:18:24.005 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 01:18:24.006 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 01:18:24.370 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 6c00db3e
2018-03-15 01:18:24.732 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 09e32727
2018-03-15 01:18:25.335 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 12fd0afe
2018-03-15 01:18:26.363 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 69303756
2018-03-15 01:18:28.488 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 21866fb1
2018-03-15 01:18:31.728 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 01:00:00.2719500
2018-03-15 01:18:31.728 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 01:18:31.728 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 01:18:31.729 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 01:18:31.729 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 738afff3
2018-03-15 01:18:31.731 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 01:18:31.734 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 01:18:31.734 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 01:18:31.737 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 01:45:44.300 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 01:45:44.405 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 02:16:14.413 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 02:16:14.643 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 02:16:32.008 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 02:16:32.008 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 02:16:32.009 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 02:16:32.009 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 02:16:32.010 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 02:16:32.284 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7160695
2018-03-15 02:16:32.284 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 02:16:32.284 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 02:16:32.284 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 02:16:32.284 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 590acf16
2018-03-15 02:16:32.335 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 02:16:32.338 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 02:16:32.338 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 02:16:32.340 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 02:16:58.947 +00:00 [INF] - Starting compaction of stores
2018-03-15 02:16:58.947 +00:00 [INF] - Starting compaction of store twins
2018-03-15 02:16:59.064 +00:00 [INF] - Starting compaction of store iothub
2018-03-15 02:16:59.090 +00:00 [INF] - Starting compaction of store messages
2018-03-15 02:16:59.110 +00:00 [INF] - Starting compaction of store default
2018-03-15 02:16:59.111 +00:00 [INF] - Starting compaction of store sessions
2018-03-15 02:16:59.124 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-15 02:46:44.640 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 02:46:44.749 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 03:14:31.991 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 03:14:31.992 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 03:14:31.992 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 03:14:31.992 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 03:14:31.993 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 03:14:32.272 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7270901
2018-03-15 03:14:32.273 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 03:14:32.273 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 03:14:32.273 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 03:14:32.273 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 6519fd78
2018-03-15 03:14:32.275 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 03:14:32.278 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 03:14:32.278 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 03:14:32.280 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 03:17:14.744 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 03:17:14.830 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 03:47:44.831 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 03:47:45.009 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 04:12:32.011 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 04:12:32.012 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 04:12:32.012 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 04:12:32.013 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 04:12:32.013 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 04:12:32.398 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 4e392ab9
2018-03-15 04:12:32.794 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 360d47a4
2018-03-15 04:12:33.439 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 227513f7
2018-03-15 04:12:34.422 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 42a9c313
2018-03-15 04:12:36.507 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 220a301a
2018-03-15 04:12:39.574 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.4260986
2018-03-15 04:12:39.574 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 04:12:39.574 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 04:12:39.574 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 04:12:39.575 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 5f398133
2018-03-15 04:12:39.576 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 04:12:39.579 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 04:12:39.579 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 04:12:39.581 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 04:16:58.941 +00:00 [INF] - Starting compaction of stores
2018-03-15 04:16:58.941 +00:00 [INF] - Starting compaction of store twins
2018-03-15 04:16:58.971 +00:00 [INF] - Starting compaction of store iothub
2018-03-15 04:16:58.998 +00:00 [INF] - Starting compaction of store messages
2018-03-15 04:16:59.016 +00:00 [INF] - Starting compaction of store default
2018-03-15 04:16:59.016 +00:00 [INF] - Starting compaction of store sessions
2018-03-15 04:16:59.032 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-15 04:18:15.021 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 04:18:15.138 +00:00 [INF] - Cleaned up 13 messages from queue for endpoint iothub and 13 messages from message store.
2018-03-15 04:48:45.132 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 04:48:45.286 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 05:10:39.000 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 05:10:39.001 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 05:10:39.001 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 05:10:39.002 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 05:10:39.002 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 05:10:39.277 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7228469
2018-03-15 05:10:39.277 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 05:10:39.278 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 05:10:39.278 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 05:10:39.279 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 3f59dc58
2018-03-15 05:10:39.281 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 05:10:39.283 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 05:10:39.284 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 05:10:39.285 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 05:19:15.285 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 05:19:15.386 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 05:49:45.386 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 05:49:45.537 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 06:08:39.011 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 06:08:39.013 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 06:08:39.114 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 06:08:39.114 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 06:08:39.115 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 06:08:39.323 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.6761039
2018-03-15 06:08:39.324 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 06:08:39.324 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 06:08:39.324 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 06:08:39.325 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 4b747cbd
2018-03-15 06:08:39.327 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 06:08:39.329 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 06:08:39.330 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 06:08:39.331 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 06:16:58.940 +00:00 [INF] - Starting compaction of stores
2018-03-15 06:16:58.940 +00:00 [INF] - Starting compaction of store twins
2018-03-15 06:16:58.961 +00:00 [INF] - Starting compaction of store iothub
2018-03-15 06:16:58.978 +00:00 [INF] - Starting compaction of store messages
2018-03-15 06:16:58.994 +00:00 [INF] - Starting compaction of store default
2018-03-15 06:16:58.995 +00:00 [INF] - Starting compaction of store sessions
2018-03-15 06:16:59.016 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-15 06:20:15.530 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 06:20:15.650 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 06:50:45.663 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 06:50:45.804 +00:00 [INF] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-15 07:06:39.002 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 07:06:39.003 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 07:06:39.003 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 07:06:39.004 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 07:06:39.004 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 07:06:39.371 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 447d07c3
2018-03-15 07:06:39.764 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 13b3f05f
2018-03-15 07:06:40.321 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 0f929ea0
2018-03-15 07:06:41.450 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 328b75c6
2018-03-15 07:06:43.407 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 23a01348
2018-03-15 07:06:46.263 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7364960
2018-03-15 07:06:46.264 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 07:06:46.264 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 07:06:46.264 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 07:06:46.264 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 1a0648ef
2018-03-15 07:06:46.266 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 07:06:46.269 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 07:06:46.269 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 07:06:46.272 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 07:21:15.810 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 07:21:15.900 +00:00 [INF] - Cleaned up 6 messages from queue for endpoint iothub and 6 messages from message store.
2018-03-15 07:51:45.900 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 07:51:45.901 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 08:04:46.013 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 08:04:46.014 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 08:04:46.015 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 08:04:46.016 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 08:04:46.017 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 08:04:46.224 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7751077
2018-03-15 08:04:46.225 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 08:04:46.225 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 08:04:46.226 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 08:04:46.226 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 3b0b8e3d
2018-03-15 08:04:46.228 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 08:04:46.229 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 08:04:46.229 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 08:04:46.231 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 08:16:58.940 +00:00 [INF] - Starting compaction of stores
2018-03-15 08:16:58.941 +00:00 [INF] - Starting compaction of store twins
2018-03-15 08:16:58.963 +00:00 [INF] - Starting compaction of store iothub
2018-03-15 08:16:58.979 +00:00 [INF] - Starting compaction of store messages
2018-03-15 08:16:59.001 +00:00 [INF] - Starting compaction of store default
2018-03-15 08:16:59.012 +00:00 [INF] - Starting compaction of store sessions
2018-03-15 08:16:59.029 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-15 08:22:15.907 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 08:22:15.908 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 08:52:45.900 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 08:52:45.901 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 09:02:46.000 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 09:02:46.002 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 09:02:46.002 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 09:02:46.003 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 09:02:46.004 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 09:02:46.281 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7189562
2018-03-15 09:02:46.281 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 09:02:46.281 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 09:02:46.282 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 09:02:46.282 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 7edf831d
2018-03-15 09:02:46.284 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 09:02:46.287 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 09:02:46.287 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 09:02:46.290 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 09:23:15.906 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 09:23:15.907 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 09:53:45.910 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 09:53:45.911 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 10:00:45.995 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 10:00:45.996 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 10:00:45.996 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 10:00:45.997 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 10:00:45.998 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 10:00:46.381 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 13b1ae11
2018-03-15 10:00:46.417 +00:00 [ERR] - LEAK: IByteBuffer.Release() was not called before it's garbage-collected. Enable advanced leak reporting to find out where the leak occurred. To enable advanced leak reporting, set environment variable io.netty.leakDetection.level to advanced or set ResourceLeakDetector.Level in code. See http://netty.io/wiki/reference-counted-objects.html for more information.
2018-03-15 10:00:46.768 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 756d051e
2018-03-15 10:00:47.409 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 6e6443ba
2018-03-15 10:00:48.550 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 5070136c
2018-03-15 10:00:50.465 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 5c0d82ac
2018-03-15 10:00:53.716 +00:00 [INF] - Obtained new token for client TC-IoT-Edge/modbus that expires in 01:00:00.2834812
2018-03-15 10:00:53.716 +00:00 [INF] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-15 10:00:53.717 +00:00 [INF] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-15 10:00:53.717 +00:00 [INF] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-15 10:00:53.717 +00:00 [INF] - ClientAuthenticated, TC-IoT-Edge/modbus, 2432e4e8
2018-03-15 10:00:53.718 +00:00 [INF] - New device connection for device TC-IoT-Edge/modbus
2018-03-15 10:00:53.721 +00:00 [INF] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-15 10:00:53.721 +00:00 [INF] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-15 10:00:53.725 +00:00 [INF] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-15 10:16:58.947 +00:00 [INF] - Starting compaction of stores
2018-03-15 10:16:58.948 +00:00 [INF] - Starting compaction of store twins
2018-03-15 10:16:58.969 +00:00 [INF] - Starting compaction of store iothub
2018-03-15 10:16:58.970 +00:00 [INF] - Starting compaction of store messages
2018-03-15 10:16:58.970 +00:00 [INF] - Starting compaction of store default
2018-03-15 10:16:58.970 +00:00 [INF] - Starting compaction of store sessions
2018-03-15 10:16:58.987 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-15 10:24:15.911 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 10:24:15.912 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 10:54:45.922 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 10:54:45.923 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 10:58:54.000 +00:00 [INF] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-15 10:58:54.001 +00:00 [WRN] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.,
2018-03-15 10:58:54.001 +00:00 [INF] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-15 10:58:54.003 +00:00 [INF] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-15 10:58:54.004 +00:00 [INF] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-15 10:58:54.676 +00:00 [WRN] - Failed to update reported  properties for TC-IoT-Edge/$edgeHub with error Exception of type 'System.OutOfMemoryException' was thrown.
2018-03-15 10:58:55.728 +00:00 [WRN] - Failed to mark a promise as failure because it's done already: TaskCompletionSource[status: RanToCompletion]
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.Threading.Tasks.Task.<>c.<EnsureContingentPropertiesInitialized>b__101_0()
   at System.Threading.LazyInitializer.EnsureInitializedCore[T](T& target, Func`1 valueFactory)
   at System.Threading.LazyInitializer.EnsureInitialized[T](T& target, Func`1 valueFactory)
   at System.Threading.Tasks.Task.EnsureContingentPropertiesInitialized()
   at System.Threading.Tasks.Task.InternalCancel(Boolean bCancelNonExecutingOnly)
   at System.Threading.Tasks.StandardTaskContinuation.Run(Task completedTask, Boolean bCanInlineContinuationTask)
   at System.Threading.Tasks.Task.RunContinuations(Object continuationObject)
   at System.Threading.Tasks.Task.FinishContinuations()
   at System.Threading.Tasks.Task`1.TrySetResult(TResult result)
   at DotNetty.Transport.Channels.Util.SafeSetSuccess(TaskCompletionSource promise, IInternalLogger logger)
   at DotNetty.Transport.Channels.AbstractChannel.AbstractUnsafe.Register0(TaskCompletionSource promise)
2018-03-15 10:58:57.934 +00:00 [WRN] - TLS handshake failed., System.IO.IOException: Channel is closed, 1f1f17dd
2018-03-15 10:58:59.532 +00:00 [WRN] - Error occurred while shutting down the channel., System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at DotNetty.Transport.Channels.ChannelOutboundBuffer.Remove0(Exception cause, Boolean notifyWritability)
   at DotNetty.Transport.Channels.ChannelOutboundBuffer.FailFlushed(Exception cause, Boolean notify)
   at DotNetty.Transport.Channels.AbstractChannel.AbstractUnsafe.CloseAsync(Exception cause, Boolean notify)
   at DotNetty.Transport.Channels.AbstractChannel.AbstractUnsafe.CloseAsync()
   at DotNetty.Transport.Channels.DefaultChannelPipeline.HeadContext.CloseAsync(IChannelHandlerContext context)
   at DotNetty.Transport.Channels.AbstractChannelHandlerContext.InvokeCloseAsync()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Microsoft.Azure.Devices.ProtocolGateway.Mqtt.MqttAdapter.<Shutdown>d__83.MoveNext(), 5b87a873
2018-03-15 10:59:01.184 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 10:59:03.166 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 10:59:04.110 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:02.163 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:03.128 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:05.159 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:07.149 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:09.168 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:11.163 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:13.189 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:15.169 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:17.154 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:19.168 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:21.163 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:23.167 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:26.185 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:29.164 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:31.167 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:33.162 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:35.166 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
2018-03-15 11:00:38.140 +00:00 [ERR] - Heartbeat.OnHeartbeat
System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.String.CtorCharCount(Char c, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.EnsureCapacity(Int32 length)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s, Int32 offset, Int32 count)
   at Microsoft.Extensions.Primitives.InplaceStringBuilder.Append(String s)
   at Microsoft.Net.Http.Headers.DateTimeFormatter.ToRfc1123String(DateTimeOffset dateTime, Boolean quoted)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.SetDateValues(DateTimeOffset value)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Http.DateHeaderValueManager.OnHeartbeat(DateTimeOffset now)
   at Microsoft.AspNetCore.Server.Kestrel.Core.Internal.Infrastructure.Heartbeat.OnHeartbeat()
FailFast: Exception thrown from SocketAsyncEngine event loop: System.OutOfMemoryException: Exception of type 'System.OutOfMemoryException' was thrown.
   at System.Threading.ThreadPool.QueueUserWorkItem(WaitCallback callBack, Object state)
   at System.Net.Sockets.SocketAsyncContext.AsyncOperation.TryCompleteOrAbortAsync(SocketAsyncContext context, Boolean abort)
   at System.Net.Sockets.SocketAsyncContext.OperationQueue`1.Complete(SocketAsyncContext context)
   at System.Net.Sockets.SocketAsyncContext.HandleEvents(SocketEvents events)
   at System.Net.Sockets.SocketAsyncEngine.EventLoop()

   at System.Environment.FailFast(System.String, System.Exception)
   at System.Environment.FailFast(System.String, System.Exception)
   at System.Net.Sockets.SocketAsyncEngine.EventLoop()
   at System.Threading.Tasks.Task.InnerInvoke()
   at System.Threading.Tasks.Task+<>c.<.cctor>b__276_1(System.Object)
   at System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object)
   at System.Threading.Tasks.Task.ExecuteWithThreadLocal(System.Threading.Tasks.Task ByRef)
   at System.Threading.Tasks.Task.ExecuteEntryUnsafe()
   at System.Threading.Tasks.ThreadPoolTaskScheduler+<>c.<.cctor>b__10_0(System.Object)
   at System.Threading.ThreadHelper.ThreadStart_Context(System.Object)
   at System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object)
   at System.Threading.ThreadHelper.ThreadStart(System.Object)
Aborted
Edge Hub Server Certificate File: /mnt/edgehub/edge-hub-server.cert.pfx
Edge Hub CA Server Certificate File: /mnt/edgehub/edge-chain-ca.cert.pem
SSL_CERTIFICATE_PATH=/mnt/edgehub
SSL_CERTIFICATE_NAME=edge-hub-server.cert.pfx
Executing: cp /mnt/edgehub/edge-chain-ca.cert.pem /usr/local/share/ca-certificates/edge-chain-ca.crt
Executing: update-ca-certificates
Updating certificates in /etc/ssl/certs...
0 added, 0 removed; done.
Running hooks in /etc/ca-certificates/update.d...
done.
Certificates installed successfully!
2018-03-15 11:01:06.972 +00:00 [INF] - User profile is available. Using '"/home/edgehubuser/.aspnet/DataProtection-Keys"' as key repository; keys will not be encrypted at rest.
2018-03-15 11:01:09.916 +00:00 [INF] - Starting Edge Hub.
2018-03-15 11:01:09.924 +00:00 [INF] - Version - 1.0.0-preview021.10543704 (c87b52c93b13f03bf34da8d9ae650d55368ccecb)
2018-03-15 11:01:09.925 +00:00 [INF] -
        █████╗ ███████╗██╗   ██╗██████╗ ███████╗
       ██╔══██╗╚══███╔╝██║   ██║██╔══██╗██╔════╝
       ███████║  ███╔╝ ██║   ██║██████╔╝█████╗
       ██╔══██║ ███╔╝  ██║   ██║██╔══██╗██╔══╝
       ██║  ██║███████╗╚██████╔╝██║  ██║███████╗
       ╚═╝  ╚═╝╚══════╝ ╚═════╝ ╚═╝  ╚═╝╚══════╝

 ██╗ ██████╗ ████████╗    ███████╗██████╗  ██████╗ ███████╗
 ██║██╔═══██╗╚══██╔══╝    ██╔════╝██╔══██╗██╔════╝ ██╔════╝
 ██║██║   ██║   ██║       █████╗  ██║  ██║██║  ███╗█████╗
 ██║██║   ██║   ██║       ██╔══╝  ██║  ██║██║   ██║██╔══╝
 ██║╚██████╔╝   ██║       ███████╗██████╔╝╚██████╔╝███████╗
 ╚═╝ ╚═════╝    ╚═╝       ╚══════╝╚═════╝  ╚═════╝ ╚══════╝

2018-03-15 11:01:09.950 +00:00 [INF] - Initializing configuration
2018-03-15 11:01:13.903 +00:00 [INF] - Created persistent store at /tmp/edgeHub
2018-03-15 11:01:14.247 +00:00 [INF] - Attempting to connect to IoT Hub for client TC-IoT-Edge/$edgeHub via AMQP...
2018-03-15 11:01:19.823 +00:00 [INF] - Connected to IoT Hub for client TC-IoT-Edge/$edgeHub via AMQP, with client operation timeout 60000.
2018-03-15 11:01:19.899 +00:00 [INF] - Created cloud connection for client TC-IoT-Edge/$edgeHub
2018-03-15 11:01:19.961 +00:00 [INF] - New device connection for device TC-IoT-Edge/$edgeHub
2018-03-15 11:01:21.117 +00:00 [INF] - Created new message store
2018-03-15 11:01:21.145 +00:00 [INF] - Started task to cleanup processed and stale messages
2018-03-15 11:01:23.032 +00:00 [INF] - Obtained edge hub config from module twin
2018-03-15 11:01:23.841 +00:00 [INF] - Set the following 1 route(s) in edge hub
2018-03-15 11:01:23.846 +00:00 [INF] - modbusToIoTHub: FROM /messages/modules/modbus/outputs/modbusOutput INTO $upstream
2018-03-15 11:01:23.852 +00:00 [INF] - Updated message store TTL to 7200 seconds
2018-03-15 11:01:23.853 +00:00 [INF] - Updated the edge hub store and forward configuration
2018-03-15 11:01:23.855 +00:00 [INF] - Initialized edge hub configuration
2018-03-15 11:01:23.856 +00:00 [INF] - Starting Http Server
2018-03-15 11:01:24.382 +00:00 [INF] - Starting MQTT Server
2018-03-15 11:01:24.496 +00:00 [INF] - Starting
2018-03-15 11:01:24.763 +00:00 [INF] - Initializing TLS endpoint on port 8883.
2018-03-15 11:01:25.279 +00:00 [INF] - Started
2018-03-15 11:31:21.191 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 11:31:21.262 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 12:01:51.261 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 12:01:51.262 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 12:32:21.270 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 12:32:21.271 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.
2018-03-15 13:01:13.908 +00:00 [INF] - Starting compaction of stores
2018-03-15 13:01:13.911 +00:00 [INF] - Starting compaction of store twins
2018-03-15 13:01:13.949 +00:00 [INF] - Starting compaction of store messages
2018-03-15 13:01:13.971 +00:00 [INF] - Starting compaction of store iothub
2018-03-15 13:01:13.971 +00:00 [INF] - Starting compaction of store sessions
2018-03-15 13:01:13.972 +00:00 [INF] - Starting compaction of store default
2018-03-15 13:01:13.972 +00:00 [INF] - Starting compaction of store checkpoints
2018-03-15 13:02:51.282 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-15 13:02:51.283 +00:00 [INF] - Cleaned up 0 messages from queue for endpoint iothub and 0 messages from message store.

Can someone help me?

Thank you

@aribeironovaes
Copy link
Contributor

Hi @termocontrole ,

From your logs looks like you are having problems on modbus module. It's getting connected to edge hub, but I can see that it's keeping having TLS Handshake failures.

Also, there was a time that you went Out of memory, which can be a problem that you are not able to send message to IoT Hub.

So, here is what I recommend:
1-1st turn Debug logs following this blog post: https://docs.microsoft.com/en-us/azure/iot-edge/troubleshoot
2 - I see that you have just 1 modbus module and 1 route. So, just to make sure your route is not broke, why don't you just use the default route? (Just to make sure you can receive the messages on the cloud).
The default route is:
"routes": {
"r1": "FROM /* INTO $upstream"
}

So, uninstall everything, make sure you have enough memory on your device (cleaning up logs) and do a deployment turning Debug logs and changing the route to the above.

3rd - Make sure you are using the latest version of both Device SDK and edge hub.

Let us know how it goes,

Thanks,

Angelo Ribeiro.

@termocontrole
Copy link
Author

Hi @aribeironovaes,

I followed your instructions and reinstalled everything, but this time with the debug logs on.

First, the debug logs helps a lot to understand what is going on with edgeAgent and edgeHub containers.

Second, things are working, the data is send from modbus container to edgeHub and to the IoT Hub. I'm using Stream Analytics to measure the amount of data that is coming, because IoT Hub gauge doesn't seem reliable. Using Stream Analytics, all data sent by IoT Edge is received by IoT Hub.

So, thank you very much for that.

But there is still somethings that I want to understand better:

edgeHub logs:

2018-03-27 13:51:03.528 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 13:51:03.529 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 13:51:03.627 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 45 with batch size 10.
2018-03-27 13:51:03.628 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 46.
2018-03-27 13:51:03.629 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 46 with batch size 10.
2018-03-27 13:51:03.629 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 46.
2018-03-27 13:51:03.858 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 13:51:04.998 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Getting new token for TC-IoT-Edge/modbus.
2018-03-27 13:51:04.999 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-27 13:51:05.000 +00:00 [WRN] [ProtocolGateway] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus., 
2018-03-27 13:51:05.001 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-27 13:51:05.002 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.DeviceProxy] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-27 13:51:05.002 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-27 13:51:05.003 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnection] - Updating device TC-IoT-Edge/modbus connection status to Disconnected
2018-03-27 13:51:05.213 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Obtained new token for client TC-IoT-Edge/modbus that expires in 00:59:59.7868522
2018-03-27 13:51:05.213 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Updated cloud connection for client TC-IoT-Edge/modbus
2018-03-27 13:51:05.213 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-27 13:51:05.213 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Authenticator] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-27 13:51:05.214 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SasTokenDeviceIdentityProvider] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-27 13:51:05.214 +00:00 [INF] [ProtocolGateway] - ClientAuthenticated, TC-IoT-Edge/modbus, 5c147a81
2018-03-27 13:51:05.214 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - Obtained cloud connection for device TC-IoT-Edge/modbus
2018-03-27 13:51:05.216 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Binding cloud listener for device TC-IoT-Edge/modbus
2018-03-27 13:51:05.216 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - New device connection for device TC-IoT-Edge/modbus
2018-03-27 13:51:05.216 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnection] - Updating device TC-IoT-Edge/modbus connection status to Connected
2018-03-27 13:51:05.225 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Device.DeviceMessageHandler] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-27 13:51:05.225 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-27 13:51:05.226 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStatePersistenceProvider] - Adding subscription $iothub/twin/res/# for client TC-IoT-Edge/modbus.
2018-03-27 13:51:05.226 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStatePersistenceProvider] - Adding subscription devices/TC-IoT-Edge/modules/modbus/# for client TC-IoT-Edge/modbus.
2018-03-27 13:51:05.227 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStatePersistenceProvider] - Adding subscription $iothub/twin/PATCH/properties/desired/# for client TC-IoT-Edge/modbus.
2018-03-27 13:51:05.228 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStateStoragePersistenceProvider] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-27 13:51:05.244 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Updating reported properties for device TC-IoT-Edge/$edgeHub
2018-03-27 13:51:05.244 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Successfully sent reported properties to cloud for TC-IoT-Edge/$edgeHub and reported properties version 0
2018-03-27 13:51:05.246 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Updated cached reported property for TC-IoT-Edge/$edgeHub at reported property version 841 cloudVerified True
2018-03-27 13:51:07.360 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Updating reported properties for device TC-IoT-Edge/$edgeHub
2018-03-27 13:51:07.361 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Successfully sent reported properties to cloud for TC-IoT-Edge/$edgeHub and reported properties version 0
2018-03-27 13:51:07.365 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Updated cached reported property for TC-IoT-Edge/$edgeHub at reported property version 841 cloudVerified True
2018-03-27 13:53:34.732 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 13:53:34.732 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 13:53:34.734 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 46 with batch size 10.
2018-03-27 13:53:34.735 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 47.
2018-03-27 13:53:34.735 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 47 with batch size 10.
2018-03-27 13:53:34.736 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 47.
2018-03-27 13:53:34.941 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 13:54:46.035 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Storage.RocksDb.DbStoreProvider] - Starting compaction of stores
2018-03-27 13:54:46.038 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Storage.RocksDb.DbStoreProvider] - Starting compaction of store checkpoints
2018-03-27 13:54:46.070 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Storage.RocksDb.DbStoreProvider] - Starting compaction of store sessions
2018-03-27 13:54:46.089 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Storage.RocksDb.DbStoreProvider] - Starting compaction of store twins
2018-03-27 13:54:46.105 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Storage.RocksDb.DbStoreProvider] - Starting compaction of store iothub
2018-03-27 13:54:46.120 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Storage.RocksDb.DbStoreProvider] - Starting compaction of store messages
2018-03-27 13:54:46.139 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Storage.RocksDb.DbStoreProvider] - Starting compaction of store default
2018-03-27 13:56:05.749 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 13:56:05.749 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 13:56:05.752 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 47 with batch size 10.
2018-03-27 13:56:05.847 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 48.
2018-03-27 13:56:05.848 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 48 with batch size 10.
2018-03-27 13:56:05.848 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 48.
2018-03-27 13:56:06.292 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 13:56:23.803 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-27 13:56:23.803 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Checkpoint for endpoint iothub is 47
2018-03-27 13:56:23.917 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-27 13:56:23.918 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Total messages cleaned up from queue for endpoint iothub = 48, and total messages cleaned up for message store = 48.
2018-03-27 13:58:36.761 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 13:58:36.762 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 13:58:36.764 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 48 with batch size 10.
2018-03-27 13:58:36.766 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 49.
2018-03-27 13:58:36.767 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 49 with batch size 10.
2018-03-27 13:58:36.866 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 49.
2018-03-27 13:58:37.107 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:01:07.779 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:01:07.779 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:01:07.781 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 49 with batch size 10.
2018-03-27 14:01:07.881 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 50.
2018-03-27 14:01:07.882 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 50 with batch size 10.
2018-03-27 14:01:07.883 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 50.
2018-03-27 14:01:08.164 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:03:38.789 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:03:38.790 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:03:38.792 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 50 with batch size 10.
2018-03-27 14:03:38.794 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 51.
2018-03-27 14:03:38.795 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 51 with batch size 10.
2018-03-27 14:03:38.796 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 51.
2018-03-27 14:03:39.069 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:06:09.808 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:06:09.808 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:06:09.811 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 51 with batch size 10.
2018-03-27 14:06:09.813 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 52.
2018-03-27 14:06:09.813 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 52 with batch size 10.
2018-03-27 14:06:09.814 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 52.
2018-03-27 14:06:10.053 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:08:40.809 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:08:40.809 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:08:40.811 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 52 with batch size 10.
2018-03-27 14:08:40.904 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 53.
2018-03-27 14:08:40.904 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 53 with batch size 10.
2018-03-27 14:08:40.904 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 53.
2018-03-27 14:08:41.135 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:11:11.818 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:11:11.818 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:11:11.909 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 53 with batch size 10.
2018-03-27 14:11:11.911 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 54.
2018-03-27 14:11:11.911 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 54 with batch size 10.
2018-03-27 14:11:11.912 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 54.
2018-03-27 14:11:12.392 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:13:42.913 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:13:42.914 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:13:42.916 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 54 with batch size 10.
2018-03-27 14:13:42.918 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 55.
2018-03-27 14:13:42.919 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 55 with batch size 10.
2018-03-27 14:13:42.920 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 55.
2018-03-27 14:13:43.167 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:16:13.928 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:16:13.929 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:16:13.931 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 55 with batch size 10.
2018-03-27 14:16:13.933 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 56.
2018-03-27 14:16:13.935 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 56 with batch size 10.
2018-03-27 14:16:14.033 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 56.
2018-03-27 14:16:14.229 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:18:44.948 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:18:44.949 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:18:44.951 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 56 with batch size 10.
2018-03-27 14:18:45.049 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 57.
2018-03-27 14:18:45.049 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 57 with batch size 10.
2018-03-27 14:18:45.050 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 57.
2018-03-27 14:18:45.312 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:21:15.948 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:21:15.948 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:21:15.951 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 57 with batch size 10.
2018-03-27 14:21:15.953 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 58.
2018-03-27 14:21:15.954 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 58 with batch size 10.
2018-03-27 14:21:15.955 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 58.
2018-03-27 14:21:16.199 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:23:46.968 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:23:46.968 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:23:46.971 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 58 with batch size 10.
2018-03-27 14:23:47.078 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 59.
2018-03-27 14:23:47.085 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 59 with batch size 10.
2018-03-27 14:23:47.086 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 59.
2018-03-27 14:23:47.335 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:26:17.989 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:26:18.083 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:26:18.087 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 59 with batch size 10.
2018-03-27 14:26:18.089 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 60.
2018-03-27 14:26:18.090 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 60 with batch size 10.
2018-03-27 14:26:18.095 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 60.
2018-03-27 14:26:18.273 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:26:53.925 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-27 14:26:53.925 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Checkpoint for endpoint iothub is 59
2018-03-27 14:26:54.079 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Cleaned up 12 messages from queue for endpoint iothub and 12 messages from message store.
2018-03-27 14:26:54.079 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Total messages cleaned up from queue for endpoint iothub = 60, and total messages cleaned up for message store = 60.
2018-03-27 14:28:49.098 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:28:49.099 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:28:49.101 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 60 with batch size 10.
2018-03-27 14:28:49.103 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 61.
2018-03-27 14:28:49.103 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 61 with batch size 10.
2018-03-27 14:28:49.104 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 61.
2018-03-27 14:28:49.323 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:31:20.122 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:31:20.123 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:31:20.126 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 61 with batch size 10.
2018-03-27 14:31:20.204 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 62.
2018-03-27 14:31:20.204 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 62 with batch size 10.
2018-03-27 14:31:20.204 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 62.
2018-03-27 14:31:20.471 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:33:51.154 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:33:51.155 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:33:51.160 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 62 with batch size 10.
2018-03-27 14:33:51.261 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 63.
2018-03-27 14:33:51.263 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 63 with batch size 10.
2018-03-27 14:33:51.264 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 63.
2018-03-27 14:33:51.497 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:36:22.169 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:36:22.169 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:36:22.174 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 63 with batch size 10.
2018-03-27 14:36:22.176 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 64.
2018-03-27 14:36:22.176 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 64 with batch size 10.
2018-03-27 14:36:22.177 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 64.
2018-03-27 14:36:22.360 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:38:53.189 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:38:53.189 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:38:53.194 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 64 with batch size 10.
2018-03-27 14:38:53.287 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 65.
2018-03-27 14:38:53.295 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 65 with batch size 10.
2018-03-27 14:38:53.296 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 65.
2018-03-27 14:38:53.524 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:41:24.209 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:41:24.209 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:41:24.312 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 65 with batch size 10.
2018-03-27 14:41:24.316 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 66.
2018-03-27 14:41:24.318 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 66 with batch size 10.
2018-03-27 14:41:24.319 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 66.
2018-03-27 14:41:24.504 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:43:55.320 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:43:55.320 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:43:55.323 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 66 with batch size 10.
2018-03-27 14:43:55.327 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 67.
2018-03-27 14:43:55.328 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 67 with batch size 10.
2018-03-27 14:43:55.328 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 67.
2018-03-27 14:43:55.681 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:46:26.332 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:46:26.333 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:46:26.335 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 67 with batch size 10.
2018-03-27 14:46:26.433 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 68.
2018-03-27 14:46:26.440 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 68 with batch size 10.
2018-03-27 14:46:26.441 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 68.
2018-03-27 14:46:26.663 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:48:57.343 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:48:57.343 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:48:57.346 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 68 with batch size 10.
2018-03-27 14:48:57.348 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 69.
2018-03-27 14:48:57.348 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 69 with batch size 10.
2018-03-27 14:48:57.349 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 69.
2018-03-27 14:48:57.638 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus
2018-03-27 14:49:05.007 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Getting new token for TC-IoT-Edge/modbus.
2018-03-27 14:49:05.017 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Existing token not found for TC-IoT-Edge/modbus. Getting new token from the client...
2018-03-27 14:49:05.018 +00:00 [WRN] [ProtocolGateway] - Closing connection for device: TC-IoT-Edge/modbus, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus., 
2018-03-27 14:49:05.019 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Disposing MessagingServiceClient for device Id TC-IoT-Edge/modbus because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device TC-IoT-Edge/modbus.
2018-03-27 14:49:05.019 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.DeviceProxy] - Closing device proxy for device Id TC-IoT-Edge/modbus
2018-03-27 14:49:05.019 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - Device connection removed for device TC-IoT-Edge/modbus
2018-03-27 14:49:05.020 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnection] - Updating device TC-IoT-Edge/modbus connection status to Disconnected
2018-03-27 14:49:05.288 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Updating reported properties for device TC-IoT-Edge/$edgeHub
2018-03-27 14:49:05.288 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Successfully sent reported properties to cloud for TC-IoT-Edge/$edgeHub and reported properties version 0
2018-03-27 14:49:05.291 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Updated cached reported property for TC-IoT-Edge/$edgeHub at reported property version 841 cloudVerified True
2018-03-27 14:49:05.297 +00:00 [WRN] [ProtocolGateway] - TLS handshake failed., System.IO.IOException: Channel is closed, 09c67dc6
2018-03-27 14:49:05.312 +00:00 [ERR] [DotNetty.Common.ResourceLeakDetector] - LEAK: IByteBuffer.Release() was not called before it's garbage-collected. Enable advanced leak reporting to find out where the leak occurred. To enable advanced leak reporting, set environment variable io.netty.leakDetection.level to advanced or set ResourceLeakDetector.Level in code. See http://netty.io/wiki/reference-counted-objects.html for more information.
2018-03-27 14:49:05.611 +00:00 [WRN] [ProtocolGateway] - TLS handshake failed., System.IO.IOException: Channel is closed, 352bfabd
2018-03-27 14:49:06.223 +00:00 [WRN] [ProtocolGateway] - TLS handshake failed., System.IO.IOException: Channel is closed, 50ded65c
2018-03-27 14:49:07.291 +00:00 [WRN] [ProtocolGateway] - TLS handshake failed., System.IO.IOException: Channel is closed, 6f15790f
2018-03-27 14:49:08.976 +00:00 [WRN] [ProtocolGateway] - TLS handshake failed., System.IO.IOException: Channel is closed, 0a1cd124
2018-03-27 14:49:12.794 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Obtained new token for client TC-IoT-Edge/modbus that expires in 01:00:00.2050982
2018-03-27 14:49:12.795 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudConnection] - Updated cloud connection for client TC-IoT-Edge/modbus
2018-03-27 14:49:12.795 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - New cloud connection created for device TC-IoT-Edge/modbus
2018-03-27 14:49:12.796 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Authenticator] - Successfully authenticated device TC-IoT-Edge/modbus
2018-03-27 14:49:12.796 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SasTokenDeviceIdentityProvider] - Successfully generated identity for clientId TC-IoT-Edge/modbus and username HIDDEN
2018-03-27 14:49:12.797 +00:00 [INF] [ProtocolGateway] - ClientAuthenticated, TC-IoT-Edge/modbus, 0f6f8e19
2018-03-27 14:49:12.797 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - Obtained cloud connection for device TC-IoT-Edge/modbus
2018-03-27 14:49:12.799 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Binding cloud listener for device TC-IoT-Edge/modbus
2018-03-27 14:49:12.799 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.ConnectionManager] - New device connection for device TC-IoT-Edge/modbus
2018-03-27 14:49:12.800 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnection] - Updating device TC-IoT-Edge/modbus connection status to Connected
2018-03-27 14:49:12.802 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Core.Device.DeviceMessageHandler] - Bind device proxy for device TC-IoT-Edge/modbus
2018-03-27 14:49:12.802 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Binding message channel for device Id TC-IoT-Edge/modbus
2018-03-27 14:49:12.803 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStatePersistenceProvider] - Adding subscription $iothub/twin/res/# for client TC-IoT-Edge/modbus.
2018-03-27 14:49:12.803 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStatePersistenceProvider] - Adding subscription devices/TC-IoT-Edge/modules/modbus/# for client TC-IoT-Edge/modbus.
2018-03-27 14:49:12.804 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStatePersistenceProvider] - Adding subscription $iothub/twin/PATCH/properties/desired/# for client TC-IoT-Edge/modbus.
2018-03-27 14:49:12.806 +00:00 [INF] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.SessionStateStoragePersistenceProvider] - Set subscriptions from session state for TC-IoT-Edge/modbus
2018-03-27 14:49:12.942 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Updating reported properties for device TC-IoT-Edge/$edgeHub
2018-03-27 14:49:12.942 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Successfully sent reported properties to cloud for TC-IoT-Edge/$edgeHub and reported properties version 0
2018-03-27 14:49:12.944 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.TwinManager] - Updated cached reported property for TC-IoT-Edge/$edgeHub at reported property version 841 cloudVerified True
2018-03-27 14:51:28.349 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Mqtt.MessagingServiceClient] - Sending message for device Id TC-IoT-Edge/modbus
2018-03-27 14:51:28.350 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Routing.RoutingEdgeHub] - Received message from TC-IoT-Edge/modbus
2018-03-27 14:51:28.352 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 69 with batch size 10.
2018-03-27 14:51:28.455 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 1. Next start offset = 70.
2018-03-27 14:51:28.456 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Getting next batch for endpoint iothub starting from 70 with batch size 10.
2018-03-27 14:51:28.456 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.Core.Storage.MessageStore] - Obtained next batch for endpoint iothub with batch size 0. Next start offset = 70.
2018-03-27 14:51:28.642 +00:00 [DBG] [Microsoft.Azure.Devices.Edge.Hub.CloudProxy.CloudProxy] - Sending message for device TC-IoT-Edge/modbus

If you look at my edgeHub logs, you are going to see that the modbus token is renewed every one hour, because the token is set to expire every one hour, is this default? Can I change the validity of this token somewhere?

Another question is that sometimes appers TLS handshake failed but does't seem to cause trouble to the data. Do you have any thoughts about this?

Thank you very much.

@aribeironovaes
Copy link
Contributor

Hi @termocontrole ,

Great that it's working.
So, to answer your question regarding token renew, this will depend on the client the modbus module is using. I don't have a lot of details on the modbus module, you would have to follow up with the modbus team to check which client they are using (c#, C, or if they are connecting directly using other non Azure IoT SDKs). When the client generates the token based on the shared key, they will establish the expiration time. Some client may let you configure that, but you would have to follow up on the specific client SDK github.

I'm not sure about the TLS handshake, it can be just a side effect of the token expiring.

Hope this helps, and let us know if there is anything else on this github issue that we can help.

Thanks,

Angelo Ribeiro.

@termocontrole
Copy link
Author

Hi @aribeironovaes,

Cool, I understand better now, I'm going to see with the modbus team.

Thank you.

@stephenctw
Copy link
Contributor

Hi,

As far as I know, there is no place to explicitly configure the token expiration time in current C# Microsoft.Azure.Devices.Client SDK. Modbus module is written based on the sample TempSensor module as a template. So I checked the sample tempSensor module log that I collected a couple weeks ago and found tempSensor module also renew its token every one hour.

2018-03-08 06:34:26.020 +00:00 [WRN] - Closing connection for device: MyDevice/tempSensor, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device MyDevice/tempSensor., 
2018-03-08 06:34:26.028 +00:00 [INF] - Disposing MessagingServiceClient for device Id MyDevice/tempSensor because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device MyDevice/tempSensor.
2018-03-08 06:34:26.060 +00:00 [INF] - Closing device proxy for device Id MyDevice/tempSensor
2018-03-08 06:34:26.061 +00:00 [INF] - Device connection removed for device MyDevice/tempSensor
2018-03-08 06:34:27.277 +00:00 [INF] - Obtained new token for client MyDevice/tempSensor that expires in 00:59:59.7226014
2018-03-08 06:34:27.277 +00:00 [INF] - New cloud connection created for device MyDevice/tempSensor
2018-03-08 06:34:27.277 +00:00 [INF] - Successfully authenticated device MyDevice/tempSensor
2018-03-08 06:34:27.277 +00:00 [INF] - Successfully generated identity for clientId MyDevice/tempSensor and username iotedgevm/MyDevice/tempSensor/api-version=2017-06-30&DeviceClientType=Microsoft.Azure.Devices.Client%2F1.17.0-preview-001%20%28.NET%20Core%204.6.0.0%3B%20Linux%204.13.0-1011-azure%20%2314-Ubuntu%20SMP%20Thu%20Feb%2015%2016%3A15%3A39%20UTC%202018%3B%20X64%29
2018-03-08 06:34:27.277 +00:00 [INF] - ClientAuthenticated, MyDevice/tempSensor, 57acaea7
2018-03-08 06:34:27.294 +00:00 [INF] - New device connection for device MyDevice/tempSensor
2018-03-08 06:34:27.294 +00:00 [INF] - Bind device proxy for device MyDevice/tempSensor
2018-03-08 06:34:27.294 +00:00 [INF] - Binding message channel for device Id MyDevice/tempSensor
2018-03-08 06:34:27.295 +00:00 [INF] - Set subscriptions from session state for MyDevice/tempSensor
2018-03-08 06:36:46.234 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-08 06:36:46.284 +00:00 [INF] - Cleaned up 365 messages from queue for endpoint iothub and 365 messages from message store.
2018-03-08 07:07:16.286 +00:00 [INF] - Started task to cleanup processed and stale messages for endpoint iothub
2018-03-08 07:07:16.357 +00:00 [INF] - Cleaned up 366 messages from queue for endpoint iothub and 366 messages from message store.
2018-03-08 07:32:27.000 +00:00 [INF] - Existing token not found for MyDevice/tempSensor. Getting new token from the client...
2018-03-08 07:32:27.000 +00:00 [WRN] - Closing connection for device: MyDevice/tempSensor, Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device MyDevice/tempSensor., 
2018-03-08 07:32:27.000 +00:00 [INF] - Disposing MessagingServiceClient for device Id MyDevice/tempSensor because of exception - Microsoft.Azure.Devices.Edge.Hub.Core.EdgeHubConnectionException: Connection closed for device MyDevice/tempSensor.
2018-03-08 07:32:27.000 +00:00 [INF] - Closing device proxy for device Id MyDevice/tempSensor

So I think it might worth checking with the Microsoft.Azure.Devices.Client SDK team if this is a expected behavior.

Thanks,
Stephen

@termocontrole termocontrole reopened this Mar 30, 2018
@termocontrole
Copy link
Author

Hi,

Thank you @606simps.

So seems that this token expiration time is default and maybe it's not configurable. If that's true, it's ok for me, but I'm still thinking if it's this that is causing the TLS handshake failure and if there is any consequence of that happening or if this is the expected behavior as pointed by @606simps.

I don't know what is the Microsoft.Azure.Devices.Client SDK team repo to open an issue referring to this, I found two that could be: Azure SDK for NET or Azure IoT SDK C. I think is the second one, but I'm not sure.

So, does anyone know where or with whom I can get this information?

Thank you very much.

@aribeironovaes
Copy link
Contributor

aribeironovaes commented Mar 30, 2018 via email

@termocontrole
Copy link
Author

Thank you @aribeironovaes, I opened an issue there.

@myagley
Copy link
Collaborator

myagley commented Apr 4, 2018

Closing as this issue seems to be resolved. Please reopen if you continue to run into it. Thanks!

@myagley myagley closed this as completed Apr 4, 2018
@termocontrole
Copy link
Author

termocontrole commented Apr 5, 2018

Hi @myagley,

Unfortunately the problem wasn't solved, it is still happening, mainly the TLS Handshake failure and System Exception. I only opened an issue with the Azure IoT SDK CSharp team to see if they could help, but seems that they can't, since they requested the attention of some IoT Edge team members, in this case you and @damonbarry.

Please, can you help there? (issue)

Well, it seems I can't reopen the issue :(

Thank you very much.

@damonbarry
Copy link
Member

@termocontrole

The TLS handshake failed failures are transient, coming from a lower layer when we close the connection at a higher layer, and nothing to worry about.

The exceptions seen on platforms at the low end of our HW requirements (like Pi) are being tracked in #475.

@termocontrole
Copy link
Author

Thanks @damonbarry for the clarifications, I will follow that issue.

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
Development

No branches or pull requests

5 participants