Attachment 933980 Details for Bug 1136751 – drift_detection_interval.log

drift_detection_interval.log

drift_interval_oneDriftOnly.log (text/plain), 28.06 KB, created by Armine Hovsepyan on 2014-09-03 08:45:40 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Armine Hovsepyan

Created: 2014-09-03 08:45:40 UTC

Size: 28.06 KB

patch

obsolete

>2014-09-02 13:37:22,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-09-02 13:37:22,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]]
>2014-09-02 13:37:22,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:37:22,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:37:22,466 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] is set for 01:37:42:466 PM
>2014-09-02 13:37:22,467 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 3 ms
>2014-09-02 13:37:23,416 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[ping], targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService}]]
>2014-09-02 13:37:23,416 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1409679443416]; exception=[>> exception was null <<]]
>2014-09-02 13:37:23,419 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getCurrentAvailability], targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService}]]
>2014-09-02 13:37:23,420 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:37:23 EDT 2014
>2014-09-02 13:37:23,423 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:37:23 EDT 2014 : Scan [startTime=1409679443420, endTime=1409679443423, runtime=3, isFull=true, isForced=false, numResources=1, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-09-02 13:37:23,423 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [1] resources with a size of [572] bytes in [3]ms
>2014-09-02 13:37:23,423 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][1][full]]; exception=[>> exception was null <<]]
>
>2014-09-02 13:37:31,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-09-02 13:37:31,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]]
>2014-09-02 13:37:31,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:37:31,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Skipping DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] because it is too early to do the next detection.
>2014-09-02 13:37:31,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] is set for 01:37:42:466 PM
>2014-09-02 13:37:31,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 0 ms
>2014-09-02 13:37:34,700 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[ping], targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService}]]
>2014-09-02 13:37:34,700 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1409679454700]; exception=[>> exception was null <<]]
>2014-09-02 13:37:34,703 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getCurrentAvailability], targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService}]]
>2014-09-02 13:37:34,704 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:37:34 EDT 2014
>2014-09-02 13:37:35,421 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:37:35 EDT 2014 : Scan [startTime=1409679454704, endTime=1409679455421, runtime=717, isFull=true, isForced=false, numResources=647, numGetAvailabilityCalls=16, numScheduledRandomly=0, numPushedByInterval=16, numAvailabilityChanges=3, numDeferToParent=14]
>2014-09-02 13:37:35,421 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [647] resources with a size of [15460] bytes in [717]ms
>2014-09-02 13:37:35,421 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][647][full]]; exception=[>> exception was null <<]]
>2014-09-02 13:37:35,977 DEBUG [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement report contains no data - not sending to Server.
>2014-09-02 13:37:38,406 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[ping], targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService}]]
>2014-09-02 13:37:38,406 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1409679458406]; exception=[>> exception was null <<]]
>2014-09-02 13:37:38,410 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getCurrentAvailability], targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService}]]
>2014-09-02 13:37:38,411 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:37:38 EDT 2014
>2014-09-02 13:37:38,414 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:37:38 EDT 2014 : Scan [startTime=1409679458411, endTime=1409679458414, runtime=3, isFull=true, isForced=false, numResources=1, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-09-02 13:37:38,414 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [1] resources with a size of [572] bytes in [3]ms
>2014-09-02 13:37:38,414 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][1][full]]; exception=[>> exception was null <<]]
>
>2014-09-02 13:37:40,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-09-02 13:37:40,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]]
>2014-09-02 13:37:40,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:37:40,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Skipping DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] because it is too early to do the next detection.
>2014-09-02 13:37:40,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] is set for 01:37:42:466 PM
>2014-09-02 13:37:40,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 0 ms
>2014-09-02 13:37:41,414 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:37:41 EDT 2014
>2014-09-02 13:37:46,514 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:37:46 EDT 2014 : Scan [startTime=1409679461414, endTime=1409679466514, runtime=5100, isFull=false, isForced=false, numResources=676, numGetAvailabilityCalls=125, numScheduledRandomly=112, numPushedByInterval=13, numAvailabilityChanges=16, numDeferToParent=14]
>2014-09-02 13:37:46,515 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [16] resources with a size of [917] bytes in [5100]ms
>2014-09-02 13:37:46,515 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2014-09-02 13:37:46,515 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Availability report content: AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][16][changesOnly]
>
>2014-09-02 13:37:49,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-09-02 13:37:49,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]]
>2014-09-02 13:37:49,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:37:49,464 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:37:49,467 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] is set for 01:38:09:466 PM
>2014-09-02 13:37:49,467 DEBUG [pool-13-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 3 ms
>2014-09-02 13:37:49,705 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[ping], targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService}]]
>2014-09-02 13:37:49,706 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1409679469706]; exception=[>> exception was null <<]]
>2014-09-02 13:37:49,709 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getCurrentAvailability], targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService}]]
>2014-09-02 13:37:49,709 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:37:49 EDT 2014
>2014-09-02 13:37:49,910 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:37:49 EDT 2014 : Scan [startTime=1409679469709, endTime=1409679469910, runtime=201, isFull=true, isForced=false, numResources=647, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=14]
>2014-09-02 13:37:49,911 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [647] resources with a size of [15460] bytes in [201]ms
>2014-09-02 13:37:49,911 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][647][full]]; exception=[>> exception was null <<]]
>2014-09-02 13:37:53,451 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[ping], targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService}]]
>2014-09-02 13:37:53,451 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1409679473451]; exception=[>> exception was null <<]]
>2014-09-02 13:37:53,454 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getCurrentAvailability], targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService}]]
>2014-09-02 13:37:53,455 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:37:53 EDT 2014
>2014-09-02 13:37:53,458 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:37:53 EDT 2014 : Scan [startTime=1409679473455, endTime=1409679473458, runtime=3, isFull=true, isForced=false, numResources=1, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-09-02 13:37:53,458 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [1] resources with a size of [572] bytes in [3]ms
>2014-09-02 13:37:53,458 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][1][full]]; exception=[>> exception was null <<]]
>
>2014-09-02 13:37:58,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-09-02 13:37:58,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]]
>2014-09-02 13:37:58,478 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:37:58,478 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Skipping DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] because it is too early to do the next detection.
>2014-09-02 13:37:58,478 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] is set for 01:38:09:466 PM
>2014-09-02 13:37:58,478 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 14 ms
>2014-09-02 13:38:04,700 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[ping], targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService}]]
>2014-09-02 13:38:04,700 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1409679484700]; exception=[>> exception was null <<]]
>2014-09-02 13:38:04,703 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getCurrentAvailability], targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService}]]
>2014-09-02 13:38:04,704 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:38:04 EDT 2014
>2014-09-02 13:38:05,276 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:38:05 EDT 2014 : Scan [startTime=1409679484704, endTime=1409679485276, runtime=572, isFull=true, isForced=false, numResources=647, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=13, numAvailabilityChanges=0, numDeferToParent=14]
>2014-09-02 13:38:05,277 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [647] resources with a size of [15460] bytes in [572]ms
>2014-09-02 13:38:05,277 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][647][full]]; exception=[>> exception was null <<]]
>2014-09-02 13:38:05,978 DEBUG [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [3] metrics took 1ms - sending report to Server...
>
>2014-09-02 13:38:07,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-09-02 13:38:07,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]]
>2014-09-02 13:38:07,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval]
>2014-09-02 13:38:07,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Skipping DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] because it is too early to do the next detection.
>2014-09-02 13:38:07,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 12340, driftDefinitionId: 0, driftDefinitionName: checkInterval] is set for 01:38:09:466 PM
>2014-09-02 13:38:07,464 DEBUG [pool-13-thread-4] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 0 ms
>2014-09-02 13:38:08,425 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[ping], targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService}]]
>2014-09-02 13:38:08,425 DEBUG [WorkerThread#6[10.16.23.132:56814]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1409679488425]; exception=[>> exception was null <<]]
>2014-09-02 13:38:08,428 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=ZIxD+QCZ+AEEt7T19i4j/jm8XSqJRIcKK0Mi4R+GkvY5VmU5OIsP/1AKEQls4rG4A8I=, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getCurrentAvailability], targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService}]]
>2014-09-02 13:38:08,428 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Tue Sep 02 13:38:08 EDT 2014
>2014-09-02 13:38:08,432 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Tue Sep 02 13:38:08 EDT 2014 : Scan [startTime=1409679488428, endTime=1409679488432, runtime=4, isFull=true, isForced=false, numResources=1, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-09-02 13:38:08,432 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [1] resources with a size of [572] bytes in [4]ms
>2014-09-02 13:38:08,432 DEBUG [WorkerThread#5[10.16.23.132:56797]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com][1][full]]; exception=[>> exception was null <<]]
>2014-09-02 13:38:10,444 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Starting configuration update check on [2] eligible roots...
>2014-09-02 13:38:10,444 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check continuing for root resource [EAP Domain Controller (master 0.0.0.0:8990)]
>2014-09-02 13:38:10,444 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check  completed for root resource [EAP Domain Controller (master 0.0.0.0:8990)] CountTime [checked resource count=0, time=0]
>2014-09-02 13:38:10,444 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check  beginning for root resource [RHQ Agent]
>2014-09-02 13:38:10,445 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Checking for updated Resource configuration for Resource[id=13084, uuid=862bacc4-4ef5-4c7c-bfe4-d453d1aa7ec7, type={RHQAgent}RHQ Agent, key=RHQ Agent, name=RHQ Agent, parent=jon33eapdom.bc.jonqe.lab.eng.bos.redhat.com, version=4.12.0.JON330ER01]...
>2014-09-02 13:38:10,451 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Checking for updated Resource configuration for Resource[id=13079, uuid=0744c503-9370-4796-ab64-45972ec10d46, type={RHQAgent}Environment Setup Script, key=environment-setup-script, name=rhq-agent-env.sh, parent=RHQ Agent, version=4.12.0.JON330ER01]...
>2014-09-02 13:38:10,452 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Checking for updated Resource configuration for Resource[id=13059, uuid=ca980e8c-d6e7-4b04-af31-ce4773ce708e, type={RHQAgent}java.util.logging, key=java.util.logging:type=Logging, name=Logging, parent=JVM]...
>2014-09-02 13:38:10,452 INFO  [ResourceContainer.invoker.daemon-3] (org.rhq.plugins.jmx.MBeanResourceComponent)- Configuration management is currently disabled for the ResourceType[id=0, name=java.util.logging, plugin=RHQAgent, category=Service] Resource with key [java.util.logging:type=Logging] - it can be enabled in the Resource's Inventory > Connection tab.
>2014-09-02 13:38:10,453 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Checking for updated Resource configuration for Resource[id=13133, uuid=fb9bbb8e-79a8-4048-94b6-3633e86759e8, type={RHQAgent}Threading, key=java.lang:type=Threading, name=Threading, parent=JVM]...
>2014-09-02 13:38:10,454 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Checking for updated Resource configuration for Resource[id=13132, uuid=059d35dd-d3c3-49bb-a2ea-fa1d5fb59536, type={RHQAgent}VM Memory System, key=java.lang:type=Memory, name=Memory Subsystem, parent=JVM]...
>2014-09-02 13:38:10,455 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Checking for updated Resource configuration for Resource[id=13134, uuid=178a8b62-0f9c-4a7b-9bba-e1944c4e643f, type={RHQAgent}VM Class Loading System, key=java.lang:type=ClassLoading, name=Class Loading, parent=JVM]...
>2014-09-02 13:38:10,455 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check  completed for root resource [RHQ Agent] CountTime [checked resource count=6, time=8]
>2014-09-02 13:38:10,455 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check complete. Checked [2] of [2] eligible roots in [11ms (0s)] wall time. CountTime [checked resource count=6, time=8]
>

Actions: View

Attachments on bug 1136751: 933978 | 933980 | 945131 | 945133 | 949327