单实体Hibernate Flush耗时超10秒的原因排查

背景 #

通过Spring Data JPA的save方法，使用Hibernate保存一个实体。该实体结构简单，仅包含若干字段，大小约30KB，主要因为存在两个JSON字段。
当前处于开发阶段，表中数据量极小（仅约一千行），未创建索引，使用MySQL数据库，无关联实体（如外键），所有配置均为默认。

问题现象 #

部分插入操作耗时极长（超过10秒），但通常仅需数毫秒，数据来源于Hibernate的统计日志：

org.hibernate.engine.internal.StatisticalLoggingSessionEventListener.end(StatisticalLoggingSessionEventListener.java:258) - Session Metrics {
906283 nanoseconds spent acquiring 1 JDBC connections;
0 nanoseconds spent releasing 0 JDBC connections;
179137263 nanoseconds spent preparing 1 JDBC statements;
4523687 nanoseconds spent executing 1 JDBC statements;
0 nanoseconds spent executing 0 JDBC batches;
0 nanoseconds spent performing 0 L2C puts;
0 nanoseconds spent performing 0 L2C hits;
0 nanoseconds spent performing 0 L2C misses;
17862368456 nanoseconds spent executing 1 flushes (flushing a total of 1 entities and 0 collections);
0 nanoseconds spent executing 0 partial-flushes (flushing a total of 0 entities and 0 collections)
}

从日志中可发现两个耗时痛点：

• JDBC语句准备耗时
• Flush操作执行耗时

每次操作始终仅执行1条语句，且仅Flush一个实体。

可能的原因及排查方向 #

1. JDBC语句准备耗时的诱因 #

• 连接池隐性等待：虽然日志显示连接获取耗时短，但连接池可能存在连接复用异常，比如部分连接处于半关闭状态，导致语句准备阶段需要重新初始化连接资源。可以检查连接池的maxIdle、minIdle配置，以及连接有效性检测机制。
• PreparedStatement缓存未命中：Hibernate和MySQL默认会缓存PreparedStatement，若缓存被清理（比如数据库重启、配置的缓存容量不足），重新生成并解析SQL会大幅增加耗时。可以开启hibernate.statement_cache.size配置，增大缓存容量。
• JSON字段序列化开销：实体的JSON字段在生成PreparedStatement时，需要完成Java对象到JSON字符串的序列化，若偶尔遇到特殊字符、隐性循环引用，会导致序列化耗时激增。可以单独测试JSON字段的序列化耗时，排查是否存在异常数据。

2. Flush操作耗时的诱因 #

• Session脏检查范围异常：即使仅操作一个实体，若当前Session中残留了之前操作的其他实体（比如事务边界不合理，Session未及时清理），Hibernate在Flush时会对所有Session内的实体做脏检查，导致耗时增加。可以检查事务的边界设置，确保每次操作使用独立的Session或及时清理Session。
• MySQL锁冲突：开发环境中可能存在其他并行操作（比如测试查询、手动数据修改），导致插入时遇到行锁或表锁等待。虽然日志显示语句执行耗时短，但Flush阶段实际在等待锁释放。可以开启MySQL的慢查询日志和锁监控，查看对应时间段的锁等待记录。
• JSON字段存储IO瓶颈：30KB的实体主要来自JSON字段，MySQL存储JSON时，若字段长度接近inline存储阈值（默认是4KB），会转为外部存储，偶尔遇到磁盘IO峰值时，Flush的磁盘写入操作会耗时激增。可以查看MySQL的innodb_json_key_column_store配置，以及磁盘IO监控数据。

3. 其他排查点 #

• 开发环境资源限制：数据库服务器的CPU、内存、磁盘IO资源不足，偶尔出现资源争抢（比如其他应用占用资源），导致操作变慢。可以监控数据库服务器的实时资源使用率。
• Hibernate Flush模式问题：默认Flush模式为AUTO，会在查询或事务提交前自动触发Flush，若存在隐式查询（比如Hibernate的内部校验操作），会导致Flush时机不可控。可以尝试将Flush模式设置为COMMIT，仅在事务提交时执行Flush。

内容的提问来源于stack exchange，提问作者jubatus