spring-boot web 项目启动后，线程中有异步操作的话，第一次 HTTP 请求会超时很久是怎么回事？

[esolve]

大致上的代码结构是

@RestController
class ApiClass{

    Logger logger = LoggerFactory.getLogger("log");
    
    @Autowired
    ApiService apiService;
    
    @RequestMapping(value = "/api")
    public String doApi{
        logger.info("HTTP 请求  ");
        String result = apiService.doService();
        return ResponseUtil.jsonSuccess(result); //这里产生类似下面日志中的 json 串
    }
     
}

@Service
class ApiService{
    
    @Transactional
    String doService(){
        logger.debug("测试 1 处");
        。。。
        logger.debug("测试 2 处");
        LocalThread.FIXED_THREAD_POOL.execute(new Runnable() {
                @Override
                public void run() {
                    doAsync();  //大量数据库操作
                }
            });
        logger.debug("测试 3 处");
        。。。
        logger.debug("测试 4 处");
        return "xxxx";
        
    }

}

其中
public class LocalThread {
    public static ExecutorService FIXED_THREAD_POOL = Executors.newFixedThreadPool(5);
}

2017-10-12 16:24:22,407 INFO  - HTTP 请求  
2017-10-12 16:26:02,943 DEBUG - 测试 1 处
2017-10-12 16:26:03,022 DEBUG - 测试 2 处
2017-10-12 16:26:03,024 DEBUG - 测试 3 处
2017-10-12 16:26:03,069 DEBUG - 测试 4 处

下面是 spring-boot 日志
2017-10-12 16:26:03,565 [http-nio-8070-exec-1] DEBUG [org.springframework.web.servlet.mvc.method.annotation.RequestResponseBodyMethodProcessor] - Written [{"code":"2001","message":"成功","body":[{"data":"xxxx"}] as "text/html" using [org.springframework.http.converter.StringHttpMessageConverter@2bdd8394]

但是之后的请求：

2017-10-12 17:28:48,323 INFO  - HTTP 请求
2017-10-12 17:28:48,324 DEBUG - 测试 1 处
2017-10-12 17:28:48,330 DEBUG - 测试 2 处
2017-10-12 17:28:48,330 DEBUG - 测试 3 处
2017-10-12 17:28:48,332 DEBUG - 测试 4 处

2017-10-12 17:28:48,337 [http-nio-8070-exec-1] DEBUG [org.springframework.web.servlet.mvc.method.annotation.RequestResponseBodyMethodProcessor] - Written [{"code":"2001","message":"成功","body":[{"data":"xxxx"}] as "text/html" using [org.springframework.http.converter.StringHttpMessageConverter@2bdd8394]

其中，我注意到：

1 第一次请求从 controller 跳转到 service 都花了将近 2 分钟 而之后的请求没这样

2 第一次请求，从测试 1 到日志打出返回 json，花了将近 0.6 秒，而第二个则是 0.01 秒左右

为何差别这么大？

[esolve]

用 IDE 在本地运行没这么大延时
然后发现，pom 里的版本是

<plugin>
<groupId>org.apache.maven.plugins</groupId>
<artifactId>maven-compiler-plugin</artifactId>
<configuration>
<source>1.8</source>
<target>1.8</target>
<showWarnings>true</showWarnings>
</configuration>
</plugin>

本地环境是 1.8
但是在部署环境中

java -version
java version "1.7.0_55"
Java(TM) SE Runtime Environment (build 1.7.0_55-b13)
Java HotSpot(TM) 64-Bit Server VM (build 24.55-b03, mixed mode)

这个影响这么大吗？

[hpeng]

我很怀疑，1.8 的字节码能在 1.7 中跑？我很怀疑你的环境。

[esolve]

@hpeng 能跑啊，否则哪来的日志

[WinterWu]

怀疑是初始化，包括数据库相关的。你把日志级别调整到 Trace，看一下整个初始化过程。

[esolve]

@WinterWu 那为何在本地 IDE 下跑就没有延迟？

[misaka19000]

1.7 虚拟机会拒绝运行由 1.8 的编译器生成的字节码的。。

[WinterWu]

1. Spring 使用了大量的延迟加载技术
2. 你自己去详细跟一下，对比一下差别就知道了，不要什么都问；另外大量问题网上都有现成答案的。
3. 1.7 的虚拟机当然是不能跑 1.8 的

[mgcnrx11]

看看服务器上 hosts 配置，主机名配置是否正确。这些会在第一次影响网络解析

[esolve]

@mgcnrx11 这个应该没问题，我对这个 web 项目重启很多次了

[GKLuke]

一般来说，java 生产用什么版本，开发测试就什么版本，精确到小版本

[esolve]

@WinterWu

开了 trace，但是这两分钟的跳跃，中间没有任何信息。。。

2017-10-13 11:12:49,017 [http-nio-8070-exec-1] DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - Creating new transaction with name [com.eversec.centertool.modules.woapi.service.WoApiService.getActivityVo]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT,readOnly; ''
2017-10-13 11:14:29,541 [http-nio-8070-exec-1] DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - Acquired Connection [ProxyConnection[PooledConnection[com.mysql.jdbc.JDBC4Connection@3440eaa3]]] for JDBC transaction

[esolve]

@hpeng 是搞错了，启动脚本里有 source /etc/profile 命令，用的是 1.8

[myz2018]

jstack 看一下就知道了额，看看那个线程在做什么，

[jack80342]

最近翻译了 Spring Boot 最新的官方文档，欢迎 Fork，https://www.gitbook.com/book/jack80342/spring-boot/details

[zranye]

@jack80342 朋友，看了你翻译的文档，先表示非常敬佩你的奉献精神。
提个意见，gitbook 在国内访问实在是太痛苦了，能不能考虑转到国内类似的平台

[jack80342]

@zranye #15 哥们，你有推荐的嘛？