You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@hawq.apache.org by "xinzhang (JIRA)" <ji...@apache.org> on 2017/12/11 07:39:00 UTC

[jira] [Updated] (HAWQ-1569) log4j log can not open level debug

     [ https://issues.apache.org/jira/browse/HAWQ-1569?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

xinzhang updated HAWQ-1569:
---------------------------
    Description: 
#vim pxf-log4j.properties
{code:html}
# log to a rolling file
log4j.rootLogger=DEBUG, ROLLINGFILE

# uncomment to see DEBUG messages from PXF service
log4j.logger.org.apache.hawq.pxf=DEBUG

{code}

#less /var/log/pxf/catalina.out

{code:java}
Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:14 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:17 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:17 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 830 ms
Dec 11, 2017 2:27:17 AM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Dec 11, 2017 2:27:17 AM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.62
Dec 11, 2017 2:27:17 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /var/pxf/pxf-service/webapps/pxf.war
Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader readClasspathFile
INFO: Trying to read classpath file /etc/pxf/conf/pxf-private.classpath
Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader addRepositories
INFO: Classpath file /etc/pxf/conf/pxf-private.classpath has 7 entries
Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader readClasspathFile
INFO: Trying to read classpath file /etc/pxf/conf/pxf-public.classpath
Dec 11, 2017 2:27:18 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader addRepositories
INFO: Classpath file /etc/pxf/conf/pxf-public.classpath has 0 entries
Dec 11, 2017 2:27:20 AM org.apache.catalina.core.ContainerBase addChildInternal
SEVERE: ContainerBase.addChild: start: 
org.apache.catalina.LifecycleException: Failed to start component [StandardEngine[Catalina].StandardHost[localhost].StandardContext[/pxf]]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:154)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1095)
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1930)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.LinkageError: loader constraint violation: loader (instance of org/apache/catalina/loader/WebappClassLoader) previously initiated loading for a different type with name "javax/servlet/ServletContext"
	at java.lang.ClassLoader.defineClass1(Native Method)
	at java.lang.ClassLoader.defineClass(ClassLoader.java:760)
	at java.security.SecureClassLoader.defineClass(SecureClassLoader.java:142)
	at java.net.URLClassLoader.defineClass(URLClassLoader.java:467)
	at java.net.URLClassLoader.access$100(URLClassLoader.java:73)
	at java.net.URLClassLoader$1.run(URLClassLoader.java:368)
	at java.net.URLClassLoader$1.run(URLClassLoader.java:362)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.net.URLClassLoader.findClass(URLClassLoader.java:361)
	at org.apache.catalina.loader.WebappClassLoader.findClass(WebappClassLoader.java:1227)
	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1690)
	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1571)
	at java.lang.Class.getDeclaredMethods0(Native Method)
	at java.lang.Class.privateGetDeclaredMethods(Class.java:2701)
	at java.lang.Class.getDeclaredMethods(Class.java:1975)
	at org.apache.catalina.util.Introspection.getDeclaredMethods(Introspection.java:127)
	at org.apache.catalina.startup.WebAnnotationSet.loadMethodsAnnotation(WebAnnotationSet.java:287)
	at org.apache.catalina.startup.WebAnnotationSet.loadApplicationServletAnnotations(WebAnnotationSet.java:140)
	at org.apache.catalina.startup.WebAnnotationSet.loadApplicationAnnotations(WebAnnotationSet.java:65)
	at org.apache.catalina.startup.ContextConfig.applicationAnnotationsConfig(ContextConfig.java:415)
	at org.apache.catalina.startup.ContextConfig.configureStart(ContextConfig.java:892)
	at org.apache.catalina.startup.ContextConfig.lifecycleEvent(ContextConfig.java:386)
	at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117)
	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:90)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5416)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
	... 10 more

Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployWAR
SEVERE: Error deploying web application archive /var/pxf/pxf-service/webapps/pxf.war
java.lang.IllegalStateException: ContainerBase.addChild: start: org.apache.catalina.LifecycleException: Failed to start component [StandardEngine[Catalina].StandardHost[localhost].StandardContext[/pxf]]
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:904)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1095)
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1930)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)

Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /var/pxf/pxf-service/webapps/pxf.war has finished in 3,031 ms
Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/ROOT
Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:03 AM org.apache.catalina.util.SessionIdGeneratorBase createSecureRandom
INFO: Creation of SecureRandom instance for session ID generation using [SHA1PRNG] took [42,488] milliseconds.
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/ROOT has finished in 42,644 ms
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/docs
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/docs has finished in 83 ms
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/examples
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/examples has finished in 451 ms
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/host-manager
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/host-manager has finished in 95 ms
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/manager
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/manager has finished in 98 ms
Dec 11, 2017 2:28:04 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 46575 ms

{code}

I conf my pxf-log4j.properties reference desc here (debug)
https://hawq.incubator.apache.org/docs/userguide/2.1.0.0-incubating/pxf/TroubleshootingPXF.html#pxfsvclogmsg

  was:


#vim pxf-log4j.properties
{code:html}
# log to a rolling file
log4j.rootLogger=DEBUG, ROLLINGFILE

# uncomment to see DEBUG messages from PXF service
log4j.logger.org.apache.hawq.pxf=DEBUG

{code}

#less /var/log/pxf/catalina.out

{code:java}
Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:14 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:17 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:27:17 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 830 ms
Dec 11, 2017 2:27:17 AM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Dec 11, 2017 2:27:17 AM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.62
Dec 11, 2017 2:27:17 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /var/pxf/pxf-service/webapps/pxf.war
Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader readClasspathFile
INFO: Trying to read classpath file /etc/pxf/conf/pxf-private.classpath
Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader addRepositories
INFO: Classpath file /etc/pxf/conf/pxf-private.classpath has 7 entries
Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader readClasspathFile
INFO: Trying to read classpath file /etc/pxf/conf/pxf-public.classpath
Dec 11, 2017 2:27:18 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader addRepositories
INFO: Classpath file /etc/pxf/conf/pxf-public.classpath has 0 entries
Dec 11, 2017 2:27:20 AM org.apache.catalina.core.ContainerBase addChildInternal
SEVERE: ContainerBase.addChild: start: 
org.apache.catalina.LifecycleException: Failed to start component [StandardEngine[Catalina].StandardHost[localhost].StandardContext[/pxf]]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:154)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1095)
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1930)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.LinkageError: loader constraint violation: loader (instance of org/apache/catalina/loader/WebappClassLoader) previously initiated loading for a different type with name "javax/servlet/ServletContext"
	at java.lang.ClassLoader.defineClass1(Native Method)
	at java.lang.ClassLoader.defineClass(ClassLoader.java:760)
	at java.security.SecureClassLoader.defineClass(SecureClassLoader.java:142)
	at java.net.URLClassLoader.defineClass(URLClassLoader.java:467)
	at java.net.URLClassLoader.access$100(URLClassLoader.java:73)
	at java.net.URLClassLoader$1.run(URLClassLoader.java:368)
	at java.net.URLClassLoader$1.run(URLClassLoader.java:362)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.net.URLClassLoader.findClass(URLClassLoader.java:361)
	at org.apache.catalina.loader.WebappClassLoader.findClass(WebappClassLoader.java:1227)
	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1690)
	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1571)
	at java.lang.Class.getDeclaredMethods0(Native Method)
	at java.lang.Class.privateGetDeclaredMethods(Class.java:2701)
	at java.lang.Class.getDeclaredMethods(Class.java:1975)
	at org.apache.catalina.util.Introspection.getDeclaredMethods(Introspection.java:127)
	at org.apache.catalina.startup.WebAnnotationSet.loadMethodsAnnotation(WebAnnotationSet.java:287)
	at org.apache.catalina.startup.WebAnnotationSet.loadApplicationServletAnnotations(WebAnnotationSet.java:140)
	at org.apache.catalina.startup.WebAnnotationSet.loadApplicationAnnotations(WebAnnotationSet.java:65)
	at org.apache.catalina.startup.ContextConfig.applicationAnnotationsConfig(ContextConfig.java:415)
	at org.apache.catalina.startup.ContextConfig.configureStart(ContextConfig.java:892)
	at org.apache.catalina.startup.ContextConfig.lifecycleEvent(ContextConfig.java:386)
	at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117)
	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:90)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5416)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
	... 10 more

Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployWAR
SEVERE: Error deploying web application archive /var/pxf/pxf-service/webapps/pxf.war
java.lang.IllegalStateException: ContainerBase.addChild: start: org.apache.catalina.LifecycleException: Failed to start component [StandardEngine[Catalina].StandardHost[localhost].StandardContext[/pxf]]
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:904)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1095)
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1930)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)

Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /var/pxf/pxf-service/webapps/pxf.war has finished in 3,031 ms
Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/ROOT
Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:03 AM org.apache.catalina.util.SessionIdGeneratorBase createSecureRandom
INFO: Creation of SecureRandom instance for session ID generation using [SHA1PRNG] took [42,488] milliseconds.
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/ROOT has finished in 42,644 ms
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/docs
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/docs has finished in 83 ms
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/examples
Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/examples has finished in 451 ms
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/host-manager
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/host-manager has finished in 95 ms
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /var/pxf/pxf-service/webapps/manager
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/manager has finished in 98 ms
Dec 11, 2017 2:28:04 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-51200"]
Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 46575 ms

{code}

log level desc here (debug)
https://hawq.incubator.apache.org/docs/userguide/2.1.0.0-incubating/pxf/TroubleshootingPXF.html#pxfsvclogmsg


> log4j log can not open level debug 
> -----------------------------------
>
>                 Key: HAWQ-1569
>                 URL: https://issues.apache.org/jira/browse/HAWQ-1569
>             Project: Apache HAWQ
>          Issue Type: Bug
>            Reporter: xinzhang
>            Assignee: Radar Lei
>
> #vim pxf-log4j.properties
> {code:html}
> # log to a rolling file
> log4j.rootLogger=DEBUG, ROLLINGFILE
> # uncomment to see DEBUG messages from PXF service
> log4j.logger.org.apache.hawq.pxf=DEBUG
> {code}
> #less /var/log/pxf/catalina.out
> {code:java}
> Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol pause
> INFO: Pausing ProtocolHandler ["http-bio-51200"]
> Dec 11, 2017 2:27:14 AM org.apache.catalina.core.StandardService stopInternal
> INFO: Stopping service Catalina
> Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol stop
> INFO: Stopping ProtocolHandler ["http-bio-51200"]
> Dec 11, 2017 2:27:14 AM org.apache.coyote.AbstractProtocol destroy
> INFO: Destroying ProtocolHandler ["http-bio-51200"]
> Dec 11, 2017 2:27:17 AM org.apache.coyote.AbstractProtocol init
> INFO: Initializing ProtocolHandler ["http-bio-51200"]
> Dec 11, 2017 2:27:17 AM org.apache.catalina.startup.Catalina load
> INFO: Initialization processed in 830 ms
> Dec 11, 2017 2:27:17 AM org.apache.catalina.core.StandardService startInternal
> INFO: Starting service Catalina
> Dec 11, 2017 2:27:17 AM org.apache.catalina.core.StandardEngine startInternal
> INFO: Starting Servlet Engine: Apache Tomcat/7.0.62
> Dec 11, 2017 2:27:17 AM org.apache.catalina.startup.HostConfig deployWAR
> INFO: Deploying web application archive /var/pxf/pxf-service/webapps/pxf.war
> Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader readClasspathFile
> INFO: Trying to read classpath file /etc/pxf/conf/pxf-private.classpath
> Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader addRepositories
> INFO: Classpath file /etc/pxf/conf/pxf-private.classpath has 7 entries
> Dec 11, 2017 2:27:17 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader readClasspathFile
> INFO: Trying to read classpath file /etc/pxf/conf/pxf-public.classpath
> Dec 11, 2017 2:27:18 AM org.apache.hawq.pxf.service.utilities.CustomWebappLoader addRepositories
> INFO: Classpath file /etc/pxf/conf/pxf-public.classpath has 0 entries
> Dec 11, 2017 2:27:20 AM org.apache.catalina.core.ContainerBase addChildInternal
> SEVERE: ContainerBase.addChild: start: 
> org.apache.catalina.LifecycleException: Failed to start component [StandardEngine[Catalina].StandardHost[localhost].StandardContext[/pxf]]
> 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:154)
> 	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
> 	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
> 	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
> 	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1095)
> 	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1930)
> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> 	at java.lang.Thread.run(Thread.java:745)
> Caused by: java.lang.LinkageError: loader constraint violation: loader (instance of org/apache/catalina/loader/WebappClassLoader) previously initiated loading for a different type with name "javax/servlet/ServletContext"
> 	at java.lang.ClassLoader.defineClass1(Native Method)
> 	at java.lang.ClassLoader.defineClass(ClassLoader.java:760)
> 	at java.security.SecureClassLoader.defineClass(SecureClassLoader.java:142)
> 	at java.net.URLClassLoader.defineClass(URLClassLoader.java:467)
> 	at java.net.URLClassLoader.access$100(URLClassLoader.java:73)
> 	at java.net.URLClassLoader$1.run(URLClassLoader.java:368)
> 	at java.net.URLClassLoader$1.run(URLClassLoader.java:362)
> 	at java.security.AccessController.doPrivileged(Native Method)
> 	at java.net.URLClassLoader.findClass(URLClassLoader.java:361)
> 	at org.apache.catalina.loader.WebappClassLoader.findClass(WebappClassLoader.java:1227)
> 	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1690)
> 	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1571)
> 	at java.lang.Class.getDeclaredMethods0(Native Method)
> 	at java.lang.Class.privateGetDeclaredMethods(Class.java:2701)
> 	at java.lang.Class.getDeclaredMethods(Class.java:1975)
> 	at org.apache.catalina.util.Introspection.getDeclaredMethods(Introspection.java:127)
> 	at org.apache.catalina.startup.WebAnnotationSet.loadMethodsAnnotation(WebAnnotationSet.java:287)
> 	at org.apache.catalina.startup.WebAnnotationSet.loadApplicationServletAnnotations(WebAnnotationSet.java:140)
> 	at org.apache.catalina.startup.WebAnnotationSet.loadApplicationAnnotations(WebAnnotationSet.java:65)
> 	at org.apache.catalina.startup.ContextConfig.applicationAnnotationsConfig(ContextConfig.java:415)
> 	at org.apache.catalina.startup.ContextConfig.configureStart(ContextConfig.java:892)
> 	at org.apache.catalina.startup.ContextConfig.lifecycleEvent(ContextConfig.java:386)
> 	at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117)
> 	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:90)
> 	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5416)
> 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
> 	... 10 more
> Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployWAR
> SEVERE: Error deploying web application archive /var/pxf/pxf-service/webapps/pxf.war
> java.lang.IllegalStateException: ContainerBase.addChild: start: org.apache.catalina.LifecycleException: Failed to start component [StandardEngine[Catalina].StandardHost[localhost].StandardContext[/pxf]]
> 	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:904)
> 	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
> 	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
> 	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1095)
> 	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1930)
> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> 	at java.lang.Thread.run(Thread.java:745)
> Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployWAR
> INFO: Deployment of web application archive /var/pxf/pxf-service/webapps/pxf.war has finished in 3,031 ms
> Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deploying web application directory /var/pxf/pxf-service/webapps/ROOT
> Dec 11, 2017 2:27:20 AM org.apache.catalina.startup.TldConfig execute
> INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
> Dec 11, 2017 2:28:03 AM org.apache.catalina.util.SessionIdGeneratorBase createSecureRandom
> INFO: Creation of SecureRandom instance for session ID generation using [SHA1PRNG] took [42,488] milliseconds.
> Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/ROOT has finished in 42,644 ms
> Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deploying web application directory /var/pxf/pxf-service/webapps/docs
> Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.TldConfig execute
> INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
> Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/docs has finished in 83 ms
> Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deploying web application directory /var/pxf/pxf-service/webapps/examples
> Dec 11, 2017 2:28:03 AM org.apache.catalina.startup.TldConfig execute
> INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/examples has finished in 451 ms
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deploying web application directory /var/pxf/pxf-service/webapps/host-manager
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.TldConfig execute
> INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/host-manager has finished in 95 ms
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deploying web application directory /var/pxf/pxf-service/webapps/manager
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.TldConfig execute
> INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.HostConfig deployDirectory
> INFO: Deployment of web application directory /var/pxf/pxf-service/webapps/manager has finished in 98 ms
> Dec 11, 2017 2:28:04 AM org.apache.coyote.AbstractProtocol start
> INFO: Starting ProtocolHandler ["http-bio-51200"]
> Dec 11, 2017 2:28:04 AM org.apache.catalina.startup.Catalina start
> INFO: Server startup in 46575 ms
> {code}
> I conf my pxf-log4j.properties reference desc here (debug)
> https://hawq.incubator.apache.org/docs/userguide/2.1.0.0-incubating/pxf/TroubleshootingPXF.html#pxfsvclogmsg



--
This message was sent by Atlassian JIRA
(v6.4.14#64029)