Skip to content

Unable to download root key pkg on Ubuntu 20.04 #747

@draco961

Description

@draco961

I have deployed the following packages on my gateway running Ubuntu 20.04

deliveryoptimization-agent_1.0.0_armhf.deb
deviceupdate-agent_1.1.0_armhf.deb
libdeliveryoptimization_1.0.0_armhf.deb

The state is the following

  • There is not IoT Edge runtime running.
  • I use a simple connection string to communicate with IoT Hub set up in the "/etc/adu/du-config.json"
  • The "adu" download folder has the right right.
  • I am able create an import manifest.
  • I can deploy the update to the gateway from IoT Hub
  • The gateway see there is a new update. and try to install

Device Update agent fails on download a root key pkg. Any idea why ?

Logs:

-- Logs begin at Wed 2025-04-30 14:38:18 CEST, end at Wed 2025-04-30 15:09:58 CEST. --
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: <- 15:07:56 CONNACK | SESSION_PRESENT: true | RETURN_CODE: 0x0
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.1109Z 26176[26176] [D] IotHub connection status: 0, reason: 6 [IoTHub_CommunicationManager_ConnectionStatus_Callback:212]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: -> 15:07:56 SUBSCRIBE | PACKET_ID: 2 | TOPIC_NAME: $iothub/twin/res/# | QOS: 0
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: <- 15:07:56 SUBACK | PACKET_ID: 2 | RETURN_CODE: 0
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: -> 15:07:56 PUBLISH | IS_DUP: false | RETAIN: 0 | QOS: DELIVER_AT_MOST_ONCE | TOPIC_NAME: $iothub/twin/GET/?$rid=3
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: <- 15:07:56 PUBLISH | IS_DUP: false | RETAIN: 0 | QOS: DELIVER_AT_MOST_ONCE | TOPIC_NAME: $iothub/twin/res/200/?$rid=3 | PAYLOAD_LEN: 4399
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.5164Z 26176[26176] [D] ComponentName:deviceUpdate, propertyName:service [ADUC_PnP_ComponentClient_PropertyUpdate_Callback:536]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.5176Z 26176[26176] [D] Update Action info string ({"fileUrls":null,"rootKeyPackageUrl":"http:\/\/iot-eu-projectname-dev--iot-eu-projectname-dev.b.nlu.dl.adu.microsoft.com\/WestEurope\/rootkeypackages\/rootkeypackage-2.json","updateManifest":"{\"manifestVersion\":\"5\",\"updateId\":{\"provider\":\"rotarex\",\"name\":\"x2000\",\"version\":\"1.0.1\"},\"compatibility\":[{\"manufacturer\":\"projectname\",\"model\":\"x2000\"}],\"instructions\":{\"steps\":[{\"handler\":\"microsoft\/script:1\",\"files\":[\"f0532a4eb60e6d462\"],\"handlerProperties\":{}}]},\"files\":{\"f0532a4eb60e6d462\":{\"fileName\":\"firmware-deployment-script.sh\",\"sizeInBytes\":62,\"hashes\":{\"sha256\":\"C6gzu\/Wmd1TGl49QIUbZ7MMjPyprg6zkMu8udEC1r7g=\"}}},\"createdDateTime\":\"2025-04-30T06:58:21Z\"}","updateManifestSignature":null,"workflow":{"action":3,"id":"06ed69af-f5a4-4bdb-a64f-b91130efd1fa"}}), property version (12) [OrchestratorUpdateCallback:433]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.5299Z 26176[26176] [D] Processing deployment 06ed69af-f5a4-4bdb-a64f-b91130efd1fa ... [OrchestratorUpdateCallback:446]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.5303Z 26176[26176] [D] Queueing message (t:0, c:0xb6fce9e8, m:{"deviceUpdate":{"__t":"c","agent":{"lastInstallResult":{"stepResults":null,"resultCode":1,"extendedResultCodes":"00000000","resultDetails":null},"state":6,"workflow":{"action":3,"id":"06ed69af-f5a4-4bdb-a64f-b91130efd1fa"}}}}) [ADUC_D2C_Message_SendAsync:526]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.5307Z 26176[26176] [E] stat path '/var/lib/adu/downloads/06ed69af-f5a4-4bdb-a64f-b91130efd1fa/rootkeypackage-2.json' failed: 2 [SystemUtils_IsFile:734]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.5309Z 26176[26176] [D] Attempting download of 'http://iot-eu-projectname-dev--iot-eu-projectname-dev.b.nlu.dl.adu.microsoft.com/WestEurope/rootkeypackages/rootkeypackage-2.json' using 'DO' [ADUC_RootKeyPackageUtils_DownloadPackage:109]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.5309Z 26176[26176] [I] Downloading File 'http://iot-eu-projectname-dev--iot-eu-projectname-dev.b.nlu.dl.adu.microsoft.com/WestEurope/rootkeypackages/rootkeypackage-2.json' to '/var/lib/adu/downloads/06ed69af-f5a4-4bdb-a64f-b91130efd1fa/rootkeypackage-2.json' [DownloadRootKeyPkg_DO:22]
*** BEGIN ERROR *** 
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7975Z 26176[26176] [E] DO error, msg: unrecognized error, code: 0x8007023e, timeout? 0 [DownloadRootKeyPkg_DO:36]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7978Z 26176[26176] [E] Unable to download root key pkg. [ADUC_RootKeyPackageUtils_DownloadPackage:115]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7980Z 26176[26176] [E] Fail update root keys, ERC 0xa017023e [RootKeyWorkflow_UpdateRootKeys:168]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7981Z 26176[26176] [E] Update Rootkey failed, 0xa017023e. Deployment cannot proceed. [OrchestratorUpdateCallback:466]
*** END ERROR *** 
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7978Z 26176[26176] [I] Download rc: 0, erc: 0xa017023e [DownloadRootKeyPkg_DO:51]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7987Z 26176[26176] [D] Replacing existing pending message. (t:0, s:{"deviceUpdate":{"__t":"c","agent":{"lastInstallResult":{"stepResults":null,"resultCode":1,"extendedResultCodes":"00000000","resultDetails":null},"state":6,"workflow":{"action":3,"id":"06ed69af-f5a4-4bdb-a64f-b91130efd1fa"}}}}) [ADUC_D2C_Message_SendAsync:521]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7989Z 26176[26176] [D] Send message completed (status:5) [OnUpdateResultD2CMessageCompleted:64]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7989Z 26176[26176] [D] Queueing message (t:0, c:0xb6fbfdc8, m:{"deviceUpdate":{"__t":"c","agent":{"lastInstallResult":{"resultCode":0,"extendedResultCodes":"A017023E","resultDetails":null},"state":255,"workflow":{"action":3,"id":"06ed69af-f5a4-4bdb-a64f-b91130efd1fa"}}}}) [ADUC_D2C_Message_SendAsync:526]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7992Z 26176[26176] [I] OrchestratorPropertyUpdateCallback ended [OrchestratorUpdateCallback:517]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7993Z 26176[26176] [I] Processing existing Device Twin data after agent started. [ADUC_PnPDeviceTwin_Callback:647]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7993Z 26176[26176] [D] Notifies components that all callback are subscribed. [ADUC_PnPDeviceTwin_Callback:650]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7994Z 26176[26176] [I] Perform startup tasks. [ADUC_Workflow_HandleStartupWorkflowData:355]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7994Z 26176[26176] [I] There's no update actions in current workflow (first time connected to IoT Hub). [ADUC_Workflow_HandleStartupWorkflowData:360]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7995Z 26176[26176] [D] cancellationType(1) => Normal [ADUC_Workflow_HandleUpdateAction:628]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7995Z 26176[26176] [W] Setting 'OperationCancelled' when there's no active workflow. (value:0) [workflow_set_operation_cancel_requested:2096]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7995Z 26176[26176] [I] Cancel received with no operation in progress - returning to Idle state [ADUC_Workflow_HandleUpdateAction:658]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.7998Z 26176[26176] [D] Queueing message (t:5, c:0xb6fbd2e8, m:{"deviceUpdate":{"__t":"c","agent":{"deviceProperties":{"manufacturer":"projectname","model":"x2000","interfaceId":null,"contractModelId":"dtmi:azure:iot:deviceUpdateContractModel;3","aduVer":"DU;agent\/1.1.0"},"compatPropertyNames":"manufacturer,model"}}}) [ADUC_D2C_Message_SendAsync:526]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8001Z 26176[26176] [I] DeviceInformation component is ready - reporting properties [DeviceInfoInterface_Connected:166]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8002Z 26176[26176] [I] Property manufacturer changed to projectname [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8003Z 26176[26176] [I] Property model changed to x2000 [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8013Z 26176[26176] [I] Property osName changed to Ubuntu [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8016Z 26176[26176] [I] Property swVersion changed to 20.04.2 LTS (Focal Fossa) [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8017Z 26176[26176] [I] Property processorArchitecture changed to armv7l [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8401Z 26176[26176] [I] Property processorManufacturer changed to ARM [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8403Z 26176[26176] [I] Property totalMemory changed to 1004236 [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8404Z 26176[26176] [I] Property totalStorage changed to 2392752 [RefreshDeviceInfoInterfaceData:131]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8407Z 26176[26176] [D] Queueing message (t:2, c:0xb6fbd410, m:{"deviceInformation":{"__t":"c","manufacturer":"projectname","model":"x2000","osName":"Ubuntu","swVersion":"20.04.2 LTS (Focal Fossa)","processorArchitecture":"armv7l","processorManufacturer":"ARM","totalMemory":1004236,"totalStorage":2392752}}) [ADUC_D2C_Message_SendAsync:526]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8411Z 26176[26176] [I] DiagnosticsInterface is connected [DiagnosticsInterface_Connected:97]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8412Z 26176[26176] [D] Sending D2C message (t:0, retries:0). [ProcessMessage:390]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8413Z 26176[26176] [D] Sending D2C message:
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: {"deviceUpdate":{"__t":"c","agent":{"lastInstallResult":{"resultCode":0,"extendedResultCodes":"A017023E","resultDetails":null},"state":255,"workflow":{"action":3,"id":"06ed69af-f5a4-4bdb-a64f-b91130efd1fa"}}}} [ADUC_D2C_Default_Message_Transport_Function:580]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8415Z 26176[26176] [D] Sending D2C message (t:2, retries:0). [ProcessMessage:390]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8415Z 26176[26176] [D] Sending D2C message:
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: {"deviceInformation":{"__t":"c","manufacturer":"projectname","model":"x2000","osName":"Ubuntu","swVersion":"20.04.2 LTS (Focal Fossa)","processorArchitecture":"armv7l","processorManufacturer":"ARM","totalMemory":1004236,"totalStorage":2392752}} [ADUC_D2C_Default_Message_Transport_Function:580]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8417Z 26176[26176] [D] Sending D2C message (t:5, retries:0). [ProcessMessage:390]
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: 2025-04-30T13:07:56.8418Z 26176[26176] [D] Sending D2C message:
Apr 30 15:07:56 ubuntu AducIotAgent[26176]: {"deviceUpdate":{"__t":"c","agent":{"deviceProperties":{"manufacturer":"projectname","model":"x2000","interfaceId":null,"contractModelId":"dtmi:azure:iot:deviceUpdateContractModel;3","aduVer":"DU;agent\/1.1.0"},"compatPropertyNames":"manufacturer,model"}}} [ADUC_D2C_Default_Message_Transport_Function:580]

Metadata

Metadata

Assignees

Labels

DesignImprovementAck of potential for future design improvement

Type

No type

Projects

No projects

Milestone

No milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions