我是否可以为spring data jpa启用任何日志，以了解它在Tomcat中运行缓慢的原因？

Question

我在DBeaver中有一个查询，运行时间约为6ms，我使用常规日志提取传入的查询，该查询与我的Tomcat服务器连接的数据库相同

select min(organizati0_.Downloaded) as col_0_0_ from oss_collection_history organizati0_ 
where organizati0_.AccountingSystem='SomeSystem' 
and (organizati0_.Org in ('ID1' , 'ID2', ..., 'ID10' ))

在针对数据库的Dockerized副本运行Spring Data JPA的JUnit测试中，该查询也运行得相当快，只需60毫秒，但是在Tomcat上，它需要8-10秒以上。

所以不完全确定发生了什么。

我只是使用相同的参数来分析函数调用。

var start = System.currentTimeMillis();
consolidatedDownloadSvc.getAvailableYearsOfDownloadedForAccountingSystem(...);
System.out.println(System.currentTimeMillis() - start);

即使我设置了全局log_queries_not_using_indexes = 'ON'，它也不会出现在 slowlog 上;因此，这将向我指示查询已编入索引。

所以我想知道是否有某种日志可以显示我在Spring-Data和Hibernate中可能丢失的任何额外的东西。

Answer 1

除了@Panagiotis所建议的，让下面的统计数据了解hibernate在哪个阶段花费更多时间也是有帮助的。

spring.jpa.properties.hibernate.generate_statistics=true
logging.level.org.hibernate.stat=DEBUG

您会在日志中看到如下内容

2021-12-29 10:54:52.408  INFO 31972 - – [           main] i.StatisticalLoggingSessionEventListener : Session Metrics {
    5070899 nanoseconds spent acquiring 1 JDBC connections;
    0 nanoseconds spent releasing 0 JDBC connections;
    4359903 nanoseconds spent preparing 20 JDBC statements;
    76117198 nanoseconds spent executing 20 JDBC statements;
    0 nanoseconds spent executing 0 JDBC batches;
    132026893 nanoseconds spent performing 40 L2C puts;
    0 nanoseconds spent performing 0 L2C hits;
    838900 nanoseconds spent performing 19 L2C misses;
    0 nanoseconds spent executing 0 flushes (flushing a total of 0 entities and 0 collections);
    16900 nanoseconds spent executing 1 partial-flushes (flushing a total of 0 entities and 0 collections)
}