Summary
This Knowledge Base article details the log entries generated by an Adaptiva Client after a rollout cycle begins. The following assumptions apply:
- An applicable strategy, bot, and patching process exist and have been triggered.
- The client on which the trace is being performed, is a member of an applicable business unit.
You can filter most logs on either **POLICY_ID** or **PATCH_ID** to show relevant information.
Details
An Adaptiva Client receives two messages from the Adaptiva Server for the PatchingPolicy object of the respective business unit. The message contains the policy ID.
*nb Policy ID can be obtained from database [ `SELECT * FROM POLICIES WHERE [NAME] LIKE 'PatchingPolicy_%'` ]
With Bulk Messaging enabled, the message arrives as bulk. The following sections indicate each process that occurs as a rollout cycle proceeds and the log name that contains those details.
**SentRecvMsg.log**
PolicyPayloadActivation Message:
yyyy-MM-dd HH:mm:ss,fff - INFO - Sender ID: 0. Correlation ID = 1680249351303 . message :Name of the message: Bulk, Sender ID: 0, Receiver ID: **CLIENT_ID**, Queue ID: -10, CORRELATION ID: 1680249351303, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: /X.X.X.X, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 4, Attribute names and their values: (Name: PayloadId, Value: hYmWkY6Nk5yWjpONhIqLiQ; Name: _orgname_, Value: PolicyPayloadActivation; Name: ActivationTime, Value: 1680258352052; Name: PolicyID, Value: **POLICY_ID**) - ReceivingTask - TID=255, ReceivingTask: Sender Id = [/X.X.X.X]
PolicyAssignment Message:
yyyy-MM-dd HH:mm:ss,fff - INFO - Sender ID: 0. Correlation ID = 1680249351305 . message :Name of the message: PolicyAssignment, Sender ID: 0, Receiver ID: **CLIENT_ID**, Queue ID: 1, CORRELATION ID: 1680249351305, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: /X.X.X.X, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 2, Attribute names and their values: (Name: **POLICY_ID**, Value: 102009; Name: PolicyVer, Value: **POLICY_VERSION**) - ReceivingTask - TID=273, ReceivingTask: Sender Id = [/X.X.X.X]
Downloads, unpacks, and executes policy:
**PolicyManager.log**
PolicyPayloadActivation:
yyyy-MM-dd HH:mm:ss,fff - INFO - SERVER: message sent by server : Name of the message: PolicyPayloadActivation, Sender ID: 0, Receiver ID: **CLIENT_ID**, Queue ID: 1, CORRELATION ID: 1680249351302, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: /X.X.X.X, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 5, Attribute names and their values: (Name: PayloadId, Value: hYmWkY6Nk5yWjpONhIqLiQ; Name: _orgname_, Value: PolicyPayloadActivation; Name: ActivationTime, Value: 1680258352052; Name: PolicyID, Value: **POLICY_ID**; Name: _SERVER_GUID_, Value: 540ff7a7-007c-0000-eaee-c817639701d9) - PolicyClientMessageProcessor - TID=259, ConsumerTask: Sender Id = [0], Retry Level: 0
yyyy-MM-dd HH:mm:ss,fff INFO - Policy payload activation request received: [ CorrelationId: 1, Operation: 7, TargetPolicyId: **POLICY_ID**, TargetPolicyVersion: 0, SourcePolicyId: **POLICY_ID**, Application data: com.adaptiva.fw.newpolicy.client.PolicyPayloadActivationClient$ActivationMessageData@8e9ac6 ] - PolicyClient - TID=103, PolicyClientMessageProcessor$MessageDispatchingThread
yyyy-MM-dd HH:mm:ss,fff - INFO - Policy payload activation request queued for processing: [ CorrelationId: 1, Operation: 7, TargetPolicyId: **POLICY_ID**, TargetPolicyVersion: 0, SourcePolicyId: **POLICY_ID**, Application data: com.adaptiva.fw.newpolicy.client.PolicyPayloadActivationClient$ActivationMessageData@8e9ac6 ] - PolicyClient - TID=103, PolicyClientMessageProcessor$MessageDispatchingThread
PolicyAssignment:
yyyy-MM-dd HH:mm:ss,fff - INFO - SERVER: message sent by server : Name of the message: PolicyAssignment, Sender ID: 0, Receiver ID: **CLIENT_ID**, Queue ID: 1, CORRELATION ID: 1680249351305, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: /X.X.X.X, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 2, Attribute names and their values: (Name: PolicyID, Value: **POLICY_ID**; Name: PolicyVer, Value: **POLICY_VERSION**) - PolicyClientMessageProcessor - TID=274, ConsumerTask: Sender Id = [0], Retry Level : 0
yyyy-MM-dd HH:mm:ss,fff - INFO - Downloading client policy for request : [ CorrelationId: 2, Operation: 3, TargetPolicyId: **POLICY_ID**, TargetPolicyVersion: **POLICY_VERSION**, SourcePolicyId: **POLICY_ID**, Application data: [Ljava.lang.Object;@1b31c4 ] - PolicyAssignmentOperationHandler - TID=276, Policy Client Thread - PolicyClientOperationThread: ID= **POLICY_ID**, Version= 11, Operation= 3
yyyy-MM-dd HH:mm:ss,fff - INFO - Launching assignment workflow for policy: com.adaptiva.patching.client.policy.PatchingPolicyClientView : [ID = **POLICY_ID**, Version = **POLICY_VERSION**, Enabled = true, Embedded = false, Expiration Time = 0, Unique Key = **POLICY_ID**] - PolicyAssignmentOperationHandler - TID=276, Policy Client Thread - PolicyClientOperationThread: ID= **POLICY_ID**, Version= 11, Operation= 3
Patching Policy Client processes the patching policy:
**PatchingPolicyClient.log**
When the policy already exists on the client:
yyyy-MM-dd HH:mm:ss,fff - INFO - Processing patching policy update [**POLICY_ID**]. - PatchingPolicyClient - TID=277, Thread-202
yyyy-MM-dd HH:mm:ss,fff - INFO - recalculatePatchDesiredState(**PATCH_ID**): - PatchingPolicyClient - TID=277, Thread-202
yyyy-MM-dd HH:mm:ss,fff - INFO - recalculatePatchDesiredState(**PATCH_ID**): Found approval with highest priority: com.adaptiva.patching.client.policy.ClientComparableApproval : policyId=**POLICY_ID**, priority=-9223372036854775807, activated=false, approvalTime=1680258180947, payload='hYmWkY6Nk5yWjpONhIqLiQ', desiredState=**DESIRED_STATE**, urgency=**URGENCY** - PatchingPolicyClient - TID=277, Thread-202
- Possible Desired States: 0: No Preference, 1: Optional Install, 2: Mandatory Install, 3: Do Not Install, 4: Rollback, 5: Uninstall
- Possible Urgencies: 0: Low, 1: Medium, 2: High, 3: Critical
Desired State updated:
yyyy-MM-dd HH:mm:ss,fff - INFO - recalculatePatchDesiredState(**PATCH_ID**): Updating PerPatchDesiredState obj for softwareId: **PATCH_ID** - PatchingPolicyClient - TID=277, Thread-202
Approvals activated:
yyyy-MM-dd HH:mm:ss,fff - INFO - policyPayloadActivated: policy[**LOCAL_POLICY_ID**], serverPolicy[**POLICY_ID**], payload[hYmWkY6Nk5yWjpONhIqLiQ]: Applying package changes, Added:
[1<
[1<com.adaptiva.patching.client.policy.ClientComparableApproval : policyId=**POLICY_ID**, priority=-9223372036854775807, activated=true, approvalTime=1680258180947, payload='hYmWkY6Nk5yWjpONhIqLiQ', desiredState=**DESIRED_STATE**, urgency=**URGENCY**>
] >
] , Removed:
[1<
[1<com.adaptiva.patching.client.policy.ClientComparableApproval : policyId=**POLICY_ID**, priority=-9223372036854775807, activated=false, approvalTime=1680258180947, payload='hYmWkY6Nk5yWjpONhIqLiQ', desiredState=**DESIRED_STATE**, urgency=**URGENCY**>
] >]
Activate payload and perform scanning cycle to detect patch applicability:
**Patching.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Executing onActivation callback for policy [**LOCAL_POLICY_ID**] (serverPolicyId [**POLICY_ID**]) and payloads: {<(0)[value=hYmWkY6Nk5yWjpONhIqLiQ]>} - PatchingPolicyClientView - TID=294, Thread-219
Scan engine scans for new patch:
yyyy-MM-dd HH:mm:ss,fff INFO - PartialScanningCycleForPatches :: Beginning for patchIDs[**PATCH_ID**] - PatchingClientController - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - performPartialMultiPassScanForPatches :: Beginning for patchIDs[**PATCH_ID**] - MultiPassScanner - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - Queued partial scanning cycle [-13] for patchIDs[**PATCH_ID**] - PatchingClientController - TID=71, AdaptivaTimer - PCC_DebounceTimer. ExecutingTask-PatchingClientController$DebouncingTimerTask
**SensorExec.log**
When using ApplicationInstalled sensor for detection, SensorExec.log shows the sensor execution
yyyy-MM-dd HH:mm:ss,fff INFO - Searching for apps installed matching NameFilter: **FILTER** Architecture: **ARCHITECTURE** Version: **VERSION** - ApplicationInstalledRuntime - TID=298, pool-20-thread-1
**Patching.log**
Patch applicability scan takes place and shows whether the patch is applicable, installed, not applicable, outdated, or scan failure:
yyyy-MM-dd HH:mm:ss,fff INFO - Scanned status[APPLICABLE] for Patches[**PATCH_ID**] - PatchScanner - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - Scanned status[INSTALLED] for Patches[] - PatchScanner - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - Scanned status[NOT_APPLICABLE] for Patches[] - PatchScanner - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - Scanned status[OUTDATED] for Patches[] - PatchScanner - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - Scanned status[SCAN_FAILURE] for Patches[] - PatchScanner - TID=297, pool-4-thread-6
Desired State Scan takes place and shows whether the patch is consistent or inconsistent with desired state:
yyyy-MM-dd HH:mm:ss,fff INFO - DesiredStateScan[**PATCH_ID**]: Consistency: [**Inconsistent**], NeededOperations: [**MandatoryInstall**] - DesiredStateScanner - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - DesiredStateScan[**PATCH_ID**]: Starting desiredState scan, DesiredState[**Required**], activated[**true**] - DesiredStateScanner - TID=297, pool-4-thread-6
Performs calculations to determine how any patching exceptions affect the respective patch:
**PatchingPolicyClient**
- > yyyy-MM-dd HH:mm:ss,fff INFO - calculatePatchExceptionsEffect patchId[**PATCH_ID**]: - PatchingPolicyClient - TID=297, pool-4-thread-6
- > yyyy-MM-dd HH:mm:ss,fff INFO - calculatePatchExceptionsEffect patchId[**PATCH_ID**]: No desiredState or lastVersion overrides for patch - PatchingPolicyClient - TID=297, pool-4-thread-6
Creates ring to send patch scan status to server:
**P2PRing.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Created new P2PRingClientSessionState with appDeliveryId=PatchScanStatus_P2PRing_AppDeliveryId,sessionId=Patch_Status_P2PRing_SessionId,codecId=210000001,submitCtx=null,compressCtx=null,expandCtx=null,mergeCtx=null,expandCtx=null,clientToRvpMessageQueueId=-2146483663,rvpToServerMessageQueueId=-2146483664,aggDuration=1000,usePersistence=true - P2PRingClient - TID=297, pool-4-thread-6
Sends ring token request message to RVP:
**SentRecvMsg.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Attempting to send (UDP Small): Receiver ID: **RVP_ID**, Receiver IP: /X.X.X.X, MsgLen: 396, Correlation ID = 1680249343929, port: 34325, transport: UDPTransport, Message: Name of the message: RingTokenReq, Sender ID: 2, Receiver ID: 1, Queue ID: 1, CORRELATION ID: 0, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: null, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 5, Attribute names and their values: (Name: SessionID, Value: Patch_Status_P2PRing_SessionId; Name: BatchNumber, Value: 4; Name: _SERVER_GUID_, Value: 540ff7a7-007c-0000-eaee-c817639701d9; Name: _CARP_IP_, Value: X.X.X.X; Name: _CARP_MAC_, Value: 00-11-22-33-44-55) - SendingThread - TID=136, SendingThread: [Queue=-4, Destination=-4]
RingTokenReply received
yyyy-MM-dd HH:mm:ss,fff INFO - Received message: [Name of the message: RingTokenReply, Sender ID: **RVP_ID**, Receiver ID: **CLIENT_ID**)), Queue ID: -4, CORRELATION ID: 0, ORIGINAL CORRELATION ID: 1680249343929, ORIGINAL RECEIVER ID: **CLIENT_ID**, TTL: 300000, REPLY TO: 1, REPLY TO IP: /X.X.X.X, IS REPLY: true, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 4, Attribute names and their values: (Name: Token, Value: 9cafdfd1-007c-0000-dfb6-b041639701d9$9c8d6750-8bf4-4d47-b4c7-3d178f68c9fa; Name: SessionID, Value: Patch_Status_P2PRing_SessionId; Name: BatchNumber, Value: 4; Name: _SERVER_GUID_, Value: 540ff7a7-007c-0000-eaee-c817639701d9)] - P2PRingClient - TID=302, LocalMessageRoutingThread:RingTokenReply
RingClientToRvp message sent to RVP
yyyy-MM-dd HH:mm:ss,fff INFO - Attempting to send (UDP Large): Receiver ID: **RVP_ID**, Receiver IP: /X.X.X.X, MsgLen: 1369, Correlation ID = 1680249343930, port: 34325, transport: UDPTransport, Message: Name of the message: RingClientToRvp, Sender ID: **CLIENT_ID**, Receiver ID: 1, Queue ID: 1, CORRELATION ID: 0, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: null, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 15, Attribute names and their values: (Name: Token, Value: 9cafdfd1-007c-0000-dfb6-b041639701d9$9c8d6750-8bf4-4d47-b4c7-3d178f68c9fa; Name: AppDeliveryID, Value: PatchScanStatus_P2PRing_AppDeliveryId; Name: SessionID, Value: Patch_Status_P2PRing_SessionId; Name: BatchNumber, Value: 4; Name: Duration, Value: 1000; Name: MessageQueueId, Value: -2146483664; Name: CodecID, Value: 210000001; Name: CompressionCtx, Value: null; Name: MergeCtx, Value: null; Name: ExpandCtx, Value: null; Name: Rows, Value: 1; Name: AggregationDuration, Value: 1000; Name: _SERVER_GUID_, Value: 540ff7a7-007c-0000-eaee-c817639701d9; Name: _CARP_IP_, Value: X.X.X.X; Name: _CARP_MAC_, Value: 00-11-22-33-44-55) - SendingThread - TID=192, SendingThread: [Queue=-2146483663, Destination=-4]
RVP sends RingRVPToSrv Message to AdaptivaServer
yyyy-MM-dd HH:mm:ss,fff INFO - Attempting to send (UDP Large): Receiver ID: 0, Receiver IP: /X.X.X.X, MsgLen: 1353, Correlation ID = 1680249343939, port: 34323, transport: UDPTransport, Message: Name of the message: RingRVPToSrv, Sender ID: **CLIENT_ID**, Receiver ID: 1, Queue ID: 1, CORRELATION ID: 0, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: null, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 12, Attribute names and their values: (Name: AppDeliveryID, Value: PatchScanStatus_P2PRing_AppDeliveryId; Name: SessionID, Value: Patch_Status_P2PRing_SessionId; Name: BatchNumber, Value: 4; Name: CodecID, Value: 210000001; Name: CompressionCtx, Value: null; Name: ExpandCtx, Value: null; Name: MergeCtx, Value: null; Name: AggregationDuration, Value: 1000; Name: MessageQueueId, Value: -2146483664; Name: UnsentRowCount, Value: 1; Name: clients, Value: 2; Name: Token, Value: 9cafdfd1-007c-0000-dfb6-b041639701d9$9c8d6750-8bf4-4d47-b4c7-3d178f68c9fa) - SendingThread - TID=96, SendingThread: [Queue=-2146483664, Destination=0]
Evaluates and updates relationships:
**SoftwareDeploymentManager.log**
yyyy-MM-dd HH:mm:ss,fff INFO - updateRelationships: request[i5OTh4eCiYiSkZCLlJ2YkA]: Found 0 pre-requisite installs - DeploymentRelationshipManager - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - updateRelationships: request[i5OTh4eCiYiSkZCLlJ2YkA]: Found 0 superseded installs - DeploymentRelationshipManager - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - updateRelationships: request[i5OTh4eCiYiSkZCLlJ2YkA]: Found 0 followup installs - DeploymentRelationshipManager - TID=297, pool-4-thread-6
Completes evaluation operations:
**Patching.log**
yyyy-MM-dd HH:mm:ss,fff INFO - PartialScanningCycleForPatches :: Completed software deployment operations - PatchingClientController - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - PartialScanningCycleForPatches :: Completed - PatchingClientController - TID=297, pool-4-thread-6
yyyy-MM-dd HH:mm:ss,fff INFO - PartialScanningCycleForPatches :: Completed delta metrics scan - PatchingClientController - TID=297, pool-4-thread-6
Downloads content if required:
**PatchContentDownloader.log**
yyyy-MM-dd HH:mm:ss,fff INFO - [Patch Content Downloader :: Start Download (i5OTh4eCiYiSkZCLlJ2YkA)] valid content ID: Adaptiva$MD$**PATCH_ID** - PatchContentDownloader - TID=73, AdaptivaTimer - SoftwareDeploymentManager. ExecutingTask-SoftwareDeploymentManager$InstallationTimerTask
Fetches the content publication data:
yyyy-MM-dd HH:mm:ss,fff INFO - [Patch Content Downloader :: Instruct Download (i5OTh4eCiYiSkZCLlJ2YkA)] Adaptiva$MD$**PATCH_ID** Fetching content publication data - PatchContentDownloader - TID=73, AdaptivaTimer - SoftwareDeploymentManager. ExecutingTask-SoftwareDeploymentManager$InstallationTimerTask
**ContentDownload.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Content publication data request sent to server: Adaptiva$MD$**PATCH_ID** - ContentDownloader - TID=73, AdaptivaTimer - SoftwareDeploymentManager. ExecutingTask-SoftwareDeploymentManager$InstallationTimerTask
**SentRecvMsg.log**
Content Download Publication Data request sent to server:
yyyy-MM-dd HH:mm:ss,fff INFO - Attempting to send (UDP Small): Receiver ID: 0, Receiver IP: /X.X.X.X, MsgLen: 303, Correlation ID = 1680249343931, port: 34323, transport: UDPTransport, Message: Name of the message: ContentDownloadPublicationDataWithoutKeyRequest, Sender ID: **CLIENT_ID**, Receiver ID: 1, Queue ID: 1, CORRELATION ID: 0, ORIGINAL CORRELATION ID: 0, ORIGINAL RECEIVER ID: 1, TTL: 900000, REPLY TO: 1, REPLY TO IP: null, IS REPLY: false, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 1, Attribute names and their values: (Name: ContentID, Value: Adaptiva$MD$**PATCH_ID**) - SendingThread - TID=99, SendingThread: [Queue=0, Destination=0]
Response received from server:
yyyy-MM-dd HH:mm:ss,fff INFO - Sender ID: 0. Correlation ID = 1680249351308 . message :Name of the message: ContentDownloadPublicationDataWithoutKeyResponse, Sender ID: 0, Receiver ID: 2, Queue ID: 1, CORRELATION ID: 1680249351308, ORIGINAL CORRELATION ID: 1680249343931, ORIGINAL RECEIVER ID: 0, TTL: 2592000000, REPLY TO: 1, REPLY TO IP: /X.X.X.X, IS REPLY: true, PREF TRANSPORT: 0, RECV TRANSPORT: 2, Attribute count: 1, Attribute names and their values: (Name: ContentID, Value: Adaptiva$MD$**PATCH_ID**) - ReceivingTask - TID=278, ReceivingTask: Sender Id = [/X.X.X.X]
Passes request to ContentDownloader:
yyyy-MM-dd HH:mm:ss,fff INFO - [Patch Content Downloader :: Instruct Download (i5OTh4eCiYiSkZCLlJ2YkA)] Adaptiva$MD$**PATCH_ID** Telling ContentDownloader to start download - PatchContentDownloader - TID=73, AdaptivaTimer - SoftwareDeploymentManager. ExecutingTask-SoftwareDeploymentManager$InstallationTimerTask
**ContentDownload.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Starting download for : Adaptiva$MD$1002001423 - ContentDownloader - TID=73, AdaptivaTimer - SoftwareDeploymentManager. ExecutingTask-SoftwareDeploymentManager$InstallationTimerTask
For first download, downloads content from CDN. Otherwise, downloaded using LAN/WAN/IP2P.
Download process starts:
yyyy-MM-dd HH:mm:ss,fff INFO - CDNDownloadProtocol started for content Id : Adaptiva$MD$1002001423 content version : 1 - CDNDownloadProtocol - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
yyyy-MM-dd HH:mm:ss,fff INFO - Current content protocol set to : CDN_DOWNLOAD_PROTOCOL_RUNNING - StateTransitionProtocol - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
Server instructs CDN download and provides sources:
yyyy-MM-dd HH:mm:ss,fff INFO - Server suggested CDN download - ServerAssistedDiscoveryProtocol - TID=283, ConsumerTask: Sender Id = [0], Retry Level : 0
yyyy-MM-dd HH:mm:ss,fff INFO - Server suggested CDN sources - StateTransitionProtocol - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
yyyy-MM-dd HH:mm:ss,fff INFO - ServerAssistedDiscoveryProtocolOutput : [ Status: 15, StatusString: USE_CDN_AS_SOURCE, Priority: -1, WAN Transport: null, WAN Transport String: null] - StateTransitionProtocol - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$1002001423
Local file allocated:
yyyy-MM-dd HH:mm:ss,fff INFO - Downloading content to file [X:\adaptivacache\adaptiva$md$**PATCH_ID**.**PATCH_VERSION**.content] for session [i5OTh4eCiYiSkZCLlJ2YkA] from CDN - HttpDirectDownloadClient - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
CDN URL provided:
yyyy-MM-dd HH:mm:ss,fff INFO - Using CDN Url [**PATCH_URL**] - CDNDownloadProtocol - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
yyyy-MM-dd HH:mm:ss,fff INFO - HttpResponseProxy{HTTP/1.1 200 OK [Connection: keep-alive, Content-Length: 3239936, Content-Type: application/octet-stream, Content-MD5: **PATCH_MD5_HASH**, Last-Modified: Fri, 30 Dec 2022 11:38:38 GMT, ETag: "0x8DAEA5A64A6CD79", Server: Windows-Azure-Blob/1.0 Microsoft-HTTPAPI/2.0, x-ms-request-id: 2d6a11a0-e01e-0003-6abb-63c301000000, x-ms-version: 2020-04-08, x-ms-creation-time: Fri, 30 Dec 2022 11:38:38 GMT, x-ms-lease-status: unlocked, x-ms-lease-state: available, x-ms-blob-type: BlockBlob, Content-Disposition: attachment; filename=**PATCH_FILENAME**, x-ms-server-encrypted: true, Via: 1.1 varnish, 1.1 varnish, Fastly-Restarts: 1, Accept-Ranges: bytes, Date: Fri, 31 Mar 2023 10:27:25 GMT, Age: 0, X-Served-By: cache-iad-kcgs7200177-IAD, cache-lhr7371-LHR, X-Cache: MISS, HIT, X-Cache-Hits: 0, 2, X-Timer: S1680258446.677315,VS0,VE90] ResponseEntityProxy{[Content-Type: application/octet-stream,Content-Length: 3239936,Chunked: false]}} - HttpDirectDownloadClient$1 - TID=323, CDNDownloadThread [i5OTh4eCiYiSkZCLlJ2YkA]
CDN download starts:
yyyy-MM-dd HH:mm:ss,fff INFO - Receiving Started (CDN DOWNLOAD) For Content : Adaptiva$MD$**PATCH_ID**... Version:**PATCH_VERSION**, URL: **PATCH_URL** - CDNDownloadProtocol - TID=323, CDNDownloadThread [i5OTh4eCiYiSkZCLlJ2YkA]
CDN download completes:
yyyy-MM-dd HH:mm:ss,fff INFO - Receiving Ended (CDN DOWNLOAD) For Content : Adaptiva$MD$**PATCH_ID**... Version:**PATCH_VERSION**, , bytes: 3239936, url: **PATCH_URL**, Status: 1 - CDNDownloadProtocol - TID=323, CDNDownloadThread [i5OTh4eCiYiSkZCLlJ2YkA]
yyyy-MM-dd HH:mm:ss,fff INFO - Content download progress percentage notification. ContentId :Adaptiva$MD$**PATCH_ID**, Content version: 1, Current download type: 0, percentage: 100, download source: CDN$**PATCH_URL**, Lan download(in bytes): 0, Wan download(in bytes): 3239936 - StateTransitionProtocol - TID=323, CDNDownloadThread [i5OTh4eCiYiSkZCLlJ2YkA]
Verifies content:
yyyy-MM-dd HH:mm:ss,fff INFO - Verifying content : d:\adaptivacache\adaptiva$md$**PATCH_ID**.1.content, Content Id : Adaptiva$MD$1002001423, Content version : **PATCH_VERSION**, Diff : false - ContentDownloader - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
Verifies hash:
yyyy-MM-dd HH:mm:ss,fff INFO - Verified secure hash for content : Adaptiva$MD$**PATCH_ID** - ContentDownloader - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
Completes download process:
yyyy-MM-dd HH:mm:ss,fff INFO - ***END STP PROTOCOL*** - StateTransitionProtocol - TID=307, STP-SessionHandle=[i5OTh4eCiYiSkZCLlJ2YkA], Content Id=[Adaptiva$MD$**PATCH_ID**]
**PatchContentDownloader.log**
PatchContentDownloader registers content download completion:
yyyy-MM-dd HH:mm:ss,fff INFO - [Patch Content Downloader :: Notification Consumption] download_complete notification - PatchContentDownloader - TID=162, AdaptivaTimer - ContentSystemNotificationTimer. ExecutingTask-ContentSystemNotificationSender$ContentNotificationSenderTask
yyyy-MM-dd HH:mm:ss,fff INFO - [Patch Content Downloader :: Notification Consumption] request GUID: i5OTh4eCiYiSkZCLlJ2YkA Content ID: Adaptiva$MD$1002001423 has completed its download. - PatchContentDownloader - TID=162, AdaptivaTimer - ContentSystemNotificationTimer. ExecutingTask-ContentSystemNotificationSender$ContentNotificationSenderTask
Installation Starts and checks whether it requires reboot:
**Utils.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Check reboot flags: - RebootUtils - TID=73, AdaptivaTimer - SoftwareDeploymentManager. ExecutingTask-SoftwareDeploymentManager$InstallationTimerTask
**Reboot.log**
yyyy-MM-dd HH:mm:ss,fff INFO - System reboot is not required at this time. - RebootManager - TID=73, AdaptivaTimer - SoftwareDeploymentManager. ExecutingTask-SoftwareDeploymentManager$InstallationTimerTask
Pre-Installation Action Sequence executes:
**SoftwareInstaller.log**
yyyy-MM-dd HH:mm:ss,fff INFO - install pre action sequence executing. Patch ID: **PATCH_ID** request GUID: i5OTh4eCiYiSkZCLlJ2YkA - SoftwareInstaller$SoftwareInstallerRunner - TID=338, Thread-251
yyyy-MM-dd HH:mm:ss,fff INFO - install action sequence executing - SoftwareInstaller$SoftwareInstallerRunner - TID=338, Thread-251
SoftwareDeploymentManager reports installation status:
**SoftwareDeploymentManager.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Progress status for patch [**PATCH_ID**] is [**STATUS_CODE**]. - SoftwareDeploymentManager - TID=339, Thread-252
Status Definitions
*Installer Progress Busy: 41* |
*Install Started: 42* |
*External Pre Install Actions: 43* |
*Install Actions: 45* |
*Post Install Actions: 46* |
*External Post Install Actions: 47* |
*Installation Timed Out: 48* |
*Installation Succeeded: 49* |
*Installation Succeeded Pending Reboot: 50* |
*Installation Failed: 51* |
*Uninstall Started: 61* |
*External Pre Uninstall Actions: 62* |
*Pre Uninstall Actions: 63* |
*Uninstall Actions: 64* |
*Post Uninstall Actions: 65* |
*External Post Uninstall Actions: 66* |
*Uninstallation Timed out: 67* |
*Uninstallation Succeeded: 68* |
*Uninstall Succeeded Pending Reboot: 69* |
*Uninstallation Failed: 70* |
Performs action execution to install software:
**ActionExec.log**
yyyy-MM-dd HH:mm:ss,fff INFO - Completed execute windows file system action actionObjectId[**ACTION_ID**] operation[1] check status row for result. return code:200006 - WindowsFileSystemActionRuntime - TID=338, Thread-251
**SoftwareInstaller.log**
yyyy-MM-dd HH:mm:ss,fff INFO - SoftwareInstallerThread exiting. Patch ID: **PATCH_ID** request GUID: i5OTh4eCiYiSkZCLlJ2YkA - SoftwareInstaller$SoftwareInstallerRunner - TID=338, Thread-251
On Success:
yyyy-MM-dd HH:mm:ss,fff INFO - Patch install finished, sending final notification - SoftwareInstaller$SoftwareInstallerRunner - TID=338, Thread-251
On Failure:
yyyy-MM-dd HH:mm:ss,fff ERROR - Install failed during execution with error code: 5. Patch ID: **PATCH_ID** request GUID: i5OTh4eCiYiSkZCLlJ2YkA - SoftwareInstaller$SoftwareInstallerRunner - TID=338, Thread-251
Comments
0 comments
Please sign in to leave a comment.