When an update is deployed, the download starts and then fails with an exception.
When looking into the do log files at /var/cache/deliveryoptimization-agent/log the first line states:
2021-04-22T13:23:18.4603502Z 821 821 info {Run} Started, DU;deliveryoptimization-agent/v0.4.0+20210422.071111.b40eeac (HEAD)
2021-04-23T10:19:26.8281728Z 821 903 trace {_TryRefresh} json file not found at /etc/deliveryoptimization-agent/admin-config.json
What should this file contain ? The sdk-config.json is present and just contains the connection string.
Complete log:
matthias@matthias-VirtualBox:~/Desktop/azure/iot-hub-device-update$ sudo AducIotAgent --log-level 0
2021-04-21T14:20:23.0557Z [I] Agent (linux; 0.6.0-public-preview) starting. [main]
2021-04-21T14:20:23.0557Z [I] Git Info: main:55abcef [main]
2021-04-21T14:20:23.0557Z [I] Agent built with handlers: microsoft/swupdate. [main]
2021-04-21T14:20:23.0559Z [I] Initalizing PnP components. [ADUC_PnP_Components_Create]
2021-04-21T14:20:23.0560Z [I] ADUC agent started. Using IoT Hub Client SDK 1.6.0 [AzureDeviceUpdateCoreInterface_Create]
2021-04-21T14:20:23.0560Z [I] Calling ADUC_Register [ADUC_MethodCall_Register]
2021-04-21T14:20:23.0561Z [I] IoTHub Device Twin callback registered. [ADUC_DeviceClient_Create]
2021-04-21T14:20:23.0564Z [I] Agent running. [main]
2021-04-21T14:20:23.4168Z [D] IotHub connection status: 0, reason:6 [ADUC_ConnectionStatus_Callback]
2021-04-21T14:20:23.8656Z [D] ComponentName:azureDeviceUpdateAgent, propertyName:service [ADUC_PnP_ComponentClient_PropertyUpdate_Callback]
2021-04-21T14:20:23.8657Z [D] OrchestratorUpdateCallback received property JSON string ({"action":255,"updateManifest":"","updateManifestSignature":""}), property version (7) [OrchestratorUpdateCallback]
2021-04-21T14:20:23.8662Z [I] Performing startup tasks. [ADUC_Workflow_HandleStartupWorkflowData]
2021-04-21T14:20:23.8663Z [I] Calling IsInstalledCallback to check if content is installed. [ADUC_MethodCall_IsInstalled]
2021-04-21T14:20:23.8664Z [I] IsInstalledCallback called [IsInstalledCallback]
2021-04-21T14:20:23.8664Z [I] IsInstalled called workflowId: , installed criteria: (null) [IsInstalled]
2021-04-21T14:20:23.8667Z [E] Failed to create content handler, updateType:(null) [IsInstalled]
2021-04-21T14:20:23.8668Z [W] IsInstalled call failed. ExtendedResultCode: -536870781 - setting state to Idle [ADUC_Workflow_HandleStartupWorkflowData]
2021-04-21T14:20:23.8668Z [I] Setting UpdateState to Idle [ADUC_SetUpdateStateHelper]
2021-04-21T14:20:23.8669Z [I] Reporting state: 0, Idle (0); HTTP 200; result 1, 0 [AzureDeviceUpdateCoreInterface_ReportStateAndResultAsync]
2021-04-21T14:20:23.8670Z [I] UpdateAction: Idle. WorkflowId: 210421142023 [ADUC_MethodCall_Idle]
2021-04-21T14:20:23.8670Z [I] Calling IdleCallback [ADUC_MethodCall_Idle]
2021-04-21T14:20:23.8670Z [I] Now idle. workflowId: 210421142023 [Idle]
2021-04-21T14:20:23.8674Z [I] Processing existing Device Twin data after agent started. [ADUC_PnPDeviceTwin_Callback]
2021-04-21T14:20:23.8674Z [D] Notifies components that all callback are subscribed. [ADUC_PnPDeviceTwin_Callback]
2021-04-21T14:20:23.8674Z [I] DeviceInformation component is ready - reporting properties [DeviceInfoInterface_Connected]
2021-04-21T14:20:23.8676Z [I] Property manufacturer changed to FuS [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:23.8677Z [I] Property model changed to imx8mm [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:23.8677Z [I] Property osName changed to Linux [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:23.8679Z [I] Property swVersion changed to 5.4.0-72-generic [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:23.8679Z [I] Property processorArchitecture changed to x86-64 [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:23.8912Z [I] Property processorManufacturer changed to GenuineIntel [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:23.8914Z [I] Property totalMemory changed to 4030200 [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:23.8915Z [I] Property totalStorage changed to 20509264 [RefreshDeviceInfoInterfaceData]
2021-04-21T14:20:38.3766Z [D] ComponentName:azureDeviceUpdateAgent, propertyName:service [ADUC_PnP_ComponentClient_PropertyUpdate_Callback]
2021-04-21T14:20:38.3772Z [D] OrchestratorUpdateCallback received property JSON string ({"action":0,"updateManifest":"{"manifestVersion":"2.0","updateId":{"provider":"Schneider","name":"newDeviceModel","version":"2.0.0"},"updateType":"microsoft/swupdate:1","installedCriteria":"test","files":{"00000":{"fileName":"workingUpdate","sizeInBytes":144549945,"hashes":{"sha256":"eNUL10t04ThmJ0l49987SZLuFvFzhYkIq/pxoQXgtdI="}}},"createdDateTime":"2021-04-19T10:11:10.4149069Z"}","updateManifestSignature":"eyJhbGciOiJSUzI1NiIsInNqd2siOiJleUpoYkdjaU9pSlNVekkxTmlJc0ltdHBaQ0k2SWtGRVZTNHlNREEzTURJdVVpSjkuZXlKcmRIa2lPaUpTVTBFaUxDSnVJam9pY2toV1FrVkdTMUl4ZG5Ob1p5dEJhRWxuTDFORVVVOHplRFJyYWpORFZWUTNaa2R1U21oQmJYVkVhSFpJWm1velowaDZhVEJVTWtsQmNVTXhlREpDUTFka1QyODFkamgwZFcxeFVtb3ZibGx3WnprM2FtcFFRMHQxWTJSUE5tMHpOMlJqVDIxaE5EWm9OMDh3YTBod2Qwd3pibFZJUjBWeVNqVkVRUzloY0ZsdWQwVmxjMlY0VkdwVU9GTndMeXRpVkhGWFJXMTZaMFF6TjNCbVpFdGhjV3AwU0V4SFZtbFpkMVpJVUhwMFFtRmlkM2RxYUVGMmVubFNXUzk1T1U5bWJYcEVabGh0Y2xreGNtOHZLekpvUlhGRmVXdDFhbmRSUlZscmFHcEtZU3RDTkRjMkt6QnRkVWQ1VjBrMVpVbDJMMjlzZERKU1pWaDRUV0k1VFd4c1dFNTViMUF6WVU1TFNVcHBZbHBOY3pkMVMyTnBkMnQ1YVZWSllWbGpUV3B6T1drdlVrVjVLMnhOT1haSlduRnlabkJEVlZoMU0zUnVNVXRuWXpKUmN5OVVaRGgwVGxSRFIxWTJkM1JXWVhGcFNYQlVaRlEwVW5KRFpFMXZUelZUVG1WbVprUjVZekpzUXpkMU9EVXJiMjFVYTJOcVVHcHRObVpoY0dSSmVVWXljV1Z0ZGxOQ1JHWkNOMk5oYWpWRVNVa3lOVmQzTlVWS1kyRjJabmxRTlRSdGNVNVJVVE5IWTAxUllqSmtaMmhwWTJ4d2FsbHZLelF6V21kWlEyUkhkR0ZhWkRKRlpreGFkMGd6VVdjeWNrUnNabXN2YVdFd0x6RjVjV2xyTDFoYU1XNXpXbFJwTUVKak5VTndUMDFGY1daT1NrWlJhek5DVjI5Qk1EVnlRMW9pTENKbElqb2lRVkZCUWlJc0ltRnNaeUk2SWxKVE1qVTJJaXdpYTJsa0lqb2lRVVJWTGpJd01EY3dNaTVTTGxNaWZRLmlTVGdBRUJYc2Q3QUFOa1FNa2FHLUZBVjZRT0dVRXV4dUhnMllmU3VXaHRZWHFicE0takk1UlZMS2VzU0xDZWhLLWxSQzl4Ni1fTGV5eE5oMURPRmMtRmE2b0NFR3dVajh6aU9GX0FUNnM2RU9tY2txUHJ4dXZDV3R5WWtrRFJGNzRkdGFLMWpOQTdTZFhyWnp2V0NzTXFPVU1OejBnQ29WUjBDczEyNTRrRk1SbVJQVmZFY2pnVDdqNGxDcHlEdVdncjlTZW5TZXFnS0xZeGphYUcwc1JoOWNkaTJkS3J3Z2FOYXFBYkhtQ3JyaHhTUENUQnpXTUV4WnJMWXp1ZEVvZnlZSGlWVlJoU0pwajBPUTE4ZWN1NERQWFYxVGN0MXkzazdMTGlvN244aXpLdXEybTNUeEY5dlBkcWI5TlA2U2M5LW15YXB0cGJGcEhlRmtVTC1GNXl0bF9VQkZLcHdOOUNMNHdwNnlaLWpkWE5hZ3JtVV9xTDFDeVh3MW9tTkNnVG1KRjNHZDNseXFLSEhEZXJEcy1NUnBtS2p3U3dwWkNRSkdEUmNSb3ZXeUwxMnZqdzNMQkpNaG1VeHNFZEJhWlA1d0dkc2ZEOGxkS1lGVkZFY1owb3JNTnJVa1NNQWw2cEl4dGVmRVhpeTVscW1pUHpxX0xKMWVSSXJxWTBfIn0.eyJzaGEyNTYiOiJHVUtvUElvWXlScGxqTC9wR2lDS2RBUmw3cWFPZ1JqMm1qbDk5SXFhZXc0PSJ9.aYxRm-eXMYXUJfd0E-QgjWkyhwh75izHnhbNz7YznFhLc46AI00YjGSZy8McjzHhpnlg4-sHN-PMqdSvU9Kl2TT9jRFSFm2Ot5YiGLwkEj5p1ehrQvgGLb9TnzQkM751iSU_RxQbV0Qpx7oFeSvbAuqfXsjmEO7OnbPrZ4eXcphSoJJoyjxo04tTjEzr7Xs4UYSCeytV6NEzMcme1iKDGgqcWImxfh6wvuqdNQE3cUTqg_rpTFQsZ9pKQ6Lu4XpJmvZvPg9gvRdlwdEhhaoesCSByS5NSPIY-59lNU0sOYn1ZLxZDLChbmAhDh6OIc1Rnliu1uhEP4m3a3u1C2bIJJ-i3wzoR3bz8QAYXY4xrnov2knBryDG7Vy-zKK4WaGC9L9rQvo6dwD2ghqbsGRXr43XcZP3pWV53_fMuXRnupCZO0_8k2P9M_v7TqvVFAyUTWjMeZFs6j2Pvy9lq6qLyRp7EacYSYWwqwl-wxnYUJfpvaMHTWr13uDJH8d0y0FS","fileUrls":{"00000":"http://fsupdateinstanz--fsdeviceupdatetest.b.nlu.dl [OrchestratorUpdateCallback]
2021-04-21T14:20:38.3814Z [I] Processing 'Download' action [ADUC_Workflow_HandleUpdateAction]
2021-04-21T14:20:38.3815Z [I] UpdateAction: Prepare - calling PrepareCallback [ADUC_MethodCall_Prepare]
2021-04-21T14:20:38.3816Z [I] {210421142023} Received Metadata, UpdateType: microsoft/swupdate:1, UpdateTypeName: microsoft/swupdate, UpdateTypeVersion: 1, FileCount: 1 [Prepare]
2021-04-21T14:20:38.3817Z [I] microsoft_swupdate_CreateFunc called. [microsoft_swupdate_CreateFunc]
2021-04-21T14:20:38.3818Z [I] Prepare succeeded. [Prepare]
2021-04-21T14:20:38.3818Z [I] UpdateAction: Download [ADUC_MethodCall_Download]
2021-04-21T14:20:38.3819Z [I] Calling SandboxCreateCallback [ADUC_MethodCall_Download]
2021-04-21T14:20:38.3821Z [I] Setting sandbox /tmp/aduc-dl-210421142023 [SandboxCreate]
2021-04-21T14:20:38.3822Z [I] Using sandbox /tmp/aduc-dl-210421142023 [ADUC_MethodCall_Download]
2021-04-21T14:20:38.3822Z [I] Setting UpdateState to DownloadStarted [ADUC_SetUpdateStateHelper]
2021-04-21T14:20:38.3824Z [I] Calling DownloadCallback [ADUC_MethodCall_Download]
2021-04-21T14:20:38.3828Z [D] Downloading 1 files to /tmp/aduc-dl-210421142023 [Download]
2021-04-21T14:20:38.3829Z [I] Downloading File 'workingUpdate' from 'http://fsupdateinstanz--fsdeviceupdatetest.b.nlu.dl.adu.microsoft.com/northeurope/FsUpdateInstanz--fsdeviceupdatetest/3444d4177cd04eec99450741d6c37d9f/workingUpdate' to '/tmp/aduc-dl-210421142023/workingUpdate' [Download]
2021-04-21T14:20:38.6892Z [I] Caught DO exception, msg: unrecognized error, code: -2147024809 [Download]
2021-04-21T14:20:38.6893Z [I] ProgressCallback: workflowId: 210421142023; Id 00000; State: Error; Bytes: 0/0 [DownloadProgressCallback]
2021-04-21T14:20:38.6893Z [I] Download resultCode: 0, extendedCode: -804847529 [Download]
2021-04-21T14:20:38.6894Z [I] Action 'Download' complete. Result: 0, -804847529 [ADUC_Workflow_WorkCompletionCallback]
2021-04-21T14:20:38.6895Z [E] Download failed. error 0, -804847529 - Expecting service to send Cancel action [ADUC_Workflow_WorkCompletionCallback]
2021-04-21T14:20:38.6896Z [I] Setting UpdateState to Failed [ADUC_SetUpdateStateHelper]
2021-04-21T14:20:38.6896Z [I] Reporting state: 255, Failed (255); HTTP 500; result 0, -804847529 [AzureDeviceUpdateCoreInterface_ReportStateAndResultAsync]
2021-04-21T14:20:40.9971Z [D] ComponentName:azureDeviceUpdateAgent, propertyName:service [ADUC_PnP_ComponentClient_PropertyUpdate_Callback]
2021-04-21T14:20:40.9977Z [D] OrchestratorUpdateCallback received property JSON string ({"action":255,"updateManifest":"","updateManifestSignature":"","fileUrls":null}), property version (9) [OrchestratorUpdateCallback]
2021-04-21T14:20:40.9988Z [I] Cancel received with no operation in progress - returning to Idle state [ADUC_Workflow_HandleUpdateAction]
2021-04-21T14:20:40.9989Z [I] Setting UpdateState to Idle [ADUC_SetUpdateStateHelper]
2021-04-21T14:20:40.9990Z [I] Reporting state: 0, Idle (0); HTTP 200; result 1, 0 [AzureDeviceUpdateCoreInterface_ReportStateAndResultAsync]
2021-04-21T14:20:40.9991Z [I] Calling SandboxDestroyCallback [ADUC_MethodCall_Idle]
2021-04-21T14:20:40.9994Z [I] Destroying sandbox /tmp/aduc-dl-210421142023. workflowId: 210421142023 [SandboxDestroy]
2021-04-21T14:20:40.9999Z [I] UpdateAction: Idle. WorkflowId: 210421142040 [ADUC_MethodCall_Idle]
2021-04-21T14:20:41.0000Z [I] Calling IdleCallback [ADUC_MethodCall_Idle]
2021-04-21T14:20:41.0000Z [I] Now idle. workflowId: 210421142040 [Idle]
^C2021-04-21T14:26:05.8100Z [I] Agent exited with code 0 [main]
2021-04-21T14:26:05.8100Z [I] Agent is shutting down with signal 2. [ShutdownAgent]
2021-04-21T14:26:05.8100Z [I] ADUC agent stopping [AzureDeviceUpdateCoreInterface_Destroy]
2021-04-21T14:26:05.8101Z [I] Calling ADUC_Unregister [ADUC_MethodCall_Unregister]
Error: Time:Wed Apr 21 16:26:05 2021 File:/tmp/azure-iot-sdk-c/c-utility/adapters/tlsio_openssl.c Func:openssl_static_locks_uninstall Line:585 Locks already uninstalled