之前在使用自定义线程池异步执行耗时任务时,一直记着如果业务方法抛出异常没有捕获,那么是看不到日志框架输出的异常日志的,所以总是在业务方法中包裹一层try-catch捕获可能发生的异常。也未去深入为什么没有打印异常日志和解决方法,这里记录下自己的总结。
1、事例
@Slf4j @SpringBootTest @RunWith(SpringRunner.class) public class ThreadPoolExecutorTest { //这里直接使用ThreadPoolExecutor类,这里没有自定义ThreadFactory,默认使用的是Executors.defaultThreadFactory() private ThreadPoolExecutor threadPool = new ThreadPoolExecutor(1, 1, 1, TimeUnit.MINUTES, new LinkedBlockingQueue<>(10)); @Test public void testSubmitTask() throws InterruptedException { log.info("开始提交任务"); threadPool.execute(this::doSomeThing); log.info("提交任务完成"); TimeUnit.SECONDS.sleep(10); } private void doSomeThing() { int value = 10 / 0; } }
我们在IDEA运行时,控制台是有打印出错误日志的(注意此时并没有设置日志输出到控制台的Appender),实际线上环境我们一般也只配置日志框架输出到日志文件的,那么这个日志是谁打印的呢?这里先不去深追,后面详解。
控制台输出日志:
Exception in thread "pool-1-thread-1" java.lang.ArithmeticException: / by zero at org.layman.study.javainaction.ThreadPoolExecutorTest.doSomeThing(ThreadPoolExecutorTest.java:33) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748)
上面的日志输出结果去我们配置的日志文件查找是找不到的,那么我之前的做法是在doSomeThing方法中使用try-catch包裹:
private void doSomeThing() { try { int value = 10 / 0; } catch (RuntimeException e) { log.error("执行异常:", e); } }
这次执行结果如下,看日志输出我们能明确知道 logger 为org.layman.study.javainaction.ThreadPoolExecutorTest类(注意第3行日志,可以对比下上面的日志输出结果):
2019-09-05 11:04:32.161 logback [main] INFO o.l.s.j.ThreadPoolExecutorTest - 开始提交任务 2019-09-05 11:04:32.162 logback [main] INFO o.l.s.j.ThreadPoolExecutorTest - 提交任务完成 2019-09-05 11:04:32.164 logback [pool-1-thread-1] ERROR o.l.s.j.ThreadPoolExecutorTest - 执行异常: java.lang.ArithmeticException: / by zero at org.layman.study.javainaction.ThreadPoolExecutorTest.doSomeThing(ThreadPoolExecutorTest.java:40) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748)
2、溯源
通常我们提交给线程池的任务后,真正帮我们执行的是Thread.Worker这个类,该类是Thread的内部类,且实现了Runnable接口,直接看run方法实现:
如果不熟悉线程池内部原理,可以去Google下
//Thread$Worker.java public void run() { runWorker(this); } //Thread.java final void runWorker(Worker w) { Thread wt = Thread.currentThread(); Runnable task = w.firstTask; //省略代码。。。 try { while (task != null || (task = getTask()) != null) { //省略代码。。。 try { beforeExecute(wt, task); //㈠任务的前置处理 Throwable thrown = null; //重要关注点 try {