在一次项目启动中,遇到了一个报错
"java.lang.NoSuchMethodError: org.slf4j.spi.LocationAwareLogger.log"
简单Google一下,是jar包冲突的问题。
按照网上的思路,首先查看mvn中冲突的包
使用命令:
mvn dependency:tree -Dverbose | grep "omitted for conflict with”
通常上面的显示的信息非常多,如果已经明确了冲突的包,比如本场景下是log4j/slf4j
使用命令:
mvn dependency:tree -Dverbose -Dincludes=log4j:log4j
具体查看pom文件,修改冲突的包。
把一些明显的冲突修改了以后,再次启动,仍然报错,报错信息变了:
Exception in thread "main" java.lang.IllegalAccessError: tried to access fieldorg.slf4j.impl.StaticLoggerBinder.SINGLETON from class org.slf4j.LoggerFactory at org.slf4j.LoggerFactory.(LoggerFactory.java:60)
通过Google找到如下:
是由于低版本的slf4j的bug,具体原因查看这个,按理说找到对应的pom配置,升级下version就可以了。
可是我将pom中的slf4j-api和slf4j-log4j12升级到1.7+,仍然报同样的错误。
查看本地M2_REPO,将低版本的slf4j-api和slf4j-log4j12删除,仍然报同样的错。
开始怀疑是从其他jar包中加载了LoggerFactory。
如何查看一个类加载的来源信息呢?
VM的-verbose参数解决了这个问题
-verbose[:class|gc|jni] 在输出设备上显示虚拟机运行信息。
在程序运行的时候有多少类被加载!你可以用verbose:class来监视,在命令行输入java -verbose:class XXX (XXX为程序名)你会在控制台看到加载的类的情况。
verbose和verbose:class含义相同,输出虚拟机装入的类的信息,显示的信息格式如下:
[Opened D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.Object from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.io.Serializable from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.Comparable from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.CharSequence from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.String from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.reflect.GenericDeclaration from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.reflect.Type from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.reflect.AnnotatedElement from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.Class from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.Cloneable from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.ClassLoader from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.System from D:\Java\jdk1.6.0_25\jre\lib\rt.jar][Loaded java.lang.Throwable from D:\Java\jdk1.6.0_25\jre\lib\rt.jar]
当虚拟机报告类找不到或类冲突时可用此参数来诊断来查看虚拟机从装入类的情况。
在本场景排查问题时,发现类LoggerFactory加载来源于com.springsource.slf4j.api,将对应的jar包移除,问题解决。
值得注意的是,-verbose参数在jvm故障诊断时同样有着重要的作用
java –verbose:gc
在虚拟机发生内存回收时在输出设备显示信息,格式如下:
[Full GC 256K->160K(124096K), 0.0042708 secs]
该参数用来监视虚拟机内存回收的情况。
public class JvmVerbose {/*** JVM -verbose[:class|gc|jni] 参数测试* @param args*/ public static void main(String[] args) { JvmVerbose jvmVerbose = new JvmVerbose(); System.gc(); }}
在这个例子中,一个新的对象被创建,由于它没有使用,所以该对象迅速地变为可达,程序编译后,执行命令:
java -verbose:gc JvmVerbose
结果:
[GC 647K->256K(124096K), 0.0274253 secs][Full GC 256K->160K(124096K), 0.0042708 secs]
箭头前后的数据256K和160K分别表示垃圾收集GC前后所有存活对象使用的内存容量,说明有256K-160K=96K的对象容量被回收,括号内的数据124096K为堆内存的总容量,收集所需要的时间是0.0042708秒(这个时间在每次执行的时候会有所不同)。
java –verbose:jni
-verbose:jni输出native方法调用的相关情况,一般用于诊断jni调用错误信息。
在虚拟机调用native方法时输出设备显示信息,格式如下:
[Dynamic-linking native method java.lang.Object.registerNatives ... JNI]
该参数用来监视虚拟机调用本地方法的情况,在发生jni错误时可为诊断提供便利。
Reference:
1. https://www.slf4j.org/faq.html
2. http://blog.csdn.net/rommal7090/article/details/4041240
3. http://www.javaranger.com/archives/367#java –verbose:jni