spring嵌套事务，日志，流程记录

2021-09-01 14:23:13.908 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Creating new transaction with name [com.ryan.practice.redislock.service.tran.ServiceA.addWillDeleteUser]: PROPAGATION_REQUIRED,ISOLATION_READ_COMMITTED,-java.lang.Exception
2021-09-01 14:23:13.909 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Acquired Connection [HikariProxyConnection@1082933369 wrapping com.mysql.jdbc.JDBC4Connection@74c074e5] for JDBC transaction
2021-09-01 14:23:13.911 DEBUG 6192 --- [nio-8010-exec-1] o.s.jdbc.datasource.DataSourceUtils      : Changing isolation level of JDBC Connection [HikariProxyConnection@1082933369 wrapping com.mysql.jdbc.JDBC4Connection@74c074e5] 

2021-09-01 14:23:13.912 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Switching JDBC Connection [HikariProxyConnection@1082933369 wrapping com.mysql.jdbc.JDBC4Connection@74c074e5] to manual commit
2021-09-01 14:23:13.919 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Creating a new SqlSession
2021-09-01 14:23:13.923 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Registering transaction synchronization for SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@4bf8dfc9]
2021-09-01 14:23:15.216 DEBUG 6192 --- [nio-8010-exec-1] o.m.s.t.SpringManagedTransaction         : JDBC Connection [HikariProxyConnection@1082933369 wrapping com.mysql.jdbc.JDBC4Connection@74c074e5] will be managed by Spring
2021-09-01 14:23:15.225 DEBUG 6192 --- [nio-8010-exec-1] c.r.p.r.mapper.UserMapper.insert         : ==>  Preparing: INSERT INTO t_user ( id, user_name, age, is_delete ) VALUES ( ?, ?, ?, ? ) 
2021-09-01 14:23:15.240 DEBUG 6192 --- [nio-8010-exec-1] c.r.p.r.mapper.UserMapper.insert         : ==> Parameters: 1432952171097329666(Long), May(String), 18(Integer), 0(Integer)
2021-09-01 14:23:15.366 DEBUG 6192 --- [nio-8010-exec-1] c.r.p.r.mapper.UserMapper.insert         : <==    Updates: 1
2021-09-01 14:23:15.366 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Releasing transactional SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@4bf8dfc9]

2021-09-01 14:23:15.367 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Creating nested transaction with name [com.ryan.practice.redislock.service.tran.ServiceA.updateWillDeleteUser]
2021-09-01 14:23:15.374 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Fetched SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@4bf8dfc9] from current transaction
2021-09-01 14:23:15.406 DEBUG 6192 --- [nio-8010-exec-1] c.r.p.r.mapper.UserMapper.update         : ==>  Preparing: UPDATE t_user SET is_delete=? WHERE user_name=? 
2021-09-01 14:23:15.407 DEBUG 6192 --- [nio-8010-exec-1] c.r.p.r.mapper.UserMapper.update         : ==> Parameters: 1(Integer), May(String)
2021-09-01 14:23:15.462 DEBUG 6192 --- [nio-8010-exec-1] c.r.p.r.mapper.UserMapper.update         : <==    Updates: 26
2021-09-01 14:23:15.462 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Releasing transactional SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@4bf8dfc9]
2021-09-01 14:23:15.462 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Rolling back transaction to savepoint

2021-09-01 14:23:15.463 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Transaction synchronization committing SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@4bf8dfc9]
2021-09-01 14:23:15.464 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Transaction synchronization deregistering SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@4bf8dfc9]
2021-09-01 14:23:15.464 DEBUG 6192 --- [nio-8010-exec-1] org.mybatis.spring.SqlSessionUtils       : Transaction synchronization closing SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@4bf8dfc9]
2021-09-01 14:23:15.464 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Initiating transaction commit
2021-09-01 14:23:15.464 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Committing JDBC transaction on Connection [HikariProxyConnection@1082933369 wrapping com.mysql.jdbc.JDBC4Connection@74c074e5]
2021-09-01 14:23:15.523 DEBUG 6192 --- [nio-8010-exec-1] o.s.jdbc.datasource.DataSourceUtils      : Resetting isolation level of JDBC Connection [HikariProxyConnection@1082933369 wrapping com.mysql.jdbc.JDBC4Connection@74c074e5] to 4
2021-09-01 14:23:15.524 DEBUG 6192 --- [nio-8010-exec-1] o.s.j.d.DataSourceTransactionManager     : Releasing JDBC Connection [HikariProxyConnection@1082933369 wrapping com.mysql.jdbc.JDBC4Connection@74c074e5] after transaction
2021-09-01 14:23:15.593 DEBUG 6192 --- [nio-8010-exec-1] m.m.a.RequestResponseBodyMethodProcessor : Using 'application/json', given [*/*] and supported [application/json, application/*+json, application/json, application/*+json]
2021-09-01 14:23:15.593 DEBUG 6192 --- [nio-8010-exec-1] m.m.a.RequestResponseBodyMethodProcessor : Writing [com.ryan.practice.redislock.common.api.ResponseData@64c198b5]
2021-09-01 14:23:15.625 DEBUG 6192 --- [nio-8010-exec-1] o.s.web.servlet.DispatcherServlet        : Completed 200 OK

jdbc connection -> new sqlsession -> 执行sql -> release transactional sqlsession -> creating nested transaction -> fetch sqlsession -> release transactional sqlsession -> transaction committing sqlsession -> 
initiating transaction commit -> commit jdbc transaction on connection -> resetting isolation level of jdbc connection -> release jdbc connection