Closed cay914 closed 4 years ago
cay914
commented
4 years ago can anybody help @tristaZero @kimmking @cherrylzhao ?
my simple project is: https://gitee.com/cay914/mall.git
my test case is: the function is the com.codebattery.service.OrderService#create call storageFeignClient.deduct("1", 1); and storageFeignClient.deduct throws Exception, But data is insert into mysql throught sharding-jdbc and seata prints rollback info in the console.
please help, thanks.
tristaZero
commented
4 years ago @cherrylzhao Hi this issue is about seata DTC, please give it a look. Thanks a alot
cherrylzhao
commented
4 years ago hi, @cay914
you can just integrate with seata-spring-cloud with sharding-jdbc module, sharding-transaction-base-seata-at should be excluded. we have supported it since #4991
cay914
commented
4 years ago @cherrylzhao Unfortunately, remove or including sharding-transaction-base-seata-at doesn't work. You can see my pom file. I have included this lib. And i found it incompatible with seata-all 1.3.0. i downgrade seata-all 1.2.0, the result is the same. I think you provide an complete intergration(sharding-jdbc 4.1.1 and seata 1.3.0 or seata 1.2.0) eample that would be better.
cay914
commented
4 years ago You told me to exclude sharding-transaction-base-seata-at? Where is org.apache.shardingsphere.transaction.base.seata.at.SeataATShardingTransactionManager? I can't find this class without sharding-transaction-base-seata-at. You are missleading
cherrylzhao
commented
4 years ago SeataATShardingTransactionManager is a database layer distribute transaction resolution. for micro service layer original Seata is ok for that, so you just need a sharding-jdbc driver for the integration.
the key point is investigate whether ShardingDataSource was wrapped to DataSourceProxy or not in your project.
cay914
commented
4 years ago @cherrylzhao I exclude sharding-transaction-base-seata-at lib
Now, the datasource is:
Can you tell me how to wrap to DataSourceProxy ??? Code exmaple please.
cherrylzhao
commented
4 years ago DataSourceProxy is implemented by Seata, you should understand the basic principle of Seata when you use it, I can't help you any more.
terrymanu
commented
4 years ago Closed because of no response anymore
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 iscom.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: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.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_txgroup'} >
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_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from torder order0 where order0_.orderid=?
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_00]), ColumnProjection(owner=order0_, name=address_id, alias=Optional[address_2_00]), ColumnProjection(owner=order0_, name=create_time, alias=Optional[create_t3_00]), ColumnProjection(owner=order0_, name=deliver_time, alias=Optional[deliver_4_00]), ColumnProjection(owner=order0_, name=escrow_trade_no, alias=Optional[escrow_t5_00]), ColumnProjection(owner=order0_, name=is_invoice, alias=Optional[is_invoi6_00]), ColumnProjection(owner=order0_, name=invoice_no, alias=Optional[invoice_7_00]), ColumnProjection(owner=order0_, name=logistics_fee, alias=Optional[logistic8_00]), ColumnProjection(owner=order0_, name=logistics_id, alias=Optional[logistic9_00]), ColumnProjection(owner=order0_, name=order_amount_total, alias=Optional[order_a10_00]), ColumnProjection(owner=order0_, name=pay_channel, alias=Optional[pay_cha11_00]), ColumnProjection(owner=order0_, name=pay_time, alias=Optional[pay_tim12_00]), ColumnProjection(owner=order0_, name=product_amount_total, alias=Optional[product13_00]), ColumnProjection(owner=order0_, name=product_count, alias=Optional[product14_00]), ColumnProjection(owner=order0_, name=settlement_status, alias=Optional[settlem15_00]), ColumnProjection(owner=order0_, name=settlement_time, alias=Optional[settlem16_00]), ColumnProjection(owner=order0_, name=shop_id, alias=Optional[shop_id17_00]), ColumnProjection(owner=order0_, name=is_unpacking_inspection, alias=Optional[is_unpa18_00]), ColumnProjection(owner=order0_, name=user_id, alias=Optional[user_id19_00])]), 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_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order0 order0 where order0_.orderid=? ::: [21]
2020-08-01 05:58:42.166 INFO 21656 --- [ AsyncThread-1] ShardingSphere-SQL : Actual SQL: order0 ::: select order0.order_id as order_id1_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order2 order0 where order0_.orderid=? ::: [21]
2020-08-01 05:58:42.166 INFO 21656 --- [ AsyncThread-1] ShardingSphere-SQL : Actual SQL: order0 ::: select order0.order_id as order_id1_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order4 order0 where order0_.orderid=? ::: [21]
2020-08-01 05:58:42.166 INFO 21656 --- [ AsyncThread-1] ShardingSphere-SQL : Actual SQL: order0 ::: select order0.order_id as order_id1_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order6 order0 where order0_.orderid=? ::: [21]
2020-08-01 05:58:42.166 INFO 21656 --- [ AsyncThread-1] ShardingSphere-SQL : Actual SQL: order1 ::: select order0.order_id as order_id1_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order1 order0 where order0_.orderid=? ::: [21]
2020-08-01 05:58:42.166 INFO 21656 --- [ AsyncThread-1] ShardingSphere-SQL : Actual SQL: order1 ::: select order0.order_id as order_id1_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order3 order0 where order0_.orderid=? ::: [21]
2020-08-01 05:58:42.167 INFO 21656 --- [ AsyncThread-1] ShardingSphere-SQL : Actual SQL: order1 ::: select order0.order_id as order_id1_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order5 order0 where order0_.orderid=? ::: [21]
2020-08-01 05:58:42.167 INFO 21656 --- [ AsyncThread-1] ShardingSphere-SQL : Actual SQL: order1 ::: select order0.order_id as order_id1_00, order0_.address_id as address_2_00, order0_.create_time as create_t3_00, order0_.deliver_time as deliver_4_00, order0_.escrow_trade_no as escrow_t5_00, order0_.is_invoice as is_invoi6_00, order0_.invoice_no as invoice_7_00, order0_.logistics_fee as logistic8_00, order0_.logistics_id as logistic9_00, order0_.order_amount_total as order_a10_00, order0_.pay_channel as pay_cha11_00, order0_.pay_time as pay_tim12_00, order0_.product_amount_total as product13_00, order0_.product_count as product14_00, order0_.settlement_status as settlem15_00, order0_.settlement_time as settlem16_00, order0_.shop_id as shop_id17_00, order0_.is_unpacking_inspection as is_unpa18_00, order0_.user_id as user_id19_00 from t_order7 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, parameterMarkerIndex=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), ParameterMarkerExpressionSegment(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