ThreadPoolExecutor执行任务，异常日志缺失问题

之前在使用自定义线程池异步执行耗时任务时，一直记着如果业务方法抛出异常没有捕获，那么是看不到日志框架输出的异常日志的，所以总是在业务方法中包裹一层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 {