几个简单的例子让你读懂什么是JAVA的堆栈跟踪

 

 

简单的来说，堆栈跟踪就是我们的程序在抛出异常时使用的方法调用列表。

 

简单的例子

通过问题中给出的示例，我们可以准确地确定应用程序中抛出异常的位置。

我们来看看堆栈跟踪：

Exception in thread "main" java.lang.NullPointerException

        at com.example.myproject.Book.getTitle(Book.java:16)

        at com.example.myproject.Author.getBookTitles(Author.java:25)

        at com.example.myproject.Bootstrap.main(Bootstrap.java:14)

这是一个非常简单的异常-空指针异常，同样也是最简单的堆栈跟踪。如果我们从第一行“at ...”开始读，我们就可以知道错误发生在哪里。我们正在寻找的是最重要的方法调用，这是我们应用程序的一部分。在这种情况下，它是：

at com.example.myproject.Book.getTitle(Book.java:16)

要调试这个，我们可以打开Book.java并查看行16，如下：

15   public String getTitle() {

16      System.out.println(title.toString());

17      return title;

18   }

这就表明某些东西空指针异常（可能title）在上面的代码中。

我们再看一个异常链的例子

有时应用程序会捕获异常并重新抛出这个异常来作为另一个异常的原因。

通常如下：

public void getBookIds(int id) {

35      try {

36         book.getId(id);    // this method it throws a NullPointerException on line 22

37      } catch (NullPointerException e) {

38         throw new IllegalStateException("A book has a null property", e)

39      }

40   }

这可能会给你一个堆栈跟踪，比如说：

Exception in thread "main" java.lang.IllegalStateException: A book has a null property

        at com.example.myproject.Author.getBookIds(Author.java:38)

        at com.example.myproject.Bootstrap.main(Bootstrap.java:14)

Caused by: java.lang.NullPointerException

        at com.example.myproject.Book.getId(Book.java:22)

        at com.example.myproject.Author.getBookIds(Author.java:36)

        ... 1 more

这个和刚才例子最大的不同就是多了个“Caused by”。有时也会有多个“Caused by”部分。对于这种异常，你通常需要找到 “root cause”。

在我们这一个例子中，它是：

Caused by: java.lang.NullPointerException <-- root cause

        at com.example.myproject.Book.getId(Book.java:22) <-- important line

同样，在这个例子中，我们要去看看行22的Book.java，看看有什么可能影响NullPointerException的代码。

 

 

更让人头疼的是library库抛异常的例子

通常来说堆栈跟踪其实比上述两个例子复杂得多。我们这里再分析一个很长的例子，但是这个例子包含了几个级别的链接异常：

javax.servlet.ServletException: Something bad happened

    at com.example.myproject.OpenSessionInViewFilter.doFilter(OpenSessionInViewFilter.java:60)

    at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)

    at com.example.myproject.ExceptionHandlerFilter.doFilter(ExceptionHandlerFilter.java:28)

    at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)

    at com.example.myproject.OutputBufferFilter.doFilter(OutputBufferFilter.java:33)

    at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)

    at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)

    at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)

    at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)

    at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)

    at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:418)

    at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)

    at org.mortbay.jetty.Server.handle(Server.java:326)

    at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542)

    at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:943)

    at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:756)

    at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:218)

    at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)

    at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:228)

    at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)

Caused by: com.example.myproject.MyProjectServletException

    at com.example.myproject.MyServlet.doPost(MyServlet.java:169)

    at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)

    at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)

    at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:511)

    at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1166)

    at com.example.myproject.OpenSessionInViewFilter.doFilter(OpenSessionInViewFilter.java:30)

    ... 27 more

Caused by: org.hibernate.exception.ConstraintViolationException: could not insert: [com.example.myproject.MyEntity]

    at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:96)

    at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)

    at org.hibernate.id.insert.AbstractSelectingDelegate.performInsert(AbstractSelectingDelegate.java:64)

    at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2329)

    at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2822)

    at org.hibernate.action.EntityIdentityInsertAction.execute(EntityIdentityInsertAction.java:71)

    at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:268)

    at org.hibernate.event.def.AbstractSaveEventListener.performSaveOrReplicate(AbstractSaveEventListener.java:321)

    at org.hibernate.event.def.AbstractSaveEventListener.performSave(AbstractSaveEventListener.java:204)

    at org.hibernate.event.def.AbstractSaveEventListener.saveWithGeneratedId(AbstractSaveEventListener.java:130)

    at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.saveWithGeneratedOrRequestedId(DefaultSaveOrUpdateEventListener.java:210)

    at org.hibernate.event.def.DefaultSaveEventListener.saveWithGeneratedOrRequestedId(DefaultSaveEventListener.java:56)

    at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.entityIsTransient(DefaultSaveOrUpdateEventListener.java:195)

    at org.hibernate.event.def.DefaultSaveEventListener.performSaveOrUpdate(DefaultSaveEventListener.java:50)

    at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.onSaveOrUpdate(DefaultSaveOrUpdateEventListener.java:93)

    at org.hibernate.impl.SessionImpl.fireSave(SessionImpl.java:705)

    at org.hibernate.impl.SessionImpl.save(SessionImpl.java:693)

    at org.hibernate.impl.SessionImpl.save(SessionImpl.java:689)

    at sun.reflect.GeneratedMethodAccessor5.invoke(Unknown Source)

    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

    at java.lang.reflect.Method.invoke(Method.java:597)

    at org.hibernate.context.ThreadLocalSessionContext$TransactionProtectionWrapper.invoke(ThreadLocalSessionContext.java:344)

    at $Proxy19.save(Unknown Source)

    at com.example.myproject.MyEntityService.save(MyEntityService.java:59) <-- relevant call (see notes below)

    at com.example.myproject.MyServlet.doPost(MyServlet.java:164)

    ... 32 more

Caused by: java.sql.SQLException: Violation of unique constraint MY_ENTITY_UK_1: duplicate value(s) for column(s) MY_COLUMN in statement [...]

    at org.hsqldb.jdbc.Util.throwError(Unknown Source)

    at org.hsqldb.jdbc.jdbcPreparedStatement.executeUpdate(Unknown Source)

    at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeUpdate(NewProxyPreparedStatement.java:105)

    at org.hibernate.id.insert.AbstractSelectingDelegate.performInsert(AbstractSelectingDelegate.java:57)

    ... 54 more

在这个例子后面还有很多的” Caused by”。但是我们最关心的是寻找来自我们自己代码的错误，就是我们自己的com.example.myproject包中的任何东西。从第二个例子中，我们首先看看抛出的异常，它是：

Caused by: java.sql.SQLException

但是，所有的方法调用都是第三方库代码。所以我们将转到上面的“Caused by”，并寻找来自我们自己的代码的第一个方法调用，它是：

at com.example.myproject.MyEntityService.save(MyEntityService.java:59)

就像在前面的例子中，我们应该看看MyEntityService.java第59行，因为这是此错误的起源，那么通过代码的排查我们最终就能发现并解决相关问题。

文章原创。

若转载，请注明出处：“来自蜗牛学院cto李懿老师”。

若没有按照以上注明出处，一经发现必追究。

作者：蜗牛学院CTO李懿老师

互联网人才孵化基地，商业项目孵化，IT团队培育。