Attachment 927269 Details for Bug 1130662 – agent.log

agent.log

agent.log (text/plain), 32.51 KB, created by Viet Nguyen on 2014-08-15 20:17:08 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Viet Nguyen

Created: 2014-08-15 20:17:08 UTC

Size: 32.51 KB

patch

obsolete

>2014-08-12 19:51:24,126 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.java-logging-start}Reconfiguring Java Logging...
>2014-08-12 19:51:24,137 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.java-logging-done}Done reconfiguring Java Logging.
>2014-08-12 19:51:24,144 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.creating-agent}Agent being created now
>2014-08-12 19:51:24,198 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.preferences-already-exist}Found preferences already loaded in persisted store; using those for the configuration
>2014-08-12 19:51:24,206 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.preferences-schema}Preferences configuration schema: [9]
>2014-08-12 19:51:24,206 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.preferences-node-path}Preferences node path where configuration is persisted: [/rhq-agent/default]
>2014-08-12 19:51:24,311 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.configuration}Agent configuration: /rhq-agent/default[rhq.agent.agent-update.enabled=false,rhq.agent.client.command-preprocessors=org.rhq.enterprise.agent.SecurityTokenCommandPreprocessor:org.rhq.enterprise.agent.ExternalizableStrategyCommandPreprocessor,rhq.agent.client.command-spool-file.compressed=true,rhq.agent.client.command-spool-file.name=command-spool.dat,rhq.agent.client.command-spool-file.params=10000000:75,rhq.agent.client.command-timeout-msecs=600000,rhq.agent.client.max-concurrent=5,rhq.agent.client.max-retries=10,rhq.agent.client.queue-size=50000,rhq.agent.client.queue-throttling=200:2000,rhq.agent.client.retry-interval-msecs=15000,rhq.agent.client.send-throttling=100:1000,rhq.agent.client.server-polling-interval-msecs=60000,rhq.agent.configuration-schema-version=9,rhq.agent.configuration-setup-flag=true,rhq.agent.data-directory=data,rhq.agent.disable-native-system=false,rhq.agent.plugins.directory=plugins,rhq.agent.primary-server-switchover-check-interval-msecs=3600000,rhq.agent.register-with-server-at-startup=true,rhq.agent.server-auto-detection=false,rhq.agent.server.alias=rhqserver,rhq.agent.server.bind-address=172.17.0.50,rhq.agent.server.bind-port=7080,rhq.agent.server.transport=servlet,rhq.agent.server.transport-params=/jboss-remoting-servlet-invoker/ServerInvokerServlet,rhq.agent.test-failover-list-at-startup=true,rhq.agent.update-plugins-at-startup=true,rhq.agent.vm-health-check.interval-msecs=5000,rhq.agent.vm-health-check.low-heap-mem-threshold=0.90,rhq.agent.vm-health-check.low-nonheap-mem-threshold=0.90,rhq.agent.wait-for-server-at-startup-msecs=60000,rhq.communications.command-authenticator=org.rhq.enterprise.agent.SecurityTokenCommandAuthenticator,rhq.communications.command-service-directory.allow-dynamic-discovery=true,rhq.communications.command-services=org.rhq.enterprise.communications.command.impl.echo.server.EchoCommandService, org.rhq.enterprise.communications.command.impl.identify.server.IdentifyCommandService,rhq.communications.configuration-schema-version=1,rhq.communications.connector.bind-port=16163,rhq.communications.connector.rhqtype=agent,rhq.communications.connector.transport=socket,rhq.communications.data-directory=data,rhq.communications.global-concurrency-limit=-1,rhq.communications.multicast-detector.bind-address=0.0.0.0,rhq.communications.multicast-detector.default-time-delay=5000,rhq.communications.multicast-detector.enabled=false,rhq.communications.multicast-detector.heartbeat-time-delay=1000,rhq.communications.multicast-detector.multicast-address=224.16.16.16,rhq.communications.multicast-detector.port=16162,rhq.communications.remote-pojos=org.rhq.enterprise.communications.PingImpl:org.rhq.enterprise.communications.Ping,rhq.communications.remote-stream-max-idle-time-msecs=300000]
>2014-08-12 19:51:24,324 DEBUG [main] (enterprise.communications.util.prefs.PreferencesUpgrade)- {PreferencesUpgrade.already-uptodate}The preferences configuration [rhq.agent.configuration-schema-version] is up-to-date at version [9]
>2014-08-12 19:51:24,324 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.args-processed}Agent container has processed its command line arguments: [--daemon]
>2014-08-12 19:51:24,519 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.identify-version}Version=[RHQ 4.12.0.JON330DR01], Build Number=[dda0a47], Build Date=[Jul 30, 2014 3:03 PM]
>2014-08-12 19:51:24,537 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.agent-name-auto-generated}The name of this agent was not predefined so it was auto-generated. The agent name is now [6b65b59f831b]
>2014-08-12 19:51:24,539 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentConfiguration.cert-file-location}Explicitly setting file location [rhq.communications.connector.security.keystore.file] to [/opt/rhq-agent/conf/keystore.dat]
>2014-08-12 19:51:24,539 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentConfiguration.cert-file-location}Explicitly setting file location [rhq.communications.connector.security.truststore.file] to [/opt/rhq-agent/conf/truststore.dat]
>2014-08-12 19:51:24,539 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentConfiguration.cert-file-location}Explicitly setting file location [rhq.agent.client.security.keystore.file] to [/opt/rhq-agent/conf/keystore.dat]
>2014-08-12 19:51:24,539 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentConfiguration.cert-file-location}Explicitly setting file location [rhq.agent.client.security.truststore.file] to [/opt/rhq-agent/conf/truststore.dat]
>2014-08-12 19:51:24,578 DEBUG [main] (org.jboss.logging)- Logging Provider: org.jboss.logging.Log4jLoggerProvider
>2014-08-12 19:51:24,580 DEBUG [main] (org.jboss.remoting)- Remoting version: 2.5.4.SP5 (Flounder)
>2014-08-12 19:51:24,589 DEBUG [main] (enterprise.communications.command.client.CommandQueue)- {CommandQueue.configured}Queue throttling is configured for [200] max commands in burst periods of [2,000] milliseconds.  These will take effect when queue throttling is enabled.
>2014-08-12 19:51:24,590 DEBUG [main] (enterprise.communications.command.client.CommandQueue)- {CommandQueue.enabled}Command Queue is now throttling - a maximum of [200] commands are allowed to be dequeued every [2,000] milliseconds
>2014-08-12 19:51:24,591 DEBUG [main] (enterprise.communications.command.client.SendThrottle)- {SendThrottle.configured}Send throttling is configured for [100] max commands and quiet periods of [1,000] milliseconds. These will take effect while send throttling is enabled.
>2014-08-12 19:51:24,595 DEBUG [main] (enterprise.communications.command.client.SendThrottle)- {SendThrottle.enabled}Send throttling is now enabled - a maximum of [100] commands are allowed to be sent in between quiet periods of [1,000] milliseconds.
>2014-08-12 19:51:24,612 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$CommandListenerStateListener@18019c40]; sender is sending=[false]; notify listener immediately=[true]
>2014-08-12 19:51:24,614 DEBUG [main] (enterprise.communications.util.prefs.PreferencesUpgrade)- {PreferencesUpgrade.already-uptodate}The preferences configuration [rhq.communications.configuration-schema-version] is up-to-date at version [1]
>2014-08-12 19:51:24,615 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
>2014-08-12 19:51:24,686 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.using-existing-mbs}Found an existing MBeanServer [DefaultDomain] - will use that
>2014-08-12 19:51:24,730 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.registry-created}Network registry has been created [rhq.remoting:type=networkregistry]
>2014-08-12 19:51:24,730 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.multicast-detector-disabled}Multicast detector has not been enabled in configuration - it will not be started and auto-discovery will not be supported
>2014-08-12 19:51:24,733 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.added-command-service}Added command service [org.rhq.enterprise.communications.command.impl.echo.server.EchoCommandService] with an id of [1]
>2014-08-12 19:51:24,735 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.added-command-service}Added command service [org.rhq.enterprise.communications.command.impl.identify.server.IdentifyCommandService] with an id of [2]
>2014-08-12 19:51:24,738 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.added-command-service}Added command service [org.rhq.enterprise.communications.command.impl.remotepojo.server.RemotePojoInvocationCommandService] with an id of [3]
>2014-08-12 19:51:24,738 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.added-remote-pojo}Added remote POJO of type [org.rhq.enterprise.communications.PingImpl] with a remote interface of [org.rhq.enterprise.communications.Ping]
>2014-08-12 19:51:24,740 DEBUG [main] (enterprise.communications.command.server.CommandServiceDirectory)- {CommandServiceDirectory.dynamic-discovery-allowed}Command Service Directory has been allowed to perform dynamic discovery
>2014-08-12 19:51:24,755 DEBUG [main] (enterprise.communications.command.server.CommandServiceDirectory)- {CommandServiceDirectory.new-supported-command}Command [echo] is now supported by [rhq.remoting:type=command,subsystem=RHQ,id=1]
>2014-08-12 19:51:24,756 DEBUG [main] (enterprise.communications.command.server.CommandServiceDirectory)- {CommandServiceDirectory.new-supported-command}Command [identify] is now supported by [rhq.remoting:type=command,subsystem=RHQ,id=2]
>2014-08-12 19:51:24,758 DEBUG [main] (enterprise.communications.command.server.CommandServiceDirectory)- {CommandServiceDirectory.new-supported-command}Command [remotepojo] is now supported by [rhq.remoting:type=command,subsystem=RHQ,id=3]
>2014-08-12 19:51:24,758 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.directory-created}Command service directory has been created [rhq.remoting:type=directory]
>2014-08-12 19:51:24,759 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.connector-uri}Connector is using locator URI of [InvokerLocator [socket://172.17.0.50:16163/?backlog=200&clientMaxPoolSize=304&enableTcpNoDelay=true&maxPoolSize=303&numAcceptThreads=1&rhq.communications.connector.rhqtype=agent&socketTimeout=60000]]
>2014-08-12 19:51:24,764 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.connector-lease-period}The connector lease period is [-1]
>2014-08-12 19:51:24,830 DEBUG [main] (org.jboss.remoting.ServerInvoker)- SocketServerInvoker[172.17.0.50:16163] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
>2014-08-12 19:51:24,830 DEBUG [main] (org.jboss.remoting.ServerInvoker)- SocketServerInvoker[172.17.0.50:16163] created server socket factory javax.net.DefaultServerSocketFactory@5ce08e48
>2014-08-12 19:51:24,848 DEBUG [main] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.authenticator-set}Command processor assigned an authenticator for command security checks: [org.rhq.enterprise.agent.SecurityTokenCommandAuthenticator@1d0975b0]
>2014-08-12 19:51:24,849 DEBUG [main] (org.jboss.remoting.ServerInvoker)- SocketServerInvoker[172.17.0.50:16163] added org.rhq.enterprise.communications.command.server.CommandProcessor@5816e125 for subsystem 'RHQ'
>2014-08-12 19:51:24,849 DEBUG [main] (jboss.remoting.transport.socket.SocketServerInvoker)- SocketServerInvoker[172.17.0.50:16163] starting
>2014-08-12 19:51:24,854 DEBUG [main] (jboss.remoting.transport.socket.SocketServerInvoker)- SocketServerInvoker[172.17.0.50:16163] created ServerSocket[addr=/172.17.0.50,localport=16163]
>2014-08-12 19:51:24,856 DEBUG [main] (org.jboss.remoting.ServerInvoker)- SocketServerInvoker[172.17.0.50:16163] started for locator InvokerLocator [socket://172.17.0.50:16163/?backlog=200&clientMaxPoolSize=304&enableTcpNoDelay=true&maxPoolSize=303&numAcceptThreads=1&rhq.communications.connector.rhqtype=agent&socketTimeout=60000]
>2014-08-12 19:51:24,857 DEBUG [main] (jboss.remoting.transport.socket.SocketServerInvoker)- SocketServerInvoker[172.17.0.50:16163] started
>2014-08-12 19:51:24,857 DEBUG [main] (org.jboss.remoting.transport.Connector)- org.jboss.remoting.transport.Connector@485ff368 started
>2014-08-12 19:51:24,859 DEBUG [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.processor-ready}Command processor is now ready to begin accepting commands from clients
>2014-08-12 19:51:24,859 INFO  [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.started}Service container started - ready to accept incoming commands
>2014-08-12 19:51:24,859 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.no-auto-detect}Server auto-detection is not enabled - starting the poller immediately
>2014-08-12 19:51:24,860 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
>2014-08-12 19:51:24,860 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.
>2014-08-12 19:51:24,867 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$RegisterStateListener@1770008e]; sender is sending=[false]; notify listener immediately=[true]
>2014-08-12 19:51:24,868 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$PluginContainerConditionalRestartListener@7c72fcf6]; sender is sending=[false]; notify listener immediately=[false]
>2014-08-12 19:51:24,869 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.waiting-for-server}The agent is waiting for the server - will sleep for [60,000] milliseconds
>2014-08-12 19:51:24,869 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@91da402]; sender is sending=[false]; notify listener immediately=[true]
>2014-08-12 19:51:24,869 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.init-callback-done}The initialize callback has finished. Callback to be invoked again? [true]
>2014-08-12 19:51:24,876 DEBUG [RHQ Server Polling Thread] (org.jboss.remoting.Client)- Client[468629236:4sh01e-517yak-hyrna8p0-1-hyrna8r0-3].connect(null)
>2014-08-12 19:51:24,888 DEBUG [RHQ Server Polling Thread] (org.jboss.remoting.MicroRemoteClientInvoker)- org.jboss.remoting.transport.http.HTTPClientInvoker@3d92d019 connecting
>2014-08-12 19:51:24,888 DEBUG [RHQ Server Polling Thread] (org.jboss.remoting.MicroRemoteClientInvoker)- org.jboss.remoting.transport.http.HTTPClientInvoker@3d92d019 connected
>2014-08-12 19:51:24,889 DEBUG [RHQ Server Polling Thread] (org.jboss.remoting.Client)- Client[468629236:4sh01e-517yak-hyrna8p0-1-hyrna8r0-3] connected to InvokerLocator [servlet://172.17.0.50:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]
>2014-08-12 19:51:24,889 DEBUG [RHQ Server Polling Thread] (org.jboss.remoting.Client)- Client[468629236:4sh01e-517yak-hyrna8p0-1-hyrna8r0-3] is connected
>2014-08-12 19:51:25,178 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ServerPollingThread)- {ServerPollingThread.server-poll-failure}Failed to successfully poll the server. This is normally due to the server not being up yet. You can usually ignore this message since it will be tried again later, however, you should ensure this failure was not really caused by a misconfiguration. Cause: org.jboss.remoting.transport.http.WebServerError:<html><head><title>JBoss Web/7.4.8.Final-redhat-4 - JBWEB000064: Error report</title><style></style> </head><body><h1>JBWEB000065: HTTP Status 404 - /jboss-remoting-servlet-invoker/ServerInvokerServlet</h1><HR size="1" noshade="noshade"><p><b>JBWEB000309: type</b> JBWEB000067: Status report</p><p><b>JBWEB000068: message</b> <u>/jboss-remoting-servlet-invoker/ServerInvokerServlet</u></p><p><b>JBWEB000069: description</b> <u>JBWEB000124: The requested resource is not available.</u></p><HR size="1" noshade="noshade"><h3>JBoss Web/7.4.8.Final-redhat-4</h3></body></html>
>2014-08-12 19:52:24,871 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.removed-state-listener}Removed the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@91da402]
>2014-08-12 19:52:24,871 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.waiting-for-server}The agent is waiting for the server - will sleep for [60,000] milliseconds
>2014-08-12 19:52:24,871 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@7088daa7]; sender is sending=[false]; notify listener immediately=[true]
>2014-08-12 19:52:25,178 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.
>2014-08-12 19:52:25,179 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.init-callback-done}The initialize callback has finished. Callback to be invoked again? [true]
>2014-08-12 19:53:24,872 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.removed-state-listener}Removed the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@7088daa7]
>2014-08-12 19:53:24,872 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.waiting-for-server}The agent is waiting for the server - will sleep for [60,000] milliseconds
>2014-08-12 19:53:24,872 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@71aaff0f]; sender is sending=[false]; notify listener immediately=[true]
>2014-08-12 19:53:25,562 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.
>2014-08-12 19:53:25,562 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.init-callback-done}The initialize callback has finished. Callback to be invoked again? [true]
>2014-08-12 19:53:25,659 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 [servlet://172.17.0.50:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]]
>2014-08-12 19:53:25,660 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.unspooled}[0] commands were unspooled and will be resent to [remoting endpoint [servlet://172.17.0.50:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]]
>2014-08-12 19:53:25,661 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.sending}Client is now going to actively send commands
>2014-08-12 19:53:25,662 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]
>2014-08-12 19:53:26,679 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=[6b65b59f831b]; address=[172.17.0.50]; port=[16163]; remote-endpoint=[socket://172.17.0.50:16163/?rhq.communications.connector.rhqtype=agent&numAcceptThreads=1&maxPoolSize=303&clientMaxPoolSize=304&socketTimeout=60000&enableTcpNoDelay=true&backlog=200]; regenerate-token=[false]; original-token=[<was null>]; agent-version=[4.12.0.JON330DR01(dda0a47)]; install-id=[null]; install-location=[/opt/rhq-agent]]
>2014-08-12 19:53:26,689 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.
>2014-08-12 19:53:26,693 DEBUG [ClientCommandSenderTask Timer Thread #0] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.init-callback-done}The initialize callback has finished. Callback to be invoked again? [true]
>2014-08-12 19:53:26,934 DEBUG [WorkerThread#0[172.17.0.50:53904]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executing}Executing command [Command: type=[remotepojo]; cmd-in-response=[false]; config=[{rhq.send-throttle=true, rhq.timeout=10000}]; params=[{targetInterfaceName=org.rhq.enterprise.communications.Ping, invocation=NameBasedInvocation[ping]}]]
>2014-08-12 19:53:26,938 DEBUG [WorkerThread#0[172.17.0.50:53904]] (enterprise.communications.command.server.CommandProcessor)- {CommandProcessor.executed}Executed command - response is [Command Response: isSuccessful=[true]; command=[null]; results=[]; exception=[>> exception was null <<]]
>2014-08-12 19:53:26,946 DEBUG [WorkerThread#0[172.17.0.50:53904]] (jboss.remoting.transport.socket.ServerThread)- WorkerThread#0[172.17.0.50:53904] closed socketWrapper: ServerSocketWrapper[Socket[addr=/172.17.0.50,port=53904,localport=16163].4b6a524]
>2014-08-12 19:54:24,873 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.removed-state-listener}Removed the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@71aaff0f]
>2014-08-12 19:54:24,873 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.waiting-for-server}The agent is waiting for the server - will sleep for [60,000] milliseconds
>2014-08-12 19:54:24,873 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.added-state-listener}Added the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@55662d9a]; sender is sending=[true]; notify listener immediately=[true]
>2014-08-12 19:54:24,873 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.removed-state-listener}Removed the command client sender state listener [org.rhq.enterprise.agent.AgentMain$2@55662d9a]
>2014-08-12 19:54:24,875 FATAL [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.startup-error}The agent encountered an error during startup and must abort
>org.rhq.core.clientapi.server.core.AgentRegistrationException: The agent cannot register with the server. Admin intervention needed!
>	at org.rhq.enterprise.agent.AgentMain.start(AgentMain.java:720)
>	at org.rhq.enterprise.agent.AgentMain.main(AgentMain.java:448)
>2014-08-12 19:54:24,877 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.shutting-down}Agent is being shut down...
>2014-08-12 19:54:25,141 INFO  [main] (org.rhq.core.pc.PluginContainer)- Plugin container is already shut down.
>2014-08-12 19:54:25,143 WARN  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.failed-to-shutdown-component}Agent failed to shutdown component [Plugin Container]. Cause: java.lang.NullPointerException:null
>2014-08-12 19:54:25,144 DEBUG [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.notifying-server-of-shutdown}Agent is notifying the server that it is shutting down.
>2014-08-12 19:54:25,144 DEBUG [main] (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.
>2014-08-12 19:54:25,145 DEBUG [ClientCommandSenderTask Timer Thread #1] (enterprise.communications.command.client.JBossRemotingRemoteCommunicator)- {JBossRemotingRemoteCommunicator.init-callback-done}The initialize callback has finished. Callback to be invoked again? [true]
>2014-08-12 19:54:25,161 DEBUG [main] (enterprise.communications.command.client.ClientRemotePojoFactory)- {ClientRemotePojoFactory.execution-failure}Failed to execute remote POJO method [agentIsShuttingDown]. 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=6b65b59f831b, rhq.externalizable-strategy=AGENT, rhq.timeout=10000}]; params=[{targetInterfaceName=org.rhq.core.clientapi.server.core.CoreServerService, invocation=NameBasedInvocation[agentIsShuttingDown]}]
>2014-08-12 19:54:25,161 WARN  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.server-shutdown-notification-failure}Agent failed to notify the server of the pending shutdown. 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=6b65b59f831b, rhq.externalizable-strategy=AGENT, rhq.timeout=10000}]; params=[{targetInterfaceName=org.rhq.core.clientapi.server.core.CoreServerService, invocation=NameBasedInvocation[agentIsShuttingDown]}]
>2014-08-12 19:54:25,162 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@1770008e]
>2014-08-12 19:54:25,162 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
>2014-08-12 19:54:25,162 DEBUG [RHQ Server Polling Thread] (enterprise.communications.command.client.ServerPollingThread)- {ServerPollingThread.stopped}Server polling thread has been stopped
>2014-08-12 19:54:47,495 DEBUG [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.test-failover-list-entry}Testing failover connectivity to server [172.17.0.50:7080]
>2014-08-12 19:54:47,497 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=CipzmFcrQDxkjVSpE3jZEpusVRbZP3aXZg8Q0lndL8txwpF472W4LAeJUnpRqOmmL1k=]]
>2014-08-12 19:54:47,496 DEBUG [main] (org.jboss.remoting.InvokerRegistry)- removed org.jboss.remoting.transport.http.HTTPClientInvoker@3d92d019 from registry
>2014-08-12 19:54:47,499 DEBUG [main] (org.jboss.remoting.Client)- Client[468629236:4sh01e-517yak-hyrna8p0-1-hyrna8r0-3] is disconnected
>2014-08-12 19:54:47,500 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.spooling}Persisting commands with guaranteed delivery to the spool file
>2014-08-12 19:54:47,500 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.spool-done}Persisted [0] commands to the spool file for later delivery to [remoting endpoint [servlet://172.17.0.50:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]]; [0] volatile commands remain in the queue and will be lost if the sender does not start again
>2014-08-12 19:54:47,501 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.no-longer-sending}Client is no longer actively sending commands to [remoting endpoint [servlet://172.17.0.50:7080/jboss-remoting-servlet-invoker/ServerInvokerServlet]]
>2014-08-12 19:54:47,501 DEBUG [main] (enterprise.communications.command.client.ClientCommandSender)- {ClientCommandSender.notifying-state-listeners}Notifying command client sender state listeners of a state change - sender is sending=[false]
>2014-08-12 19:54:47,501 DEBUG [main] (enterprise.communications.command.client.CommandQueue)- {CommandQueue.disabled}Command Queue is not throttling - commands will be dequeued as fast as possible
>2014-08-12 19:54:47,501 DEBUG [main] (enterprise.communications.command.client.SendThrottle)- {SendThrottle.disabled}Send throttling is disabled - commands will be sent as fast as possible
>2014-08-12 19:54:47,501 INFO  [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.shutting-down}Service container shutting down...
>2014-08-12 19:54:47,502 DEBUG [ServerSocketRefresh] (jboss.remoting.transport.socket.SocketServerInvoker)- ServerSocketRefresh shutting down
>2014-08-12 19:54:47,502 DEBUG [main] (jboss.remoting.transport.socket.SocketServerInvoker)- SocketServerInvoker[172.17.0.50:16163] stopped threads in clientpool
>2014-08-12 19:54:47,503 DEBUG [main] (jboss.remoting.transport.socket.SocketServerInvoker)- SocketServerInvoker[172.17.0.50:16163] stopped threads in threadpool
>2014-08-12 19:54:47,503 DEBUG [main] (jboss.remoting.transport.socket.SocketServerInvoker)- SocketServerInvoker[172.17.0.50:16163] exiting
>2014-08-12 19:54:47,506 DEBUG [main] (org.jboss.remoting.ServerInvoker)- SocketServerInvoker[172.17.0.50:16163] stopped
>2014-08-12 19:54:47,507 DEBUG [RHQ Agent Registration Thread] (org.rhq.enterprise.agent.AgentMain)- {SecurityTokenCommandPreprocessor.new-security-token}A new security token has been persisted: CipzmFcrQDxkjVSpE3jZEpusVRbZP3aXZg8Q0lndL8txwpF472W4LAeJUnpRqOmmL1k=
>2014-08-12 19:54:47,508 DEBUG [main] (enterprise.communications.command.server.CommandServiceDirectory)- {CommandServiceDirectory.removed-command-support}Command [echo] is no longer supported; removed [rhq.remoting:type=command,subsystem=RHQ,id=1]
>2014-08-12 19:54:47,508 DEBUG [main] (enterprise.communications.command.server.CommandServiceDirectory)- {CommandServiceDirectory.removed-command-support}Command [identify] is no longer supported; removed [rhq.remoting:type=command,subsystem=RHQ,id=2]
>2014-08-12 19:54:47,509 DEBUG [main] (enterprise.communications.command.server.CommandServiceDirectory)- {CommandServiceDirectory.removed-command-support}Command [remotepojo] is no longer supported; removed [rhq.remoting:type=command,subsystem=RHQ,id=3]
>2014-08-12 19:54:47,509 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]
>2014-08-12 19:54:47,509 INFO  [main] (org.rhq.enterprise.communications.ServiceContainer)- {ServiceContainer.shutdown}Service container shut down - no longer accepting incoming commands
>2014-08-12 19:54:47,509 INFO  [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.shut-down}Agent has been shut down
>2014-08-12 19:54:47,510 FATAL [main] (org.rhq.enterprise.agent.AgentMain)- {AgentMain.start-failure}Failed to start the agent
>org.rhq.core.clientapi.server.core.AgentRegistrationException: The agent cannot register with the server. Admin intervention needed!
>	at org.rhq.enterprise.agent.AgentMain.start(AgentMain.java:720)
>	at org.rhq.enterprise.agent.AgentMain.main(AgentMain.java:448)

Actions: View

Attachments on bug 1130662: 927269 | 927270