Industrial-IoT: Unhandled error during Publish. BadTimeout 'BadTimeout'

Hi, we are having some issues with the publisher. Getting bad timeout. Can you see from the logs what is causing this? Had a stream running for the publisher for several hours, that suddenly stopped working. When checking the logs there were errors there regarding bad timeout. After the restart it is publishing data, but this error keeps coming on regular intervals. This is some timeout error related to OPC client (publisher) and OPC UA server?

[13:46:37 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #15 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Running Ubuntu 18.04 IIOT 2.8.1 all in one services Publisher 2.8.1

[13:46:36 DBG Microsoft.Azure.IIoT.Agent.Framework.Agent.Worker] Sending heartbeat...
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] Sending POST request to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat...
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] POST to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat returned OK (took 00:00:00.0518594).
[13:46:36 DBG Microsoft.Azure.IIoT.Agent.Framework.Agent.Worker+JobProcess] Sending job processor heartbeat...
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] Sending POST request to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat...
[13:46:36 DBG Microsoft.Azure.IIoT.OpcUa.Protocol.Services.DefaultSessionManager] Runner Keepalive reset due to checkAlive
[13:46:36 DBG Microsoft.Azure.IIoT.OpcUa.Protocol.Services.DefaultSessionManager] Refreshing session '2cc38d59fc2d08bc4bf6abe37aecde7d3ffe0640'
[13:46:36 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Service) Read Called. RequestHandle=394, PendingRequestCount=2
[13:46:36 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Service) Read Completed. RequestHandle=394, PendingRequestCount=1
[13:46:36 DBG Microsoft.Azure.IIoT.OpcUa.Protocol.Services.DefaultSessionManager] Refreshing done for session '2cc38d59fc2d08bc4bf6abe37aecde7d3ffe0640'
[13:46:36 DBG Microsoft.Azure.IIoT.Agent.Framework.Agent.Worker] Sending heartbeat...
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] Sending POST request to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat...
[13:46:36 DBG Microsoft.Azure.IIoT.Agent.Framework.Agent.Worker] Sending heartbeat...
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] Sending POST request to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat...
[13:46:36 DBG Microsoft.Azure.IIoT.Agent.Framework.Agent.Worker] Sending heartbeat...
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] Sending POST request to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat...
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] POST to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat returned OK (took 00:00:00.0887432).
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] POST to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat returned OK (took 00:00:00.0477676).
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] POST to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat returned OK (took 00:00:00.0484443).
[13:46:36 VRB Microsoft.Azure.IIoT.Http.Default.HttpClient] POST to https://svc-bifrost-p2-weu.azurewebsites.net/edge/publisher/v2/heartbeat returned OK (took 00:00:00.0529546).
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) PUBLISH #15 RECEIVED
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Service) ***EXCEPTION*** BadTimeout
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Service) Publish Completed. RequestHandle=0, PendingRequestCount=0, StatusCode=Bad
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) Publish #15, Reconnecting=False, Error: BadTimeout
[13:46:37 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #15 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'


========================================
Id: BadTimeout
Description: BadTimeout
>>> BadTimeout
---    at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
---    at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
---    at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
========================================

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Service) Publish Called. RequestHandle=16, PendingRequestCount=1
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) PUBLISH #16 SENT
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) PUBLISH #16 RECEIVED
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Service) Publish Completed. RequestHandle=16, PendingRequestCount=0
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) Error - Publish call finished. ResultCode=BadSequenceNumberUnknown; SubscriptionId=2403595714;
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) NOTIFICATION RECEIVED: SubId=2403595714, SeqNo=8
[13:46:37 DBG Microsoft.Azure.IIoT.OpcUa.Protocol.Services.DefaultSessionManager] Keepalive received from session '2cc38d59fc2d08bc4bf6abe37aecde7d3ffe0640': server current state: Running
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) NotificationReceived: ClientHandle=169, Value=8
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) NotificationReceived: ClientHandle=94, Value=3.2
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) NotificationReceived: ClientHandle=133, Value=6.5
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) NotificationReceived: ClientHandle=129, Value=3.2
[13:46:37 VRB Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Information) NotificationReceived: ClientHandle=49, Value=0

Best regards

About this issue

  • Original URL
  • State: closed
  • Created 3 years ago
  • Comments: 17 (8 by maintainers)

Commits related to this issue

Most upvoted comments


[12:20:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #59 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:20:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:04:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             32 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          6,355 (All time ~1.65/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          6,355
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             32
  # Encoder avg Notifications/Message  :            199
  # Encoder avg IoT Message body size  :         24,762 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             32 (0.01/s)
  # Connection retries                 :              0

[12:20:33 INF Microsoft.Azure.IIoT.Module.Framework.Client.IoTSdkFactory] 1: Module kkv-edge-gateway_publisher disconnected due to Communication_Error - now Disconnected_Retrying...
[12:20:33 INF Microsoft.Azure.IIoT.Module.Framework.Client.IoTSdkFactory] 1: Device DataSetWriterV2_uat812748106293fcf387e1c4922cb0e7bbf1b8c049 disconnected due to Communication_Error - now Disconnected_Retrying...
[12:20:33 INF Microsoft.Azure.IIoT.Module.Framework.Client.IoTSdkFactory] 1: Module kkv-edge-gateway_publisher reconnected due to Connection_Ok.
[12:20:33 INF Microsoft.Azure.IIoT.Module.Framework.Client.IoTSdkFactory] 1: Device DataSetWriterV2_uat812748106293fcf387e1c4922cb0e7bbf1b8c049 reconnected due to Connection_Ok.
[12:21:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:04:59 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             33 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          6,570 (All time ~1.68/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          6,570
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             33
  # Encoder avg Notifications/Message  :            199
  # Encoder avg IoT Message body size  :         24,823 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :            6.1
  # Estimated IoT Chunks (4 KB) per day:          5,118
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             33 (0.01/s)
  # Connection retries                 :              0

[12:21:25 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:21:25 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:22:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:06:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             33 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          6,570 (All time ~1.66/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          6,570
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             33
  # Encoder avg Notifications/Message  :            199
  # Encoder avg IoT Message body size  :         24,823 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :            6.1
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             33 (0.01/s)
  # Connection retries                 :              0

[12:23:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:07:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             34 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          6,673 (All time ~1.66/s; 103 in last 60s ~1.72/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          6,673
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             34
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,477 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,384
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             34 (0.01/s)
  # Connection retries                 :              0

[12:24:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:08:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             34 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          6,673 (All time ~1.64/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          6,673
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             34
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,477 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,320
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             34 (0.01/s)
  # Connection retries                 :              0

[12:24:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #62 - Unhandled error during Publish.
 BadRequestTimeout 'BadRequestTimeout'

Opc.Ua.ServiceResultException: BadRequestTimeout
   at Opc.Ua.Bindings.ChannelAsyncOperation`1.End(Int32 timeout, Boolean throwOnError)
   at Opc.Ua.Bindings.UaSCUaBinaryClientChannel.EndSendRequest(IAsyncResult result)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:24:14 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:24:14 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:25:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:09:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             35 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          6,888 (All time ~1.66/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          6,888
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             35
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,543 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,383
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             35 (0.01/s)
  # Connection retries                 :              0

[12:26:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:10:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             35 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          6,888 (All time ~1.64/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          6,888
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             35
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,543 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,320
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             35 (0.01/s)
  # Connection retries                 :              0

[12:26:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #64 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:26:28 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:26:28 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:27:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:11:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             36 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          7,103 (All time ~1.67/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,103
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             36
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,604 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,111
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             36 (0.01/s)
  # Connection retries                 :              0

[12:28:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:12:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             36 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          7,103 (All time ~1.64/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,103
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             36
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,604 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             36 (0.01/s)
  # Connection retries                 :              0

[12:28:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #66 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:29:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:13:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             37 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          7,318 (All time ~1.67/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,318
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             37
  # Encoder avg Notifications/Message  :            198
  # Encoder avg IoT Message body size  :         24,662 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,109
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             37 (0.01/s)
  # Connection retries                 :              0

[12:29:16 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:29:16 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:30:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:14:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             37 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          7,318 (All time ~1.65/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,318
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             37
  # Encoder avg Notifications/Message  :            198
  # Encoder avg IoT Message body size  :         24,662 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             37 (0.01/s)
  # Connection retries                 :              0

[12:30:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #68 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:31:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:15:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             38 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          7,533 (All time ~1.67/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,533
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             38
  # Encoder avg Notifications/Message  :            198
  # Encoder avg IoT Message body size  :         24,714 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,107
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             38 (0.01/s)
  # Connection retries                 :              0

[12:31:32 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:31:32 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:32:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:16:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             38 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          7,533 (All time ~1.65/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,533
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             38
  # Encoder avg Notifications/Message  :            198
  # Encoder avg IoT Message body size  :         24,714 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             38 (0.01/s)
  # Connection retries                 :              0

[12:32:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #70 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:33:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:17:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             39 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          7,748 (All time ~1.68/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,748
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             39
  # Encoder avg Notifications/Message  :            199
  # Encoder avg IoT Message body size  :         24,766 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,105
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             39 (0.01/s)
  # Connection retries                 :              0

[12:34:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #72 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:34:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:18:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             39 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          7,748 (All time ~1.66/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,748
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             39
  # Encoder avg Notifications/Message  :            199
  # Encoder avg IoT Message body size  :         24,766 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             39 (0.01/s)
  # Connection retries                 :              0

[12:34:19 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:34:19 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:35:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:18:59 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             40 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          7,963 (All time ~1.68/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,963
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             40
  # Encoder avg Notifications/Message  :            199
  # Encoder avg IoT Message body size  :         24,816 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :            6.1
  # Estimated IoT Chunks (4 KB) per day:          5,104
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             40 (0.01/s)
  # Connection retries                 :              0

[12:36:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:20:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             40 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          7,963 (All time ~1.66/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          7,963
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             40
  # Encoder avg Notifications/Message  :            199
  # Encoder avg IoT Message body size  :         24,816 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :            6.1
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             40 (0.01/s)
  # Connection retries                 :              0

[12:36:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #74 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:36:35 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:36:35 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:37:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:21:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             41 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          8,068 (All time ~1.66/s; 105 in last 60s ~1.75/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,068
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             41
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,536 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,373
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             41 (0.01/s)
  # Connection retries                 :              0

[12:38:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:22:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             41 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          8,068 (All time ~1.64/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,068
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             41
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,536 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,320
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             41 (0.01/s)
  # Connection retries                 :              0

[12:38:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #76 - Unhandled error during Publish.
 BadRequestTimeout 'BadRequestTimeout'

Opc.Ua.ServiceResultException: BadRequestTimeout
   at Opc.Ua.Bindings.ChannelAsyncOperation`1.End(Int32 timeout, Boolean throwOnError)
   at Opc.Ua.Bindings.UaSCUaBinaryClientChannel.EndSendRequest(IAsyncResult result)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:39:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:23:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             42 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          8,283 (All time ~1.66/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,283
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             42
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,589 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,101
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             42 (0.01/s)
  # Connection retries                 :              0

[12:39:22 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:39:22 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:40:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #78 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:40:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:24:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             42 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          8,283 (All time ~1.64/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,283
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             42
  # Encoder avg Notifications/Message  :            197
  # Encoder avg IoT Message body size  :         24,589 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             42 (0.01/s)
  # Connection retries                 :              0

[12:41:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:24:59 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             43 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          8,498 (All time ~1.67/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,498
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             43
  # Encoder avg Notifications/Message  :            198
  # Encoder avg IoT Message body size  :         24,639 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,099
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             43 (0.01/s)
  # Connection retries                 :              0

[12:41:38 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:41:38 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:42:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:25:59 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             43 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          8,498 (All time ~1.65/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,498
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             43
  # Encoder avg Notifications/Message  :            198
  # Encoder avg IoT Message body size  :         24,639 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          5,040
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             43 (0.01/s)
  # Connection retries                 :              0

[12:43:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:27:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             44 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          8,606 (All time ~1.65/s; 108 in last 60s ~1.8/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,606
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             44
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,390 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,370
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             44 (0.01/s)
  # Connection retries                 :              0

[12:44:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:28:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             44 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          8,606 (All time ~1.63/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,606
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             44
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,390 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,320
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             44 (0.01/s)
  # Connection retries                 :              0

[12:44:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #81 - Unhandled error during Publish.
 BadRequestTimeout 'BadRequestTimeout'

Opc.Ua.ServiceResultException: BadRequestTimeout
   at Opc.Ua.Bindings.ChannelAsyncOperation`1.End(Int32 timeout, Boolean throwOnError)
   at Opc.Ua.Bindings.UaSCUaBinaryClientChannel.EndSendRequest(IAsyncResult result)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:44:26 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:44:26 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:45:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:29:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             45 (All time ~0.01/s; 01 in last 60s ~0.02/s)
  # Ingress ValueChanges (from OPC)    :          8,821 (All time ~1.65/s; 215 in last 60s ~3.58/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,821
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             45
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,443 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,369
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             45 (0.01/s)
  # Connection retries                 :              0

[12:46:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:30:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             45 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          8,821 (All time ~1.63/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          8,821
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             45
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,443 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,320
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             45 (0.01/s)
  # Connection retries                 :              0

[12:46:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #83 - Unhandled error during Publish.
 BadRequestTimeout 'BadRequestTimeout'

Opc.Ua.ServiceResultException: BadRequestTimeout
   at Opc.Ua.Bindings.ChannelAsyncOperation`1.End(Int32 timeout, Boolean throwOnError)
   at Opc.Ua.Bindings.UaSCUaBinaryClientChannel.EndSendRequest(IAsyncResult result)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:46:41 INF Microsoft.Azure.IIoT.Modules.OpcUa.Publisher.Controller.ConfigurationSettingsController] Agent configuration updated.
[12:46:41 INF Microsoft.Azure.IIoT.Module.Framework.Hosting.ModuleHost] New settings processed.
[12:47:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:31:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             46 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          9,036 (All time ~1.65/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          9,036
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             46
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,493 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,367
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             46 (0.01/s)
  # Connection retries                 :              0

[12:48:12 ERR Microsoft.Azure.IIoT.OpcUa.Protocol.Services.StackLogger] (Error) PUBLISH #85 - Unhandled error during Publish.
 BadTimeout 'BadTimeout'

Opc.Ua.ServiceResultException: BadTimeout
   at Opc.Ua.ClientBase.ValidateResponse(ResponseHeader header)
   at Opc.Ua.SessionClient.EndPublish(IAsyncResult result, UInt32& subscriptionId, UInt32Collection& availableSequenceNumbers, Boolean& moreNotifications, NotificationMessage& notificationMessage, StatusCodeCollection& results, DiagnosticInfoCollection& diagnosticInfos)
   at Opc.Ua.Client.Session.OnPublishComplete(IAsyncResult result)
[12:48:12 INF Microsoft.Azure.IIoT.OpcUa.Edge.Publisher.Engine.DataFlowProcessingEngine]
  DIAGNOSTICS INFORMATION for          : uat812748106293fcf387e1c4922cb0e7bbf1b8c049
  # Ingestion duration                 :    00:01:32:00 (dd:hh:mm:ss)
  # Ingress DataChanges (from OPC)     :             46 (All time ~0.01/s; 00 in last 60s ~0/s)
  # Ingress ValueChanges (from OPC)    :          9,036 (All time ~1.64/s; 00 in last 60s ~0/s)
  # Ingress BatchBlock buffer size     :              0
  # Encoding Block input/output size   :              0 | 0
  # Encoder Notifications processed    :          9,036
  # Encoder Notifications dropped      :              0
  # Encoder IoT Messages processed     :             46
  # Encoder avg Notifications/Message  :            196
  # Encoder avg IoT Message body size  :         24,493 (9%)
  # Encoder avg IoT Chunk (4 KB) usage :              6
  # Estimated IoT Chunks (4 KB) per day:          4,320
  # Outgress Batch Block buffer size   :              0
  # Outgress input buffer count        :              0
  # Outgress input buffer dropped      :              0
  # Outgress IoT message count         :             46 (0.01/s)
  # Connection retries                 :              0
+1
mwiot on Dec 10, 2021
Read more comments on GitHub
← go-autorest: Validation is overly strict and causing problems
iotedge: One or more errors occurred. (Permission denied /var/run/iotedge/mgmt.sock) caused by: docker returned exit code: 1, stderr = One or more errors occurred. (Permission denied  →