Attachment 876558 Details for Bug 1078479 – agent.log

agent.log

agent.log (text/x-log), 14.93 KB, created by Armine Hovsepyan on 2014-03-19 18:33:01 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Armine Hovsepyan

Created: 2014-03-19 18:33:01 UTC

Size: 14.93 KB

patch

obsolete

>2014-03-19 14:16:13,567 ERROR [main] (rhq.core.pc.inventory.InventoryManager)- Resource activation or upgrade failed with an exception. An attempt to merely activate the resources will be made now.
>java.lang.IllegalStateException: The sender object is currently not sending commands now. Command not sent: [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.timeout=0, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[mergeInventoryReport], targetInterfaceName=org.rhq.core.clientapi.server.discovery.DiscoveryServerService}]]
>	at org.rhq.enterprise.communications.command.client.ClientCommandSender.sendSynch(ClientCommandSender.java:631)
>	at org.rhq.enterprise.communications.command.client.ClientRemotePojoFactory$RemotePojoProxyHandler.invoke(ClientRemotePojoFactory.java:418)
>	at com.sun.proxy.$Proxy2.mergeInventoryReport(Unknown Source)
>	at org.rhq.core.pc.inventory.InventoryManager.handleReport(InventoryManager.java:1142)
>	at org.rhq.core.pc.inventory.InventoryManager.activateAndUpgradeResources(InventoryManager.java:3395)
>	at org.rhq.core.pc.inventory.InventoryManager.initialize(InventoryManager.java:256)
>	at org.rhq.core.pc.PluginContainer.startContainerService(PluginContainer.java:513)
>	at org.rhq.core.pc.PluginContainer.initialize(PluginContainer.java:327)
>	at org.rhq.enterprise.agent.AgentMain.startPluginContainer(AgentMain.java:1959)
>	at org.rhq.enterprise.agent.AgentMain.start(AgentMain.java:688)
>	at org.rhq.enterprise.agent.AgentMain.main(AgentMain.java:429)
>2014-03-19 14:16:13,603 INFO  [ResourceContainer.invoker.daemon-1] (org.rhq.plugins.platform.LinuxPlatformComponent)- Internal yum server is disabled.
>2014-03-19 14:16:14,180 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2014-03-19 14:16:14,261 INFO  [ResourceContainer.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Found 816 MBeans - starting load...
>2014-03-19 14:16:15,264 INFO  [ResourceContainer.invoker.daemon-3] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2014-03-19 14:16:15,264 INFO  [ResourceContainer.invoker.daemon-3] (ems.impl.jmx.connection.DConnection)- Found 29 MBeans - starting load...
>2014-03-19 14:16:15,325 INFO  [ResourceContainer.invoker.daemon-3] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2014-03-19 14:16:15,325 INFO  [ResourceContainer.invoker.daemon-3] (ems.impl.jmx.connection.DConnection)- Found 29 MBeans - starting load...
>2014-03-19 14:16:16,737 INFO  [ResourceContainer.invoker.daemon-3] (rhq.modules.plugins.jbossas7.BaseServerComponent)- ResourceType[id=0, name=JBossAS7 Standalone Server, plugin=JBossAS7, category=Server] [hostConfig: /home/hudson/jon-server-3.2.0.GA/jbossas/standalone/configuration/standalone-full.xml] has just come UP.
>2014-03-19 14:16:17,640 INFO  [ResourceContainer.invoker.daemon-3] (org.rhq.plugins.server.JBossAS7JMXComponent)- Loading AS7 connection [service:jmx:remoting-jmx://127.0.0.1:6999] with install path [null]...
>2014-03-19 14:16:17,684 INFO  [ResourceContainer.invoker.daemon-3] (org.xnio)- XNIO Version 3.0.7.GA-redhat-1
>2014-03-19 14:16:17,701 INFO  [ResourceContainer.invoker.daemon-3] (org.xnio.nio)- XNIO NIO Implementation Version 3.0.7.GA-redhat-1
>2014-03-19 14:16:17,716 INFO  [ResourceContainer.invoker.daemon-3] (org.jboss.remoting)- JBoss Remoting version 3.2.16.GA-redhat-1
>2014-03-19 14:16:18,271 INFO  [main] (rhq.core.pc.inventory.InventoryManager)- Inventory Manager initialized.
>2014-03-19 14:16:18,272 INFO  [main] (rhq.core.pc.measurement.MeasurementManager)- Initializing Measurement Manager...
>2014-03-19 14:16:18,364 INFO  [main] (rhq.core.pc.measurement.MeasurementManager)- Measurement Manager initialized.
>2014-03-19 14:16:18,370 INFO  [main] (rhq.core.pc.content.ContentManager)- Initializing Content Manager...
>2014-03-19 14:16:18,371 INFO  [main] (rhq.core.pc.content.ContentManager)- Initializing scheduled content discovery...
>2014-03-19 14:16:18,374 INFO  [main] (rhq.core.pc.content.ContentManager)- Content Manager initialized...
>2014-03-19 14:16:18,395 INFO  [main] (rhq.core.pc.drift.DriftManager)- Finished initializing drift detection schedules in 15 ms
>2014-03-19 14:16:18,395 INFO  [main] (rhq.core.pc.drift.DriftManager)- Scanning for change set content to resend...
>2014-03-19 14:16:18,395 INFO  [main] (rhq.core.pc.drift.DriftManager)- Checking for deleted drift definitions
>2014-03-19 14:16:18,396 INFO  [main] (rhq.core.pc.drift.DriftManager)- Finished initialization in 21 ms
>2014-03-19 14:16:18,396 INFO  [main] (org.rhq.core.pc.PluginContainer)- Plugin Container initialized.
>2014-03-19 14:16:18,399 INFO  [RHQ Primary Server Switchover Thread] (org.rhq.enterprise.agent.AgentMain)- {PrimaryServerSwitchoverThread.started}The primary server switchover thread has started.
>2014-03-19 14:16:28,272 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Executing server discovery scan...
>2014-03-19 14:16:29,086 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Discovered 0 new server(s).
>2014-03-19 14:16:29,086 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [server] inventory report to Server...
>2014-03-19 14:16:29,087 WARN  [InventoryManager.discovery-1] (rhq.core.pc.inventory.AutoDiscoveryExecutor)- Exception caught while executing server discovery scan.
>java.lang.IllegalStateException: The sender object is currently not sending commands now. Command not sent: [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.timeout=0, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[mergeInventoryReport], targetInterfaceName=org.rhq.core.clientapi.server.discovery.DiscoveryServerService}]]
>	at org.rhq.enterprise.communications.command.client.ClientCommandSender.sendSynch(ClientCommandSender.java:631)
>	at org.rhq.enterprise.communications.command.client.ClientRemotePojoFactory$RemotePojoProxyHandler.invoke(ClientRemotePojoFactory.java:418)
>	at com.sun.proxy.$Proxy2.mergeInventoryReport(Unknown Source)
>	at org.rhq.core.pc.inventory.InventoryManager.handleReport(InventoryManager.java:1142)
>	at org.rhq.core.pc.inventory.AutoDiscoveryExecutor.call(AutoDiscoveryExecutor.java:130)
>	at org.rhq.core.pc.inventory.AutoDiscoveryExecutor.run(AutoDiscoveryExecutor.java:92)
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
>	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>	at java.lang.Thread.run(Thread.java:744)
>2014-03-19 14:16:38,272 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Executing runtime discovery scan rooted at [platform]...
>2014-03-19 14:16:38,304 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (ems.impl.jmx.connection.DConnection)- Querying MBeanServer for all MBeans...
>2014-03-19 14:16:38,730 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentEnvironmentScriptDiscoveryComponent)- Discovering RHQ Agent's environment setup script...
>2014-03-19 14:16:38,731 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentLauncherScriptDiscoveryComponent)- Discovering RHQ Agent's launcher script service...
>2014-03-19 14:16:38,734 INFO  [ResourceDiscoveryComponent.invoker.daemon-1] (org.rhq.plugins.agent.AgentJavaServiceWrapperDiscoveryComponent)- Discovering RHQ Agent's JSW service...
>2014-03-19 14:16:40,398 ERROR [ResourceDiscoveryComponent.invoker.daemon-3] (org.modeshape.rhq.ModeShapePlugin)- No ModeShape Engine discovered
>2014-03-19 14:16:46,717 WARN  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Failure during discovery for [Query Cache] Resources - failed after 65 ms.
>java.lang.Exception: Discovery component invocation failed.
>	at org.rhq.core.pc.util.DiscoveryComponentProxyFactory$ComponentInvocationThread.call(DiscoveryComponentProxyFactory.java:309)
>	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>	at java.lang.Thread.run(Thread.java:744)
>Caused by: java.lang.IllegalArgumentException: Plugin error: Resource key [deployment=rhq.ear,subdeployment=rhq-core-domain-ejb3.jar,subsystem=jpa,hibernate-persistence-unit=rhq.ear/rhq-core-domain-ejb3.jar#rhqpu,query-cache=SELECT_space_COUNT_left_paren__star__right_paren__newline_FROM_space_AlertDefinition_space_alertdefinition_newline_WHERE_space__left_paren__space_alertdefinition.resourceType.id_space__equal__space__colon_alertTemplateResourceTypeId_space__newline_AND_space_LOWER_left_paren__space_alertdefinition.name_space__right_paren__space_like_space__colon_name_space_ESCAPE_space__quote_\\_quote__space__space__newline_AND_space_alertdefinition.deleted_space__equal__space__colon_deleted_space__right_paren__newline_] specified by [ResourceType[id=0, name=Query Cache, plugin=JBossAS7, category=Service]] discovery component is longer than the maximum length (500).
>	at org.rhq.core.pluginapi.inventory.DiscoveredResourceDetails.setResourceKey(DiscoveredResourceDetails.java:143)
>	at org.rhq.core.pluginapi.inventory.DiscoveredResourceDetails.<init>(DiscoveredResourceDetails.java:95)
>	at org.rhq.modules.plugins.jbossas7.SubsystemDiscovery.discoverResources(SubsystemDiscovery.java:158)
>	at sun.reflect.GeneratedMethodAccessor49.invoke(Unknown Source)
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>	at java.lang.reflect.Method.invoke(Method.java:606)
>	at org.rhq.core.pc.util.DiscoveryComponentProxyFactory$ComponentInvocationThread.call(DiscoveryComponentProxyFactory.java:305)
>	... 4 more
>2014-03-19 14:16:48,838 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2014-03-19 14:16:48,839 WARN  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Could not transmit availability report to server
>java.lang.IllegalStateException: The sender object is currently not sending commands now. Command not sent: [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[mergeAvailabilityReport], targetInterfaceName=org.rhq.core.clientapi.server.discovery.DiscoveryServerService}]]
>	at org.rhq.enterprise.communications.command.client.ClientCommandSender.sendSynch(ClientCommandSender.java:631)
>	at org.rhq.enterprise.communications.command.client.ClientRemotePojoFactory$RemotePojoProxyHandler.invoke(ClientRemotePojoFactory.java:418)
>	at com.sun.proxy.$Proxy2.mergeAvailabilityReport(Unknown Source)
>	at org.rhq.core.pc.inventory.InventoryManager.handleReport(InventoryManager.java:1100)
>	at org.rhq.core.pc.inventory.AvailabilityExecutor.run(AvailabilityExecutor.java:112)
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
>	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>	at java.lang.Thread.run(Thread.java:744)
>2014-03-19 14:16:52,400 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Scanned platform and 0 server(s) and discovered 0 new descendant Resource(s).
>2014-03-19 14:16:52,400 INFO  [InventoryManager.discovery-1] (rhq.core.pc.inventory.InventoryManager)- Sending [runtime] inventory report to Server...
>2014-03-19 14:16:52,400 WARN  [InventoryManager.discovery-1] (rhq.core.pc.inventory.RuntimeDiscoveryExecutor)- Exception caught while executing runtime discovery scan rooted at [platform].
>java.lang.IllegalStateException: The sender object is currently not sending commands now. Command not sent: [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.timeout=0, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[mergeInventoryReport], targetInterfaceName=org.rhq.core.clientapi.server.discovery.DiscoveryServerService}]]
>	at org.rhq.enterprise.communications.command.client.ClientCommandSender.sendSynch(ClientCommandSender.java:631)
>	at org.rhq.enterprise.communications.command.client.ClientRemotePojoFactory$RemotePojoProxyHandler.invoke(ClientRemotePojoFactory.java:418)
>	at com.sun.proxy.$Proxy2.mergeInventoryReport(Unknown Source)
>	at org.rhq.core.pc.inventory.InventoryManager.handleReport(InventoryManager.java:1142)
>	at org.rhq.core.pc.inventory.RuntimeDiscoveryExecutor.call(RuntimeDiscoveryExecutor.java:126)
>	at org.rhq.core.pc.inventory.RuntimeDiscoveryExecutor.run(RuntimeDiscoveryExecutor.java:93)
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
>	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
>	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
>	at java.lang.Thread.run(Thread.java:744)
>2014-03-19 14:16:56,836 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=naVYYp9A7U2EuOQVwHOQAl4qdTn8EbbmXUu1owkNrbOsBeagIR9MMbH4VxcmlL7W2OU=]]
>2014-03-19 14:16:57,117 WARN  [ClientCommandSenderTask Timer Thread #0] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.server-thinks-agent-is-down}The server thinks this agent is down. Will notify the server of up-to-date information when possible.
>2014-03-19 14:16:57,146 INFO  [WorkerThread#0[10.16.23.173:59436]] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.updating-complete}Completed updating the plugins to their latest versions.
>2014-03-19 14:17:18,397 INFO  [RHQ Agent Ping Thread-1] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.ping-executor.stop-polling-resume-ping}Stopping polling and resuming pinging
>2014-03-19 14:17:19,082 INFO  [InventoryManager.availability-1] (rhq.core.pc.inventory.InventoryManager)- Sending availability report to Server...
>2014-03-19 14:21:18,369 INFO  [ConfigurationManager.threadpool-1] (rhq.core.pc.configuration.ConfigurationCheckExecutor)- Starting configuration update check...
>

Actions: View

Attachments on bug 1078479: 876558