Richard_Lucas

Tomcat Agent problem

Discussion created by Richard_Lucas on Aug 23, 2011
Latest reply on Aug 23, 2011 by Richard_Lucas
I am trying to install an agent in to Blackboard Learn which uses wrapper to start tomcat. I believe I have everything setup correctly, but startup is failing. Here's the start of the failure, it does continue and throw a bunch of other exceptions until it dies. The Autoprobe log is produced, but there is nothing in it. The main thing I see is the message about the delegate agent, I'm not sure what that means though.

Thanks,
Richard


STATUS | wrapper | 2011/08/22 16:27:31 | --> Wrapper Started as Daemon
STATUS | wrapper | 2011/08/22 16:27:31 | Launching a JVM...
INFO | jvm 1 | 2011/08/22 16:27:31 | -XX:ConcGCThreads=1 -XX:+DisableExplicitGC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/usr/local/blackboard/logs/tomcat -XX:Initial
HeapSize=6442450944 -XX:+ManagementServer -XX:MaxHeapSize=6442450944 -XX:MaxNewSize=1610612736 -XX:MaxPermSize=402653184 -XX:NewSize=1610612736 -XX:OldPLABSize=16 -XX:ParallelGCTh
reads=2 -XX:+PrintCommandLineFlags -XX:+PrintGC -XX:+PrintGCApplicationConcurrentTime -XX:+PrintGCApplicationStoppedTime -XX:+PrintGCDetails -XX:+PrintGCTaskTimeStamps -XX:+PrintG
CTimeStamps -XX:+PrintVMOptions -XX:SurvivorRatio=4 -XX:ThreadStackSize=320 -XX:+TraceClassUnloading -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC -XX:+UseParNewGC -XX:+UseTLAB
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Introscope Agent Release 8.2.3.0 (Build 476542)
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Using Java VM version "Java HotSpot(TM) 64-Bit Server VM 1.6.0_24" from Sun Microsystems Inc.
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Trying to load agent profile based on system property "com.wily.introscope.agentProfile"
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Trying to load file from /usr/local/wily/IntroscopeAgent.profile
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Loaded file from /usr/local/wily/IntroscopeAgent.profile
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Agent Metric Aging is turned on
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Agent Metric Aging: property introscope.agent.metricAging.heartbeatInterval is set to 1800
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Agent Metric Aging: property introscope.agent.metricAging.dataChunk is set to 500
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Agent Metric Aging: property introscope.agent.metricAging.numberTimeslices is set to 3000
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Looking for agent profile property "introscope.agent.extensions.directory" to locate the extensions directory.
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] The agent extensions directory /usr/local/wily/ext was successfully located
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Introscope AutoProbe Release 8.2.3.0 (Build 476542)
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] The Introscope AutoProbe log has been created at /usr/local/wily/logs/AutoProbe.20110822-162732.log
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] AutoProbe has reloaded all directive configuration. This is because a change to a directive configuration file was detected.
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [INFO] [IntroscopeAgent] Introscope Agent startup complete.
INFO | jvm 1 | 2011/08/22 16:27:32 | 8/22/11 04:27:32 PM EDT [ERROR] [IntroscopeAgent] A problem occurred while attempting to create the delegate agent
INFO | jvm 1 | 2011/08/22 16:27:32 | WrapperManager class initialized by thread: main Using classloader: sun.misc.Launcher$AppClassLoader@53004901
INFO | jvm 1 | 2011/08/22 16:27:32 | Wrapper (Version 3.2.3) http://wrapper.tanukisoftware.org
INFO | jvm 1 | 2011/08/22 16:27:32 | Copyright 1999-2006 Tanuki Software, Inc. All Rights Reserved.
INFO | jvm 1 | 2011/08/22 16:27:32 |
INFO | jvm 1 | 2011/08/22 16:27:32 | Wrapper Manager: JVM #1
INFO | jvm 1 | 2011/08/22 16:27:32 | Running a 64-bit JVM.
INFO | jvm 1 | 2011/08/22 16:27:32 | Wrapper Manager: Using wrapper
INFO | jvm 1 | 2011/08/22 16:27:32 | Load native library. One or more attempts may fail if platform specific libraries do not exist.
INFO | jvm 1 | 2011/08/22 16:27:32 | Loading native library failed: libwrapper.64-linux-x86-64.so Cause: java.lang.UnsatisfiedLinkError: no wrapper.64-linux-x86-64 in java.library.path
INFO | jvm 1 | 2011/08/22 16:27:32 | Loaded native library: libwrapper.64.so
INFO | jvm 1 | 2011/08/22 16:27:32 | Calling native initialization method.
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option 'MaxPermSize=384m'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+HeapDumpOnOutOfMemoryError'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option 'HeapDumpPath=/usr/local/blackboard/logs/tomcat'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintGCDetails'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option 'NewSize=1536m'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option 'MaxNewSize=1536m'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option 'SurvivorRatio=4'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+UseConcMarkSweepGC'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+UseParNewGC'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+UseTLAB'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option 'ParallelCMSThreads=1'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option 'ParallelGCThreads=2'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+UseCompressedOops'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintVMOptions'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintGCDetails'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintGCTimeStamps'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintGCTaskTimeStamps'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintCommandLineFlags'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintGCApplicationStoppedTime'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+PrintGCApplicationConcurrentTime'
INFO | jvm 1 | 2011/08/22 16:27:32 | VM option '+DisableExplicitGC'
INFO | jvm 1 | 2011/08/22 16:27:32 | Inside native WrapperManager initialization method
INFO | jvm 1 | 2011/08/22 16:27:32 | Java Version : 1.6.0_24-b07 Java HotSpot(TM) 64-Bit Server VM
INFO | jvm 1 | 2011/08/22 16:27:32 | Java VM Vendor : Sun Microsystems Inc.
INFO | jvm 1 | 2011/08/22 16:27:32 |
INFO | jvm 1 | 2011/08/22 16:27:32 | Control event monitor thread started.
INFO | jvm 1 | 2011/08/22 16:27:32 | WrapperManager.start(org.tanukisoftware.wrapper.WrapperStartStopApp@14718242, args["start"]) called by thread: main
INFO | jvm 1 | 2011/08/22 16:27:32 | Startup runner thread started.
INFO | jvm 1 | 2011/08/22 16:27:32 | Communications runner thread started.
INFO | jvm 1 | 2011/08/22 16:27:32 | Open socket to wrapper...Wrapper-Connection
INFO | jvm 1 | 2011/08/22 16:27:32 | Opened Socket from 31000 to 32001
INFO | jvm 1 | 2011/08/22 16:27:32 | Send a packet KEY : lFLVPI2AWmAe5c1f
INFO | jvm 1 | 2011/08/22 16:27:32 | handleSocket(Socket[addr=ltvbblapp01.itap.purdue.edu/127.0.0.1,port=32001,localport=31000])
INFO | jvm 1 | 2011/08/22 16:27:32 | Received a packet LOW_LOG_LEVEL : 2
INFO | jvm 1 | 2011/08/22 16:27:32 | [ThreadDumpWrapper] ThreadDumpWrapper enabled.
INFO | jvm 1 | 2011/08/22 16:27:32 | [ThreadDumpWrapper] Monitoring for existence of /usr/local/blackboard/apps/tomcat/temp/wrapper.command
INFO | jvm 1 | 2011/08/22 16:27:37 | 8/22/11 04:27:37 PM EDT [INFO] [IntroscopeAgent] Statement Normalizer: will use preconfigured normalization scheme
INFO | jvm 1 | 2011/08/22 16:27:37 | 8/22/11 04:27:37 PM EDT [INFO] [IntroscopeAgent] Statement Normalizer: No sql normalizer extension was configured in the properties, falling back to the preconfigured normalization scheme
INFO | jvm 1 | 2011/08/22 16:27:43 | log4j:WARN Error during default initialization
INFO | jvm 1 | 2011/08/22 16:27:43 | java.lang.NoClassDefFoundError: Could not initialize class sun.awt.AppContext
INFO | jvm 1 | 2011/08/22 16:27:43 | at java.beans.Introspector.getBeanInfo(Introspector.java:159)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.config.PropertySetter.introspect(PropertySetter.java:76)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.config.PropertySetter.getPropertyDescriptor(PropertySetter.java:244)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.config.PropertySetter.setProperty(PropertySetter.java:156)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:130)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:97)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:684)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:647)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:544)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:440)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:476)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.helpers.OptionConverter.selectAndConfigure(OptionConverter.java:471)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.LogManager.<clinit>(LogManager.java:125)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.log4j.Logger.getLogger(Logger.java:105)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.impl.Log4JLogger.getLogger(Log4JLogger.java:289)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.impl.Log4JLogger.<init>(Log4JLogger.java:109)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
INFO | jvm 1 | 2011/08/22 16:27:43 | at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.impl.LogFactoryImpl.createLogFromClass(LogFactoryImpl.java:1116)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.impl.LogFactoryImpl.discoverLogImplementation(LogFactoryImpl.java:914)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.impl.LogFactoryImpl.newInstance(LogFactoryImpl.java:604)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.impl.LogFactoryImpl.getInstance(LogFactoryImpl.java:336)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.impl.LogFactoryImpl.getInstance(LogFactoryImpl.java:310)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.logging.LogFactory.getLog(LogFactory.java:685)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.beanutils.ConvertUtilsBean.<init>(ConvertUtilsBean.java:157)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.beanutils.BeanUtilsBean.<init>(BeanUtilsBean.java:117)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.beanutils.BeanUtilsBean$1.initialValue(BeanUtilsBean.java:68)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.beanutils.ContextClassLoaderLocal.get(ContextClassLoaderLocal.java:153)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.beanutils.BeanUtilsBean.getInstance(BeanUtilsBean.java:80)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.commons.beanutils.BeanUtils.setProperty(BeanUtils.java:456)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.messagequeue.impl.activemq.ActiveMQMessageBrokerConfig.populateFromElementAttributes(ActiveMQMessageBrokerConfig.java:218)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.messagequeue.impl.activemq.ActiveMQMessageQueueService.initializeFromConfig(ActiveMQMessageQueueService.java:138)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.messagequeue.impl.activemq.ActiveMQMessageQueueService.serviceInit(ActiveMQMessageQueueService.java:88)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager$ServiceImplWrapper.doInit(BbServiceManager.java:929)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager$ServiceImplWrapper.<init>(BbServiceManager.java:903)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager.registerService(BbServiceManager.java:468)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager.doInitService(BbServiceManager.java:778)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager.init(BbServiceManager.java:343)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager.init(BbServiceManager.java:242)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager.init(BbServiceManager.java:150)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager.init(BbServiceManager.java:128)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.platform.BbServiceManager.initFromSystemProps(BbServiceManager.java:119)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.tomcat.servletcontainer.TomcatLifecycleListener.lifecycleEvent(TomcatLifecycleListener.java:67)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.catalina.core.StandardServer.start(StandardServer.java:703)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.catalina.startup.Catalina.start(Catalina.java:583)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
INFO | jvm 1 | 2011/08/22 16:27:43 | at java.lang.reflect.Method.invoke(Method.java:597)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
INFO | jvm 1 | 2011/08/22 16:27:43 | at blackboard.tomcat.startup.ThreadDumpWrapper.main(ThreadDumpWrapper.java:249)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
INFO | jvm 1 | 2011/08/22 16:27:43 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
INFO | jvm 1 | 2011/08/22 16:27:43 | at java.lang.reflect.Method.invoke(Method.java:597)
INFO | jvm 1 | 2011/08/22 16:27:43 | at org.tanukisoftware.wrapper.WrapperStartStopApp.run(WrapperStartStopApp.java:238)
INFO | jvm 1 | 2011/08/22 16:27:43 | at java.lang.Thread.run(Thread.java:662)
INFO | jvm 1 | 2011/08/22 16:27:43 | Debug - Exception during Service Initialization

Outcomes