Attachment 575321 Details for Bug 810124 – excerpt of agent.log illustrating the issue

excerpt of agent.log illustrating the issue

logs-excerpt.log (text/plain), 10.02 KB, created by Lukas Krejci on 2012-04-05 08:35:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Lukas Krejci

Created: 2012-04-05 08:35:51 UTC

Size: 10.02 KB

patch

obsolete

>2012-03-29 00:40:58,986 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.processor-ready}Command processor is now ready to begin accepting commands from clients
>2012-03-29 00:40:58,986 INFO  [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.started}Service container started - ready to accept incoming commands
>2012-03-29 00:40:58,987 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ServerPollingThread)- {ServerPollingThread.started}Server polling thread has started and will poll for server status every [60,000] milliseconds
>2012-03-29 00:40:58,988 DEBUG [main] (enterprise.communications.command.client.ServerPollingThread)- {ServerPollingThread.added-polling-listener}Added the polling listener [org.rhq.enterprise.agent.AgentMain$ClockCheckPollingListener@72e8a021]
>2012-03-29 00:40:59,016 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$RegisterStateListener@6e92b1a1]; sender is sending=[false]; notify listener immediately=[true]
>2012-03-29 00:40:59,017 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$UpdatePluginsStateListener@77546dbc]; sender is sending=[false]; notify listener immediately=[true]
>2012-03-29 00:40:59,018 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$PluginContainerConditionalRestartListener@6da5db4b]; sender is sending=[false]; notify listener immediately=[false]
>2012-03-29 00:40:59,019 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.waiting-for-server}The agent is waiting for the server - will sleep for [60,000] milliseconds
>2012-03-29 00:40:59,019 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@5bf0aecc]; sender is sending=[false]; notify listener immediately=[true]
>2012-03-29 00:40:59,411 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.init-callback-done}The initialize callback has finished. Callback to be invoked again? [false]
>2012-03-29 00:40:59,428 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.loading-command-spool}Loading [0] commands from the spool file and queueing them up to be sent to [remoting endpoint [sslservlet://myjonserver1:7443/jboss-remoting-servlet-invoker/ServerInvokerServlet]]
>2012-03-29 00:40:59,428 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.unspooled}[0] commands were unspooled and will be resent to [remoting endpoint [sslservlet://myjonserver1:7443/jboss-remoting-servlet-invoker/ServerInvokerServlet]]
>2012-03-29 00:40:59,428 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.sending}Client is now going to actively send commands
>2012-03-29 00:40:59,428 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.notifying-state-listeners}Notifying command client sender state listeners of a state change - sender is sending=[true]
>2012-03-29 00:41:00,442 DEBUG [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.agent-registration-attempt}Agent will now attempt to register with the server [AgentRegistrationRequest: [name=[myagent1]; address=[192.168.0.2]; port=[19000]; remote-endpoint=[sslsocket://192.168.0.2:19000/?rhq.communications.connector.rhqtype=agent&numAcceptThreads=1&maxPoolSize=303&clientMaxPoolSize=304&socketTimeout=60000&enableTcpNoDelay=true&backlog=200]; regenerate-token=[false]; original-token=[<was not null>]; agent-version=[4.2.0.JON300.GA(9f35d78)]]
>2012-03-29 00:41:00,448 DEBUG [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor)- {SecurityTokenCommandPreprocessor.no-security-token-yet}There is no security token yet - the server will not accept commands from this agent until the agent is registered.
>2012-03-29 00:41:59,032 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.removed-state-listener}Removed the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@5bf0aecc]
>2012-03-29 00:41:59,050 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.waiting-to-be-registered-begin}The agent will now wait until it has registered with the server...
>2012-03-29 00:41:59,442 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.removed-state-listener}Removed the command client sender state listener [org.rhq.enterprise.agent.AgentMain$RegisterStateListener@6e92b1a1]
>2012-03-29 00:41:59,447 DEBUG [RHQ Server Polling Thread] (org.rhq.enterprise.agent.PluginUpdate)- {PluginUpdate.updating}Updating plugins to their latest versions.
>2012-03-29 00:41:59,569 DEBUG [RHQ Server Polling Thread] (org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor)- {SecurityTokenCommandPreprocessor.no-security-token-yet}There is no security token yet - the server will not accept commands from this agent until the agent is registered.
>2012-03-29 00:41:59,610 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientRemotePojoFactory)- {ClientRemotePojoFactory.execution-failure}Failed to execute remote POJO method [getLatestPlugins]. 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=myagent1, rhq.externalizable-strategy=AGENT, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getLatestPlugins], targetInterfaceName=org.rhq.core.clientapi.server.core.CoreServerService}]
>2012-03-29 00:41:59,611 WARN  [RHQ Server Polling Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.plugin-update-failure}Failed to update the plugins.. 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=myagent1, rhq.externalizable-strategy=AGENT, rhq.send-throttle=true}]; params=[{invocation=NameBasedInvocation[getLatestPlugins], targetInterfaceName=org.rhq.core.clientapi.server.core.CoreServerService}]
>2012-03-29 00:41:59,611 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.removed-state-listener}Removed the command client sender state listener [org.rhq.enterprise.agent.AgentMain$UpdatePluginsStateListener@77546dbc]
>2012-03-29 00:41:59,611 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-03-29 00:42:59,622 DEBUG [RHQ Server Polling Thread] (org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor)- {SecurityTokenCommandPreprocessor.no-security-token-yet}There is no security token yet - the server will not accept commands from this agent until the agent is registered.
>2012-03-29 00:43:59,668 DEBUG [RHQ Server Polling Thread] (org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor)- {SecurityTokenCommandPreprocessor.no-security-token-yet}There is no security token yet - the server will not accept commands from this agent until the agent is registered.
>2012-03-29 00:44:59,708 DEBUG [RHQ Server Polling Thread] (org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor)- {SecurityTokenCommandPreprocessor.no-security-token-yet}There is no security token yet - the server will not accept commands from this agent until the agent is registered.
>2012-03-29 00:45:59,751 DEBUG [RHQ Server Polling Thread] (org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor)- {SecurityTokenCommandPreprocessor.no-security-token-yet}There is no security token yet - the server will not accept commands from this agent until the agent is registered.
>2012-03-29 00:46:59,799 DEBUG [RHQ Server Polling Thread] (org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor)- {SecurityTokenCommandPreprocessor.no-security-token-yet}There is no security token yet - the server will not accept commands from this agent until the agent is registered.
>2012-03-29 00:47:18,789 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=hzdpky+hrQvYnMTekXy7D1K7r9bopoLyV2hTidaXGEDFP75oz0KK3Y4Zrahy9yRabEU=]]
>2012-03-29 00:47:18,799 DEBUG [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {SecurityTokenCommandPreprocessor.new-security-token}A new security token has been persisted: hzdpky+hrQvYnMTekXy7D1K7r9bopoLyV2hTidaXGEDFP75oz0KK3Y4Zrahy9yRabEU=
>2012-03-29 00:47:18,801 DEBUG [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.failover-list-persisted}The server failover list has been persisted to [data/failover-list.dat]
>2012-03-29 00:48:02,626 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.waiting-to-be-registered-end}Done! The agent is now registered with the server.
>2012-03-29 00:48:02,629 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.creating-plugin-container-server-services}Creating server service client end points.
>2012-03-29 00:48:04,697 INFO  [main] (org.rhq.core.pc.PluginContainer)- Initializing Plugin Container v4.2.0.JON300.GA...

Actions: View

Attachments on bug 810124: 575321