Loading

NullPointerException 没有打印异常栈问题追踪

今天去服务器后台看日志,发现有很多NullPointerException异常。我下意识的去找异常栈信息,想看下到底是哪行代码导致了空指针。但是发现日志中只打印出了如下日志:

null
java.lang.NullPointerException: null

我怀疑是不是打印日志的时候没有将异常栈打印出来,于是又去翻看应用代码核实。但是发现日志打印的代码也是正常的。

logger.error(e.getMessage(),e);

这就纳闷了,于是又怀疑是不是日志配置有问题。折腾了一番发现这些配置都没问题。一时没有了思路,只好去求助万能的百度。

问题原因

我在网上找到了这么一段描述:

JVM 虚拟机会对异常信息进行优化,当相同异常出现很多次,会认为它是热点异常,忽略掉异常堆栈信息;通过增加 JVM 参数:-XX:-OmitStackTraceInFastThrow 可解决。

这个描述能很好的解释我发现的问题。代码中出现空指针异常的地方是一个定时任务在不停地调用,当这个异常出现次数太多时 JVM 就会将其过滤掉。

为了验证我的猜想,我去找了下这个服务刚刚启动时的代码,发现这个异常栈是正常打出的,这也验证了自己的猜想,通过异常栈信息也找到了导致空指针异常的代码。

问题重现

下面是自己写的一段代码来显示这个额问题:

public class NullPointStackMissBug {

    static Logger logger = LoggerFactory.getLogger(NullPointStackMissBug.class);

    public static void main(String[] args) {

        for (int i = 0; i < 100000 ; i++) {
            try{
                System.out.println("Loop:"+(i+1));
                String str = "test";
                if(true){
                    str = null;
                }
                str.toUpperCase();
            }catch (Exception e){
                logger.error(e.getMessage(),e);
            }
        }
    }

}

在JVM启动参数中增加:-XX:-OmitStackTraceInFastThrow后,异常就能正常输出。

posted @ 2020-05-21 14:32  程序员自由之路  阅读(3918)  评论(2编辑  收藏  举报