-
Notifications
You must be signed in to change notification settings - Fork 457
New issue
Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.
By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.
Already on GitHub? Sign in to your account
Error while refreshing the service identity #6936
Comments
Hi @adcoly, have a few questions to help understand the problem more :) Is this something that was not a problem with 1.1, then you upgraded and started seeing the issue? Also is this a prod environment? Would you mind also sharing the output of the following: Example output: |
As far as I remember/noticed, it was not a problem when aziot-edged was in 1.4.9 and edgeAgent/edgeHub in 1.1. The 3 services were stopped since 5 days. We restarted it few minutes before sharing you status:
By the way, we noticed on this page that the identity service is now resposible of components identity that communicates with the IoTHub. We are currently verifying our configuration for this specific service. It seems it is configured through the
|
@adcoly so edgeHub will not be able to be connected when keyd is not up, which based on above Is this still an ongoing issue for you? Also, any idea on what might have been going on that might have caused those daemons to not be up and running? Any changes to this particular device? Or just from refreshing service gateway? If this is reproducible, it will be great if you can get logs for keyd, certd, and identityd too :) Curious to know what happened |
We still have some issues in the edgeHub. edgeHub logs here``` <6> 2023-03-03 09:33:57.710 +00:00 [INF] - Entering periodic task to reauthenticate connected clients <6> 2023-03-03 09:33:57.711 +00:00 [INF] - Unable to authenticate client 00137A1000011656 with cached service identity 00137A1000011656 (Found: False). Resyncing service identity... <6> 2023-03-03 09:33:57.711 +00:00 [INF] - Refreshing service identity for 00137A1000011656 <4> 2023-03-03 09:33:58.181 +00:00 [WRN] - Error while refreshing the service identity: 00137A1000011656 OnBehalfOf: carsi4iiotedge1 Microsoft.Azure.Devices.Edge.Hub.Core.DeviceInvalidStateException: Device not in scope: [Unauthorized: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:0f8522d5e91e48f98998ba9a112f14fa-G:0-TimeStamp:03/03/2023 09:33:58"}]. ---> Microsoft.Azure.Devices.Edge.Hub.CloudProxy.DeviceScopeApiException: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:0f8522d5e91e48f98998ba9a112f14fa-G:0-TimeStamp:03/03/2023 09:33:58"} at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfInternalAsync(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 191 at Microsoft.Azure.Devices.Edge.Util.TaskEx.TimeoutAfter[T](Task`1 task, TimeSpan timeout, Action action) in /mnt/vss/_work/1/s/edge-util/src/Microsoft.Azure.Devices.Edge.Util/TaskEx.cs:line 137 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfWithRetry(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 140 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 at Microsoft.Azure.Devices.Edge.Hub.Core.DeviceScopeIdentitiesCache.RefreshServiceIdentityInternal(String refreshTarget, String onBehalfOfDevice, Boolean invokeServiceIdentitiesUpdated) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.Core/DeviceScopeIdentitiesCache.cs:line 201 <6> 2023-03-03 09:33:58.182 +00:00 [INF] - Removed node: 00137A1000011656 <6> 2023-03-03 09:33:58.183 +00:00 [INF] - Device scope identities refresh is ready because a refresh was signalled. <6> 2023-03-03 09:33:58.183 +00:00 [INF] - Starting refresh of device scope identities cache <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1 <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/$edgeAgent <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/$edgeHub <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/GenericDecoder <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/MetadataEnricher <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/NkeSensorsDecoder <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/NetvoxSensorsDecoder <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/NKESensorsDecoderPy <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/Kafka2Pi <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/LoRaWanNetworkSrvModule <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/metricbeat <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/filebeat <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Not changed node: carsi4iiotedge1/DefenderIotMicroAgent <6> 2023-03-03 09:34:10.600 +00:00 [INF] - Done refreshing device scope identities cache. Waiting for 60 minutes. <6> 2023-03-03 09:34:10.601 +00:00 [INF] - Unable to authenticate client 70B3D5E75E00CA92 with cached service identity 70B3D5E75E00CA92 (Found: False). Resyncing service identity... <6> 2023-03-03 09:34:10.601 +00:00 [INF] - Refreshing service identity for 70B3D5E75E00CA92 <4> 2023-03-03 09:34:10.780 +00:00 [WRN] - Error while refreshing the service identity: 70B3D5E75E00CA92 OnBehalfOf: carsi4iiotedge1 Microsoft.Azure.Devices.Edge.Hub.Core.DeviceInvalidStateException: Device not in scope: [Unauthorized: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:7c25ef70e08142cd80aadc0c4cbc5012-G:0-TimeStamp:03/03/2023 09:34:10"}]. ---> Microsoft.Azure.Devices.Edge.Hub.CloudProxy.DeviceScopeApiException: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:7c25ef70e08142cd80aadc0c4cbc5012-G:0-TimeStamp:03/03/2023 09:34:10"} at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfInternalAsync(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 191 at Microsoft.Azure.Devices.Edge.Util.TaskEx.TimeoutAfter[T](Task`1 task, TimeSpan timeout, Action action) in /mnt/vss/_work/1/s/edge-util/src/Microsoft.Azure.Devices.Edge.Util/TaskEx.cs:line 137 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfWithRetry(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 140 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 at Microsoft.Azure.Devices.Edge.Hub.Core.DeviceScopeIdentitiesCache.RefreshServiceIdentityInternal(String refreshTarget, String onBehalfOfDevice, Boolean invokeServiceIdentitiesUpdated) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.Core/DeviceScopeIdentitiesCache.cs:line 201 <6> 2023-03-03 09:34:10.781 +00:00 [INF] - Removed node: 70B3D5E75E00CA92 <6> 2023-03-03 09:34:10.782 +00:00 [INF] - Skipping cache refresh, waiting 119 seconds until refreshing again. <6> 2023-03-03 09:34:12.584 +00:00 [INF] - "Request starting HTTP/1.1 GET http://edgeHub:9600/metrics - -" <6> 2023-03-03 09:34:12.586 +00:00 [INF] - "Request finished HTTP/1.1 GET http://edgeHub:9600/metrics - - - 200 - text/plain;+version=0.0.4;+charset=utf-8 2.3684ms" <6> 2023-03-03 09:34:42.583 +00:00 [INF] - "Request starting HTTP/1.1 GET http://edgeHub:9600/metrics - -" <6> 2023-03-03 09:34:42.585 +00:00 [INF] - "Request finished HTTP/1.1 GET http://edgeHub:9600/metrics - - - 200 - text/plain;+version=0.0.4;+charset=utf-8 2.0757ms" <6> 2023-03-03 09:35:12.583 +00:00 [INF] - "Request starting HTTP/1.1 GET http://edgeHub:9600/metrics - -" <6> 2023-03-03 09:35:12.585 +00:00 [INF] - "Request finished HTTP/1.1 GET http://edgeHub:9600/metrics - - - 200 - text/plain;+version=0.0.4;+charset=utf-8 1.7187ms" <6> 2023-03-03 09:35:42.585 +00:00 [INF] - "Request starting HTTP/1.1 GET http://edgeHub:9600/metrics - -" <6> 2023-03-03 09:35:42.587 +00:00 [INF] - "Request finished HTTP/1.1 GET http://edgeHub:9600/metrics - - - 200 - text/plain;+version=0.0.4;+charset=utf-8 1.9077ms" <6> 2023-03-03 09:36:12.585 +00:00 [INF] - "Request starting HTTP/1.1 GET http://edgeHub:9600/metrics - -" <6> 2023-03-03 09:36:12.587 +00:00 [INF] - "Request finished HTTP/1.1 GET http://edgeHub:9600/metrics - - - 200 - text/plain;+version=0.0.4;+charset=utf-8 2.5326ms" <6> 2023-03-03 09:36:42.584 +00:00 [INF] - "Request starting HTTP/1.1 GET http://edgeHub:9600/metrics - -" <6> 2023-03-03 09:36:42.586 +00:00 [INF] - "Request finished HTTP/1.1 GET http://edgeHub:9600/metrics - - - 200 - text/plain;+version=0.0.4;+charset=utf-8 1.5566ms" <6> 2023-03-03 09:38:57.710 +00:00 [INF] - Entering periodic task to reauthenticate connected clients <6> 2023-03-03 09:38:57.710 +00:00 [INF] - Unable to authenticate client 00137A1000011656 with cached service identity 00137A1000011656 (Found: False). Resyncing service identity... <6> 2023-03-03 09:38:57.710 +00:00 [INF] - Refreshing service identity for 00137A1000011656 <4> 2023-03-03 09:38:57.881 +00:00 [WRN] - Error while refreshing the service identity: 00137A1000011656 OnBehalfOf: carsi4iiotedge1 Microsoft.Azure.Devices.Edge.Hub.Core.DeviceInvalidStateException: Device not in scope: [Unauthorized: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:a0b467104fd84c89a1ec069719a6664b-G:0-TimeStamp:03/03/2023 09:38:57"}]. ---> Microsoft.Azure.Devices.Edge.Hub.CloudProxy.DeviceScopeApiException: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:a0b467104fd84c89a1ec069719a6664b-G:0-TimeStamp:03/03/2023 09:38:57"} at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfInternalAsync(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 191 at Microsoft.Azure.Devices.Edge.Util.TaskEx.TimeoutAfter[T](Task`1 task, TimeSpan timeout, Action action) in /mnt/vss/_work/1/s/edge-util/src/Microsoft.Azure.Devices.Edge.Util/TaskEx.cs:line 137 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfWithRetry(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 140 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 at Microsoft.Azure.Devices.Edge.Hub.Core.DeviceScopeIdentitiesCache.RefreshServiceIdentityInternal(String refreshTarget, String onBehalfOfDevice, Boolean invokeServiceIdentitiesUpdated) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.Core/DeviceScopeIdentitiesCache.cs:line 201 <6> 2023-03-03 09:38:57.882 +00:00 [INF] - Removed node: 00137A1000011656 <6> 2023-03-03 09:38:57.882 +00:00 [INF] - Device scope identities refresh is ready because a refresh was signalled. <6> 2023-03-03 09:38:57.882 +00:00 [INF] - Starting refresh of device scope identities cache <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1 <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/$edgeAgent <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/$edgeHub <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/GenericDecoder <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/MetadataEnricher <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/NkeSensorsDecoder <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/NetvoxSensorsDecoder <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/NKESensorsDecoderPy <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/Kafka2Pi <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/LoRaWanNetworkSrvModule <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/metricbeat <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/filebeat <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Not changed node: carsi4iiotedge1/DefenderIotMicroAgent <6> 2023-03-03 09:38:58.755 +00:00 [INF] - Done refreshing device scope identities cache. Waiting for 60 minutes. <6> 2023-03-03 09:38:58.756 +00:00 [INF] - Unable to authenticate client 70B3D5E75E00CA92 with cached service identity 70B3D5E75E00CA92 (Found: False). Resyncing service identity... <6> 2023-03-03 09:38:58.756 +00:00 [INF] - Refreshing service identity for 70B3D5E75E00CA92 <4> 2023-03-03 09:38:59.281 +00:00 [WRN] - Error while refreshing the service identity: 70B3D5E75E00CA92 OnBehalfOf: carsi4iiotedge1 Microsoft.Azure.Devices.Edge.Hub.Core.DeviceInvalidStateException: Device not in scope: [Unauthorized: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:068258dd7a8c49c88448dfaf64a440dc-G:0-TimeStamp:03/03/2023 09:38:59"}]. ---> Microsoft.Azure.Devices.Edge.Hub.CloudProxy.DeviceScopeApiException: Message: Error getting device scope result from upstream, HttpStatusCode: Unauthorized, Content: {"Message":"ErrorCode:IotHubUnauthorizedAccess;Unauthorized","ExceptionMessage":"Tracking ID:068258dd7a8c49c88448dfaf64a440dc-G:0-TimeStamp:03/03/2023 09:38:59"} at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfInternalAsync(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 191 at Microsoft.Azure.Devices.Edge.Util.TaskEx.TimeoutAfter[T](Task`1 task, TimeSpan timeout, Action action) in /mnt/vss/_work/1/s/edge-util/src/Microsoft.Azure.Devices.Edge.Util/TaskEx.cs:line 137 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.NestedDeviceScopeApiClient.GetIdentityOnBehalfOfWithRetry(Uri uri, String deviceId, Option`1 moduleId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/NestedDeviceScopeApiClient.cs:line 140 at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Edge.Hub.CloudProxy.ServiceProxy.GetServiceIdentity(String deviceId, String onBehalfOfDevice) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.CloudProxy/ServiceProxy.cs:line 101 at Microsoft.Azure.Devices.Edge.Hub.Core.DeviceScopeIdentitiesCache.RefreshServiceIdentityInternal(String refreshTarget, String onBehalfOfDevice, Boolean invokeServiceIdentitiesUpdated) in /mnt/vss/_work/1/s/edge-hub/core/src/Microsoft.Azure.Devices.Edge.Hub.Core/DeviceScopeIdentitiesCache.cs:line 201 <6> 2023-03-03 09:38:59.282 +00:00 [INF] - Removed node: 70B3D5E75E00CA92 <6> 2023-03-03 09:38:59.283 +00:00 [INF] - Skipping cache refresh, waiting 119 seconds until refreshing again. ```By the way, the error in the identityd logs just stops with no reason 2 days ago 😶 identityd logs here``` Mar 01 08:19:20 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:20Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 08:19:20 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:20Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:23 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:23Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:23 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:23Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:23 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:23Z [INFO] - <-- GET /identities/identity?api-version=2020-09-01 {"host": "foo", "user-agent": "libcurl-agent/1.0", "accept": "*/*"} Mar 01 08:19:26 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:26Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:26 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:26Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [ERR!] - !!! internal error Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [ERR!] - !!! caused by: master identity key error Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [ERR!] - !!! caused by: error trying to connect: Connection refused (os error 111) Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [ERR!] - !!! caused by: Connection refused (os error 111) Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [INFO] - --> 500 {"content-type": "application/json"} Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:29 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:29Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 08:19:32 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:32Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:32 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:32Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:35 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:35Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:35 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:35Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:38Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:38Z [ERR!] - !!! internal error Mar 01 08:19:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:38Z [ERR!] - !!! caused by: master identity key error Mar 01 08:19:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:38Z [ERR!] - !!! caused by: error trying to connect: Connection refused (os error 111) Mar 01 08:19:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:38Z [ERR!] - !!! caused by: Connection refused (os error 111) Mar 01 08:19:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:38Z [INFO] - --> 500 {"content-type": "application/json"} Mar 01 08:19:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:38Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:41 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:41Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:41 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:41Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:44 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:44Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:44 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:44Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [ERR!] - !!! internal error Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [ERR!] - !!! caused by: master identity key error Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [ERR!] - !!! caused by: error trying to connect: Connection refused (os error 111) Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [ERR!] - !!! caused by: Connection refused (os error 111) Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [INFO] - --> 500 {"content-type": "application/json"} Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [INFO] - <-- GET /identities/modules/$edgeAgent?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 08:19:47 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:47Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:50 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:50Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:50 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:50Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:53 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:53Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:53 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:53Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:53 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:53Z [INFO] - <-- GET /identities/identity?api-version=2020-09-01 {"host": "foo", "user-agent": "libcurl-agent/1.0", "accept": "*/*"} Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [ERR!] - !!! internal error Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [ERR!] - !!! caused by: master identity key error Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [ERR!] - !!! caused by: error trying to connect: Connection refused (os error 111) Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [ERR!] - !!! caused by: Connection refused (os error 111) Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [INFO] - --> 500 {"content-type": "application/json"} Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:56Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 08:19:59 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:59Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:19:59 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:19:59Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:20:02 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:02Z [WARN] - Failed to send HTTP request (attempt 2 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:20:02 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:02Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Connection refused (os error 111) Mar 01 08:20:05 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:05Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 08:20:05 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:05Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 08:20:05 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:05Z [INFO] - <-- GET /identities/modules/$edgeAgent?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 08:20:05 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:05Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 08:20:05 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:05Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 08:20:06 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:20:06Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 08:51:07 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:51:07Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 08:51:08 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T08:51:08Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 09:10:41 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:10:41Z [INFO] - <-- GET /identities/modules/$edgeAgent?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 09:10:41 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:10:41Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 09:11:07 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:11:07Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 09:11:07 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:11:07Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 09:21:08 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:21:08Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 09:21:08 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:21:08Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 09:51:09 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:51:09Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 09:51:09 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:51:09Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 09:59:10 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:59:10Z [INFO] - <-- GET /identities/modules?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 09:59:11 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:59:11Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 09:59:11 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:59:11Z [INFO] - <-- PUT /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 09:59:11 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T09:59:11Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:00:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:00:56Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:00:56 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:00:56Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:01:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:01:42Z [INFO] - <-- GET /identities/modules/$edgeAgent?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:01:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:01:42Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:02:13 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:02:13Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:02:14 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:02:14Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:03:40 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:03:40Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:03:40 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:03:40Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:05:26 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:05:26Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:05:26 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:05:26Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:05:50 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:05:50Z [INFO] - <-- GET /identities/modules?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:05:50 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:05:50Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:05:50 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:05:50Z [INFO] - <-- PUT /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 10:05:50 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:05:50Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:32 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:32Z [INFO] - <-- GET /identities/modules/$edgeAgent?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:07:32 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:32Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:36 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:36Z [INFO] - <-- GET /identities/modules?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:07:37 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:37Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:37 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:37Z [INFO] - <-- PUT /identities/modules/GenericDecoder?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": > Mar 01 10:07:37 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:37Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:37 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:37Z [INFO] - <-- PUT /identities/modules/NkeSensorsDecoder?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host> Mar 01 10:07:37 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:37Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:37 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:37Z [INFO] - <-- PUT /identities/modules/filebeat?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 10:07:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:38Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:38Z [INFO] - <-- PUT /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 10:07:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:38Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:38Z [INFO] - <-- PUT /identities/modules/metricbeat?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ide> Mar 01 10:07:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:38Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:38 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:38Z [INFO] - <-- PUT /identities/modules/LoRaWanNetworkSrvModule?api-version=2020-09-01&type=aziot {"content-type": "application/json",> Mar 01 10:07:39 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:39Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:39 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:39Z [INFO] - <-- PUT /identities/modules/NetvoxSensorsDecoder?api-version=2020-09-01&type=aziot {"content-type": "application/json", "h> Mar 01 10:07:39 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:39Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:39 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:39Z [INFO] - <-- PUT /identities/modules/MetadataEnricher?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host"> Mar 01 10:07:39 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:39Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:39 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:39Z [INFO] - <-- PUT /identities/modules/NKESensorsDecoderPy?api-version=2020-09-01&type=aziot {"content-type": "application/json", "ho> Mar 01 10:07:40 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:40Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:07:40 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:40Z [INFO] - <-- PUT /identities/modules/Kafka2Pi?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 10:07:40 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:07:40Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:41 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:41Z [INFO] - <-- GET /identities/modules?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:08:41 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:41Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:41 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:41Z [INFO] - <-- PUT /identities/modules/GenericDecoder?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": > Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - <-- PUT /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - <-- PUT /identities/modules/filebeat?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - <-- PUT /identities/modules/NkeSensorsDecoder?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host> Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:42 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:42Z [INFO] - <-- PUT /identities/modules/metricbeat?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ide> Mar 01 10:08:43 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:43Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:43 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:43Z [INFO] - <-- PUT /identities/modules/LoRaWanNetworkSrvModule?api-version=2020-09-01&type=aziot {"content-type": "application/json",> Mar 01 10:08:43 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:43Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:43 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:43Z [INFO] - <-- PUT /identities/modules/NetvoxSensorsDecoder?api-version=2020-09-01&type=aziot {"content-type": "application/json", "h> Mar 01 10:08:43 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:43Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:43 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:43Z [INFO] - <-- PUT /identities/modules/MetadataEnricher?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host"> Mar 01 10:08:44 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:44Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:44 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:44Z [INFO] - <-- PUT /identities/modules/NKESensorsDecoderPy?api-version=2020-09-01&type=aziot {"content-type": "application/json", "ho> Mar 01 10:08:44 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:44Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:08:44 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:44Z [INFO] - <-- PUT /identities/modules/Kafka2Pi?api-version=2020-09-01&type=aziot {"content-type": "application/json", "host": "ident> Mar 01 10:08:44 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:08:44Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:09:02 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:09:02Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:09:02 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:09:02Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:09:03 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:09:03Z [INFO] - <-- GET /identities/modules/$edgeHub?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} Mar 01 10:09:03 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:09:03Z [INFO] - --> 200 {"content-type": "application/json"} Mar 01 10:09:23 carsi4iiotedge1 aziot-identityd[3884456]: 2023-03-01T10:09:23Z [INFO] - <-- GET /identities/modules/LoRaWanNetworkSrvModule?api-version=2020-09-01&type=aziot {"host": "identityd.sock"} ```( N.B.: sorry for the logs display, code section is not taken into account. I had logs in a linked file. Identity_service_Logs_and_LoRaWanNetworkSrvModule_Logs.txt ) |
Based on the error @adcoly it appears that keyd is not actually up and running or there might be some socket isssue for keyd. I believe you should see the socket for keyd here: |
@adcoly did it help? |
(Sorry for the late answer)
|
@adcoly hmm interesting, so you have all the sockets and if you do |
I have almost no errors (only two few days ago as shown below)
(By the way I don't know how often this module has to do requests but has you can see just below, I have almost 2000 lines in only one hour) aziot-keyd logs for 1h
I also noticed I have less issues in my edgeHub than before. I let the logs for the last 24h here if it can help. edgeHub logs for the last 24 hours
|
@adcoly is your device named Also, it appears |
I tried this modification and let you know later in the day if there is any news 😃 |
@adcoly any updates? |
Sorry for the delay, It seems we still have some issues, but less than before. Here are the edgeHub logs for the last 3 days. edgeHub logs (since 3 days)
N.B.: |
@adcoly would you mind also putting the output of |
Here is a check with verbose option: iotedge check --verbose
|
@adcoly would you mind running |
Here is the check after an apply : iotedge check --verbose
I'll check if there is less connectivity issues in the edgeHub in the following hours. |
@adcoly still looks like connectivity from modules to outside is broken... if you docker exec into one of the nodes are you able to ping or make any outside network calls? Also, would you mind running this on the device? |
@adcoly any update on this? Were you able to test the suggestion provided above? |
@adcoly let me know if I can go ahead and close this issue? |
Closing the issue as there is no response. @adcoly feel free to re-open or create a new item if necessary |
Expected Behavior
I shouldn't have connection issue when the edgeHub try to refresh the service identity for my Gateway (LBS)
Current Behavior
I meet issue on refreshing the service identity for my Gateway (see logs below). I have a "System.TimeoutException: Operation timed out" error.
Steps to Reproduce
I do not have specific steps to reproduce to head to this issue.
Context (Environment)
Output of
iotedge check
Click here
Device Information
Runtime Versions
iotedge version
]: 1.4.9docker version
]:Click here
Logs
Logs for the last 30min:
Additional Information
I had never saw this issue with those runtime versions:
iotedge version
]: 1.4.9The text was updated successfully, but these errors were encountered: