Attachment 945133 Details for Bug 1136751 – drift_detection.log

drift_detection.log

dirft_detection.log (text/plain), 69.00 KB, created by Armine Hovsepyan on 2014-10-08 18:55:21 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Armine Hovsepyan

Created: 2014-10-08 18:55:21 UTC

Size: 69.00 KB

patch

obsolete

>2014-10-08 08:36:41,609 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:36:41,609 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]]
>2014-10-08 08:36:41,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:36:41,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:36:41,614 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec] is set for 08:37:01:614 AM
>2014-10-08 08:36:41,614 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 5 ms
>2014-10-08 08:36:41,764 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:36:41 EDT 2014
>2014-10-08 08:36:44,039 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:36:44 EDT 2014 : Scan [startTime=1412771801764, endTime=1412771804038, runtime=2274, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=58, numScheduledRandomly=0, numPushedByInterval=57, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:36:44,039 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [2275]ms
>2014-10-08 08:36:49,126 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:36:49,126 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771809126]; exception=[>> exception was null <<]]
>2014-10-08 08:36:49,132 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:36:49,133 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:36:49 EDT 2014
>2014-10-08 08:36:49,182 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:36:49 EDT 2014 : Scan [startTime=1412771809133, endTime=1412771809182, runtime=49, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:36:49,183 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [49]ms
>2014-10-08 08:36:49,183 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:37:00,419 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [54] metrics took 1251ms - sending report to Server...
>2014-10-08 08:37:01,609 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:37:01,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]]
>2014-10-08 08:37:01,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]
>2014-10-08 08:37:01,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]
>2014-10-08 08:37:01,614 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec] is set for 08:37:11:614 AM
>2014-10-08 08:37:01,615 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 6 ms
>2014-10-08 08:37:04,108 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:37:04,108 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771824108]; exception=[>> exception was null <<]]
>2014-10-08 08:37:04,112 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:37:04,112 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:37:04 EDT 2014
>2014-10-08 08:37:04,204 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:37:04 EDT 2014 : Scan [startTime=1412771824112, endTime=1412771824203, runtime=91, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:37:04,215 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [102]ms
>2014-10-08 08:37:04,215 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:37:14,039 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:37:14 EDT 2014
>2014-10-08 08:37:14,053 DEBUG [ResourceContainer.invoker.availCheck.daemon-71] (org.rhq.plugins.cassandra.CassandraNodeComponent)- Finished availability check in 12 ms
>2014-10-08 08:37:14,062 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:37:14 EDT 2014 : Scan [startTime=1412771834039, endTime=1412771834062, runtime=23, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:37:14,062 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [23]ms
>2014-10-08 08:37:19,097 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:37:19,097 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771839097]; exception=[>> exception was null <<]]
>2014-10-08 08:37:19,103 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:37:19,104 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:37:19 EDT 2014
>2014-10-08 08:37:19,153 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:37:19 EDT 2014 : Scan [startTime=1412771839104, endTime=1412771839153, runtime=49, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:37:19,154 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [49]ms
>2014-10-08 08:37:19,154 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:37:21,609 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:37:21,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]]
>2014-10-08 08:37:21,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]
>2014-10-08 08:37:21,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]
>2014-10-08 08:37:21,615 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec] is set for 08:38:01:615 AM
>2014-10-08 08:37:21,615 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 6 ms
>2014-10-08 08:37:30,420 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [42] metrics took 911ms - sending report to Server...
>2014-10-08 08:37:30,428 DEBUG [ResourceContainer.invoker.daemon-10] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.DataDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:37:30,428 DEBUG [ResourceContainer.invoker.daemon-10] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.FreeDiskToDataSizeRatio] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:37:30,428 DEBUG [ResourceContainer.invoker.daemon-10] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.TotalDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:37:34,105 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:37:34,106 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771854105]; exception=[>> exception was null <<]]
>2014-10-08 08:37:34,132 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:37:34,133 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:37:34 EDT 2014
>2014-10-08 08:37:35,920 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:37:35 EDT 2014 : Scan [startTime=1412771854133, endTime=1412771855920, runtime=1787, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=37, numScheduledRandomly=0, numPushedByInterval=37, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:37:35,921 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [1787]ms
>2014-10-08 08:37:35,921 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:37:41,609 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:37:41,609 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]]
>2014-10-08 08:37:41,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]
>2014-10-08 08:37:41,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]
>2014-10-08 08:37:41,614 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec] is set for 08:38:11:614 AM
>2014-10-08 08:37:41,614 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 5 ms
>2014-10-08 08:37:44,063 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:37:44 EDT 2014
>2014-10-08 08:37:44,078 DEBUG [ResourceContainer.invoker.availCheck.daemon-17] (org.rhq.plugins.cassandra.CassandraNodeComponent)- Finished availability check in 12 ms
>2014-10-08 08:37:45,048 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:37:45 EDT 2014 : Scan [startTime=1412771864062, endTime=1412771865048, runtime=986, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=29, numScheduledRandomly=0, numPushedByInterval=28, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:37:45,049 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [987]ms
>2014-10-08 08:37:49,097 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:37:49,098 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771869098]; exception=[>> exception was null <<]]
>2014-10-08 08:37:49,103 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:37:49,104 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:37:49 EDT 2014
>2014-10-08 08:37:49,112 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:37:49 EDT 2014 : Scan [startTime=1412771869104, endTime=1412771869112, runtime=8, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:37:49,113 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [8]ms
>2014-10-08 08:37:49,113 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:38:00,419 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [68] metrics took 1436ms - sending report to Server...
>2014-10-08 08:38:00,583 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Skipping configuration update check, no eligible roots.
>2014-10-08 08:38:01,609 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:38:01,609 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]]
>2014-10-08 08:38:01,610 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:38:01,610 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:38:01,615 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec] is set for 08:38:21:614 AM
>2014-10-08 08:38:01,615 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 6 ms
>2014-10-08 08:38:04,108 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:38:04,108 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771884108]; exception=[>> exception was null <<]]
>2014-10-08 08:38:04,114 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:38:04,122 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:38:04 EDT 2014
>2014-10-08 08:38:04,213 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:38:04 EDT 2014 : Scan [startTime=1412771884122, endTime=1412771884213, runtime=91, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:38:04,214 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [91]ms
>2014-10-08 08:38:04,214 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:38:15,049 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:38:15 EDT 2014
>2014-10-08 08:38:15,072 DEBUG [ResourceContainer.invoker.availCheck.daemon-47] (org.rhq.plugins.cassandra.CassandraNodeComponent)- Finished availability check in 22 ms
>2014-10-08 08:38:15,087 DEBUG [ResourceContainer.invoker.availCheck.daemon-52] (org.rhq.plugins.cassandra.CassandraNodeComponent)- Finished availability check in 9 ms
>2014-10-08 08:38:18,664 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:38:18 EDT 2014 : Scan [startTime=1412771895049, endTime=1412771898664, runtime=3615, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=87, numScheduledRandomly=0, numPushedByInterval=86, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:38:18,664 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [3615]ms
>2014-10-08 08:38:19,095 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:38:19,095 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771899095]; exception=[>> exception was null <<]]
>2014-10-08 08:38:19,101 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:38:19,102 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:38:19 EDT 2014
>2014-10-08 08:38:19,108 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:38:19 EDT 2014 : Scan [startTime=1412771899102, endTime=1412771899108, runtime=6, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:38:19,109 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [6]ms
>2014-10-08 08:38:19,109 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:38:21,609 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:38:21,609 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]]
>2014-10-08 08:38:21,610 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]
>2014-10-08 08:38:21,610 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]
>2014-10-08 08:38:21,614 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec] is set for 08:38:31:614 AM
>2014-10-08 08:38:21,614 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 5 ms
>2014-10-08 08:38:30,420 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [51] metrics took 1144ms - sending report to Server...
>2014-10-08 08:38:30,432 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.DataDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:38:30,433 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.FreeDiskToDataSizeRatio] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:38:30,433 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.TotalDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:38:34,112 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:38:34,113 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771914113]; exception=[>> exception was null <<]]
>2014-10-08 08:38:34,119 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:38:34,120 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:38:34 EDT 2014
>2014-10-08 08:38:34,170 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:38:34 EDT 2014 : Scan [startTime=1412771914120, endTime=1412771914170, runtime=50, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:38:34,171 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [50]ms
>2014-10-08 08:38:34,171 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:38:41,609 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:38:41,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]]
>2014-10-08 08:38:41,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]
>2014-10-08 08:38:41,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]
>2014-10-08 08:38:41,614 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec] is set for 08:39:21:614 AM
>2014-10-08 08:38:41,614 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 5 ms
>2014-10-08 08:38:48,664 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:38:48 EDT 2014
>2014-10-08 08:38:49,135 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:38:49,136 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771929135]; exception=[>> exception was null <<]]
>2014-10-08 08:38:49,141 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:38:52,359 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:38:52 EDT 2014 : Scan [startTime=1412771928664, endTime=1412771932359, runtime=3695, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=85, numScheduledRandomly=0, numPushedByInterval=84, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:38:52,359 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [3695]ms
>2014-10-08 08:38:52,359 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:38:52 EDT 2014
>2014-10-08 08:38:52,404 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:38:52 EDT 2014 : Scan [startTime=1412771932359, endTime=1412771932404, runtime=45, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:38:52,405 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [45]ms
>2014-10-08 08:38:52,405 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:39:00,419 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [66] metrics took 2065ms - sending report to Server...
>2014-10-08 08:39:01,609 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:39:01,609 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]]
>2014-10-08 08:39:01,610 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]
>2014-10-08 08:39:01,610 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]
>2014-10-08 08:39:01,614 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec] is set for 08:39:31:614 AM
>2014-10-08 08:39:01,614 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 5 ms
>2014-10-08 08:39:04,107 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:39:04,107 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771944107]; exception=[>> exception was null <<]]
>2014-10-08 08:39:04,113 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:39:04,114 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:39:04 EDT 2014
>2014-10-08 08:39:04,163 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:39:04 EDT 2014 : Scan [startTime=1412771944114, endTime=1412771944163, runtime=49, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:39:04,164 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [49]ms
>2014-10-08 08:39:04,164 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:39:07,941 DEBUG [RHQ Idle Output Stream Timer Thread] (command.impl.stream.server.RemoteOutputStreamCommandService)- {RemoteOutputStreamCommandService.removed-stream}An output stream with ID=[1] has been removed and is no longer accessible by remote clients
>2014-10-08 08:39:07,942 DEBUG [RHQ Idle Output Stream Timer Thread] (command.impl.stream.server.RemoteOutputStreamCommandService)- {RemoteOutputStreamCommandService.removed-idle-stream}Output stream with ID=[1] was removed because it was idle for longer than its allowed max of [300,000] milliseconds
>2014-10-08 08:39:19,106 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:39:19,106 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771959106]; exception=[>> exception was null <<]]
>2014-10-08 08:39:19,112 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:39:19,132 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:39:19 EDT 2014
>2014-10-08 08:39:20,897 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:39:20 EDT 2014 : Scan [startTime=1412771959132, endTime=1412771960897, runtime=1765, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=35, numScheduledRandomly=0, numPushedByInterval=35, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:39:20,898 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [1765]ms
>2014-10-08 08:39:20,898 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:39:21,609 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:39:21,609 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]]
>2014-10-08 08:39:21,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:39:21,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:39:21,614 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec] is set for 08:39:41:614 AM
>2014-10-08 08:39:21,614 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 5 ms
>2014-10-08 08:39:22,359 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:39:22 EDT 2014
>2014-10-08 08:39:22,378 DEBUG [ResourceContainer.invoker.availCheck.daemon-59] (org.rhq.plugins.cassandra.CassandraNodeComponent)- Finished availability check in 17 ms
>2014-10-08 08:39:22,396 DEBUG [ResourceContainer.invoker.availCheck.daemon-64] (org.rhq.plugins.cassandra.CassandraNodeComponent)- Finished availability check in 8 ms
>2014-10-08 08:39:23,894 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:39:23 EDT 2014 : Scan [startTime=1412771962359, endTime=1412771963894, runtime=1535, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=44, numScheduledRandomly=0, numPushedByInterval=43, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:39:23,895 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [1535]ms
>2014-10-08 08:39:30,420 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [62] metrics took 1460ms - sending report to Server...
>2014-10-08 08:39:30,433 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.DataDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:39:30,433 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.FreeDiskToDataSizeRatio] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:39:30,433 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.TotalDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:39:34,113 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:39:34,113 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771974113]; exception=[>> exception was null <<]]
>2014-10-08 08:39:34,118 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:39:34,120 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:39:34 EDT 2014
>2014-10-08 08:39:34,127 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:39:34 EDT 2014 : Scan [startTime=1412771974120, endTime=1412771974127, runtime=7, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:39:34,128 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [7]ms
>2014-10-08 08:39:34,128 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:39:41,609 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:39:41,609 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]]
>2014-10-08 08:39:41,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]
>2014-10-08 08:39:41,610 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]
>2014-10-08 08:39:41,614 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec] is set for 08:39:51:614 AM
>2014-10-08 08:39:41,614 DEBUG [pool-9-thread-3] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 5 ms
>2014-10-08 08:39:49,103 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:39:49,103 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412771989103]; exception=[>> exception was null <<]]
>2014-10-08 08:39:49,108 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:39:49,110 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:39:49 EDT 2014
>2014-10-08 08:39:51,092 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:39:51 EDT 2014 : Scan [startTime=1412771989110, endTime=1412771991092, runtime=1982, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=41, numScheduledRandomly=0, numPushedByInterval=41, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:39:51,093 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [1982]ms
>2014-10-08 08:39:51,093 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:39:53,895 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:39:53 EDT 2014
>2014-10-08 08:39:55,372 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:39:55 EDT 2014 : Scan [startTime=1412771993895, endTime=1412771995372, runtime=1477, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=43, numScheduledRandomly=0, numPushedByInterval=42, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:39:55,373 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [1478]ms
>2014-10-08 08:40:00,419 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [59] metrics took 1128ms - sending report to Server...
>2014-10-08 08:40:00,583 DEBUG [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Skipping configuration update check, no eligible roots.
>2014-10-08 08:40:01,609 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:40:01,610 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec]]
>2014-10-08 08:40:01,610 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]
>2014-10-08 08:40:01,611 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]
>2014-10-08 08:40:01,615 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec] is set for 08:40:41:615 AM
>2014-10-08 08:40:01,615 DEBUG [pool-9-thread-4] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 6 ms
>2014-10-08 08:40:04,100 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:40:04,101 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412772004101]; exception=[>> exception was null <<]]
>2014-10-08 08:40:04,107 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:40:04,108 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:40:04 EDT 2014
>2014-10-08 08:40:04,157 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:40:04 EDT 2014 : Scan [startTime=1412772004108, endTime=1412772004157, runtime=49, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:40:04,158 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [49]ms
>2014-10-08 08:40:04,159 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:40:19,119 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:40:19,120 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412772019120]; exception=[>> exception was null <<]]
>2014-10-08 08:40:19,127 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:40:19,128 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:40:19 EDT 2014
>2014-10-08 08:40:20,917 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:40:20 EDT 2014 : Scan [startTime=1412772019128, endTime=1412772020917, runtime=1789, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=36, numScheduledRandomly=0, numPushedByInterval=36, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:40:20,918 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [1789]ms
>2014-10-08 08:40:20,918 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:40:21,609 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:40:21,609 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec]]
>2014-10-08 08:40:21,610 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]
>2014-10-08 08:40:21,611 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]
>2014-10-08 08:40:21,615 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec] is set for 08:40:51:615 AM
>2014-10-08 08:40:21,615 DEBUG [pool-9-thread-1] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 6 ms
>2014-10-08 08:40:25,374 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:40:25 EDT 2014
>2014-10-08 08:40:25,387 DEBUG [ResourceContainer.invoker.availCheck.daemon-26] (org.rhq.plugins.cassandra.CassandraNodeComponent)- Finished availability check in 12 ms
>2014-10-08 08:40:26,442 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:40:26 EDT 2014 : Scan [startTime=1412772025373, endTime=1412772026442, runtime=1069, isFull=false, isForced=false, numResources=1313, numGetAvailabilityCalls=32, numScheduledRandomly=0, numPushedByInterval=31, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:40:26,442 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [0] resources with a size of [290] bytes in [1069]ms
>2014-10-08 08:40:30,419 DEBUG [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [58] metrics took 1493ms - sending report to Server...
>2014-10-08 08:40:30,427 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.DataDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:40:30,427 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.FreeDiskToDataSizeRatio] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:40:30,427 DEBUG [ResourceContainer.invoker.daemon-9] (org.rhq.plugins.jmx.MBeanResourceComponent)- Unable to collect measurement, attribute [Calculated.TotalDiskUsedPercentage] not found on [org.apache.cassandra.db:type=StorageService]
>2014-10-08 08:40:34,122 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:40:34,123 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412772034123]; exception=[>> exception was null <<]]
>2014-10-08 08:40:34,167 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:40:34,168 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:40:34 EDT 2014
>2014-10-08 08:40:35,607 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:40:35 EDT 2014 : Scan [startTime=1412772034168, endTime=1412772035606, runtime=1438, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=31, numScheduledRandomly=0, numPushedByInterval=31, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:40:35,607 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [1439]ms
>2014-10-08 08:40:35,608 DEBUG [WorkerThread#4[10.16.23.157:57000]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[AV:[jon33er4.bc.jonqe.lab.eng.bos.redhat.com][612][full]]; exception=[>> exception was null <<]]
>2014-10-08 08:40:41,609 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Starting drift detection...
>2014-10-08 08:40:41,610 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Fetching next schedule from ScheduleQueue[DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10011, driftDefinitionName: 10sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10021, driftDefinitionName: 40sec], DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 10031, driftDefinitionName: 30sec]]
>2014-10-08 08:40:41,610 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Processing DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:40:41,610 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Generating drift change set for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec]
>2014-10-08 08:40:41,615 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.ScheduleQueueImpl)- The next drift detection run for DriftDetectionSchedule[resourceId: 10955, driftDefinitionId: 0, driftDefinitionName: 15sec] is set for 08:41:01:615 AM
>2014-10-08 08:40:41,615 DEBUG [pool-9-thread-5] (rhq.core.pc.drift.DriftDetector)- Finished drift detection in 6 ms
>2014-10-08 08:40:49,156 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.timeout=5000, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.ping.PingAgentService, invocation=NameBasedInvocation[ping]}]]
>2014-10-08 08:40:49,157 DEBUG [WorkerThread#2[10.16.23.157:56949]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[1412772049157]; exception=[>> exception was null <<]]
>2014-10-08 08:40:49,163 DEBUG [WorkerThread#3[10.16.23.157:56952]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.security-token=WhdNiomv7cGXPm8xMWjv+fx3o1FhYpZxQ+zcA9JJPt9aZQsZfKN9Wp2Ua/50MEHXoHw=, rhq.send-throttle=true}]; params=[{targetInterfaceName=org.rhq.core.clientapi.agent.discovery.DiscoveryAgentService, invocation=NameBasedInvocation[getCurrentAvailability]}]]
>2014-10-08 08:40:49,164 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Wed Oct 08 08:40:49 EDT 2014
>2014-10-08 08:40:49,301 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Wed Oct 08 08:40:49 EDT 2014 : Scan [startTime=1412772049164, endTime=1412772049301, runtime=137, isFull=true, isForced=false, numResources=612, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2014-10-08 08:40:49,302 DEBUG [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Built availability report for [612] resources with a size of [14641] bytes in [137]ms
>

Actions: View

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