Attachment 581985 Details for Bug 818773 – agent log

agent log

agent.log (text/x-log), 218.23 KB, created by Mike Foley on 2012-05-03 23:16:58 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Mike Foley

Created: 2012-05-03 23:16:58 UTC

Size: 218.23 KB

patch

obsolete

>2012-05-03 17:01:00,644 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.clean-data-dir}Agent has been asked to start up clean - cleaning out the data directory: /home/mfoley/Downloads/rhq-agent/data
>2012-05-03 17:01:00,761 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.identify-version}Version=[RHQ 4.4.0.JON310GA], Build Number=[4ccefd7], Build Date=[May 2, 2012 9:58 AM]
>2012-05-03 17:01:05,677 INFO  [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.global-concurrency-limit-disabled}Global concurrency limit has been disabled - there is no limit to the number of incoming commands allowed
>2012-05-03 17:01:05,796 INFO  [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.started}Service container started - ready to accept incoming commands
>2012-05-03 17:01:05,796 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.no-auto-detect}Server auto-detection is not enabled - starting the poller immediately
>2012-05-03 17:01:05,912 ERROR [RHQ Server Polling Thread] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.init-callback-failed}The initialize callback has failed. It will be tried again. Cause: org.rhq.enterprise.communications.command.server.AuthenticationException:Command failed to be authenticated!  This command will be ignored and not processed: Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.agent-name=foleymonsterbox1.foleyhomenetwork, rhq.security-token=TPRqdf2r228jvvUCw0CZwJrt6fmg0KRec4tUNvGdLEk1YXb8Hlgxcz1RILCyxvqI0as=, rhq.externalizable-strategy=AGENT}]; params=[{invocation=NameBasedInvocation[connectAgent], targetInterfaceName=org.rhq.core.clientapi.server.core.CoreServerService}]. Cause: org.rhq.enterprise.communications.command.server.AuthenticationException: Command failed to be authenticated!  This command will be ignored and not processed: Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.agent-name=foleymonsterbox1.foleyhomenetwork, rhq.security-token=TPRqdf2r228jvvUCw0CZwJrt6fmg0KRec4tUNvGdLEk1YXb8Hlgxcz1RILCyxvqI0as=, rhq.externalizable-strategy=AGENT}]; params=[{invocation=NameBasedInvocation[connectAgent], targetInterfaceName=org.rhq.core.clientapi.server.core.CoreServerService}]
>2012-05-03 17:01:07,128 INFO  [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.agent-registration-results}Agent has successfully registered with the server. The results are: [AgentRegistrationResults: [agent-token=6+sdrWraKkqJ3aHxawCa24/7E+tymuDUmmj0Xn7e4A3/DLZ8P7mNJPMeMCzBxnJnd54=]]
>2012-05-03 17:01:07,138 INFO  [RHQ Agent Registration Thread] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.changing-endpoint}Communicator is changing endpoint from [InvokerLocator [servlet://192.168.0.111:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]] to [InvokerLocator [servlet://foleymonsterbox1.foleyhomenetwork:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]]
>2012-05-03 17:01:07,310 INFO  [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.failed-over-to-server}The agent has triggered its failover mechanism and switched to server [servlet://foleymonsterbox1.foleyhomenetwork:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]
>2012-05-03 17:01:07,313 INFO  [ClientCommandSenderTask Timer Thread #0] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.not-sending-dup-connect}Not sending another connect message since one was recently sent: [servlet://foleymonsterbox1.foleyhomenetwork:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet@Thu May 03 17:01:07 EDT 2012]
>2012-05-03 17:01:07,333 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-script-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:07,396 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [Script] has been updated at [rhq-script-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:07,396 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-platform-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:07,460 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [Platforms] has been updated at [rhq-platform-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:07,460 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-jmx-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:07,670 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [JMX] has been updated at [rhq-jmx-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:07,670 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-agent-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:07,710 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [RHQAgent] has been updated at [rhq-agent-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:07,711 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-iis-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:07,743 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [IIS] has been updated at [rhq-iis-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:07,743 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-database-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:07,776 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [Database] has been updated at [rhq-database-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:07,776 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-postgres-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:07,926 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [Postgres] has been updated at [rhq-postgres-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:07,927 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-augeas-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:08,162 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [Augeas] has been updated at [rhq-augeas-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:08,163 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-apache-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:08,301 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [Apache] has been updated at [rhq-apache-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:08,301 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [rhq-ant-bundle-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 17:01:08,838 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [AntBundlePlugin] has been updated at [rhq-ant-bundle-plugin-4.4.0.JON310GA.jar].
>2012-05-03 17:01:08,838 INFO  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.updating-complete}Completed updating the plugins to their latest versions.
>2012-05-03 17:01:08,855 INFO  [RHQ Server Polling Thread] (enterprise.communications.command.client.ServerPollingThread)- {ServerPollingThread.server-online}The server has come back online; client has been told to start sending commands again
>2012-05-03 17:01:10,891 INFO  [main] (org.rhq.core.pc.PluginContainer)- Initializing Plugin Container v4.4.0.JON310GA...
>2012-05-03 17:01:12,713 INFO  [main] (rhq.core.pc.plugin.PluginManager)- Deployed plugins: [AgentPlugin [id=0, name=Script, md5=ec96dbd931420b0ebc848aa0214e89dd], AgentPlugin [id=0, name=Platforms, md5=3907e2ed1fe7f543436e9bfb9706030a], AgentPlugin [id=0, name=JMX, md5=88cec6c262fc5e3e7c86860922d7e421], AgentPlugin [id=0, name=RHQAgent, md5=d277e15657497e6f32df76b2354dcd7b], AgentPlugin [id=0, name=IIS, md5=da47f7f2f53bd4ec5f7cd51d57213862], AgentPlugin [id=0, name=Database, md5=e5f156b654e877df10a07e54ca85b8e2], AgentPlugin [id=0, name=Postgres, md5=2d52a8899a50e9b9eb87ddc7ccd2a212], AgentPlugin [id=0, name=Augeas, md5=e4ff494457e36ef5d6bb27cd2a39fc41], AgentPlugin [id=0, name=Apache, md5=894984e422da5db4ee0c0dc085a45e9f], AgentPlugin [id=0, name=AntBundlePlugin, md5=e518b008e186812430b70d4b8151f7aa]]
>2012-05-03 17:01:12,713 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Initializing Inventory Manager...
>2012-05-03 17:01:12,736 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Detected new Platform [Resource[id=0, uuid=baaf0f66-1485-49d9-9b7b-34c147ef5047, type={Platforms}Linux, key=foleymonsterbox1.foleyhomenetwork, name=foleymonsterbox1.foleyhomenetwork, parent=<null>, version=Linux 2.6.35.12-88.fc14.x86_64]] - adding to local inventory...
>2012-05-03 17:01:12,737 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Starting resource activation and upgrade.
>2012-05-03 17:01:12,737 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Executing the initial inventory synchronization before upgrade.
>2012-05-03 17:01:12,737 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 17:01:12,784 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Starting to activate (and upgrade) resources.
>2012-05-03 17:01:12,791 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Resource ID is 0! Operation features will not work until the resource is synced with server
>2012-05-03 17:01:12,792 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Resource ID is 0! Content features will not work until the resource is synced with server
>2012-05-03 17:01:12,793 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Resource ID is 0! Operation features will not work until the resource is synced with server
>2012-05-03 17:01:12,793 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Resource ID is 0! Content features will not work until the resource is synced with server
>2012-05-03 17:01:12,797 INFO  [ResourceContainer.invoker.daemon-1] (org.rhq.plugins.platform.LinuxPlatformComponent)- Internal yum server is disabled.
>2012-05-03 17:01:12,797 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Inventory activated and upgrade requests gathered in 60ms.
>2012-05-03 17:01:12,797 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Sending the upgrade requests to the server.
>2012-05-03 17:01:12,798 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Resource activation and upgrade finished.
>2012-05-03 17:01:12,802 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Inventory Manager initialized.
>2012-05-03 17:01:12,809 INFO  [main] (rhq.core.pc.inventory.ResourceFactoryManager)- Initializing
>2012-05-03 17:01:12,809 INFO  [main] (rhq.core.pc.content.ContentManager)- Initializing Content Manager...
>2012-05-03 17:01:12,810 INFO  [main] (rhq.core.pc.content.ContentManager)- Initializing scheduled content discovery...
>2012-05-03 17:01:12,811 INFO  [main] (rhq.core.pc.content.ContentManager)- Content Manager initialized...
>2012-05-03 17:01:12,817 INFO  [main] (rhq.core.pc.drift.DriftManager)- Finished initializing drift detection schedules in 0 ms
>2012-05-03 17:01:12,817 INFO  [main] (rhq.core.pc.drift.DriftManager)- Scanning for change set content to resend...
>2012-05-03 17:01:12,817 INFO  [main] (rhq.core.pc.drift.DriftManager)- Checking for deleted drift definitions
>2012-05-03 17:01:12,817 INFO  [main] (rhq.core.pc.drift.DriftManager)- Finished initialization in 4 ms
>2012-05-03 17:01:12,817 INFO  [main] (org.rhq.core.pc.PluginContainer)- Plugin Container initialized.
>2012-05-03 17:01:12,818 INFO  [RHQ Agent Ping Thread-1] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.ping-executor.stop-polling-resume-ping}Stopping polling and resuming pinging
>2012-05-03 17:01:12,819 INFO  [RHQ Primary Server Switchover Thread] (org.rhq.enterprise.agent.AgentMain)- {PrimaryServerSwitchoverThread.started}The primary server switchover thread has started.
>2012-05-03 17:01:22,802 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 17:01:23,005 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Detected new Server [Resource[id=0, uuid=4380815c-4632-4308-bf8f-13c47897b162, type={RHQAgent}RHQ Agent, key=foleymonsterbox1.foleyhomenetwork RHQ Agent, name=RHQ Agent, parent=<null>, version=4.4.0.JON310GA]] - adding to local inventory...
>2012-05-03 17:01:23,076 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered new platform with 1 child server(s).
>2012-05-03 17:01:23,076 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 17:01:23,443 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:01:28,449 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 17:01:28,449 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 17:01:28,449 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:01:28,469 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:01:32,802 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 17:01:32,802 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 17:01:32,802 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:01:32,822 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:02:12,811 WARN  [Content.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Cannot get a resource container for an invalid resource ID=0
>2012-05-03 17:02:12,812 WARN  [Content.discovery-1] (rhq.core.pc.content.ContentDiscoveryRunner)- Exception received from component while attempting content retrieval
>org.rhq.core.clientapi.agent.PluginContainerException: Resource component container could not be retrieved for resource: 0
>	at org.rhq.core.pc.util.ComponentUtil.getComponent(ComponentUtil.java:102)
>	at org.rhq.core.pc.content.ContentManager.performContentDiscovery(ContentManager.java:418)
>	at org.rhq.core.pc.content.ContentDiscoveryRunner.call(ContentDiscoveryRunner.java:123)
>	at org.rhq.core.pc.content.ContentDiscoveryRunner.run(ContentDiscoveryRunner.java:90)
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
>	at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351)
>	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>	at java.lang.Thread.run(Thread.java:636)
>2012-05-03 17:02:12,814 WARN  [Content.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Cannot get a resource container for an invalid resource ID=0
>2012-05-03 17:06:12,808 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Starting configuration update check...
>2012-05-03 17:06:12,808 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check completed in 0ms
>2012-05-03 17:16:23,449 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 17:16:23,624 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 1 new server(s).
>2012-05-03 17:16:23,624 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 17:16:23,720 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:22,166 INFO  [WorkerThread#0[192.168.0.111:58516]] (rhq.core.pc.inventory.InventoryManager)- Synchronizing local inventory with Server inventory for Resource [10001] and its descendants...
>2012-05-03 17:19:22,167 INFO  [WorkerThread#0[192.168.0.111:58516]] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:22,323 INFO  [WorkerThread#0[192.168.0.111:58516]] (core.pc.drift.sync.DriftSyncManager)- Starting server sync for drift definitions...
>2012-05-03 17:19:22,357 INFO  [WorkerThread#0[192.168.0.111:58516]] (core.pc.drift.sync.DriftSyncManager)- Finished server sync for drift definitions. 0 added and 0 deleted in 34 ms
>2012-05-03 17:19:22,358 INFO  [WorkerThread#0[192.168.0.111:58516]] (core.pc.drift.sync.DriftSyncManager)- Starting drift content sync...
>2012-05-03 17:19:22,358 INFO  [WorkerThread#0[192.168.0.111:58516]] (rhq.core.pc.drift.DriftManager)- Scanning for change set content to resend...
>2012-05-03 17:19:22,358 INFO  [WorkerThread#0[192.168.0.111:58516]] (core.pc.drift.sync.DriftSyncManager)- Finished drift content sync in 0 ms
>2012-05-03 17:19:22,382 INFO  [ResourceContainer.invoker.daemon-2] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 17:19:22,383 INFO  [ResourceContainer.invoker.daemon-2] (ems.impl.jmx.connection.DConnection)- Found 28 MBeans - starting load...
>2012-05-03 17:19:22,435 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [Resource[id=10001, uuid=baaf0f66-1485-49d9-9b7b-34c147ef5047, type={Platforms}Linux, key=foleymonsterbox1.foleyhomenetwork, name=foleymonsterbox1.foleyhomenetwork, parent=<null>, version=Linux 2.6.35.12-88.fc14.x86_64]]...
>2012-05-03 17:19:22,436 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:22 EDT 2012
>2012-05-03 17:19:22,438 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:22 EDT 2012 : Scan [startTime=1336079962435, endTime=1336079962438, runtime=3, isFull=true, isForced=false, numResources=2, numGetAvailabilityCalls=2, numScheduledRandomly=1, numPushedByInterval=0, numAvailabilityChanges=2, numDeferToParent=0]
>2012-05-03 17:19:22,438 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 17:19:22,489 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:22 EDT 2012
>2012-05-03 17:19:22,489 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:22 EDT 2012 : Scan [startTime=1336079962489, endTime=1336079962489, runtime=0, isFull=false, isForced=false, numResources=2, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:19:23,062 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 14 new descendant Resource(s).
>2012-05-03 17:19:23,062 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:19:23,358 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:23,536 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:23 EDT 2012
>2012-05-03 17:19:23,582 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:23 EDT 2012 : Scan [startTime=1336079963536, endTime=1336079963582, runtime=46, isFull=true, isForced=false, numResources=15, numGetAvailabilityCalls=14, numScheduledRandomly=13, numPushedByInterval=0, numAvailabilityChanges=13, numDeferToParent=0]
>2012-05-03 17:19:23,582 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 17:19:27,435 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 17:19:27,436 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:27 EDT 2012
>2012-05-03 17:19:27,436 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:27 EDT 2012 : Scan [startTime=1336079967436, endTime=1336079967436, runtime=0, isFull=false, isForced=false, numResources=15, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:19:27,542 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 17:19:27,550 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 17:19:27,551 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 17:19:27,579 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 5 new descendant Resource(s).
>2012-05-03 17:19:27,579 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:19:27,822 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:27,951 INFO  [ResourceContainer.invoker.daemon-2] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 17:19:27,951 INFO  [ResourceContainer.invoker.daemon-2] (ems.impl.jmx.connection.DConnection)- Found 28 MBeans - starting load...
>2012-05-03 17:19:28,025 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:28 EDT 2012
>2012-05-03 17:19:28,026 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:28 EDT 2012 : Scan [startTime=1336079968025, endTime=1336079968026, runtime=1, isFull=true, isForced=false, numResources=20, numGetAvailabilityCalls=6, numScheduledRandomly=5, numPushedByInterval=0, numAvailabilityChanges=5, numDeferToParent=0]
>2012-05-03 17:19:28,026 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 17:19:28,536 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 17:19:28,536 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:28 EDT 2012
>2012-05-03 17:19:28,537 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:28 EDT 2012 : Scan [startTime=1336079968536, endTime=1336079968537, runtime=1, isFull=false, isForced=false, numResources=20, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:19:28,642 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 17:19:28,642 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 17:19:28,642 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 17:19:28,670 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 6 new descendant Resource(s).
>2012-05-03 17:19:28,670 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:19:28,906 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:28,983 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:28 EDT 2012
>2012-05-03 17:19:28,984 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:28 EDT 2012 : Scan [startTime=1336079968983, endTime=1336079968984, runtime=1, isFull=true, isForced=false, numResources=26, numGetAvailabilityCalls=7, numScheduledRandomly=6, numPushedByInterval=0, numAvailabilityChanges=6, numDeferToParent=0]
>2012-05-03 17:19:28,984 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 17:19:33,025 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 17:19:33,025 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:33 EDT 2012
>2012-05-03 17:19:33,026 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:33 EDT 2012 : Scan [startTime=1336079973025, endTime=1336079973026, runtime=1, isFull=false, isForced=false, numResources=26, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:19:33,132 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 17:19:33,133 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 17:19:33,133 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 17:19:33,167 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 7 new descendant Resource(s).
>2012-05-03 17:19:33,167 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:19:33,454 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:33,534 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:33 EDT 2012
>2012-05-03 17:19:33,535 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:33 EDT 2012 : Scan [startTime=1336079973534, endTime=1336079973535, runtime=1, isFull=true, isForced=false, numResources=33, numGetAvailabilityCalls=8, numScheduledRandomly=7, numPushedByInterval=0, numAvailabilityChanges=7, numDeferToParent=0]
>2012-05-03 17:19:33,536 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 17:19:33,983 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 17:19:33,983 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:33 EDT 2012
>2012-05-03 17:19:33,984 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:33 EDT 2012 : Scan [startTime=1336079973983, endTime=1336079973983, runtime=0, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:19:34,089 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 17:19:34,090 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 17:19:34,090 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 17:19:34,131 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 17:19:34,131 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:19:34,168 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:38,534 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 17:19:38,534 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:38 EDT 2012
>2012-05-03 17:19:38,535 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:38 EDT 2012 : Scan [startTime=1336079978534, endTime=1336079978535, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:19:38,639 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 17:19:38,640 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 17:19:38,641 INFO  [ResourceDiscoveryComponent.invoker.daemon-3] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 17:19:38,677 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 17:19:38,677 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 17:19:38,719 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:19:47,808 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:19:47 EDT 2012
>2012-05-03 17:19:47,808 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:19:47 EDT 2012 : Scan [startTime=1336079987808, endTime=1336079987808, runtime=0, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:20:12,806 INFO  [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [110] metrics took 592ms - sending report to Server...
>2012-05-03 17:20:17,809 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:20:17 EDT 2012
>2012-05-03 17:20:17,810 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:20:17 EDT 2012 : Scan [startTime=1336080017809, endTime=1336080017810, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:20:47,810 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:20:47 EDT 2012
>2012-05-03 17:20:47,811 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:20:47 EDT 2012 : Scan [startTime=1336080047810, endTime=1336080047811, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:21:17,812 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:21:17 EDT 2012
>2012-05-03 17:21:17,831 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:21:17 EDT 2012 : Scan [startTime=1336080077812, endTime=1336080077831, runtime=19, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:21:47,831 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:21:47 EDT 2012
>2012-05-03 17:21:47,832 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:21:47 EDT 2012 : Scan [startTime=1336080107831, endTime=1336080107832, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:22:17,833 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:22:17 EDT 2012
>2012-05-03 17:22:17,833 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:22:17 EDT 2012 : Scan [startTime=1336080137832, endTime=1336080137833, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:22:47,834 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:22:47 EDT 2012
>2012-05-03 17:22:47,835 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:22:47 EDT 2012 : Scan [startTime=1336080167833, endTime=1336080167835, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:23:17,835 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:23:17 EDT 2012
>2012-05-03 17:23:17,836 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:23:17 EDT 2012 : Scan [startTime=1336080197835, endTime=1336080197836, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:23:47,836 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:23:47 EDT 2012
>2012-05-03 17:23:47,838 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:23:47 EDT 2012 : Scan [startTime=1336080227836, endTime=1336080227837, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:24:17,838 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:24:17 EDT 2012
>2012-05-03 17:24:17,842 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:24:17 EDT 2012 : Scan [startTime=1336080257838, endTime=1336080257842, runtime=4, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:24:47,842 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:24:47 EDT 2012
>2012-05-03 17:24:47,853 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:24:47 EDT 2012 : Scan [startTime=1336080287842, endTime=1336080287853, runtime=11, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:25:17,853 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:25:17 EDT 2012
>2012-05-03 17:25:17,856 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:25:17 EDT 2012 : Scan [startTime=1336080317853, endTime=1336080317856, runtime=3, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:25:47,856 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:25:47 EDT 2012
>2012-05-03 17:25:47,866 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:25:47 EDT 2012 : Scan [startTime=1336080347856, endTime=1336080347866, runtime=10, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:26:17,866 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:26:17 EDT 2012
>2012-05-03 17:26:17,868 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:26:17 EDT 2012 : Scan [startTime=1336080377866, endTime=1336080377868, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:26:47,868 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:26:47 EDT 2012
>2012-05-03 17:26:47,869 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:26:47 EDT 2012 : Scan [startTime=1336080407868, endTime=1336080407869, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:27:17,869 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:27:17 EDT 2012
>2012-05-03 17:27:17,870 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:27:17 EDT 2012 : Scan [startTime=1336080437869, endTime=1336080437870, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:27:47,870 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:27:47 EDT 2012
>2012-05-03 17:27:47,871 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:27:47 EDT 2012 : Scan [startTime=1336080467870, endTime=1336080467871, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:28:17,871 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:28:17 EDT 2012
>2012-05-03 17:28:17,872 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:28:17 EDT 2012 : Scan [startTime=1336080497871, endTime=1336080497872, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:28:47,872 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:28:47 EDT 2012
>2012-05-03 17:28:47,873 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:28:47 EDT 2012 : Scan [startTime=1336080527872, endTime=1336080527873, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:29:17,873 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:29:17 EDT 2012
>2012-05-03 17:29:17,874 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:29:17 EDT 2012 : Scan [startTime=1336080557873, endTime=1336080557874, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:29:47,874 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:29:47 EDT 2012
>2012-05-03 17:29:47,878 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:29:47 EDT 2012 : Scan [startTime=1336080587874, endTime=1336080587878, runtime=4, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:30:12,805 INFO  [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [12] metrics took 6ms - sending report to Server...
>2012-05-03 17:30:17,878 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:30:17 EDT 2012
>2012-05-03 17:30:17,880 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:30:17 EDT 2012 : Scan [startTime=1336080617878, endTime=1336080617880, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:30:47,880 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:30:47 EDT 2012
>2012-05-03 17:30:47,881 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:30:47 EDT 2012 : Scan [startTime=1336080647880, endTime=1336080647881, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:31:17,881 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:31:17 EDT 2012
>2012-05-03 17:31:17,900 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:31:17 EDT 2012 : Scan [startTime=1336080677881, endTime=1336080677900, runtime=19, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:31:23,724 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 17:31:23,867 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 17:31:23,867 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 17:31:23,912 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:31:47,901 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:31:47 EDT 2012
>2012-05-03 17:31:47,902 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:31:47 EDT 2012 : Scan [startTime=1336080707900, endTime=1336080707902, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:32:17,902 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:32:17 EDT 2012
>2012-05-03 17:32:17,903 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:32:17 EDT 2012 : Scan [startTime=1336080737902, endTime=1336080737903, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:32:47,903 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:32:47 EDT 2012
>2012-05-03 17:32:47,904 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:32:47 EDT 2012 : Scan [startTime=1336080767903, endTime=1336080767904, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:33:17,905 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:33:17 EDT 2012
>2012-05-03 17:33:17,905 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:33:17 EDT 2012 : Scan [startTime=1336080797905, endTime=1336080797905, runtime=0, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:33:47,906 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:33:47 EDT 2012
>2012-05-03 17:33:47,910 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:33:47 EDT 2012 : Scan [startTime=1336080827906, endTime=1336080827910, runtime=4, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:34:17,910 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:34:17 EDT 2012
>2012-05-03 17:34:17,916 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:34:17 EDT 2012 : Scan [startTime=1336080857910, endTime=1336080857916, runtime=6, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:34:47,916 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:34:47 EDT 2012
>2012-05-03 17:34:47,927 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:34:47 EDT 2012 : Scan [startTime=1336080887916, endTime=1336080887927, runtime=11, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:35:17,927 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:35:17 EDT 2012
>2012-05-03 17:35:17,930 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:35:17 EDT 2012 : Scan [startTime=1336080917927, endTime=1336080917930, runtime=3, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:35:47,930 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:35:47 EDT 2012
>2012-05-03 17:35:47,940 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:35:47 EDT 2012 : Scan [startTime=1336080947930, endTime=1336080947940, runtime=10, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:36:17,941 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:36:17 EDT 2012
>2012-05-03 17:36:17,942 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:36:17 EDT 2012 : Scan [startTime=1336080977940, endTime=1336080977942, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:36:47,943 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:36:47 EDT 2012
>2012-05-03 17:36:47,943 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:36:47 EDT 2012 : Scan [startTime=1336081007943, endTime=1336081007943, runtime=0, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:37:17,944 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:37:17 EDT 2012
>2012-05-03 17:37:17,944 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:37:17 EDT 2012 : Scan [startTime=1336081037943, endTime=1336081037944, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:37:47,945 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:37:47 EDT 2012
>2012-05-03 17:37:47,945 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:37:47 EDT 2012 : Scan [startTime=1336081067945, endTime=1336081067945, runtime=0, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:38:17,946 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:38:17 EDT 2012
>2012-05-03 17:38:17,947 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:38:17 EDT 2012 : Scan [startTime=1336081097946, endTime=1336081097947, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:38:47,947 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:38:47 EDT 2012
>2012-05-03 17:38:47,948 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:38:47 EDT 2012 : Scan [startTime=1336081127947, endTime=1336081127948, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:39:17,948 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:39:17 EDT 2012
>2012-05-03 17:39:17,949 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:39:17 EDT 2012 : Scan [startTime=1336081157948, endTime=1336081157949, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:39:47,949 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:39:47 EDT 2012
>2012-05-03 17:39:47,950 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:39:47 EDT 2012 : Scan [startTime=1336081187949, endTime=1336081187950, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:40:12,805 INFO  [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [67] metrics took 56ms - sending report to Server...
>2012-05-03 17:40:17,951 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:40:17 EDT 2012
>2012-05-03 17:40:17,952 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:40:17 EDT 2012 : Scan [startTime=1336081217951, endTime=1336081217952, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:40:47,952 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:40:47 EDT 2012
>2012-05-03 17:40:47,953 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:40:47 EDT 2012 : Scan [startTime=1336081247952, endTime=1336081247953, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:41:17,954 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:41:17 EDT 2012
>2012-05-03 17:41:17,972 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:41:17 EDT 2012 : Scan [startTime=1336081277954, endTime=1336081277972, runtime=18, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:41:47,972 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:41:47 EDT 2012
>2012-05-03 17:41:47,974 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:41:47 EDT 2012 : Scan [startTime=1336081307972, endTime=1336081307974, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:42:17,974 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:42:17 EDT 2012
>2012-05-03 17:42:17,975 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:42:17 EDT 2012 : Scan [startTime=1336081337974, endTime=1336081337975, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:42:47,975 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:42:47 EDT 2012
>2012-05-03 17:42:47,977 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:42:47 EDT 2012 : Scan [startTime=1336081367975, endTime=1336081367977, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:43:17,977 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:43:17 EDT 2012
>2012-05-03 17:43:17,978 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:43:17 EDT 2012 : Scan [startTime=1336081397977, endTime=1336081397978, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:43:47,978 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:43:47 EDT 2012
>2012-05-03 17:43:47,980 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:43:47 EDT 2012 : Scan [startTime=1336081427978, endTime=1336081427980, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:44:17,980 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:44:17 EDT 2012
>2012-05-03 17:44:17,983 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:44:17 EDT 2012 : Scan [startTime=1336081457980, endTime=1336081457983, runtime=3, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:44:47,984 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:44:47 EDT 2012
>2012-05-03 17:44:47,994 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:44:47 EDT 2012 : Scan [startTime=1336081487984, endTime=1336081487994, runtime=10, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:45:17,995 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:45:17 EDT 2012
>2012-05-03 17:45:17,997 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:45:17 EDT 2012 : Scan [startTime=1336081517995, endTime=1336081517997, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:45:47,997 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:45:47 EDT 2012
>2012-05-03 17:45:48,007 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:45:48 EDT 2012 : Scan [startTime=1336081547997, endTime=1336081548007, runtime=10, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:46:18,007 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:46:18 EDT 2012
>2012-05-03 17:46:18,009 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:46:18 EDT 2012 : Scan [startTime=1336081578007, endTime=1336081578009, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:46:23,915 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 17:46:24,071 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 17:46:24,071 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 17:46:24,115 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 17:46:48,009 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:46:48 EDT 2012
>2012-05-03 17:46:48,010 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:46:48 EDT 2012 : Scan [startTime=1336081608009, endTime=1336081608010, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:47:18,010 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:47:18 EDT 2012
>2012-05-03 17:47:18,011 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:47:18 EDT 2012 : Scan [startTime=1336081638010, endTime=1336081638011, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:47:48,011 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:47:48 EDT 2012
>2012-05-03 17:47:48,012 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:47:48 EDT 2012 : Scan [startTime=1336081668011, endTime=1336081668012, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:48:18,012 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:48:18 EDT 2012
>2012-05-03 17:48:18,013 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:48:18 EDT 2012 : Scan [startTime=1336081698012, endTime=1336081698013, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:48:48,013 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:48:48 EDT 2012
>2012-05-03 17:48:48,014 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:48:48 EDT 2012 : Scan [startTime=1336081728013, endTime=1336081728014, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:49:18,014 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:49:18 EDT 2012
>2012-05-03 17:49:18,015 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:49:18 EDT 2012 : Scan [startTime=1336081758014, endTime=1336081758015, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:49:48,016 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:49:48 EDT 2012
>2012-05-03 17:49:48,019 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:49:48 EDT 2012 : Scan [startTime=1336081788015, endTime=1336081788019, runtime=4, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:50:12,805 INFO  [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [12] metrics took 5ms - sending report to Server...
>2012-05-03 17:50:18,019 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:50:18 EDT 2012
>2012-05-03 17:50:18,021 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:50:18 EDT 2012 : Scan [startTime=1336081818019, endTime=1336081818021, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:50:48,021 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:50:48 EDT 2012
>2012-05-03 17:50:48,022 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:50:48 EDT 2012 : Scan [startTime=1336081848021, endTime=1336081848022, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:51:18,022 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:51:18 EDT 2012
>2012-05-03 17:51:18,041 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:51:18 EDT 2012 : Scan [startTime=1336081878022, endTime=1336081878041, runtime=19, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:51:48,042 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:51:48 EDT 2012
>2012-05-03 17:51:48,043 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:51:48 EDT 2012 : Scan [startTime=1336081908042, endTime=1336081908043, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:52:18,043 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:52:18 EDT 2012
>2012-05-03 17:52:18,044 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:52:18 EDT 2012 : Scan [startTime=1336081938043, endTime=1336081938044, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:52:48,044 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:52:48 EDT 2012
>2012-05-03 17:52:48,045 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:52:48 EDT 2012 : Scan [startTime=1336081968044, endTime=1336081968045, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:53:18,046 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:53:18 EDT 2012
>2012-05-03 17:53:18,046 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:53:18 EDT 2012 : Scan [startTime=1336081998045, endTime=1336081998046, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:53:48,046 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:53:48 EDT 2012
>2012-05-03 17:53:48,048 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:53:48 EDT 2012 : Scan [startTime=1336082028046, endTime=1336082028048, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:54:18,048 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:54:18 EDT 2012
>2012-05-03 17:54:18,053 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:54:18 EDT 2012 : Scan [startTime=1336082058048, endTime=1336082058053, runtime=5, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:54:48,053 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:54:48 EDT 2012
>2012-05-03 17:54:48,064 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:54:48 EDT 2012 : Scan [startTime=1336082088053, endTime=1336082088064, runtime=11, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:55:18,065 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:55:18 EDT 2012
>2012-05-03 17:55:18,067 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:55:18 EDT 2012 : Scan [startTime=1336082118065, endTime=1336082118067, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:55:48,067 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:55:48 EDT 2012
>2012-05-03 17:55:48,078 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:55:48 EDT 2012 : Scan [startTime=1336082148067, endTime=1336082148078, runtime=11, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:56:18,078 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:56:18 EDT 2012
>2012-05-03 17:56:18,080 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:56:18 EDT 2012 : Scan [startTime=1336082178078, endTime=1336082178080, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:56:48,080 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:56:48 EDT 2012
>2012-05-03 17:56:48,081 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:56:48 EDT 2012 : Scan [startTime=1336082208080, endTime=1336082208081, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:57:18,081 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:57:18 EDT 2012
>2012-05-03 17:57:18,082 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:57:18 EDT 2012 : Scan [startTime=1336082238081, endTime=1336082238082, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:57:48,082 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:57:48 EDT 2012
>2012-05-03 17:57:48,083 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:57:48 EDT 2012 : Scan [startTime=1336082268082, endTime=1336082268083, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:58:18,083 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:58:18 EDT 2012
>2012-05-03 17:58:18,084 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:58:18 EDT 2012 : Scan [startTime=1336082298083, endTime=1336082298084, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:58:48,084 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:58:48 EDT 2012
>2012-05-03 17:58:48,085 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:58:48 EDT 2012 : Scan [startTime=1336082328084, endTime=1336082328085, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:59:18,085 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:59:18 EDT 2012
>2012-05-03 17:59:18,086 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:59:18 EDT 2012 : Scan [startTime=1336082358085, endTime=1336082358086, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 17:59:48,086 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 17:59:48 EDT 2012
>2012-05-03 17:59:48,088 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 17:59:48 EDT 2012 : Scan [startTime=1336082388086, endTime=1336082388088, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:00:12,805 INFO  [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [67] metrics took 59ms - sending report to Server...
>2012-05-03 18:00:18,088 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:00:18 EDT 2012
>2012-05-03 18:00:18,090 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:00:18 EDT 2012 : Scan [startTime=1336082418088, endTime=1336082418090, runtime=2, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:00:38,069 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.prompt-command-invoked}Prompt command invoked: [discovery, -f]
>2012-05-03 18:00:38,070 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:00:38,246 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 18:00:38,246 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:00:38,292 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:00:38,295 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:00:38,295 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:00:38 EDT 2012
>2012-05-03 18:00:38,296 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:00:38 EDT 2012 : Scan [startTime=1336082438295, endTime=1336082438296, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:00:38,402 INFO  [ResourceDiscoveryComponent.invoker.daemon-6] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:00:38,404 INFO  [ResourceDiscoveryComponent.invoker.daemon-6] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:00:38,405 INFO  [ResourceDiscoveryComponent.invoker.daemon-6] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:00:38,440 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 18:00:38,440 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:00:38,478 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:00:42,037 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.prompt-command-invoked}Prompt command invoked: [plugins, update]
>2012-05-03 18:00:42,037 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.core.pc.PluginContainer)- Plugin container is being shutdown...
>2012-05-03 18:00:42,039 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.content.ContentManager)- Shutting down Content Manager...
>2012-05-03 18:00:42,040 INFO  [MeasurementManager.collector-1] (rhq.core.pc.measurement.MeasurementManager)- Shutting down measurement collection...
>2012-05-03 18:00:42,147 WARN  [RHQ Agent Prompt Input Thread] (rhq.core.pc.plugin.PluginClassLoader)- Failed to purge embedded jars directory. Cause: java.io.IOException: Failed to delete file or directory: data/tmp/rhq-ant-bundle-plugin-4.4.0.JON310GA.jar4128961195911082857.classloader
>2012-05-03 18:00:42,147 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.core.pc.PluginContainer)- Plugin container is now shutdown.
>2012-05-03 18:00:42,263 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [jopr-jboss-as-5-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 18:00:42,681 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [JBossAS5] has been updated at [jopr-jboss-as-5-plugin-4.4.0.JON310GA.jar].
>2012-05-03 18:00:42,681 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [jopr-jboss-as-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 18:00:43,105 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [JBossAS] has been updated at [jopr-jboss-as-plugin-4.4.0.JON310GA.jar].
>2012-05-03 18:00:43,106 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading}Downloading the plugin [jopr-rhq-server-plugin-4.4.0.JON310GA.jar]...
>2012-05-03 18:00:43,163 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.downloading-complete}The plugin [RHQServer] has been updated at [jopr-rhq-server-plugin-4.4.0.JON310GA.jar].
>2012-05-03 18:00:43,164 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.updating-complete}Completed updating the plugins to their latest versions.
>2012-05-03 18:00:45,165 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.core.pc.PluginContainer)- Initializing Plugin Container v4.4.0.JON310GA...
>2012-05-03 18:00:47,164 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.plugin.PluginManager)- Deployed plugins: [AgentPlugin [id=0, name=Script, md5=ec96dbd931420b0ebc848aa0214e89dd], AgentPlugin [id=0, name=Platforms, md5=3907e2ed1fe7f543436e9bfb9706030a], AgentPlugin [id=0, name=JMX, md5=88cec6c262fc5e3e7c86860922d7e421], AgentPlugin [id=0, name=RHQAgent, md5=d277e15657497e6f32df76b2354dcd7b], AgentPlugin [id=0, name=JBossAS5, md5=83dfdafecc9e7d88fabd526f69ad4dbe], AgentPlugin [id=0, name=JBossAS, md5=84a60aa0135e81795f80b01f0921c125], AgentPlugin [id=0, name=RHQServer, md5=a5d7139319a67a43a4fcac6f47ac29b1], AgentPlugin [id=0, name=IIS, md5=da47f7f2f53bd4ec5f7cd51d57213862], AgentPlugin [id=0, name=Database, md5=e5f156b654e877df10a07e54ca85b8e2], AgentPlugin [id=0, name=Postgres, md5=2d52a8899a50e9b9eb87ddc7ccd2a212], AgentPlugin [id=0, name=Augeas, md5=e4ff494457e36ef5d6bb27cd2a39fc41], AgentPlugin [id=0, name=Apache, md5=894984e422da5db4ee0c0dc085a45e9f], AgentPlugin [id=0, name=AntBundlePlugin, md5=e518b008e186812430b70d4b8151f7aa]]
>2012-05-03 18:00:47,164 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Initializing Inventory Manager...
>2012-05-03 18:00:47,165 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Loading inventory from data file [data/inventory.dat]...
>2012-05-03 18:00:47,328 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Inventory with size [33] loaded from data file in [164ms]
>2012-05-03 18:00:47,337 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Starting resource activation and upgrade.
>2012-05-03 18:00:47,337 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Executing the initial inventory synchronization before upgrade.
>2012-05-03 18:00:47,337 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:00:47,375 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:00:47,388 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Starting to activate (and upgrade) resources.
>2012-05-03 18:00:47,393 INFO  [ResourceContainer.invoker.daemon-1] (org.rhq.plugins.platform.LinuxPlatformComponent)- Internal yum server is disabled.
>2012-05-03 18:00:47,441 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 18:00:47,441 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Found 27 MBeans - starting load...
>2012-05-03 18:00:47,451 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 18:00:47,451 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Found 27 MBeans - starting load...
>2012-05-03 18:00:47,492 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Inventory activated and upgrade requests gathered in 155ms.
>2012-05-03 18:00:47,492 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Sending the upgrade requests to the server.
>2012-05-03 18:00:47,492 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Resource activation and upgrade finished.
>2012-05-03 18:00:47,493 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.InventoryManager)- Inventory Manager initialized.
>2012-05-03 18:00:47,497 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.inventory.ResourceFactoryManager)- Initializing
>2012-05-03 18:00:47,497 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.content.ContentManager)- Initializing Content Manager...
>2012-05-03 18:00:47,497 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.content.ContentManager)- Initializing scheduled content discovery...
>2012-05-03 18:00:47,497 INFO  [RHQ Agent Prompt Input Thread] (rhq.core.pc.content.ContentManager)- Content Manager initialized...
>2012-05-03 18:00:47,498 WARN  [RHQ Agent Prompt Input Thread] (rhq.core.pc.drift.DriftManager)- Could not create change sets directory data/changesets
>2012-05-03 18:00:47,498 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.core.pc.PluginContainer)- Plugin Container initialized.
>2012-05-03 18:00:52,493 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:00:52 EDT 2012
>2012-05-03 18:00:52,538 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:00:52 EDT 2012 : Scan [startTime=1336082452492, endTime=1336082452538, runtime=46, isFull=true, isForced=false, numResources=33, numGetAvailabilityCalls=33, numScheduledRandomly=32, numPushedByInterval=0, numAvailabilityChanges=33, numDeferToParent=0]
>2012-05-03 18:00:52,539 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 18:00:52,582 INFO  [RHQ Agent Prompt Input Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.prompt-command-invoked}Prompt command invoked: [discovery, -f]
>2012-05-03 18:00:52,583 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:00:52,739 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Detected new Server [Resource[id=0, uuid=a2dc08ae-5931-4adb-85f3-1adf1819ed87, type={JBossAS5}JBossAS Server, key=/root/EAP5.1/jboss-eap-5.1/jboss-as/server/default, name=EAP localhost:1099 default, parent=<null>, version=EAP 5.1.1]] - adding to local inventory...
>2012-05-03 18:00:52,741 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:00:52,742 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool-scoped.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:00:52,742 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/common/lib/jboss-as-profileservice.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:00:52,883 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Detected new Server [Resource[id=0, uuid=f6feeda0-d51d-473d-93c6-1b83d53976e1, type={JBossAS}JBossAS Server, key=/root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas/server/default, name=foleymonsterbox1.foleyhomenetwork:2099 RHQ Server, parent=<null>, version=AS 4.2.3.GA]] - adding to local inventory...
>2012-05-03 18:00:52,914 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 2 new server(s).
>2012-05-03 18:00:52,914 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:00:53,263 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:00:53,266 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:00:53 EDT 2012
>2012-05-03 18:00:53,266 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:00:53,267 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:00:53 EDT 2012 : Scan [startTime=1336082453266, endTime=1336082453267, runtime=1, isFull=true, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:00:53,268 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 18:00:53,298 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:00:53 EDT 2012
>2012-05-03 18:00:53,299 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:00:53 EDT 2012 : Scan [startTime=1336082453298, endTime=1336082453298, runtime=0, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:00:53,408 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:00:53,415 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:00:53,417 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:00:53,452 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 18:00:53,452 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:00:53,492 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:00:57,493 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:00:57,632 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 2 new server(s).
>2012-05-03 18:00:57,632 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:00:57,799 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:00:58,266 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:00:58,267 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:00:58 EDT 2012
>2012-05-03 18:00:58,267 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:00:58 EDT 2012 : Scan [startTime=1336082458267, endTime=1336082458267, runtime=0, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:00:58,372 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:00:58,373 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:00:58,374 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:00:58,410 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 18:00:58,410 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:00:58,449 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:07,493 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:01:07,493 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:07 EDT 2012
>2012-05-03 18:01:07,494 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:07 EDT 2012 : Scan [startTime=1336082467493, endTime=1336082467494, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:07,607 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:01:07,608 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:01:07,608 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:01:07,644 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2012-05-03 18:01:07,644 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:01:07,683 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:22,587 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:22 EDT 2012
>2012-05-03 18:01:22,588 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:22 EDT 2012 : Scan [startTime=1336082482587, endTime=1336082482588, runtime=1, isFull=false, isForced=false, numResources=33, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:33,549 INFO  [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Synchronizing local inventory with Server inventory for Resource [10034] and its descendants...
>2012-05-03 18:01:33,549 INFO  [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:33,635 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Starting server sync for drift definitions...
>2012-05-03 18:01:33,649 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Finished server sync for drift definitions. 0 added and 0 deleted in 13 ms
>2012-05-03 18:01:33,649 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Starting drift content sync...
>2012-05-03 18:01:33,649 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Finished drift content sync in 0 ms
>2012-05-03 18:01:33,651 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:33,651 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool-scoped.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:33,651 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/common/lib/jboss-as-profileservice.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:34,005 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Discovered libraries in 0 ms
>2012-05-03 18:01:34,011 WARN  [ResourceContainer.invoker.daemon-1] (jboss.on.common.jbossas.JmxConnectionHelper)- Could not establish connection to the instance [1] times.
>org.mc4j.ems.connection.EmsConnectException: Could not connect [jnp://127.0.0.1:1099] javax.naming.NameNotFoundException: RMIAdaptor not bound
>	at org.mc4j.ems.impl.jmx.connection.support.providers.AbstractConnectionProvider.connect(AbstractConnectionProvider.java:106)
>	at org.jboss.on.common.jbossas.JmxConnectionHelper.loadConnection(JmxConnectionHelper.java:211)
>	at org.jboss.on.common.jbossas.JmxConnectionHelper.getEmsConnection(JmxConnectionHelper.java:101)
>	at org.rhq.plugins.jbossas5.ApplicationServerComponent.initializeEmsConnection(ApplicationServerComponent.java:505)
>	at org.rhq.plugins.jbossas5.ApplicationServerComponent.start(ApplicationServerComponent.java:181)
>	at sun.reflect.GeneratedMethodAccessor46.invoke(Unknown Source)
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>	at java.lang.reflect.Method.invoke(Method.java:616)
>	at org.rhq.core.pc.inventory.ResourceContainer$ComponentInvocationThread.call(ResourceContainer.java:632)
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>	at java.lang.Thread.run(Thread.java:636)
>Caused by: javax.naming.NameNotFoundException: RMIAdaptor not bound
>	at org.jnp.server.NamingServer.getBinding(NamingServer.java:771)
>	at org.jnp.server.NamingServer.getBinding(NamingServer.java:779)
>	at org.jnp.server.NamingServer.getObject(NamingServer.java:785)
>	at org.jnp.server.NamingServer.lookup(NamingServer.java:443)
>	at org.jnp.server.NamingServer.lookup(NamingServer.java:399)
>	at org.jnp.server.NamingServer.lookup(NamingServer.java:399)
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>	at java.lang.reflect.Method.invoke(Method.java:616)
>	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:322)
>	at sun.rmi.transport.Transport$1.run(Transport.java:177)
>	at java.security.AccessController.doPrivileged(Native Method)
>	at sun.rmi.transport.Transport.serviceCall(Transport.java:173)
>	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:553)
>	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:808)
>	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:667)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>	at java.lang.Thread.run(Thread.java:636)
>	at sun.rmi.transport.StreamRemoteCall.exceptionReceivedFromServer(StreamRemoteCall.java:273)
>	at sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:251)
>	at sun.rmi.server.UnicastRef.invoke(UnicastRef.java:160)
>	at org.jnp.server.NamingServer_Stub.lookup(Unknown Source)
>	at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:726)
>	at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:686)
>	at javax.naming.InitialContext.lookup(InitialContext.java:409)
>	at org.mc4j.ems.impl.jmx.connection.support.providers.JBossConnectionProvider.doConnect(JBossConnectionProvider.java:77)
>	at org.mc4j.ems.impl.jmx.connection.support.providers.AbstractConnectionProvider.connect(AbstractConnectionProvider.java:101)
>	... 13 more
>2012-05-03 18:01:34,011 ERROR [ResourceContainer.invoker.daemon-1] (jboss.on.common.jbossas.JmxConnectionHelper)- Component attempting to access a connection that could not be loaded
>2012-05-03 18:01:34,030 INFO  [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Detected new Platform [Resource[id=0, uuid=12ea3036-e421-435d-92e4-aa8c11483d3a, type={Platforms}Linux, key=foleymonsterbox1.foleyhomenetwork, name=foleymonsterbox1.foleyhomenetwork, parent=<null>, version=Linux 2.6.35.12-88.fc14.x86_64]] - adding to local inventory...
>2012-05-03 18:01:34,030 INFO  [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Deleted resource #[10001] - this will trigger a server scan now
>2012-05-03 18:01:34,030 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:01:34,074 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- No committed resources to send in our availability report - the platform/agent was deleted, let's re-register again
>2012-05-03 18:01:34,078 INFO  [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Synchronizing local inventory with Server inventory for Resource [10035] and its descendants...
>2012-05-03 18:01:34,078 INFO  [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:34,078 INFO  [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Got unknown resource: 10035
>2012-05-03 18:01:34,116 ERROR [WorkerThread#0[192.168.0.111:50213]] (rhq.core.pc.inventory.InventoryManager)- Existing platform [Resource[id=0, uuid=12ea3036-e421-435d-92e4-aa8c11483d3a, type={Platforms}Linux, key=foleymonsterbox1.foleyhomenetwork, name=foleymonsterbox1.foleyhomenetwork, parent=<null>, version=Linux 2.6.35.12-88.fc14.x86_64]] has different Resource type and/or Resource key than platform in Server inventory: Resource[id=10035, uuid=f6feeda0-d51d-473d-93c6-1b83d53976e1, type={JBossAS}JBossAS Server, key=/root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas/server/default, name=foleymonsterbox1.foleyhomenetwork:2099 RHQ Server, parent=foleymonsterbox1.foleyhomenetwork, version=AS 4.2.3.GA]
>2012-05-03 18:01:34,126 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [client/jboss-common.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,137 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/xercesImpl.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,137 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/xml-apis.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,137 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/dom4j.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,138 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [client/jmx-rmi-connector-client.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,145 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/endorsed/xml-apis.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,148 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Discovered libraries in 23 ms
>2012-05-03 18:01:34,148 INFO  [ResourceContainer.invoker.daemon-1] (org.rhq.plugins.jbossas.JBossASServerComponent)- Loading JBoss connection [jnp://127.0.0.1:2099] with install path [/root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas]...
>2012-05-03 18:01:34,164 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Detected new Platform [Resource[id=0, uuid=ca19f682-f0f3-4d3e-9224-4c007219cf8f, type={Platforms}Linux, key=foleymonsterbox1.foleyhomenetwork, name=foleymonsterbox1.foleyhomenetwork, parent=<null>, version=Linux 2.6.35.12-88.fc14.x86_64]] - adding to local inventory...
>2012-05-03 18:01:34,167 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Detected new Server [Resource[id=0, uuid=68f7a845-b106-4ff2-b5e9-d076c4bee480, type={RHQAgent}RHQ Agent, key=foleymonsterbox1.foleyhomenetwork RHQ Agent, name=RHQ Agent, parent=<null>, version=4.4.0.JON310GA]] - adding to local inventory...
>2012-05-03 18:01:34,176 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Detected new Server [Resource[id=0, uuid=fafeebd8-6797-4756-a207-fb778e885108, type={JBossAS5}JBossAS Server, key=/root/EAP5.1/jboss-eap-5.1/jboss-as/server/default, name=EAP localhost:1099 default, parent=<null>, version=EAP 5.1.1]] - adding to local inventory...
>2012-05-03 18:01:34,177 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:34,177 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool-scoped.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:34,177 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/common/lib/jboss-as-profileservice.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:34,196 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Detected new Server [Resource[id=0, uuid=c53a5501-2557-498d-a03a-d6986235f1dd, type={JBossAS}JBossAS Server, key=/root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas/server/default, name=foleymonsterbox1.foleyhomenetwork:2099 RHQ Server, parent=<null>, version=AS 4.2.3.GA]] - adding to local inventory...
>2012-05-03 18:01:34,202 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered new platform with 3 child server(s).
>2012-05-03 18:01:34,202 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:01:34,388 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:34,388 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Got unknown resource: 10001
>2012-05-03 18:01:34,424 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 18:01:34,502 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Found 670 MBeans - starting load...
>2012-05-03 18:01:34,599 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Starting server sync for drift definitions...
>2012-05-03 18:01:34,609 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Finished server sync for drift definitions. 0 added and 0 deleted in 10 ms
>2012-05-03 18:01:34,609 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Starting drift content sync...
>2012-05-03 18:01:34,609 INFO  [WorkerThread#0[192.168.0.111:50213]] (core.pc.drift.sync.DriftSyncManager)- Finished drift content sync in 0 ms
>2012-05-03 18:01:34,661 INFO  [ResourceContainer.invoker.daemon-1] (org.rhq.plugins.platform.LinuxPlatformComponent)- Internal yum server is disabled.
>2012-05-03 18:01:34,695 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [client/jboss-common.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,702 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/xercesImpl.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,703 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/xml-apis.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,703 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/dom4j.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,704 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [client/jmx-rmi-connector-client.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,710 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Connection library dependancy [lib/endorsed/xml-apis.jar] not found  in directory: /root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas
>2012-05-03 18:01:34,713 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Discovered libraries in 19 ms
>2012-05-03 18:01:34,713 INFO  [ResourceContainer.invoker.daemon-1] (org.rhq.plugins.jbossas.JBossASServerComponent)- Loading JBoss connection [jnp://127.0.0.1:2099] with install path [/root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas]...
>2012-05-03 18:01:34,717 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 18:01:34,762 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Found 670 MBeans - starting load...
>2012-05-03 18:01:34,774 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:34,774 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/lib/jboss-classpool-scoped.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:34,774 WARN  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.jbossas5.ApplicationServerDiscoveryComponent)- Client JAR [file:/root/EAP5.1/jboss-eap-5.1/jboss-as/common/lib/jboss-as-profileservice.jar] does not exist or is not readable (note, this JAR  may not be required for some app server versions).
>2012-05-03 18:01:34,783 INFO  [ResourceContainer.invoker.daemon-1] (org.mc4j.ems.connection.ConnectionFactory)- Discovered libraries in 0 ms
>2012-05-03 18:01:34,789 ERROR [ResourceContainer.invoker.daemon-1] (jboss.on.common.jbossas.JmxConnectionHelper)- Component attempting to access a connection that could not be loaded
>2012-05-03 18:01:34,790 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 18:01:34,790 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Found 28 MBeans - starting load...
>2012-05-03 18:01:34,792 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2012-05-03 18:01:34,792 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Found 28 MBeans - starting load...
>2012-05-03 18:01:35,004 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Starting server sync for drift definitions...
>2012-05-03 18:01:35,025 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Finished server sync for drift definitions. 0 added and 0 deleted in 21 ms
>2012-05-03 18:01:35,025 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Starting drift content sync...
>2012-05-03 18:01:35,025 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Finished drift content sync in 0 ms
>2012-05-03 18:01:35,028 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [Resource[id=10035, uuid=f6feeda0-d51d-473d-93c6-1b83d53976e1, type={JBossAS}JBossAS Server, key=/root/rhq/JON3.1RC1/jon-server-3.1.0.GA/jbossas/server/default, name=foleymonsterbox1.foleyhomenetwork:2099 RHQ Server, parent=<null>, version=AS 4.2.3.GA]]...
>2012-05-03 18:01:35,135 INFO  [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.agent-registration-results}Agent has successfully registered with the server. The results are: [AgentRegistrationResults: [agent-token=6+sdrWraKkqJ3aHxawCa24/7E+tymuDUmmj0Xn7e4A3/DLZ8P7mNJPMeMCzBxnJnd54=]]
>2012-05-03 18:01:35,140 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:35 EDT 2012
>2012-05-03 18:01:35,196 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:35 EDT 2012 : Scan [startTime=1336082495140, endTime=1336082495196, runtime=56, isFull=true, isForced=false, numResources=35, numGetAvailabilityCalls=35, numScheduledRandomly=34, numPushedByInterval=0, numAvailabilityChanges=35, numDeferToParent=0]
>2012-05-03 18:01:35,197 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 18:01:35,271 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:35 EDT 2012
>2012-05-03 18:01:35,271 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:35 EDT 2012 : Scan [startTime=1336082495271, endTime=1336082495271, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:35,272 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:35 EDT 2012
>2012-05-03 18:01:35,272 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:35 EDT 2012 : Scan [startTime=1336082495272, endTime=1336082495272, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:35,671 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 147 new descendant Resource(s).
>2012-05-03 18:01:35,671 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:01:38,381 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:39,221 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:01:39,221 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:39 EDT 2012
>2012-05-03 18:01:39,222 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:39 EDT 2012 : Scan [startTime=1336082499221, endTime=1336082499222, runtime=1, isFull=true, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:39,224 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 18:01:39,366 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 18:01:39,366 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:01:39,481 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:39,485 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:01:39,486 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:39 EDT 2012
>2012-05-03 18:01:39,486 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:39 EDT 2012 : Scan [startTime=1336082499486, endTime=1336082499486, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:39,718 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:01:39,719 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:01:39,720 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:01:39,753 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 147 new descendant Resource(s).
>2012-05-03 18:01:39,753 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:01:41,685 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:41,689 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:01:41,690 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:41 EDT 2012
>2012-05-03 18:01:41,690 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:41 EDT 2012 : Scan [startTime=1336082501690, endTime=1336082501690, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:41,927 INFO  [ResourceDiscoveryComponent.invoker.daemon-2] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:01:41,927 INFO  [ResourceDiscoveryComponent.invoker.daemon-2] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:01:41,928 INFO  [ResourceDiscoveryComponent.invoker.daemon-2] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:01:41,963 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 147 new descendant Resource(s).
>2012-05-03 18:01:41,963 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:01:43,919 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:43,923 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:01:43,924 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:43 EDT 2012
>2012-05-03 18:01:43,924 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:43 EDT 2012 : Scan [startTime=1336082503924, endTime=1336082503924, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:44,134 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:01:44,134 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:01:44,134 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:01:44,168 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 147 new descendant Resource(s).
>2012-05-03 18:01:44,169 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:01:46,095 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:46,099 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 18:01:46,099 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:46 EDT 2012
>2012-05-03 18:01:46,099 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:46 EDT 2012 : Scan [startTime=1336082506099, endTime=1336082506099, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:01:46,297 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 18:01:46,298 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 18:01:46,298 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 18:01:46,332 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 147 new descendant Resource(s).
>2012-05-03 18:01:46,332 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 18:01:47,494 INFO  [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [110] metrics took 73ms - sending report to Server...
>2012-05-03 18:01:48,207 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:01:52,588 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:01:52 EDT 2012
>2012-05-03 18:01:52,589 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:01:52 EDT 2012 : Scan [startTime=1336082512588, endTime=1336082512589, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:02:22,589 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:02:22 EDT 2012
>2012-05-03 18:02:22,600 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:02:22 EDT 2012 : Scan [startTime=1336082542589, endTime=1336082542600, runtime=11, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:02:52,600 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:02:52 EDT 2012
>2012-05-03 18:02:52,606 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:02:52 EDT 2012 : Scan [startTime=1336082572600, endTime=1336082572606, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:03:22,606 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:03:22 EDT 2012
>2012-05-03 18:03:22,607 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:03:22 EDT 2012 : Scan [startTime=1336082602606, endTime=1336082602607, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:03:52,607 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:03:52 EDT 2012
>2012-05-03 18:03:52,613 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:03:52 EDT 2012 : Scan [startTime=1336082632607, endTime=1336082632613, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:04:22,614 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:04:22 EDT 2012
>2012-05-03 18:04:22,617 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:04:22 EDT 2012 : Scan [startTime=1336082662614, endTime=1336082662617, runtime=3, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:04:52,617 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:04:52 EDT 2012
>2012-05-03 18:04:52,623 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:04:52 EDT 2012 : Scan [startTime=1336082692617, endTime=1336082692623, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:05:22,623 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:05:22 EDT 2012
>2012-05-03 18:05:22,624 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:05:22 EDT 2012 : Scan [startTime=1336082722623, endTime=1336082722624, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:05:47,497 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Starting configuration update check...
>2012-05-03 18:05:47,502 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- New configuration version detected on resource: Resource[id=10002, uuid=4380815c-4632-4308-bf8f-13c47897b162, type={RHQAgent}RHQ Agent, key=foleymonsterbox1.foleyhomenetwork RHQ Agent, name=RHQ Agent, parent=foleymonsterbox1.foleyhomenetwork, version=4.4.0.JON310GA]
>2012-05-03 18:05:47,505 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- New configuration version detected on resource: Resource[id=10017, uuid=32077513-afff-479c-b320-a81e928a890f, type={RHQAgent}Environment Setup Script, key=environment-setup-script, name=rhq-agent-env.sh, parent=RHQ Agent, version=4.4.0.JON310GA]
>2012-05-03 18:05:47,505 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- New configuration version detected on resource: Resource[id=10021, uuid=7832d10f-0032-490f-973d-4c7c767e1db1, type={RHQAgent}VM Class Loading System, key=java.lang:type=ClassLoading, name=Class Loading, parent=JVM]
>2012-05-03 18:05:47,506 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- New configuration version detected on resource: Resource[id=10022, uuid=2886bf8a-744b-4737-8321-e6db30e55091, type={RHQAgent}Threading, key=java.lang:type=Threading, name=Threading, parent=JVM]
>2012-05-03 18:05:47,506 INFO  [ResourceContainer.invoker.daemon-2] (org.rhq.plugins.jmx.MBeanResourceComponent)- Configuration management is currently disabled for the {RHQAgent}java.util.logging Resource with key [java.util.logging:type=Logging] - it can be enabled in the Resource's Inventory > Connection tab.
>2012-05-03 18:05:47,506 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- New configuration version detected on resource: Resource[id=10026, uuid=eb9a095f-acdc-4c86-a4f8-e8c4a77092ed, type={RHQAgent}VM Memory System, key=java.lang:type=Memory, name=Memory Subsystem, parent=JVM]
>2012-05-03 18:05:47,507 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check completed in 10ms
>2012-05-03 18:05:52,624 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:05:52 EDT 2012
>2012-05-03 18:05:52,630 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:05:52 EDT 2012 : Scan [startTime=1336082752624, endTime=1336082752630, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:06:22,630 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:06:22 EDT 2012
>2012-05-03 18:06:22,631 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:06:22 EDT 2012 : Scan [startTime=1336082782630, endTime=1336082782631, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:06:52,631 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:06:52 EDT 2012
>2012-05-03 18:06:52,651 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:06:52 EDT 2012 : Scan [startTime=1336082812631, endTime=1336082812651, runtime=20, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:07:22,651 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:07:22 EDT 2012
>2012-05-03 18:07:22,656 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:07:22 EDT 2012 : Scan [startTime=1336082842651, endTime=1336082842656, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:07:52,657 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:07:52 EDT 2012
>2012-05-03 18:07:52,662 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:07:52 EDT 2012 : Scan [startTime=1336082872656, endTime=1336082872662, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:08:22,662 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:08:22 EDT 2012
>2012-05-03 18:08:22,663 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:08:22 EDT 2012 : Scan [startTime=1336082902662, endTime=1336082902663, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:08:52,664 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:08:52 EDT 2012
>2012-05-03 18:08:52,679 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:08:52 EDT 2012 : Scan [startTime=1336082932664, endTime=1336082932679, runtime=15, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:09:22,679 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:09:22 EDT 2012
>2012-05-03 18:09:22,689 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:09:22 EDT 2012 : Scan [startTime=1336082962679, endTime=1336082962689, runtime=10, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:09:52,689 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:09:52 EDT 2012
>2012-05-03 18:09:52,696 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:09:52 EDT 2012 : Scan [startTime=1336082992689, endTime=1336082992696, runtime=7, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:10:22,696 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:10:22 EDT 2012
>2012-05-03 18:10:22,696 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:10:22 EDT 2012 : Scan [startTime=1336083022696, endTime=1336083022696, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:10:52,697 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:10:52 EDT 2012
>2012-05-03 18:10:52,703 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:10:52 EDT 2012 : Scan [startTime=1336083052697, endTime=1336083052703, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:11:22,704 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:11:22 EDT 2012
>2012-05-03 18:11:22,704 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:11:22 EDT 2012 : Scan [startTime=1336083082704, endTime=1336083082704, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:11:52,705 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:11:52 EDT 2012
>2012-05-03 18:11:52,710 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:11:52 EDT 2012 : Scan [startTime=1336083112705, endTime=1336083112710, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:12:17,494 INFO  [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [15] metrics took 17ms - sending report to Server...
>2012-05-03 18:12:22,711 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:12:22 EDT 2012
>2012-05-03 18:12:22,723 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:12:22 EDT 2012 : Scan [startTime=1336083142711, endTime=1336083142723, runtime=12, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:12:52,723 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:12:52 EDT 2012
>2012-05-03 18:12:52,729 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:12:52 EDT 2012 : Scan [startTime=1336083172723, endTime=1336083172729, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:13:22,729 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:13:22 EDT 2012
>2012-05-03 18:13:22,730 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:13:22 EDT 2012 : Scan [startTime=1336083202729, endTime=1336083202730, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:13:52,730 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:13:52 EDT 2012
>2012-05-03 18:13:52,736 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:13:52 EDT 2012 : Scan [startTime=1336083232730, endTime=1336083232736, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:14:22,736 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:14:22 EDT 2012
>2012-05-03 18:14:22,739 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:14:22 EDT 2012 : Scan [startTime=1336083262736, endTime=1336083262739, runtime=3, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:14:52,739 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:14:52 EDT 2012
>2012-05-03 18:14:52,744 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:14:52 EDT 2012 : Scan [startTime=1336083292739, endTime=1336083292744, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:15:22,744 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:15:22 EDT 2012
>2012-05-03 18:15:22,745 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:15:22 EDT 2012 : Scan [startTime=1336083322744, endTime=1336083322745, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:15:52,745 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:15:52 EDT 2012
>2012-05-03 18:15:52,751 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:15:52 EDT 2012 : Scan [startTime=1336083352745, endTime=1336083352751, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:15:57,803 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:15:57,985 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 18:15:57,985 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:15:58,097 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:16:22,751 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:16:22 EDT 2012
>2012-05-03 18:16:22,752 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:16:22 EDT 2012 : Scan [startTime=1336083382751, endTime=1336083382752, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:16:52,752 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:16:52 EDT 2012
>2012-05-03 18:16:52,775 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:16:52 EDT 2012 : Scan [startTime=1336083412752, endTime=1336083412775, runtime=23, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:17:22,775 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:17:22 EDT 2012
>2012-05-03 18:17:22,777 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:17:22 EDT 2012 : Scan [startTime=1336083442775, endTime=1336083442777, runtime=2, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:17:52,777 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:17:52 EDT 2012
>2012-05-03 18:17:52,782 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:17:52 EDT 2012 : Scan [startTime=1336083472777, endTime=1336083472782, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:18:22,783 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:18:22 EDT 2012
>2012-05-03 18:18:22,783 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:18:22 EDT 2012 : Scan [startTime=1336083502783, endTime=1336083502783, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:18:52,784 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:18:52 EDT 2012
>2012-05-03 18:18:52,798 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:18:52 EDT 2012 : Scan [startTime=1336083532784, endTime=1336083532798, runtime=14, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:19:22,799 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:19:22 EDT 2012
>2012-05-03 18:19:22,808 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:19:22 EDT 2012 : Scan [startTime=1336083562799, endTime=1336083562808, runtime=9, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:19:52,808 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:19:52 EDT 2012
>2012-05-03 18:19:52,814 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:19:52 EDT 2012 : Scan [startTime=1336083592808, endTime=1336083592814, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:20:22,814 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:20:22 EDT 2012
>2012-05-03 18:20:22,815 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:20:22 EDT 2012 : Scan [startTime=1336083622814, endTime=1336083622815, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:20:52,815 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:20:52 EDT 2012
>2012-05-03 18:20:52,821 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:20:52 EDT 2012 : Scan [startTime=1336083652815, endTime=1336083652821, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:21:22,821 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:21:22 EDT 2012
>2012-05-03 18:21:22,822 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:21:22 EDT 2012 : Scan [startTime=1336083682821, endTime=1336083682822, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:21:52,822 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:21:52 EDT 2012
>2012-05-03 18:21:52,828 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:21:52 EDT 2012 : Scan [startTime=1336083712822, endTime=1336083712828, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:22:17,494 INFO  [MeasurementManager.sender-1] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [71] metrics took 63ms - sending report to Server...
>2012-05-03 18:22:22,828 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:22:22 EDT 2012
>2012-05-03 18:22:22,839 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:22:22 EDT 2012 : Scan [startTime=1336083742828, endTime=1336083742839, runtime=11, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:22:52,839 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:22:52 EDT 2012
>2012-05-03 18:22:52,844 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:22:52 EDT 2012 : Scan [startTime=1336083772839, endTime=1336083772844, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:23:22,844 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:23:22 EDT 2012
>2012-05-03 18:23:22,845 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:23:22 EDT 2012 : Scan [startTime=1336083802844, endTime=1336083802845, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:23:52,845 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:23:52 EDT 2012
>2012-05-03 18:23:52,850 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:23:52 EDT 2012 : Scan [startTime=1336083832845, endTime=1336083832850, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:24:22,851 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:24:22 EDT 2012
>2012-05-03 18:24:22,853 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:24:22 EDT 2012 : Scan [startTime=1336083862851, endTime=1336083862852, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:24:52,853 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:24:52 EDT 2012
>2012-05-03 18:24:52,858 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:24:52 EDT 2012 : Scan [startTime=1336083892853, endTime=1336083892858, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:25:22,859 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:25:22 EDT 2012
>2012-05-03 18:25:22,859 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:25:22 EDT 2012 : Scan [startTime=1336083922859, endTime=1336083922859, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:25:52,860 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:25:52 EDT 2012
>2012-05-03 18:25:52,866 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:25:52 EDT 2012 : Scan [startTime=1336083952860, endTime=1336083952866, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:26:22,866 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:26:22 EDT 2012
>2012-05-03 18:26:22,866 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:26:22 EDT 2012 : Scan [startTime=1336083982866, endTime=1336083982866, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:26:52,867 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:26:52 EDT 2012
>2012-05-03 18:26:52,883 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:26:52 EDT 2012 : Scan [startTime=1336084012867, endTime=1336084012883, runtime=16, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:27:22,884 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:27:22 EDT 2012
>2012-05-03 18:27:22,885 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:27:22 EDT 2012 : Scan [startTime=1336084042883, endTime=1336084042885, runtime=2, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:27:52,885 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:27:52 EDT 2012
>2012-05-03 18:27:52,891 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:27:52 EDT 2012 : Scan [startTime=1336084072885, endTime=1336084072891, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:28:22,891 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:28:22 EDT 2012
>2012-05-03 18:28:22,892 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:28:22 EDT 2012 : Scan [startTime=1336084102891, endTime=1336084102892, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:28:52,893 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:28:52 EDT 2012
>2012-05-03 18:28:52,907 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:28:52 EDT 2012 : Scan [startTime=1336084132892, endTime=1336084132906, runtime=14, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:29:22,907 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:29:22 EDT 2012
>2012-05-03 18:29:22,916 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:29:22 EDT 2012 : Scan [startTime=1336084162907, endTime=1336084162916, runtime=9, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:29:52,917 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:29:52 EDT 2012
>2012-05-03 18:29:52,926 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:29:52 EDT 2012 : Scan [startTime=1336084192916, endTime=1336084192926, runtime=10, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:30:22,926 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:30:22 EDT 2012
>2012-05-03 18:30:22,926 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:30:22 EDT 2012 : Scan [startTime=1336084222926, endTime=1336084222926, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:30:52,927 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:30:52 EDT 2012
>2012-05-03 18:30:52,937 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:30:52 EDT 2012 : Scan [startTime=1336084252927, endTime=1336084252937, runtime=10, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:30:58,100 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:30:58,282 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 18:30:58,282 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:30:58,395 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:31:22,938 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:31:22 EDT 2012
>2012-05-03 18:31:22,938 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:31:22 EDT 2012 : Scan [startTime=1336084282938, endTime=1336084282938, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:31:52,939 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:31:52 EDT 2012
>2012-05-03 18:31:52,944 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:31:52 EDT 2012 : Scan [startTime=1336084312938, endTime=1336084312944, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:32:17,494 INFO  [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [16] metrics took 12ms - sending report to Server...
>2012-05-03 18:32:22,945 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:32:22 EDT 2012
>2012-05-03 18:32:22,957 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:32:22 EDT 2012 : Scan [startTime=1336084342945, endTime=1336084342957, runtime=12, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:32:52,957 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:32:52 EDT 2012
>2012-05-03 18:32:52,963 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:32:52 EDT 2012 : Scan [startTime=1336084372957, endTime=1336084372963, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:33:22,964 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:33:22 EDT 2012
>2012-05-03 18:33:22,965 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:33:22 EDT 2012 : Scan [startTime=1336084402964, endTime=1336084402964, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:33:52,965 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:33:52 EDT 2012
>2012-05-03 18:33:52,970 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:33:52 EDT 2012 : Scan [startTime=1336084432965, endTime=1336084432970, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:34:22,971 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:34:22 EDT 2012
>2012-05-03 18:34:22,973 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:34:22 EDT 2012 : Scan [startTime=1336084462970, endTime=1336084462973, runtime=3, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:34:52,973 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:34:52 EDT 2012
>2012-05-03 18:34:52,978 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:34:52 EDT 2012 : Scan [startTime=1336084492973, endTime=1336084492978, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:35:22,979 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:35:22 EDT 2012
>2012-05-03 18:35:22,979 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:35:22 EDT 2012 : Scan [startTime=1336084522979, endTime=1336084522979, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:35:52,980 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:35:52 EDT 2012
>2012-05-03 18:35:52,987 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:35:52 EDT 2012 : Scan [startTime=1336084552980, endTime=1336084552987, runtime=7, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:36:22,988 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:36:22 EDT 2012
>2012-05-03 18:36:22,988 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:36:22 EDT 2012 : Scan [startTime=1336084582988, endTime=1336084582988, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:36:52,988 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:36:52 EDT 2012
>2012-05-03 18:36:53,008 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:36:53 EDT 2012 : Scan [startTime=1336084612988, endTime=1336084613008, runtime=20, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:37:23,009 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:37:23 EDT 2012
>2012-05-03 18:37:23,010 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:37:23 EDT 2012 : Scan [startTime=1336084643009, endTime=1336084643010, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:37:53,011 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:37:53 EDT 2012
>2012-05-03 18:37:53,015 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:37:53 EDT 2012 : Scan [startTime=1336084673010, endTime=1336084673015, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:38:23,016 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:38:23 EDT 2012
>2012-05-03 18:38:23,016 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:38:23 EDT 2012 : Scan [startTime=1336084703015, endTime=1336084703016, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:38:53,017 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:38:53 EDT 2012
>2012-05-03 18:38:53,031 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:38:53 EDT 2012 : Scan [startTime=1336084733017, endTime=1336084733031, runtime=14, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:39:23,031 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:39:23 EDT 2012
>2012-05-03 18:39:23,041 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:39:23 EDT 2012 : Scan [startTime=1336084763031, endTime=1336084763041, runtime=10, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:39:53,041 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:39:53 EDT 2012
>2012-05-03 18:39:53,046 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:39:53 EDT 2012 : Scan [startTime=1336084793041, endTime=1336084793046, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:40:23,046 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:40:23 EDT 2012
>2012-05-03 18:40:23,047 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:40:23 EDT 2012 : Scan [startTime=1336084823046, endTime=1336084823047, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:40:53,047 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:40:53 EDT 2012
>2012-05-03 18:40:53,054 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:40:53 EDT 2012 : Scan [startTime=1336084853047, endTime=1336084853054, runtime=7, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:41:23,054 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:41:23 EDT 2012
>2012-05-03 18:41:23,055 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:41:23 EDT 2012 : Scan [startTime=1336084883054, endTime=1336084883055, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:41:53,055 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:41:53 EDT 2012
>2012-05-03 18:41:53,061 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:41:53 EDT 2012 : Scan [startTime=1336084913055, endTime=1336084913061, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:42:17,494 INFO  [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [69] metrics took 61ms - sending report to Server...
>2012-05-03 18:42:23,061 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:42:23 EDT 2012
>2012-05-03 18:42:23,072 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:42:23 EDT 2012 : Scan [startTime=1336084943061, endTime=1336084943072, runtime=11, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:42:53,072 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:42:53 EDT 2012
>2012-05-03 18:42:53,077 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:42:53 EDT 2012 : Scan [startTime=1336084973072, endTime=1336084973077, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:43:23,078 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:43:23 EDT 2012
>2012-05-03 18:43:23,078 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:43:23 EDT 2012 : Scan [startTime=1336085003078, endTime=1336085003078, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:43:53,079 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:43:53 EDT 2012
>2012-05-03 18:43:53,084 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:43:53 EDT 2012 : Scan [startTime=1336085033079, endTime=1336085033084, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:44:23,084 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:44:23 EDT 2012
>2012-05-03 18:44:23,086 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:44:23 EDT 2012 : Scan [startTime=1336085063084, endTime=1336085063086, runtime=2, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:44:53,087 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:44:53 EDT 2012
>2012-05-03 18:44:53,092 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:44:53 EDT 2012 : Scan [startTime=1336085093086, endTime=1336085093092, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:45:23,092 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:45:23 EDT 2012
>2012-05-03 18:45:23,093 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:45:23 EDT 2012 : Scan [startTime=1336085123092, endTime=1336085123093, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:45:53,093 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:45:53 EDT 2012
>2012-05-03 18:45:53,099 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:45:53 EDT 2012 : Scan [startTime=1336085153093, endTime=1336085153099, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:45:58,399 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 18:45:58,581 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 18:45:58,581 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 18:45:58,694 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 18:46:23,099 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:46:23 EDT 2012
>2012-05-03 18:46:23,099 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:46:23 EDT 2012 : Scan [startTime=1336085183099, endTime=1336085183099, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:46:53,100 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:46:53 EDT 2012
>2012-05-03 18:46:53,118 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:46:53 EDT 2012 : Scan [startTime=1336085213100, endTime=1336085213118, runtime=18, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:47:23,119 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:47:23 EDT 2012
>2012-05-03 18:47:23,121 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:47:23 EDT 2012 : Scan [startTime=1336085243118, endTime=1336085243120, runtime=2, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:47:53,121 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:47:53 EDT 2012
>2012-05-03 18:47:53,126 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:47:53 EDT 2012 : Scan [startTime=1336085273121, endTime=1336085273126, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:48:23,126 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:48:23 EDT 2012
>2012-05-03 18:48:23,127 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:48:23 EDT 2012 : Scan [startTime=1336085303126, endTime=1336085303127, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:48:53,128 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:48:53 EDT 2012
>2012-05-03 18:48:53,141 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:48:53 EDT 2012 : Scan [startTime=1336085333128, endTime=1336085333141, runtime=13, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:49:23,142 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:49:23 EDT 2012
>2012-05-03 18:49:23,151 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:49:23 EDT 2012 : Scan [startTime=1336085363142, endTime=1336085363151, runtime=9, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:49:53,152 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:49:53 EDT 2012
>2012-05-03 18:49:53,158 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:49:53 EDT 2012 : Scan [startTime=1336085393151, endTime=1336085393158, runtime=7, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:50:23,158 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:50:23 EDT 2012
>2012-05-03 18:50:23,158 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:50:23 EDT 2012 : Scan [startTime=1336085423158, endTime=1336085423158, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:50:53,158 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:50:53 EDT 2012
>2012-05-03 18:50:53,168 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:50:53 EDT 2012 : Scan [startTime=1336085453158, endTime=1336085453168, runtime=10, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:51:23,169 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:51:23 EDT 2012
>2012-05-03 18:51:23,169 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:51:23 EDT 2012 : Scan [startTime=1336085483169, endTime=1336085483169, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:51:53,170 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:51:53 EDT 2012
>2012-05-03 18:51:53,175 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:51:53 EDT 2012 : Scan [startTime=1336085513170, endTime=1336085513175, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:52:17,494 INFO  [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [16] metrics took 10ms - sending report to Server...
>2012-05-03 18:52:23,175 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:52:23 EDT 2012
>2012-05-03 18:52:23,187 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:52:23 EDT 2012 : Scan [startTime=1336085543175, endTime=1336085543187, runtime=12, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:52:53,187 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:52:53 EDT 2012
>2012-05-03 18:52:53,193 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:52:53 EDT 2012 : Scan [startTime=1336085573187, endTime=1336085573193, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:53:23,194 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:53:23 EDT 2012
>2012-05-03 18:53:23,194 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:53:23 EDT 2012 : Scan [startTime=1336085603194, endTime=1336085603194, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:53:53,195 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:53:53 EDT 2012
>2012-05-03 18:53:53,200 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:53:53 EDT 2012 : Scan [startTime=1336085633195, endTime=1336085633200, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:54:23,200 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:54:23 EDT 2012
>2012-05-03 18:54:23,203 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:54:23 EDT 2012 : Scan [startTime=1336085663200, endTime=1336085663202, runtime=2, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:54:53,203 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:54:53 EDT 2012
>2012-05-03 18:54:53,208 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:54:53 EDT 2012 : Scan [startTime=1336085693203, endTime=1336085693208, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:55:23,208 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:55:23 EDT 2012
>2012-05-03 18:55:23,209 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:55:23 EDT 2012 : Scan [startTime=1336085723208, endTime=1336085723209, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:55:53,209 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:55:53 EDT 2012
>2012-05-03 18:55:53,215 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:55:53 EDT 2012 : Scan [startTime=1336085753209, endTime=1336085753215, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:56:23,215 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:56:23 EDT 2012
>2012-05-03 18:56:23,216 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:56:23 EDT 2012 : Scan [startTime=1336085783215, endTime=1336085783216, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:56:53,216 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:56:53 EDT 2012
>2012-05-03 18:56:53,238 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:56:53 EDT 2012 : Scan [startTime=1336085813216, endTime=1336085813238, runtime=22, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:57:23,239 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:57:23 EDT 2012
>2012-05-03 18:57:23,241 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:57:23 EDT 2012 : Scan [startTime=1336085843239, endTime=1336085843240, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:57:53,241 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:57:53 EDT 2012
>2012-05-03 18:57:53,246 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:57:53 EDT 2012 : Scan [startTime=1336085873241, endTime=1336085873246, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:58:23,246 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:58:23 EDT 2012
>2012-05-03 18:58:23,247 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:58:23 EDT 2012 : Scan [startTime=1336085903246, endTime=1336085903247, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:58:53,248 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:58:53 EDT 2012
>2012-05-03 18:58:53,262 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:58:53 EDT 2012 : Scan [startTime=1336085933248, endTime=1336085933262, runtime=14, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:59:23,262 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:59:23 EDT 2012
>2012-05-03 18:59:23,271 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:59:23 EDT 2012 : Scan [startTime=1336085963262, endTime=1336085963271, runtime=9, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 18:59:53,272 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 18:59:53 EDT 2012
>2012-05-03 18:59:53,278 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 18:59:53 EDT 2012 : Scan [startTime=1336085993272, endTime=1336085993278, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:00:23,278 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:00:23 EDT 2012
>2012-05-03 19:00:23,279 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:00:23 EDT 2012 : Scan [startTime=1336086023278, endTime=1336086023279, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:00:53,279 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:00:53 EDT 2012
>2012-05-03 19:00:53,297 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:00:53 EDT 2012 : Scan [startTime=1336086053279, endTime=1336086053297, runtime=18, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:00:58,697 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 19:00:58,873 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 19:00:58,873 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 19:00:58,992 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 19:01:23,297 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:01:23 EDT 2012
>2012-05-03 19:01:23,298 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:01:23 EDT 2012 : Scan [startTime=1336086083297, endTime=1336086083298, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:01:53,298 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:01:53 EDT 2012
>2012-05-03 19:01:53,304 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:01:53 EDT 2012 : Scan [startTime=1336086113298, endTime=1336086113304, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:02:17,494 INFO  [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [71] metrics took 65ms - sending report to Server...
>2012-05-03 19:02:23,304 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:02:23 EDT 2012
>2012-05-03 19:02:23,315 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:02:23 EDT 2012 : Scan [startTime=1336086143304, endTime=1336086143315, runtime=11, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:02:53,315 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:02:53 EDT 2012
>2012-05-03 19:02:53,321 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:02:53 EDT 2012 : Scan [startTime=1336086173315, endTime=1336086173321, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:03:23,321 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:03:23 EDT 2012
>2012-05-03 19:03:23,322 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:03:23 EDT 2012 : Scan [startTime=1336086203321, endTime=1336086203321, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:03:53,322 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:03:53 EDT 2012
>2012-05-03 19:03:53,330 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:03:53 EDT 2012 : Scan [startTime=1336086233322, endTime=1336086233330, runtime=8, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:04:23,330 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:04:23 EDT 2012
>2012-05-03 19:04:23,332 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:04:23 EDT 2012 : Scan [startTime=1336086263330, endTime=1336086263332, runtime=2, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:04:53,333 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:04:53 EDT 2012
>2012-05-03 19:04:53,338 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:04:53 EDT 2012 : Scan [startTime=1336086293332, endTime=1336086293338, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:05:23,339 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:05:23 EDT 2012
>2012-05-03 19:05:23,339 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:05:23 EDT 2012 : Scan [startTime=1336086323339, endTime=1336086323339, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:05:47,497 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Starting configuration update check...
>2012-05-03 19:05:47,501 INFO  [ResourceContainer.invoker.daemon-2] (org.rhq.plugins.jmx.MBeanResourceComponent)- Configuration management is currently disabled for the {RHQAgent}java.util.logging Resource with key [java.util.logging:type=Logging] - it can be enabled in the Resource's Inventory > Connection tab.
>2012-05-03 19:05:47,501 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Configuration update check completed in 4ms
>2012-05-03 19:05:53,340 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:05:53 EDT 2012
>2012-05-03 19:05:53,348 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:05:53 EDT 2012 : Scan [startTime=1336086353340, endTime=1336086353348, runtime=8, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:06:23,348 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:06:23 EDT 2012
>2012-05-03 19:06:23,349 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:06:23 EDT 2012 : Scan [startTime=1336086383348, endTime=1336086383349, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:06:53,349 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:06:53 EDT 2012
>2012-05-03 19:06:53,370 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:06:53 EDT 2012 : Scan [startTime=1336086413349, endTime=1336086413370, runtime=21, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:07:23,371 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:07:23 EDT 2012
>2012-05-03 19:07:23,373 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:07:23 EDT 2012 : Scan [startTime=1336086443371, endTime=1336086443372, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:07:53,373 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:07:53 EDT 2012
>2012-05-03 19:07:53,378 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:07:53 EDT 2012 : Scan [startTime=1336086473373, endTime=1336086473378, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:08:23,378 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:08:23 EDT 2012
>2012-05-03 19:08:23,379 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:08:23 EDT 2012 : Scan [startTime=1336086503378, endTime=1336086503379, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:08:53,380 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:08:53 EDT 2012
>2012-05-03 19:08:53,394 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:08:53 EDT 2012 : Scan [startTime=1336086533380, endTime=1336086533394, runtime=14, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:09:23,394 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:09:23 EDT 2012
>2012-05-03 19:09:23,403 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:09:23 EDT 2012 : Scan [startTime=1336086563394, endTime=1336086563403, runtime=9, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:09:53,404 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:09:53 EDT 2012
>2012-05-03 19:09:53,410 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:09:53 EDT 2012 : Scan [startTime=1336086593404, endTime=1336086593410, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:10:23,410 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:10:23 EDT 2012
>2012-05-03 19:10:23,410 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:10:23 EDT 2012 : Scan [startTime=1336086623410, endTime=1336086623410, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:10:53,411 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:10:53 EDT 2012
>2012-05-03 19:10:53,416 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:10:53 EDT 2012 : Scan [startTime=1336086653411, endTime=1336086653416, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=6, numScheduledRandomly=0, numPushedByInterval=5, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:11:23,416 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:11:23 EDT 2012
>2012-05-03 19:11:23,417 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:11:23 EDT 2012 : Scan [startTime=1336086683416, endTime=1336086683417, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:11:24,328 INFO  [WorkerThread#1[192.168.0.111:47750]] (rhq.core.pc.drift.DriftManager)- Recived request to update schedule for DriftDefinition[id: 10001, name: mydrift, resourceId: 10001]
>2012-05-03 19:11:24,329 INFO  [WorkerThread#1[192.168.0.111:47750]] (rhq.core.pc.drift.DriftManager)- No matching schedule was found in the queue. This must be a request to add a new schedule. Adding DriftDetectionSchedule[resourceId: 10001, driftDefinitionId: 10001, driftDefinitionName: mydrift] to ScheduleQueue[]
>2012-05-03 19:11:27,565 INFO  [WorkerThread#1[192.168.0.111:47750]] (rhq.core.pc.drift.DriftManager)- Received request to schedule drift detection immediately for [resourceId: 10001, driftDefinitionId: 10001, driftDefinitionName: mydrift]
>2012-05-03 19:11:53,417 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:11:53 EDT 2012
>2012-05-03 19:11:53,423 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:11:53 EDT 2012 : Scan [startTime=1336086713417, endTime=1336086713423, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:12:17,494 INFO  [MeasurementManager.sender-2] (rhq.core.pc.measurement.MeasurementSenderRunner)- Measurement collection for [16] metrics took 10ms - sending report to Server...
>2012-05-03 19:12:23,424 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:12:23 EDT 2012
>2012-05-03 19:12:23,435 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:12:23 EDT 2012 : Scan [startTime=1336086743424, endTime=1336086743435, runtime=11, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:12:49,131 INFO  [WorkerThread#0[192.168.0.111:47800]] (rhq.core.pc.drift.DriftManager)- Recived request to update schedule for DriftDefinition[id: 0, name: mydrift, resourceId: 10001]
>2012-05-03 19:12:49,131 INFO  [WorkerThread#0[192.168.0.111:47800]] (rhq.core.pc.drift.DriftManager)- DriftDetectionSchedule[resourceId: 10001, driftDefinitionId: 10001, driftDefinitionName: mydrift] has been updated.
>2012-05-03 19:12:53,436 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:12:53 EDT 2012
>2012-05-03 19:12:53,441 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:12:53 EDT 2012 : Scan [startTime=1336086773436, endTime=1336086773441, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:12:57,166 INFO  [WorkerThread#0[192.168.0.111:47800]] (rhq.core.pc.drift.DriftManager)- Received request to schedule drift detection immediately for [resourceId: 10001, driftDefinitionId: 10001, driftDefinitionName: mydrift]
>2012-05-03 19:12:57,166 WARN  [WorkerThread#0[192.168.0.111:47800]] (rhq.core.pc.drift.DriftManager)- No schedule found in the queue for [resourceId: 10001, driftDefinitionId: 10001, driftDefinitionName: mydrift]. No  work will be scheduled.
>2012-05-03 19:13:23,442 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:13:23 EDT 2012
>2012-05-03 19:13:23,443 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:13:23 EDT 2012 : Scan [startTime=1336086803442, endTime=1336086803442, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:13:53,443 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:13:53 EDT 2012
>2012-05-03 19:13:53,448 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:13:53 EDT 2012 : Scan [startTime=1336086833443, endTime=1336086833448, runtime=5, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=4, numScheduledRandomly=0, numPushedByInterval=3, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:14:23,448 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:14:23 EDT 2012
>2012-05-03 19:14:23,451 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:14:23 EDT 2012 : Scan [startTime=1336086863448, endTime=1336086863451, runtime=3, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=3, numScheduledRandomly=0, numPushedByInterval=2, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:14:53,451 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:14:53 EDT 2012
>2012-05-03 19:14:53,459 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:14:53 EDT 2012 : Scan [startTime=1336086893451, endTime=1336086893458, runtime=7, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:15:23,459 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:15:23 EDT 2012
>2012-05-03 19:15:23,460 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:15:23 EDT 2012 : Scan [startTime=1336086923459, endTime=1336086923459, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=2, numScheduledRandomly=0, numPushedByInterval=1, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:15:53,460 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:15:53 EDT 2012
>2012-05-03 19:15:53,466 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:15:53 EDT 2012 : Scan [startTime=1336086953460, endTime=1336086953466, runtime=6, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=5, numScheduledRandomly=0, numPushedByInterval=4, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:15:58,996 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2012-05-03 19:15:59,183 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2012-05-03 19:15:59,183 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2012-05-03 19:15:59,296 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 19:15:59,336 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Starting server sync for drift definitions...
>2012-05-03 19:15:59,352 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Finished server sync for drift definitions. 0 added and 0 deleted in 16 ms
>2012-05-03 19:15:59,352 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Starting drift content sync...
>2012-05-03 19:15:59,352 INFO  [InventoryManager.discovery-1] (core.pc.drift.sync.DriftSyncManager)- Finished drift content sync in 0 ms
>2012-05-03 19:15:59,353 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:15:59 EDT 2012
>2012-05-03 19:15:59,353 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:15:59 EDT 2012 : Scan [startTime=1336086959353, endTime=1336086959353, runtime=0, isFull=true, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:15:59,354 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2012-05-03 19:16:04,353 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2012-05-03 19:16:04,353 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:16:04 EDT 2012
>2012-05-03 19:16:04,354 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:16:04 EDT 2012 : Scan [startTime=1336086964353, endTime=1336086964354, runtime=1, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:16:04,573 INFO  [ResourceDiscoveryComponent.invoker.daemon-7] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2012-05-03 19:16:04,574 INFO  [ResourceDiscoveryComponent.invoker.daemon-7] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2012-05-03 19:16:04,575 INFO  [ResourceDiscoveryComponent.invoker.daemon-7] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2012-05-03 19:16:04,609 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 1 server(s) and discovered 147 new descendant Resource(s).
>2012-05-03 19:16:04,609 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2012-05-03 19:16:06,570 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Syncing local inventory with Server inventory...
>2012-05-03 19:16:23,467 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:16:23 EDT 2012
>2012-05-03 19:16:23,467 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:16:23 EDT 2012 : Scan [startTime=1336086983467, endTime=1336086983467, runtime=0, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=1, numScheduledRandomly=0, numPushedByInterval=0, numAvailabilityChanges=0, numDeferToParent=0]
>2012-05-03 19:16:53,467 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Starting: Thu May 03 19:16:53 EDT 2012
>2012-05-03 19:16:53,483 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.AvailabilityExecutor)- Scan Ended   : Thu May 03 19:16:53 EDT 2012 : Scan [startTime=1336087013467, endTime=1336087013483, runtime=16, isFull=false, isForced=false, numResources=35, numGetAvailabilityCalls=13, numScheduledRandomly=0, numPushedByInterval=12, numAvailabilityChanges=0, numDeferToParent=0]

Actions: View

Attachments on bug 818773: 581985 | 581986