cy0826
commented
4 years ago 
Open cy0826 opened 4 years ago
cy0826
commented
4 years ago
cy0826
commented
4 years ago 
cy0826
commented
4 years ago 2020-07-31 17:33:35.489 INFO [http-nio-9007-exec-4] o.b.bytetcc.CompensableCoordinator - 67fc6188f07a7859a230d52f42fc1fd0| compensable transaction begin!
2020-07-31 17:33:35.497 DEBUG [http-nio-9007-exec-4] c.c.c.s.m.d.V.getAllMarketMsg - ==> Preparing: select from tb_vegetables_market where id = ?
2020-07-31 17:33:35.498 DEBUG [http-nio-9007-exec-4] c.c.c.s.m.d.V.getAllMarketMsg - ==> Parameters: 1(Integer)
2020-07-31 17:33:35.513 DEBUG [http-nio-9007-exec-4] c.c.c.s.m.d.V.getAllMarketMsg - <== Total: 1
2020-07-31 17:33:35.518 INFO [http-nio-9007-exec-4] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| enlist remote resource: 127.0.0.1:OPEN-API-DELIVERY:9010.
2020-07-31 17:33:35.699 DEBUG [http-nio-9007-exec-6] c.c.c.s.m.d.V.getShopId - ==> Preparing: select from tb_vegetables_market where is_delete = 0 and id = ?
2020-07-31 17:33:35.700 DEBUG [http-nio-9007-exec-6] c.c.c.s.m.d.V.getShopId - ==> Parameters: 1(Integer)
2020-07-31 17:33:35.715 DEBUG [http-nio-9007-exec-6] c.c.c.s.m.d.V.getShopId - <== Total: 1
2020-07-31 17:33:39.237 DEBUG [http-nio-9007-exec-3] c.c.c.s.m.d.V.getMarketKindList - ==> Preparing: select id, kind, market_id, sort, is_activity, create_time, update_time, create_id, update_id, is_delete, total_sales, status from tb_vegetables_market_goods_kind where status = 0
2020-07-31 17:33:39.243 DEBUG [http-nio-9007-exec-3] c.c.c.s.m.d.V.getMarketKindList - ==> Parameters:
2020-07-31 17:33:39.256 DEBUG [http-nio-9007-exec-3] c.c.c.s.m.d.V.getMarketKindList - <== Total: 10
2020-07-31 17:33:39.261 DEBUG [http-nio-9007-exec-3] c.c.c.s.m.d.V.getGoodsMsgList - ==> Preparing: SELECT * FROM tb_vegetables_market_goods a LEFT JOIN tb_vegetables_market_goods_img b on a.id = b.goods_id LEFT JOIN tb_vegetables_market_goods_kind c on a.kinds = c.id where b.type = 1 and a.id in ( ? )
2020-07-31 17:33:39.262 DEBUG [http-nio-9007-exec-3] c.c.c.s.m.d.V.getGoodsMsgList - ==> Parameters: 720(Integer)
2020-07-31 17:33:39.282 DEBUG [http-nio-9007-exec-3] c.c.c.s.m.d.V.getGoodsMsgList - <== Total: 1
2020-07-31 17:33:39.835 INFO [http-nio-9007-exec-7] o.b.bytejta.TransactionCoordinator - 67fc619fc27a7859a230d5986c31e736> begin-participant
2020-07-31 17:33:39.835 INFO [http-nio-9007-exec-7] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| register compensable service: 67fc6188f07a7859a230d52f42fc1fd0.
2020-07-31 17:33:39.842 INFO [http-nio-9007-exec-7] org.bytesoft.bytejta.TransactionImpl - 67fc619fc27a7859a230d5986c31e736> commit-participant start
2020-07-31 17:33:39.852 INFO [http-nio-9007-exec-7] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| try: identifier= 67fc6188f07a7859a230d52f42fc1fd0, resourceKey= null, resourceXid= null.
2020-07-31 17:33:39.853 INFO [http-nio-9007-exec-7] org.bytesoft.bytejta.TransactionImpl - 67fc619fc27a7859a230d5986c31e736> commit-participant complete successfully
2020-07-31 17:33:40.416 INFO [http-nio-9007-exec-8] o.b.bytejta.TransactionCoordinator - 67fc61a1197a7859a230d59a90b6565b> begin-participant
2020-07-31 17:33:40.419 INFO [http-nio-9007-exec-8] org.bytesoft.bytejta.TransactionImpl - 67fc61a1197a7859a230d59a90b6565b> commit-participant start
2020-07-31 17:33:40.437 ERROR [http-nio-9007-exec-8] o.b.t.i.TransactionListenerList - Illegal state: maybe the try phase operation has timed out.!
java.lang.IllegalStateException: Illegal state: maybe the try phase operation has timed out.!
at org.bytesoft.bytetcc.CompensableTransactionImpl.onCompletionPhaseCommitSuccess(CompensableTransactionImpl.java:982)
at org.bytesoft.bytetcc.CompensableTransactionImpl.onCommitSuccess(CompensableTransactionImpl.java:922)
at org.bytesoft.transaction.internal.TransactionListenerList.onCommitSuccess(TransactionListenerList.java:84)
at org.bytesoft.bytejta.TransactionImpl.compensableOnePhaseCommit(TransactionImpl.java:277)
at org.bytesoft.bytejta.TransactionImpl.participantCommit(TransactionImpl.java:223)
at org.bytesoft.bytejta.TransactionCoordinator.commit(TransactionCoordinator.java:204)
at org.bytesoft.bytetcc.CompensableManagerImpl.invokeTransactionCommitIfLocalTransaction(CompensableManagerImpl.java:360)
at org.bytesoft.bytetcc.CompensableManagerImpl.invokeTransactionCommit(CompensableManagerImpl.java:341)
at org.bytesoft.bytetcc.CompensableManagerImpl.invokeTransactionCommitIfNecessary(CompensableManagerImpl.java:331)
at org.bytesoft.bytetcc.CompensableManagerImpl.commit(CompensableManagerImpl.java:310)
at org.bytesoft.bytetcc.TransactionManagerImpl.commit(TransactionManagerImpl.java:95)
at org.bytesoft.bytetcc.UserCompensableImpl.commit(UserCompensableImpl.java:97)
at org.springframework.transaction.jta.JtaTransactionManager.doCommit(JtaTransactionManager.java:1034)
at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:746)
at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:714)
at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:532)
at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:304)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:98)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:185)
at org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:88)
at org.bytesoft.bytetcc.supports.spring.CompensableMethodInterceptor.invoke(CompensableMethodInterceptor.java:88)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:644)
at org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:633)
at org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:70)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:174)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:92)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:185)
at org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:688)
at cn.com.citydo.supermarket.market.client.InventoryTccService$$EnhancerBySpringCGLIB$$7e9459a.confirmCreateSubmitOrderEvent(
cy0826
commented
4 years ago 2020-07-31 17:33:39.421 INFO [http-nio-9006-exec-5] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| enlist remote resource: 127.0.0.1:OPEN-API-CART:9005.
2020-07-31 17:33:39.878 INFO [http-nio-9006-exec-5] org.bytesoft.bytejta.TransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0> delist: xares= xa-res-archive[descriptor: local-xa-resource[dataSource]], branch= 67fc619dbd7a7859a230d5353dbdebd4, flags= 67108864
2020-07-31 17:33:39.881 INFO [http-nio-9006-exec-5] org.bytesoft.bytejta.TransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0> commit-participant start
2020-07-31 17:33:39.931 INFO [http-nio-9006-exec-5] o.b.b.resource.XATerminatorOptd - 67fc6188f07a7859a230d52f42fc1fd0> commit: xares= xa-res-archive[descriptor: local-xa-resource[dataSource]], branch= 67fc619dbd7a7859a230d5353dbdebd4, opc= false
2020-07-31 17:33:39.933 INFO [http-nio-9006-exec-5] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| try: identifier= 67fc6188f07a7859a230d52f42fc1fd0, resourceKey= dataSource, resourceXid= 1207-67fc6188f07a7859a230d52f42fc1fd0-67fc619dbd7a7859a230d5353dbdebd4.
2020-07-31 17:33:39.941 INFO [http-nio-9006-exec-5] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| try completed.
2020-07-31 17:33:39.942 INFO [http-nio-9006-exec-5] org.bytesoft.bytejta.TransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0> commit-participant complete successfully
2020-07-31 17:33:39.958 INFO [http-nio-9006-exec-5] o.b.bytejta.TransactionCoordinator - 67fc619dc87a7859a230d536a036079e> begin-participant
2020-07-31 17:33:40.027 INFO [http-nio-9006-exec-5] org.bytesoft.bytejta.TransactionImpl - 67fc619dc87a7859a230d536a036079e> enlist: xares= xa-res-archive[descriptor: local-xa-resource[dataSource]], branch= 67fc6188f07a7859a230d52f42fc1fd0, flags: 0
2020-07-31 17:33:40.042 DEBUG [http-nio-9006-exec-5] c.c.c.s.o.d.T.updateOrderStatusByOrderId - ==> Preparing: update tb_order set status = ? where id = ?
2020-07-31 17:33:40.042 DEBUG [http-nio-9006-exec-5] c.c.c.s.o.d.T.updateOrderStatusByOrderId - ==> Parameters: 1(String), 1934(Long)
2020-07-31 17:33:40.052 DEBUG [http-nio-9006-exec-5] c.c.c.s.o.d.T.updateOrderStatusByOrderId - <== Updates: 1
2020-07-31 17:33:40.107 DEBUG [http-nio-9006-exec-5] c.c.c.s.o.d.O.insertSelective - ==> Preparing: insert into order_operation ( order_id, operation_type, operation_content, gmt_create, gmt_modified ) values ( ?, ?, ?, ?, ? )
2020-07-31 17:33:40.108 DEBUG [http-nio-9006-exec-5] c.c.c.s.o.d.O.insertSelective - ==> Parameters: 15961880143060(Long), 1(Integer), 完成订单创建，订单编号为：15961880143060(String), 2020-07-31 17:33:40.107(Timestamp), 2020-07-31 17:33:40.107(Timestamp)
2020-07-31 17:33:40.120 DEBUG [http-nio-9006-exec-5] c.c.c.s.o.d.O.insertSelective - <== Updates: 1
2020-07-31 17:33:40.122 INFO [http-nio-9006-exec-5] org.bytesoft.bytejta.TransactionImpl - 67fc619dc87a7859a230d536a036079e> delist: xares= xa-res-archive[descriptor: local-xa-resource[dataSource]], branch= 67fc6188f07a7859a230d52f42fc1fd0, flags= 67108864
2020-07-31 17:33:40.123 INFO [http-nio-9006-exec-5] org.bytesoft.bytejta.TransactionImpl - 67fc619dc87a7859a230d536a036079e> commit-participant start
2020-07-31 17:33:40.151 INFO [http-nio-9006-exec-5] o.b.b.resource.XATerminatorOptd - 67fc619dc87a7859a230d536a036079e> commit: xares= xa-res-archive[descriptor: local-xa-resource[dataSource]], branch= 67fc6188f07a7859a230d52f42fc1fd0, opc= false
2020-07-31 17:33:40.153 INFO [http-nio-9006-exec-5] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| confirm: identifier= 67fc6188f07a7859a230d52f42fc1fd0, resourceKey= dataSource, resourceXid= 1207-67fc619dc87a7859a230d536a036079e-67fc6188f07a7859a230d52f42fc1fd0.
2020-07-31 17:33:40.155 INFO [http-nio-9006-exec-5] org.bytesoft.bytejta.TransactionImpl - 67fc619dc87a7859a230d536a036079e> commit-participant complete successfully
2020-07-31 17:33:40.247 WARN [http-nio-9006-exec-5] o.s.h.c.x.MappingJackson2XmlHttpMessageConverter - Failed to evaluate Jackson deserialization for type [[simple type, class void]]: com.fasterxml.jackson.databind.JsonMappingException: Internal error: can't find deserializer for void
2020-07-31 17:33:40.247 WARN [http-nio-9006-exec-5] o.s.h.c.j.MappingJackson2HttpMessageConverter - Failed to evaluate Jackson deserialization for type [[simple type, class void]]: com.fasterxml.jackson.databind.JsonMappingException: Internal error: can't find deserializer for void
2020-07-31 17:33:40.650 INFO [http-nio-9006-exec-5] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| confirm remote branch: 127.0.0.1:OPEN-API-MARKET:9007
2020-07-31 17:33:40.651 INFO [http-nio-9006-exec-5] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| compensable transaction committed!
2020-07-31 17:33:40.656 INFO [http-nio-9006-exec-5] o.b.b.CompensableTransactionImpl - 67fc6188f07a7859a230d52f42fc1fd0| forget transaction.
liuyangming
commented
4 years ago Try阶段操作先进入commit，之后超时，随后Confirm/Cancel的也进入了commit，然而如果此时Try操作的本地事务才真正完成commit操作，这时候就会出现这个提示。因为事务管理器发现当前commit的应该是Confirm/Cancel阶段本地事务，但实际上却是Try阶段的事务，与预期的不符。此时，就会抛出一个异常来终止后续全局事务状态变更。
如果业务数据并没有出现不一致，该异常可以忽略。
luokaixz
commented
3 years ago Try阶段操作先进入commit，之后超时，随后Confirm/Cancel的也进入了commit，然而如果此时Try操作的本地事务才真正完成commit操作，这时候就会出现这个提示。因为事务管理器发现当前commit的应该是Confirm/Cancel阶段本地事务，但实际上却是Try阶段的事务，与预期的不符。此时，就会抛出一个异常来终止后续全局事务状态变更。
如果业务数据并没有出现不一致，该异常可以忽略。
一直报这个错，导致服务调用超时了，主控服务后续的流程就无法继续进行了。请问这个问题怎么处理呀
liuyangming
commented
3 years ago Try阶段操作先进入commit，之后超时，随后Confirm/Cancel的也进入了commit，然而如果此时Try操作的本地事务才真正完成commit操作，这时候就会出现这个提示。因为事务管理器发现当前commit的应该是Confirm/Cancel阶段本地事务，但实际上却是Try阶段的事务，与预期的不符。此时，就会抛出一个异常来终止后续全局事务状态变更。 如果业务数据并没有出现不一致，该异常可以忽略。
一直报这个错，导致服务调用超时了，主控服务后续的流程就无法继续进行了。请问这个问题怎么处理呀
try阶段操作超时情况下会报这个错，而不是报这个错导致超时。你需要做的是定位一下try阶段超时的操作。
kuailexiaoyi
commented
3 years ago 遇到了一样的问题，有没有参数可以设置这个超时的时间；
try: identifier= 67fc448f057a7859a230d5236933f035, resourceKey= null, resourceXid= null.