cay914 opened a new issue #6562:
URL: https://github.com/apache/shardingsphere/issues/6562


   I used the springcloud + jpa + jdbc. inserting the undo_log is ok.
   but when i used the springcloud + sharding-jdbc + jpa, the sharding-jdbc 
can't rollback and the undo_table is alway empty.
   
   my setting is:
   seata:1.3.0
   shardingsphere:4.1.1
   
   and the log:
   onnected to the target VM, address: '127.0.0.1:1443', transport: 'socket'
   2020-08-01 05:57:53.056  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'org.springframework.cloud.autoconfigure.ConfigurationPropertiesRebinderAutoConfiguration'
 of type 
[org.springframework.cloud.autoconfigure.ConfigurationPropertiesRebinderAutoConfiguration$$EnhancerBySpringCGLIB$$1478a6f0]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   
     .   ____          _            __ _ _
    /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
   ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
    \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
     '  |____| .__|_| |_|_| |_\__, | / / / /
    =========|_|==============|___/=/_/_/_/
    :: Spring Boot ::       (v2.1.10.RELEASE)
   
   2020-08-01 05:57:54.898  INFO 21656 --- [           main] 
b.c.PropertySourceBootstrapConfiguration : Located property source: 
CompositePropertySource {name='NACOS', propertySources=[NacosPropertySource 
{name='order-service.yaml'}]}
   2020-08-01 05:57:54.904  INFO 21656 --- [           main] 
com.codebattery.Application              : No active profile set, falling back 
to default profiles: default
   2020-08-01 05:57:56.535  WARN 21656 --- [           main] 
o.s.boot.actuate.endpoint.EndpointId     : Endpoint ID 'nacos-config' contains 
invalid characters, please migrate to a valid format.
   2020-08-01 05:57:56.540  WARN 21656 --- [           main] 
o.s.boot.actuate.endpoint.EndpointId     : Endpoint ID 'nacos-discovery' 
contains invalid characters, please migrate to a valid format.
   2020-08-01 05:57:56.982  INFO 21656 --- [           main] 
.s.d.r.c.RepositoryConfigurationDelegate : Multiple Spring Data modules found, 
entering strict repository configuration mode!
   2020-08-01 05:57:56.983  INFO 21656 --- [           main] 
.s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data 
repositories in DEFAULT mode.
   2020-08-01 05:57:57.115  INFO 21656 --- [           main] 
.s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository 
scanning in 121ms. Found 1 repository interfaces.
   2020-08-01 05:57:57.132  INFO 21656 --- [           main] 
.s.d.r.c.RepositoryConfigurationDelegate : Multiple Spring Data modules found, 
entering strict repository configuration mode!
   2020-08-01 05:57:57.133  INFO 21656 --- [           main] 
.s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data 
repositories in DEFAULT mode.
   2020-08-01 05:57:57.161  INFO 21656 --- [           main] 
.RepositoryConfigurationExtensionSupport : Spring Data Redis - Could not safely 
identify store assignment for repository candidate interface 
com.codebattery.repository.OrderRepository. If you want this repository to be a 
Redis repository, consider annotating your entities with one of these 
annotations: org.springframework.data.redis.core.RedisHash (preferred), or 
consider extending one of the following types with your repository: 
org.springframework.data.keyvalue.repository.KeyValueRepository.
   2020-08-01 05:57:57.161  INFO 21656 --- [           main] 
.s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository 
scanning in 16ms. Found 0 repository interfaces.
   2020-08-01 05:57:57.293  WARN 21656 --- [           main] 
o.s.boot.actuate.endpoint.EndpointId     : Endpoint ID 'service-registry' 
contains invalid characters, please migrate to a valid format.
   2020-08-01 05:57:57.810  INFO 21656 --- [           main] 
o.s.cloud.context.scope.GenericScope     : BeanFactory 
id=c2353cba-f6eb-32ff-bdb6-a33d2f44b06e
   2020-08-01 05:57:57.865  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'com.codebattery.client.StorageFeignClient' of type 
[org.springframework.cloud.openfeign.FeignClientFactoryBean] is not eligible 
for getting processed by all BeanPostProcessors (for example: not eligible for 
auto-proxying)
   2020-08-01 05:57:57.976  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'stringToNoneShardingStrategyConfigurationConverter' of type 
[org.apache.shardingsphere.spring.boot.converter.StringToNoneShardingStrategyConfigurationConverter]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:57.985  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'spring.cloud.alibaba.seata-com.alibaba.cloud.seata.SeataProperties' of type 
[com.alibaba.cloud.seata.SeataProperties] is not eligible for getting processed 
by all BeanPostProcessors (for example: not eligible for auto-proxying)
   2020-08-01 05:57:57.985  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'com.alibaba.cloud.seata.GlobalTransactionAutoConfiguration' of type 
[com.alibaba.cloud.seata.GlobalTransactionAutoConfiguration$$EnhancerBySpringCGLIB$$858aad6e]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:58.066  INFO 21656 --- [           main] 
io.seata.config.FileConfiguration        : The configuration file used is 
registry.conf
   2020-08-01 05:57:58.094  INFO 21656 --- [           main] 
io.seata.config.FileConfiguration        : The configuration file used is 
file.conf
   2020-08-01 05:57:58.098  INFO 21656 --- [           main] 
i.s.s.a.GlobalTransactionScanner         : Initializing Global Transaction 
Clients ... 
   2020-08-01 05:57:58.312  INFO 21656 --- [           main] 
i.s.c.r.netty.AbstractRpcRemotingClient  : RpcClientBootstrap has started
   2020-08-01 05:57:58.316  INFO 21656 --- [           main] 
i.s.s.a.GlobalTransactionScanner         : Transaction Manager Client is 
initialized. applicationId[order-service] txServiceGroup[my_test_tx_group]
   2020-08-01 05:57:58.343  INFO 21656 --- [           main] 
io.seata.rm.datasource.AsyncWorker       : Async Commit Buffer Limit: 10000
   2020-08-01 05:57:58.344  INFO 21656 --- [           main] 
i.s.rm.datasource.xa.ResourceManagerXA   : ResourceManagerXA init ...
   2020-08-01 05:57:58.356  INFO 21656 --- [           main] 
i.s.c.r.netty.AbstractRpcRemotingClient  : RpcClientBootstrap has started
   2020-08-01 05:57:58.357  INFO 21656 --- [           main] 
i.s.s.a.GlobalTransactionScanner         : Resource Manager is initialized. 
applicationId[order-service] txServiceGroup[my_test_tx_group]
   2020-08-01 05:57:58.357  INFO 21656 --- [           main] 
i.s.s.a.GlobalTransactionScanner         : Global Transaction Clients are 
initialized. 
   2020-08-01 05:57:58.404  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'spring.shardingsphere.sharding-org.apache.shardingsphere.shardingjdbc.spring.boot.sharding.SpringBootShardingRuleConfigurationProperties'
 of type 
[org.apache.shardingsphere.shardingjdbc.spring.boot.sharding.SpringBootShardingRuleConfigurationProperties]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:58.411  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'spring.shardingsphere.masterslave-org.apache.shardingsphere.shardingjdbc.spring.boot.masterslave.SpringBootMasterSlaveRuleConfigurationProperties'
 of type 
[org.apache.shardingsphere.shardingjdbc.spring.boot.masterslave.SpringBootMasterSlaveRuleConfigurationProperties]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:58.416  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'spring.shardingsphere.encrypt-org.apache.shardingsphere.shardingjdbc.spring.boot.encrypt.SpringBootEncryptRuleConfigurationProperties'
 of type 
[org.apache.shardingsphere.shardingjdbc.spring.boot.encrypt.SpringBootEncryptRuleConfigurationProperties]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:58.423  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'spring.shardingsphere.shadow-org.apache.shardingsphere.shardingjdbc.spring.boot.shadow.SpringBootShadowRuleConfigurationProperties'
 of type 
[org.apache.shardingsphere.shardingjdbc.spring.boot.shadow.SpringBootShadowRuleConfigurationProperties]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:58.430  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'spring.shardingsphere-org.apache.shardingsphere.shardingjdbc.spring.boot.common.SpringBootPropertiesConfigurationProperties'
 of type 
[org.apache.shardingsphere.shardingjdbc.spring.boot.common.SpringBootPropertiesConfigurationProperties]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:58.861  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'org.apache.shardingsphere.shardingjdbc.spring.boot.SpringBootConfiguration' of 
type 
[org.apache.shardingsphere.shardingjdbc.spring.boot.SpringBootConfiguration$$EnhancerBySpringCGLIB$$8c4f8b4c]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:58.970  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration'
 of type 
[org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$f85ea3f3]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:59.083  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 'redisConfig' of type 
[com.codebattery.config.RedisConfig$$EnhancerBySpringCGLIB$$ab7a53e4] is not 
eligible for getting processed by all BeanPostProcessors (for example: not 
eligible for auto-proxying)
   2020-08-01 05:57:59.182  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'com.alibaba.cloud.seata.feign.SeataFeignClientAutoConfiguration$FeignBeanPostProcessorConfiguration'
 of type 
[com.alibaba.cloud.seata.feign.SeataFeignClientAutoConfiguration$FeignBeanPostProcessorConfiguration$$EnhancerBySpringCGLIB$$e5702cb9]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:59.195  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 'seataFeignObjectWrapper' of 
type [com.alibaba.cloud.seata.feign.SeataFeignObjectWrapper] is not eligible 
for getting processed by all BeanPostProcessors (for example: not eligible for 
auto-proxying)
   2020-08-01 05:57:59.249  INFO 21656 --- [           main] 
trationDelegate$BeanPostProcessorChecker : Bean 
'org.springframework.cloud.autoconfigure.ConfigurationPropertiesRebinderAutoConfiguration'
 of type 
[org.springframework.cloud.autoconfigure.ConfigurationPropertiesRebinderAutoConfiguration$$EnhancerBySpringCGLIB$$1478a6f0]
 is not eligible for getting processed by all BeanPostProcessors (for example: 
not eligible for auto-proxying)
   2020-08-01 05:57:59.954  INFO 21656 --- [           main] 
o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat initialized with port(s): 
9014 (http)
   2020-08-01 05:58:00.027  INFO 21656 --- [           main] 
o.apache.catalina.core.StandardService   : Starting service [Tomcat]
   2020-08-01 05:58:00.029  INFO 21656 --- [           main] 
org.apache.catalina.core.StandardEngine  : Starting Servlet engine: [Apache 
Tomcat/9.0.27]
   2020-08-01 05:58:00.573  INFO 21656 --- [           main] 
o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring embedded 
WebApplicationContext
   2020-08-01 05:58:00.573  INFO 21656 --- [           main] 
o.s.web.context.ContextLoader            : Root WebApplicationContext: 
initialization completed in 5638 ms
   Loading class `com.mysql.jdbc.Driver'. This is deprecated. The new driver 
class is `com.mysql.cj.jdbc.Driver'. The driver is automatically registered via 
the SPI and manual loading of the driver class is generally unnecessary.
   2020-08-01 05:58:03.035  INFO 21656 --- [           main] 
com.alibaba.druid.pool.DruidDataSource   : {dataSource-1} inited
   2020-08-01 05:58:03.662  INFO 21656 --- [           main] 
com.alibaba.druid.pool.DruidDataSource   : {dataSource-2} inited
   2020-08-01 05:58:03.933  INFO 21656 --- [           main] 
o.a.s.core.log.ConfigurationLogger       : ShardingRuleConfiguration:
   bindingTables:
   - t_order
   tables:
     t_order:
       actualDataNodes: order0.t_order_$->{[0,2,4,6]}, 
order1.t_order_$->{[1,3,5,7]}
       databaseStrategy:
         inline:
           algorithmExpression: order${user_id % 2}
           shardingColumn: user_id
       keyGenerator:
         column: order_id
         type: SNOWFLAKE
       logicTable: t_order
       tableStrategy:
         inline:
           algorithmExpression: t_order_$->{user_id % 8}
           shardingColumn: user_id
   
   2020-08-01 05:58:03.935  INFO 21656 --- [           main] 
o.a.s.core.log.ConfigurationLogger       : Properties:
   sql.show: 'true'
   
   2020-08-01 05:58:03.965  INFO 21656 --- [           main] 
ShardingSphere-metadata                  : Loading 1 logic tables' meta data.
   2020-08-01 05:58:04.031  INFO 21656 --- [           main] 
ShardingSphere-metadata                  : Meta data load finished, cost 96 
milliseconds.
   2020-08-01 05:58:06.853  INFO 21656 --- [           main] 
io.seata.config.FileConfiguration        : The configuration file used is 
seata.conf
   2020-08-01 05:58:08.833  INFO 21656 --- [           main] 
i.s.c.r.netty.NettyClientChannelManager  : will connect to 192.168.3.31:8091
   2020-08-01 05:58:08.834  INFO 21656 --- [           main] 
io.seata.core.rpc.netty.RmRpcClient      : RM will register 
:jdbc:mysql://192.168.3.32:3306/order0
   2020-08-01 05:58:08.841  INFO 21656 --- [           main] 
i.s.core.rpc.netty.NettyPoolableFactory  : NettyPool create channel to 
transactionRole:RMROLE,address:192.168.3.31:8091,msg:< 
RegisterRMRequest{resourceIds='jdbc:mysql://192.168.3.32:3306/order0', 
applicationId='order-service', transactionServiceGroup='my_test_tx_group'} >
   2020-08-01 05:58:10.886  INFO 21656 --- [           main] 
io.seata.core.rpc.netty.RmRpcClient      : register RM success. server 
version:1.2.0,channel:[id: 0x1612b070, L:/192.168.3.15:1505 - 
R:/192.168.3.31:8091]
   2020-08-01 05:58:10.901  INFO 21656 --- [           main] 
i.s.core.rpc.netty.NettyPoolableFactory  : register success, cost 175 ms, 
version:1.2.0,role:RMROLE,channel:[id: 0x1612b070, L:/192.168.3.15:1505 - 
R:/192.168.3.31:8091]
   2020-08-01 05:58:10.903  INFO 21656 --- [           main] 
io.seata.core.rpc.netty.RmRpcClient      : will register 
resourceId:jdbc:mysql://192.168.3.32:3306/order1
   2020-08-01 05:58:11.806  INFO 21656 --- [           main] 
o.hibernate.jpa.internal.util.LogHelper  : HHH000204: Processing 
PersistenceUnitInfo [
        name: default
        ...]
   2020-08-01 05:58:12.267  INFO 21656 --- [           main] 
org.hibernate.Version                    : HHH000412: Hibernate Core 
{5.3.13.Final}
   2020-08-01 05:58:12.273  INFO 21656 --- [           main] 
org.hibernate.cfg.Environment            : HHH000206: hibernate.properties not 
found
   2020-08-01 05:58:12.709  INFO 21656 --- [           main] 
o.hibernate.annotations.common.Version   : HCANN000001: Hibernate Commons 
Annotations {5.0.4.Final}
   2020-08-01 05:58:13.001  INFO 21656 --- [           main] 
org.hibernate.dialect.Dialect            : HHH000400: Using dialect: 
org.hibernate.dialect.MySQL5Dialect
   2020-08-01 05:58:14.812  INFO 21656 --- [           main] 
j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory 
for persistence unit 'default'
   服务启动初始化配置
   2020-08-01 05:58:15.909  INFO 21656 --- [           main] 
i.s.s.a.GlobalTransactionScanner         : 
Bean[com.codebattery.service.OrderService] with name [orderService] would use 
interceptor [io.seata.spring.annotation.GlobalTransactionalInterceptor]
   2020-08-01 05:58:16.255  WARN 21656 --- [           main] 
aWebConfiguration$JpaWebMvcConfiguration : spring.jpa.open-in-view is enabled 
by default. Therefore, database queries may be performed during view rendering. 
Explicitly configure spring.jpa.open-in-view to disable this warning
   2020-08-01 05:58:16.541  INFO 21656 --- [           main] 
o.s.b.a.e.web.EndpointLinksResolver      : Exposing 21 endpoint(s) beneath base 
path '/actuator'
   2020-08-01 05:58:16.765  INFO 21656 --- [           main] 
o.s.s.concurrent.ThreadPoolTaskExecutor  : Initializing ExecutorService
   2020-08-01 05:58:16.767  INFO 21656 --- [           main] 
o.s.s.concurrent.ThreadPoolTaskExecutor  : Initializing ExecutorService 
'taskExecutor'
   2020-08-01 05:58:17.296  INFO 21656 --- [           main] 
pertySourcedRequestMappingHandlerMapping : Mapped URL path [/v2/api-docs] onto 
method [public 
org.springframework.http.ResponseEntity<springfox.documentation.spring.web.json.Json>
 
springfox.documentation.swagger2.web.Swagger2Controller.getDocumentation(java.lang.String,javax.servlet.http.HttpServletRequest)]
   2020-08-01 05:58:17.355  WARN 21656 --- [           main] 
c.n.c.sources.URLConfigurationSource     : No URLs will be polled as dynamic 
configuration sources.
   2020-08-01 05:58:17.355  INFO 21656 --- [           main] 
c.n.c.sources.URLConfigurationSource     : To enable URLs as dynamic 
configuration sources, define System property 
archaius.configurationSource.additionalUrls or make config.properties available 
on classpath.
   2020-08-01 05:58:17.364  WARN 21656 --- [           main] 
c.n.c.sources.URLConfigurationSource     : No URLs will be polled as dynamic 
configuration sources.
   2020-08-01 05:58:17.365  INFO 21656 --- [           main] 
c.n.c.sources.URLConfigurationSource     : To enable URLs as dynamic 
configuration sources, define System property 
archaius.configurationSource.additionalUrls or make config.properties available 
on classpath.
   2020-08-01 05:58:17.752  INFO 21656 --- [           main] 
o.s.s.c.ThreadPoolTaskScheduler          : Initializing ExecutorService
   2020-08-01 05:58:19.827  INFO 21656 --- [           main] 
d.s.w.p.DocumentationPluginsBootstrapper : Context refreshed
   2020-08-01 05:58:19.863  INFO 21656 --- [           main] 
d.s.w.p.DocumentationPluginsBootstrapper : Found 1 custom documentation 
plugin(s)
   2020-08-01 05:58:19.965  INFO 21656 --- [           main] 
s.d.s.w.s.ApiListingReferenceScanner     : Scanning for api listing references
   2020-08-01 05:58:20.393  INFO 21656 --- [           main] 
o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat started on port(s): 9014 
(http) with context path ''
   2020-08-01 05:58:20.626  INFO 21656 --- [           main] 
c.a.c.n.registry.NacosServiceRegistry    : nacos registry, order-service 
192.168.3.15:9014 register finished
   2020-08-01 05:58:21.943  INFO 21656 --- [           main] 
com.codebattery.Application              : Started Application in 32.008 
seconds (JVM running for 37.674)
   2020-08-01 05:58:22.540  INFO 21656 --- [1)-192.168.3.15] 
o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring 
DispatcherServlet 'dispatcherServlet'
   2020-08-01 05:58:22.541  INFO 21656 --- [1)-192.168.3.15] 
o.s.web.servlet.DispatcherServlet        : Initializing Servlet 
'dispatcherServlet'
   2020-08-01 05:58:22.563  INFO 21656 --- [1)-192.168.3.15] 
o.s.web.servlet.DispatcherServlet        : Completed initialization in 22 ms
   2020-08-01 05:58:23.547  INFO 21656 --- [2)-192.168.3.15] ShardingSphere-SQL 
                      : Logic SQL: /* ping */ SELECT 1
   2020-08-01 05:58:23.548  INFO 21656 --- [2)-192.168.3.15] ShardingSphere-SQL 
                      : SQLStatement: 
SelectStatementContext(super=CommonSQLStatementContext(sqlStatement=org.apache.shardingsphere.sql.parser.sql.statement.dml.SelectStatement@64fd22d8,
 
tablesContext=org.apache.shardingsphere.sql.parser.binder.segment.table.TablesContext@40b7e5af),
 
tablesContext=org.apache.shardingsphere.sql.parser.binder.segment.table.TablesContext@40b7e5af,
 projectionsContext=ProjectionsContext(startIndex=18, stopIndex=18, 
distinctRow=false, projections=[ExpressionProjection(expression=1, 
alias=Optional.empty)]), 
groupByContext=org.apache.shardingsphere.sql.parser.binder.segment.select.groupby.GroupByContext@17be19ec,
 
orderByContext=org.apache.shardingsphere.sql.parser.binder.segment.select.orderby.OrderByContext@4170b92c,
 
paginationContext=org.apache.shardingsphere.sql.parser.binder.segment.select.pagination.PaginationContext@4c16b838,
 containsSubquery=false)
   2020-08-01 05:58:23.548  INFO 21656 --- [2)-192.168.3.15] ShardingSphere-SQL 
                      : Actual SQL: order1 ::: /* ping */ SELECT 1
   2020-08-01 05:58:23.751  INFO 21656 --- [     parallel-1] 
io.lettuce.core.EpollProvider            : Starting without optional epoll 
library
   2020-08-01 05:58:23.756  INFO 21656 --- [     parallel-1] 
io.lettuce.core.KqueueProvider           : Starting without optional kqueue 
library
   2020-08-01 05:58:41.852  INFO 21656 --- [  AsyncThread-1] 
io.seata.tm.TransactionManagerHolder     : TransactionManager Singleton 
io.seata.tm.DefaultTransactionManager@54a477f6
   2020-08-01 05:58:41.867  INFO 21656 --- [  AsyncThread-1] 
i.s.c.r.netty.NettyClientChannelManager  : will connect to 192.168.3.31:8091
   2020-08-01 05:58:41.868  INFO 21656 --- [  AsyncThread-1] 
i.s.core.rpc.netty.NettyPoolableFactory  : NettyPool create channel to 
transactionRole:TMROLE,address:192.168.3.31:8091,msg:< 
RegisterTMRequest{applicationId='order-service', 
transactionServiceGroup='my_test_tx_group'} >
   2020-08-01 05:58:41.893  INFO 21656 --- [  AsyncThread-1] 
io.seata.core.rpc.netty.TmRpcClient      : register TM success. server 
version:1.2.0,channel:[id: 0x0985816e, L:/192.168.3.15:1608 - 
R:/192.168.3.31:8091]
   2020-08-01 05:58:41.893  INFO 21656 --- [  AsyncThread-1] 
i.s.core.rpc.netty.NettyPoolableFactory  : register success, cost 16 ms, 
version:1.2.0,role:TMROLE,channel:[id: 0x0985816e, L:/192.168.3.15:1608 - 
R:/192.168.3.31:8091]
   2020-08-01 05:58:42.022  INFO 21656 --- [  AsyncThread-1] 
i.seata.tm.api.DefaultGlobalTransaction  : Begin new global transaction 
[192.168.3.31:8091:2018424739]
   2020-08-01 05:58:42.165  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Logic SQL: select order0_.order_id as order_id1_0_0_, 
order0_.address_id as address_2_0_0_, order0_.create_time as create_t3_0_0_, 
order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no as 
escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order order0_ where order0_.order_id=?
   2020-08-01 05:58:42.165  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : SQLStatement: 
SelectStatementContext(super=CommonSQLStatementContext(sqlStatement=org.apache.shardingsphere.sql.parser.sql.statement.dml.SelectStatement@47858701,
 
tablesContext=org.apache.shardingsphere.sql.parser.binder.segment.table.TablesContext@6d5c731e),
 
tablesContext=org.apache.shardingsphere.sql.parser.binder.segment.table.TablesContext@6d5c731e,
 projectionsContext=ProjectionsContext(startIndex=7, stopIndex=776, 
distinctRow=false, projections=[ColumnProjection(owner=order0_, name=order_id, 
alias=Optional[order_id1_0_0_]), ColumnProjection(owner=order0_, 
name=address_id, alias=Optional[address_2_0_0_]), 
ColumnProjection(owner=order0_, name=create_time, 
alias=Optional[create_t3_0_0_]), ColumnProjection(owner=order0_, 
name=deliver_time, alias=Optional[deliver_4_0_0_]), 
ColumnProjection(owner=order0_, name=escrow_trade_no, 
alias=Optional[escrow_t5_0_0_]), ColumnProjection(owner=
 order0_, name=is_invoice, alias=Optional[is_invoi6_0_0_]), 
ColumnProjection(owner=order0_, name=invoice_no, 
alias=Optional[invoice_7_0_0_]), ColumnProjection(owner=order0_, 
name=logistics_fee, alias=Optional[logistic8_0_0_]), 
ColumnProjection(owner=order0_, name=logistics_id, 
alias=Optional[logistic9_0_0_]), ColumnProjection(owner=order0_, 
name=order_amount_total, alias=Optional[order_a10_0_0_]), 
ColumnProjection(owner=order0_, name=pay_channel, 
alias=Optional[pay_cha11_0_0_]), ColumnProjection(owner=order0_, name=pay_time, 
alias=Optional[pay_tim12_0_0_]), ColumnProjection(owner=order0_, 
name=product_amount_total, alias=Optional[product13_0_0_]), 
ColumnProjection(owner=order0_, name=product_count, 
alias=Optional[product14_0_0_]), ColumnProjection(owner=order0_, 
name=settlement_status, alias=Optional[settlem15_0_0_]), 
ColumnProjection(owner=order0_, name=settlement_time, 
alias=Optional[settlem16_0_0_]), ColumnProjection(owner=order0_, name=shop_id, 
alias=Optional[shop_id17_0_0_]), Co
 lumnProjection(owner=order0_, name=is_unpacking_inspection, 
alias=Optional[is_unpa18_0_0_]), ColumnProjection(owner=order0_, name=user_id, 
alias=Optional[user_id19_0_0_])]), 
groupByContext=org.apache.shardingsphere.sql.parser.binder.segment.select.groupby.GroupByContext@5494d7b6,
 
orderByContext=org.apache.shardingsphere.sql.parser.binder.segment.select.orderby.OrderByContext@7e97ad9b,
 
paginationContext=org.apache.shardingsphere.sql.parser.binder.segment.select.pagination.PaginationContext@154180c7,
 containsSubquery=false)
   2020-08-01 05:58:42.166  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order0 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_0 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.166  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order0 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_2 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.166  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order0 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_4 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.166  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order0 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_6 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.166  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order1 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_1 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.166  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order1 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_3 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.167  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order1 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_5 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.167  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order1 ::: select order0_.order_id as 
order_id1_0_0_, order0_.address_id as address_2_0_0_, order0_.create_time as 
create_t3_0_0_, order0_.deliver_time as deliver_4_0_0_, order0_.escrow_trade_no 
as escrow_t5_0_0_, order0_.is_invoice as is_invoi6_0_0_, order0_.invoice_no as 
invoice_7_0_0_, order0_.logistics_fee as logistic8_0_0_, order0_.logistics_id 
as logistic9_0_0_, order0_.order_amount_total as order_a10_0_0_, 
order0_.pay_channel as pay_cha11_0_0_, order0_.pay_time as pay_tim12_0_0_, 
order0_.product_amount_total as product13_0_0_, order0_.product_count as 
product14_0_0_, order0_.settlement_status as settlem15_0_0_, 
order0_.settlement_time as settlem16_0_0_, order0_.shop_id as shop_id17_0_0_, 
order0_.is_unpacking_inspection as is_unpa18_0_0_, order0_.user_id as 
user_id19_0_0_ from t_order_7 order0_ where order0_.order_id=? ::: [21]
   2020-08-01 05:58:42.384  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Logic SQL: insert into t_order (address_id, 
create_time, deliver_time, escrow_trade_no, is_invoice, invoice_no, 
logistics_fee, logistics_id, order_amount_total, pay_channel, pay_time, 
product_amount_total, product_count, settlement_status, settlement_time, 
shop_id, is_unpacking_inspection, user_id, order_id) values (?, ?, ?, ?, ?, ?, 
?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
   2020-08-01 05:58:42.384  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : SQLStatement: 
InsertStatementContext(super=CommonSQLStatementContext(sqlStatement=org.apache.shardingsphere.sql.parser.sql.statement.dml.InsertStatement@73868f38,
 
tablesContext=org.apache.shardingsphere.sql.parser.binder.segment.table.TablesContext@70674639),
 
tablesContext=org.apache.shardingsphere.sql.parser.binder.segment.table.TablesContext@70674639,
 columnNames=[address_id, create_time, deliver_time, escrow_trade_no, 
is_invoice, invoice_no, logistics_fee, logistics_id, order_amount_total, 
pay_channel, pay_time, product_amount_total, product_count, settlement_status, 
settlement_time, shop_id, is_unpacking_inspection, user_id, order_id], 
insertValueContexts=[InsertValueContext(parametersCount=19, 
valueExpressions=[ParameterMarkerExpressionSegment(startIndex=307, 
stopIndex=307, parameterMarkerIndex=0), 
ParameterMarkerExpressionSegment(startIndex=310, stopIndex=310, parameterMarker
 Index=1), ParameterMarkerExpressionSegment(startIndex=313, stopIndex=313, 
parameterMarkerIndex=2), ParameterMarkerExpressionSegment(startIndex=316, 
stopIndex=316, parameterMarkerIndex=3), 
ParameterMarkerExpressionSegment(startIndex=319, stopIndex=319, 
parameterMarkerIndex=4), ParameterMarkerExpressionSegment(startIndex=322, 
stopIndex=322, parameterMarkerIndex=5), 
ParameterMarkerExpressionSegment(startIndex=325, stopIndex=325, 
parameterMarkerIndex=6), ParameterMarkerExpressionSegment(startIndex=328, 
stopIndex=328, parameterMarkerIndex=7), 
ParameterMarkerExpressionSegment(startIndex=331, stopIndex=331, 
parameterMarkerIndex=8), ParameterMarkerExpressionSegment(startIndex=334, 
stopIndex=334, parameterMarkerIndex=9), 
ParameterMarkerExpressionSegment(startIndex=337, stopIndex=337, 
parameterMarkerIndex=10), ParameterMarkerExpressionSegment(startIndex=340, 
stopIndex=340, parameterMarkerIndex=11), 
ParameterMarkerExpressionSegment(startIndex=343, stopIndex=343, 
parameterMarkerIndex=12), Param
 eterMarkerExpressionSegment(startIndex=346, stopIndex=346, 
parameterMarkerIndex=13), ParameterMarkerExpressionSegment(startIndex=349, 
stopIndex=349, parameterMarkerIndex=14), 
ParameterMarkerExpressionSegment(startIndex=352, stopIndex=352, 
parameterMarkerIndex=15), ParameterMarkerExpressionSegment(startIndex=355, 
stopIndex=355, parameterMarkerIndex=16), 
ParameterMarkerExpressionSegment(startIndex=358, stopIndex=358, 
parameterMarkerIndex=17), ParameterMarkerExpressionSegment(startIndex=361, 
stopIndex=361, parameterMarkerIndex=18)], parameters=[1, 2020-08-01 12:51:00.0, 
2020-08-01 12:51:00.0, 1, null, 1, 1, 1, 100, 支付宝, 2020-08-01 12:51:00.0, 100, 
1, null, null, 1, null, 4, 21])], 
generatedKeyContext=Optional[GeneratedKeyContext(columnName=order_id, 
generated=false, generatedValues=[21])])
   2020-08-01 05:58:42.384  INFO 21656 --- [  AsyncThread-1] ShardingSphere-SQL 
                      : Actual SQL: order0 ::: insert into t_order_4 
(address_id, create_time, deliver_time, escrow_trade_no, is_invoice, 
invoice_no, logistics_fee, logistics_id, order_amount_total, pay_channel, 
pay_time, product_amount_total, product_count, settlement_status, 
settlement_time, shop_id, is_unpacking_inspection, user_id, order_id) values 
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) ::: [1, 2020-08-01 
12:51:00.0, 2020-08-01 12:51:00.0, 1, null, 1, 1, 1, 100, 支付宝, 2020-08-01 
12:51:00.0, 100, 1, null, null, 1, null, 4, 21]
   2020-08-01 05:58:45.912  INFO 21656 --- [lector_RMROLE_1] 
i.s.c.r.netty.AbstractRpcRemotingClient  : channel [id: 0x1612b070, 
L:/192.168.3.15:1505 - R:/192.168.3.31:8091] read idle.
   2020-08-01 05:58:45.912  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.NettyPoolableFactory  : will destroy channel:[id: 
0x1612b070, L:/192.168.3.15:1505 - R:/192.168.3.31:8091]
   2020-08-01 05:58:45.913  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.AbstractRpcRemoting   : 
ChannelHandlerContext(AbstractRpcRemotingClient$ClientHandler#0, [id: 
0x1612b070, L:/192.168.3.15:1505 - R:/192.168.3.31:8091]) will closed
   2020-08-01 05:58:45.915  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.AbstractRpcRemoting   : 
ChannelHandlerContext(AbstractRpcRemotingClient$ClientHandler#0, [id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]) will closed
   2020-08-01 05:58:45.916  INFO 21656 --- [lector_RMROLE_1] 
i.s.c.r.netty.NettyClientChannelManager  : return to pool, rm channel:[id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]
   2020-08-01 05:58:45.916  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.NettyPoolableFactory  : channel valid false,channel:[id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]
   2020-08-01 05:58:45.916  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.NettyPoolableFactory  : will destroy channel:[id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]
   2020-08-01 05:58:45.916  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.AbstractRpcRemoting   : 
ChannelHandlerContext(AbstractRpcRemotingClient$ClientHandler#0, [id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]) will closed
   2020-08-01 05:58:45.916  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.AbstractRpcRemoting   : 
ChannelHandlerContext(AbstractRpcRemotingClient$ClientHandler#0, [id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]) will closed
   2020-08-01 05:58:45.917  INFO 21656 --- [lector_RMROLE_1] 
i.s.c.r.netty.AbstractRpcRemotingClient  : channel inactive: [id: 0x1612b070, 
L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]
   2020-08-01 05:58:45.917  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.NettyPoolableFactory  : channel valid false,channel:[id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]
   2020-08-01 05:58:45.917  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.NettyPoolableFactory  : will destroy channel:[id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]
   2020-08-01 05:58:45.917  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.AbstractRpcRemoting   : 
ChannelHandlerContext(AbstractRpcRemotingClient$ClientHandler#0, [id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]) will closed
   2020-08-01 05:58:45.917  INFO 21656 --- [lector_RMROLE_1] 
i.s.core.rpc.netty.AbstractRpcRemoting   : 
ChannelHandlerContext(AbstractRpcRemotingClient$ClientHandler#0, [id: 
0x1612b070, L:/192.168.3.15:1505 ! R:/192.168.3.31:8091]) will closed
   2020-08-01 05:58:58.357  INFO 21656 --- [imeoutChecker_2] 
i.s.c.r.netty.NettyClientChannelManager  : will connect to 192.168.3.31:8091
   2020-08-01 05:58:58.357  INFO 21656 --- [imeoutChecker_2] 
io.seata.core.rpc.netty.RmRpcClient      : RM will register 
:jdbc:mysql://192.168.3.32:3306/order1,jdbc:mysql://192.168.3.32:3306/order0
   2020-08-01 05:58:58.358  INFO 21656 --- [imeoutChecker_2] 
i.s.core.rpc.netty.NettyPoolableFactory  : NettyPool create channel to 
transactionRole:RMROLE,address:192.168.3.31:8091,msg:< 
RegisterRMRequest{resourceIds='jdbc:mysql://192.168.3.32:3306/order1,jdbc:mysql://192.168.3.32:3306/order0',
 applicationId='order-service', transactionServiceGroup='my_test_tx_group'} >
   2020-08-01 05:58:58.376  INFO 21656 --- [imeoutChecker_2] 
io.seata.core.rpc.netty.RmRpcClient      : register RM success. server 
version:1.2.0,channel:[id: 0x940ab8ed, L:/192.168.3.15:1676 - 
R:/192.168.3.31:8091]
   2020-08-01 05:58:58.376  INFO 21656 --- [imeoutChecker_2] 
i.s.core.rpc.netty.NettyPoolableFactory  : register success, cost 11 ms, 
version:1.2.0,role:RMROLE,channel:[id: 0x940ab8ed, L:/192.168.3.15:1676 - 
R:/192.168.3.31:8091]
   2020-08-01 05:59:47.941  INFO 21656 --- [  AsyncThread-1] 
c.netflix.config.ChainedDynamicProperty  : Flipping property: 
storage-service.ribbon.ActiveConnectionsLimit to use NEXT property: 
niws.loadbalancer.availabilityFilteringRule.activeConnectionsLimit = 2147483647
   2020-08-01 05:59:47.970  INFO 21656 --- [  AsyncThread-1] 
c.netflix.loadbalancer.BaseLoadBalancer  : Client: storage-service instantiated 
a LoadBalancer: 
DynamicServerListLoadBalancer:{NFLoadBalancer:name=storage-service,current list 
of Servers=[],Load balancer stats=Zone stats: {},Server stats: 
[]}ServerList:null
   2020-08-01 05:59:47.979  INFO 21656 --- [  AsyncThread-1] 
c.n.l.DynamicServerListLoadBalancer      : Using serverListUpdater 
PollingServerListUpdater
   2020-08-01 05:59:48.018  INFO 21656 --- [  AsyncThread-1] 
c.n.l.DynamicServerListLoadBalancer      : DynamicServerListLoadBalancer for 
client storage-service initialized: 
DynamicServerListLoadBalancer:{NFLoadBalancer:name=storage-service,current list 
of Servers=[],Load balancer stats=Zone stats: {},Server stats: 
[]}ServerList:com.alibaba.cloud.nacos.ribbon.NacosServerList@59c92b1f
   2020-08-01 05:59:48.170  INFO 21656 --- [  AsyncThread-1] 
i.seata.tm.api.DefaultGlobalTransaction  : [192.168.3.31:8091:2018424739] 
rollback status: Rollbacked
   2020-08-01 06:00:10.832  INFO 21656 --- [tch_RMROLE_1_16] 
i.s.core.rpc.netty.RmMessageListener     : 
onMessage:UndoLogDeleteRequest{resourceId='jdbc:mysql://192.168.3.32:3306/order1',
 saveDays=7, branchType=AT}
   2020-08-01 06:00:10.832  INFO 21656 --- [tch_RMROLE_1_16] 
i.s.core.rpc.netty.RmMessageListener     : 
onMessage:UndoLogDeleteRequest{resourceId='jdbc:mysql://192.168.3.32:3306/order0',
 saveDays=7, branchType=AT}
   2020-08-01 06:00:10.859 ERROR 21656 --- [tch_RMROLE_1_16] 
c.a.druid.pool.DruidAbstractDataSource   : discard long time none received 
connection. , jdbcUrl : 
jdbc:mysql://192.168.3.32:3306/order1?useUnicode=true&characterEncoding=utf8&autoReconnect=true&allowMultiQueries=true&serverTimezone=Asia/Shanghai&zeroDateTimeBehavior=CONVERT_TO_NULL,
 jdbcUrl : 
jdbc:mysql://192.168.3.32:3306/order1?useUnicode=true&characterEncoding=utf8&autoReconnect=true&allowMultiQueries=true&serverTimezone=Asia/Shanghai&zeroDateTimeBehavior=CONVERT_TO_NULL,
 lastPacketReceivedIdleMillis : 88431
   2020-08-01 06:00:10.863 ERROR 21656 --- [tch_RMROLE_1_16] 
c.a.druid.pool.DruidAbstractDataSource   : discard long time none received 
connection. , jdbcUrl : 
jdbc:mysql://192.168.3.32:3306/order0?useUnicode=true&characterEncoding=utf8&autoReconnect=true&allowMultiQueries=true&serverTimezone=Asia/Shanghai&zeroDateTimeBehavior=CONVERT_TO_NULL,
 jdbcUrl : 
jdbc:mysql://192.168.3.32:3306/order0?useUnicode=true&characterEncoding=utf8&autoReconnect=true&allowMultiQueries=true&serverTimezone=Asia/Shanghai&zeroDateTimeBehavior=CONVERT_TO_NULL,
 lastPacketReceivedIdleMillis : 88432
   


----------------------------------------------------------------
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

For queries about this service, please contact Infrastructure at:
[email protected]


Reply via email to