flow发起事务失败,seata 报错: Adjusted frame length exceeds

Blade 未结 1 2801
zzy
zzy 剑者 2019-10-27 13:02

一、该问题的重现步骤是什么?

1. 按照文档部署seata, 执行请假流程demo

2. 发起事务失败,seata在终端报错


二、你期待的结果是什么?实际看到的又是什么?

期待seata正常运行,正常发起事务

或者说能不能不用seata,并且使用flow?

三、你正在使用的是什么产品,什么版本?在什么操作系统上?

bladex 2.1 release

seata 0.9

win10 x64


四、请提供详细的错误堆栈信息,这很重要。

flow 的堆栈错误:

2019-10-27 12:59:46.026  INFO 9580 --- [XNIO-1 task-252] o.s.core.log.aspect.RequestLogAspect     : 

================  Request Start  ================
===> POST: /clientstart-process-instance-by-id Parameters: {"processDefinitionId":"Leave:2:c1b02179-f7ce-11e9-b470-309c233f25f8","businessKey":"blade_process_leave:1188319351235694593","empty":false}
===Headers===  authorization : Basic eHdzc2FiZXI6eHdzc2FiZXJfc2VjcmV0
===Headers===  TX_XID : 10.0.75.1:8091:2025880354
===Headers===  Accept : */*
===Headers===  xws-auth : bearer eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJ0ZW5hbnRfaWQiOiIwMDAwMDAiLCJ1c2VyX25hbWUiOiJhZG1pbiIsInJlYWxfbmFtZSI6IueuoeeQhuWRmCIsImF2YXRhciI6IiIsImF1dGhvcml0aWVzIjpbImFkbWluaXN0cmF0b3IiXSwiY2xpZW50X2lkIjoieHdzc2FiZXIiLCJyb2xlX25hbWUiOiJhZG1pbmlzdHJhdG9yIiwibGljZW5zZSI6InBvd2VyZWQgYnkgYmxhZGV4IiwidXNlcl9pZCI6IjExMjM1OTg4MjE3Mzg2NzUyMDEiLCJyb2xlX2lkIjoiMTEyMzU5ODgxNjczODY3NTIwMSIsInNjb3BlIjpbImFsbCJdLCJuaWNrX25hbWUiOiLnrqHnkIblkZgiLCJleHAiOjE1NzIxNTU3MDAsImRlcHRfaWQiOiIxMTIzNTk4ODEzNzM4Njc1MjAxIiwianRpIjoiNTczOTNlMGYtYmEzMS00NTY0LTlhYTgtNDg3YTM5MjRlZTI1IiwiYWNjb3VudCI6ImFkbWluIn0.Ujmo5FEAwbGyuKoiiAcqEIjd7RIvS5lSl7efrCKZh3A
===Headers===  User-Agent : okhttp/3.8.1
===Headers===  Connection : Keep-Alive
===Headers===  Host : 10.0.75.1:8008
===Headers===  Accept-Encoding : gzip
===Headers===  x-forwarded-for : 127.0.0.1
===Headers===  Content-Length : 74
===Headers===  Content-Type : application/json;charset=UTF-8
================  Request  End   ================

2019-10-27 12:59:50.750  INFO 9580 --- [XNIO-1 task-252] o.s.core.log.aspect.RequestLogAspect     : 

================  Response Start  ===============
<=== POST: /clientstart-process-instance-by-id (4723 ms)
================  Response  End   ===============

2019-10-27 12:59:50.753 ERROR 9580 --- [XNIO-1 task-252] o.s.c.l.e.BladeRestExceptionTranslator   : 服务器异常

org.springframework.transaction.TransactionSystemException: Could not commit JDBC transaction; nested exception is java.sql.SQLException: io.seata.core.exception.RmTransactionException: Response[ TransactionException[Failed to store branch xid = 10.0.75.1:8091:2025880354 branchId = 2025880357] ]
 at org.springframework.jdbc.datasource.DataSourceTransactionManager.doCommit(DataSourceTransactionManager.java:332) ~[spring-jdbc-5.1.9.RELEASE.jar:5.1.9.RELEASE]
 at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:746) ~[spring-tx-5.1.9.RELEASE.jar:5.1.9.RELEASE]
 at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:714) ~[spring-tx-5.1.9.RELEASE.jar:5.1.9.RELEASE]
 at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:152) ~[spring-tx-5.1.9.RELEASE.jar:5.1.9.RELEASE]
 at org.flowable.common.spring.SpringTransactionInterceptor.execute(SpringTransactionInterceptor.java:56) ~[flowable-spring-common-6.4.2.jar:6.4.2]
 at org.flowable.common.engine.impl.interceptor.LogInterceptor.execute(LogInterceptor.java:30) ~[flowable-engine-common-6.4.2.jar:6.4.2]
 at org.flowable.common.engine.impl.cfg.CommandExecutorImpl.execute(CommandExecutorImpl.java:56) ~[flowable-engine-common-6.4.2.jar:6.4.2]
 at org.flowable.common.engine.impl.cfg.CommandExecutorImpl.execute(CommandExecutorImpl.java:51) ~[flowable-engine-common-6.4.2.jar:6.4.2]
 at org.flowable.engine.impl.RuntimeServiceImpl.startProcessInstanceById(RuntimeServiceImpl.java:164) ~[flowable-engine-6.4.2.jar:6.4.2]
 at org.springblade.flow.business.feign.FlowClient.startProcessInstanceById(FlowClient.java:57) ~[classes/:na]
 at org.springblade.flow.business.feign.FlowClient$$FastClassBySpringCGLIB$$32a63bf1.invoke(


seata log:

PS C:\Users\Dizzy\Documents\Code\blade> .\seata\bin\seata-server.bat -p 8091 -h 127.0.0.1
Java HotSpot(TM) 64-Bit Server VM warning: UseCMSCompactAtFullCollection is deprecated and will likely be removed in a future release.
Java HotSpot(TM) 64-Bit Server VM warning: Cannot open file ${BASEDIR}/logs/seata_gc.log due to No such file or directory

12:59:11,553 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
12:59:11,554 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
12:59:11,554 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Users/Dizzy/Documents/Code/blade/seata/conf/logback.xml]
12:59:11,555 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath.
12:59:11,555 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/C:/Users/Dizzy/Documents/Code/blade/seata/conf/logback.xml]
12:59:11,555 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [jar:file:/C:/Users/Dizzy/Documents/Code/blade/seata/lib/seata-server-0.9.0.jar!/logback.xml]
12:59:11,656 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
12:59:11,663 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/C:/Users/Dizzy/Documents/Code/blade/seata/conf/logback.xml]
12:59:11,663 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 1 minutes
12:59:11,668 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
12:59:11,672 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdout]
12:59:11,744 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
12:59:11,748 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [seata-default]
12:59:11,760 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1887813102 - Archive files will be limited to [2048 MB] each.
12:59:11,764 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1887813102 - No compression will be used
12:59:11,765 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1887813102 - Will use the pattern C:/Users/Dizzy/logs/seata/seata-server.log.%d{yyyy-MM-dd}.%i for the active file
12:59:11,769 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@1ce92674 - The date pattern is 'yyyy-MM-dd' from file name pattern 'C:/Users/Dizzy/logs/seata/seata-server.log.%d{yyyy-MM-dd}.%i'.
12:59:11,769 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@1ce92674 - Roll-over at midnight.
12:59:11,773 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@1ce92674 - Setting initial period to Sun Oct 27 12:58:51 CST 2019
12:59:11,776 |-INFO in c.q.l.core.rolling.SizeAndTimeBasedRollingPolicy@1887813102 - Cleaning on start up
12:59:11,777 |-INFO in c.q.l.core.rolling.helper.TimeBasedArchiveRemover - first clean up after appender initialization
12:59:11,777 |-INFO in c.q.l.core.rolling.helper.TimeBasedArchiveRemover - Multiple periods, i.e. 32 periods, seem to have elapsed. This is expected at application start.
12:59:11,777 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
12:59:11,779 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[seata-default] - Active log file name: C:\Users\Dizzy/logs/seata/seata-server.log
12:59:11,779 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[seata-default] - File property is set to [C:\Users\Dizzy/logs/seata/seata-server.log]
12:59:11,781 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [io.seata.server.store.file.FileTransactionStoreManager] to false
12:59:11,781 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - io.seata.server.store.file.FileTransactionStoreManager level set to INFO
12:59:11,781 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [seata-default] to Logger[io.seata.server.store.file.FileTransactionStoreManager]
12:59:11,782 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
12:59:11,782 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [seata-default] to Logger[ROOT]
12:59:11,782 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdout] to Logger[ROOT]
12:59:11,782 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
12:59:11,783 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@5700d6b1 - Registering current configuration as safe fallback point

2019-10-27 12:59:11.910 INFO [main]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load ConfigurationProvider[Nacos] extension by class[io.seata.config.nacos.NacosConfigurationProvider]
2019-10-27 12:59:13.438 INFO [main]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load DataSourceGenerator[dbcp] extension by class[io.seata.server.store.db.DbcpDataSourceGenerator]
2019-10-27 12:59:15.059 INFO [main]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load LogStore[DB] extension by class[io.seata.core.store.db.LogStoreDataBaseDAO]
2019-10-27 12:59:15.061 INFO [main]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load TransactionStoreManager[DB] extension by class[io.seata.server.store.db.DatabaseTransactionStoreManager]
2019-10-27 12:59:15.062 INFO [main]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load SessionManager[DB] extension by class[io.seata.server.session.db.DataBaseSessionManager]
2019-10-27 12:59:19.207 INFO [main]io.seata.core.rpc.netty.AbstractRpcRemotingServer.start:156 -Server started ... 
2019-10-27 12:59:19.224 INFO [main]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load RegistryProvider[Nacos] extension by class[io.seata.discovery.registry.nacos.NacosRegistryProvider]
2019-10-27 12:59:19.299 INFO [NettyServerNIOWorker_1_8]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load Codec[SEATA] extension by class[io.seata.codec.seata.SeataCodec]
2019-10-27 12:59:19.316 INFO [NettyServerNIOWorker_1_8]io.seata.core.rpc.DefaultServerMessageListenerImpl.onRegTmMessage:133 -checkAuth for client:10.0.75.1:58442 vgroup:blade-desk-group ok
2019-10-27 12:59:19.402 INFO [ServerHandlerThread_1_500]io.seata.core.rpc.DefaultServerMessageListenerImpl.onRegRmMessage:116 -rm register success,message:RegisterRMRequest{resourceIds='jdbc:mysql://bj-cdb-2w109e23.sql.tencentcdb.com:5116/bladex', applicationId='blade-desk', transactionServiceGroup='blade-desk-group'},channel:[id: 0x2de87183, L:/10.0.75.1:8091 - R:/10.0.75.1:58444]
2019-10-27 12:59:21.750 INFO [NettyServerNIOWorker_3_8]io.seata.core.rpc.DefaultServerMessageListenerImpl.onRegTmMessage:133 -checkAuth for client:10.0.75.1:58488 vgroup:blade-flow-group ok
2019-10-27 12:59:21.932 INFO [ServerHandlerThread_2_500]io.seata.core.rpc.DefaultServerMessageListenerImpl.onRegRmMessage:116 -rm register success,message:RegisterRMRequest{resourceIds='jdbc:mysql://bj-cdb-2w109e23.sql.tencentcdb.com:5116/bladex_flow', applicationId='blade-flow', transactionServiceGroup='blade-flow-group'},channel:[id: 0x37949d9f, L:/10.0.75.1:8091 - R:/10.0.75.1:58491]
2019-10-27 12:59:42.945 INFO [NettyServerNIOWorker_5_8]io.seata.core.rpc.netty.RpcServer.exceptionCaught:381 -channel exx:Adjusted frame length exceeds 8388608: 539976035 - discarded,channel:[id: 0x0ec7fdd9, L:/10.0.75.1:8091 - R:/10.0.75.1:58796]
2019-10-27 12:59:42.953 ERROR[NettyServerNIOWorker_5_8]io.seata.core.rpc.netty.AbstractRpcRemoting.exceptionCaught:424 -0318
io.netty.handler.codec.TooLongFrameException: Adjusted frame length exceeds 8388608: 539976035 - discarded
        at io.netty.handler.codec.LengthFieldBasedFrameDecoder.fail(LengthFieldBasedFrameDecoder.java:522)
        at io.netty.handler.codec.LengthFieldBasedFrameDecoder.failIfNecessary(LengthFieldBasedFrameDecoder.java:500)
        at io.netty.handler.codec.LengthFieldBasedFrameDecoder.exceededFrameLength(LengthFieldBasedFrameDecoder.java:387)
        at io.netty.handler.codec.LengthFieldBasedFrameDecoder.decode(LengthFieldBasedFrameDecoder.java:430)
        at io.seata.core.rpc.netty.v1.ProtocolV1Decoder.decode(ProtocolV1Decoder.java:79)
        at io.netty.handler.codec.LengthFieldBasedFrameDecoder.decode(LengthFieldBasedFrameDecoder.java:343)
        at io.netty.handler.codec.ByteToMessageDecoder.decodeRemovalReentryProtection(ByteToMessageDecoder.java:502)
        at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:441)
        at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:278)
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:362)
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:348)
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:340)
        at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286)
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:362)
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:348)
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:340)
        at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1434)
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:362)
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:348)
        at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:965)
        at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163)
        at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:644)
        at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:579)
        at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:496)
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:458)
        at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:897)
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
        at java.lang.Thread.run(Thread.java:748)
2019-10-27 12:59:42.981 INFO [NettyServerNIOWorker_5_8]io.seata.core.rpc.netty.AbstractRpcRemotingServer.destroyChannel:189 -will destroy channel:[id: 0x0ec7fdd9, L:/10.0.75.1:8091 - R:/10.0.75.1:58796],address:10.0.75.1:58796
2019-10-27 12:59:42.982 INFO [NettyServerNIOWorker_5_8]io.seata.core.rpc.netty.AbstractRpcRemoting.close:445 -ChannelHandlerContext(RpcServer#0, [id: 0x0ec7fdd9, L:/10.0.75.1:8091 - R:/10.0.75.1:58796]) will closed
2019-10-27 12:59:42.984 INFO [NettyServerNIOWorker_5_8]io.seata.core.rpc.netty.AbstractRpcRemoting.close:445 -ChannelHandlerContext(RpcServer#0, [id: 0x0ec7fdd9, L:/10.0.75.1:8091 ! R:/10.0.75.1:58796]) will closed
2019-10-27 12:59:43.001 INFO [NettyServerNIOWorker_5_8]io.seata.core.rpc.netty.RpcServer.handleDisconnect:333 -10.0.75.1:58796 to server channel inactive.
2019-10-27 12:59:43.002 INFO [NettyServerNIOWorker_5_8]io.seata.core.rpc.netty.RpcServer.handleDisconnect:342 -remove unused channel:[id: 0x0ec7fdd9, L:/10.0.75.1:8091 ! R:/10.0.75.1:58796]
2019-10-27 12:59:45.669 INFO [batchLoggerPrint_1]io.seata.core.rpc.DefaultServerMessageListenerImpl.run:198 -SeataMergeMessage timeout=60000,transactionName=startProcess(org.springblade.desk.entity.ProcessLeave)
,clientIp:10.0.75.1,vgroup:blade-desk-group
2019-10-27 12:59:45.860 INFO [ServerHandlerThread_3_500]io.seata.server.coordinator.DefaultCore.begin:145 -Successfully begin global transaction xid = 10.0.75.1:8091:2025880354
2019-10-27 12:59:47.143 INFO [batchLoggerPrint_1]io.seata.core.rpc.DefaultServerMessageListenerImpl.run:198 -SeataMergeMessage xid=10.0.75.1:8091:2025880354,branchType=AT,resourceId=jdbc:mysql://bj-cdb-2w109e23.sql.tencentcdb.com:5116/bladex_flow,lockKey=ACT_HI_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_VARINST:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2;ACT_RU_TASK:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_VARIABLE:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_HI_TASKINST:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_PROCINST:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d30fa-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_HI_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d30ff-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2
,clientIp:10.0.75.1,vgroup:blade-flow-group
2019-10-27 12:59:47.404 INFO [ServerHandlerThread_4_500]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load LockStore[DB] extension by class[io.seata.core.store.db.LockStoreDataBaseDAO]
2019-10-27 12:59:47.409 INFO [ServerHandlerThread_4_500]io.seata.common.loader.EnhancedServiceLoader.loadFile:236 -load Locker[db] extension by class[io.seata.server.lock.db.DataBaseLocker]
2019-10-27 12:59:49.209 ERROR[ServerHandlerThread_4_500]io.seata.core.exception.AbstractExceptionHandler.exceptionHandleTemplate:120 -Catch TransactionException while do RPC, request: xid=10.0.75.1:8091:2025880354,branchType=AT,resourceId=jdbc:mysql://bj-cdb-2w109e23.sql.tencentcdb.com:5116/bladex_flow,lockKey=ACT_HI_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_VARINST:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2;ACT_RU_TASK:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_VARIABLE:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_HI_TASKINST:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_PROCINST:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d30fa-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_HI_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d30ff-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2
io.seata.core.exception.BranchTransactionException: Failed to store branch xid = 10.0.75.1:8091:2025880354 branchId = 2025880357
        at io.seata.server.coordinator.DefaultCore.lambda$branchRegister$0(DefaultCore.java:92)
        at io.seata.server.session.GlobalSession.lockAndExcute(GlobalSession.java:597)
        at io.seata.server.coordinator.DefaultCore.branchRegister(DefaultCore.java:70)
        at io.seata.server.coordinator.DefaultCoordinator.doBranchRegister(DefaultCoordinator.java:201)
        at io.seata.server.AbstractTCInboundHandler$4.execute(AbstractTCInboundHandler.java:171)
        at io.seata.server.AbstractTCInboundHandler$4.execute(AbstractTCInboundHandler.java:166)
        at io.seata.core.exception.AbstractExceptionHandler.exceptionHandleTemplate(AbstractExceptionHandler.java:117)
        at io.seata.server.AbstractTCInboundHandler.handle(AbstractTCInboundHandler.java:166)
        at io.seata.core.protocol.transaction.BranchRegisterRequest.handle(BranchRegisterRequest.java:136)
        at io.seata.server.coordinator.DefaultCoordinator.onRequest(DefaultCoordinator.java:533)
        at io.seata.core.rpc.DefaultServerMessageListenerImpl.onTrxMessage(DefaultServerMessageListenerImpl.java:89)
        at io.seata.core.rpc.netty.RpcServer.dispatch(RpcServer.java:299)
        at io.seata.core.rpc.netty.AbstractRpcRemoting$3.run(AbstractRpcRemoting.java:371)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
        at java.lang.Thread.run(Thread.java:748)
2019-10-27 12:59:49.256 INFO [batchLoggerPrint_1]io.seata.core.rpc.DefaultServerMessageListenerImpl.run:198 -SeataMergeMessage xid=10.0.75.1:8091:2025880354,branchType=AT,resourceId=jdbc:mysql://bj-cdb-2w109e23.sql.tencentcdb.com:5116/bladex_flow,lockKey=ACT_HI_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_VARINST:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2;ACT_RU_TASK:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_VARIABLE:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_HI_TASKINST:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_PROCINST:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d30fa-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_HI_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d30ff-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2
,clientIp:10.0.75.1,vgroup:blade-flow-group
2019-10-27 12:59:50.698 ERROR[ServerHandlerThread_5_500]io.seata.core.exception.AbstractExceptionHandler.exceptionHandleTemplate:120 -Catch TransactionException while do RPC, request: xid=10.0.75.1:8091:2025880354,branchType=AT,resourceId=jdbc:mysql://bj-cdb-2w109e23.sql.tencentcdb.com:5116/bladex_flow,lockKey=ACT_HI_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fd-f876-11e9-9a50-e2accb88a1c2;ACT_HI_VARINST:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fb-f876-11e9-9a50-e2accb88a1c2;ACT_RU_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2;ACT_RU_TASK:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_VARIABLE:988d09e5-f876-11e9-9a50-e2accb88a1c2,988d30f7-f876-11e9-9a50-e2accb88a1c2,988d30f8-f876-11e9-9a50-e2accb88a1c2,988d30f9-f876-11e9-9a50-e2accb88a1c2;ACT_HI_TASKINST:988d30fe-f876-11e9-9a50-e2accb88a1c2;ACT_HI_PROCINST:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_RU_ACTINST:988d30fc-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d30fa-f876-11e9-9a50-e2accb88a1c2;ACT_RU_EXECUTION:988d09e4-f876-11e9-9a50-e2accb88a1c2;ACT_HI_IDENTITYLINK:988d30f6-f876-11e9-9a50-e2accb88a1c2,988d30ff-f876-11e9-9a50-e2accb88a1c2,988d3100-f876-11e9-9a50-e2accb88a1c2
io.seata.core.exception.BranchTransactionException: Failed to store branch xid = 10.0.75.1:8091:2025880354 branchId = 2025880361
        at io.seata.server.coordinator.DefaultCore.lambda$branchRegister$0(DefaultCore.java:92)
        at io.seata.server.session.GlobalSession.lockAndExcute(GlobalSession.java:597)
        at io.seata.server.coordinator.DefaultCore.branchRegister(DefaultCore.java:70)
        at io.seata.server.coordinator.DefaultCoordinator.doBranchRegister(DefaultCoordinator.java:201)
        at io.seata.server.AbstractTCInboundHandler$4.execute(AbstractTCInboundHandler.java:171)
        at io.seata.server.AbstractTCInboundHandler$4.execute(AbstractTCInboundHandler.java:166)
        at io.seata.core.exception.AbstractExceptionHandler.exceptionHandleTemplate(AbstractExceptionHandler.java:117)
        at io.seata.server.AbstractTCInboundHandler.handle(AbstractTCInboundHandler.java:166)
        at io.seata.core.protocol.transaction.BranchRegisterRequest.handle(BranchRegisterRequest.java:136)
        at io.seata.server.coordinator.DefaultCoordinator.onRequest(DefaultCoordinator.java:533)
        at io.seata.core.rpc.DefaultServerMessageListenerImpl.onTrxMessage(DefaultServerMessageListenerImpl.java:89)
        at io.seata.core.rpc.netty.RpcServer.dispatch(RpcServer.java:299)
        at io.seata.core.rpc.netty.AbstractRpcRemoting$3.run(AbstractRpcRemoting.java:371)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
        at java.lang.Thread.run(Thread.java:748)
2019-10-27 12:59:50.886 INFO [batchLoggerPrint_1]io.seata.core.rpc.DefaultServerMessageListenerImpl.run:198 -SeataMergeMessage xid=10.0.75.1:8091:2025880354,extraData=null
,clientIp:10.0.75.1,vgroup:blade-desk-group
2019-10-27 12:59:51.343 INFO [ServerHandlerThread_6_500]io.seata.server.coordinator.DefaultCore.doGlobalRollback:437 -Successfully rollback global, xid = 10.0.75.1:8091:2025880354

五、若有更多详细信息,请在下面提供。



1条回答
  • 2019-10-27 13:47

    问题已经解决:

    这是seata旧版本的一个bug,现在已经修复,但要求seata-server和seata-all版本一致.

    如图更新依赖即可

    image.png

    0 讨论(3)
提交回复