incubator-seata icon indicating copy to clipboard operation
incubator-seata copied to clipboard

Published 20 hours ago • verified publisher icon apache

seata2.1.0全局事务提交后，全局锁一直不释放

Open Self-revolution opened this issue 4 months ago • 5 comments

线上偶发seata2.1.0.jre17全局事务提交后，全局锁一直不释放，服务端报错Global lock on [transport_task:1212475] is holding by 5576031298779548712，但事务已提交Committing global transaction is successfully done, xid = 192.168.12.222:8091:5576031298779548712.：

seata服务端日志如下，：

2024-10-18 18:23:01.987  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalBeginRequest{transactionName='insertTask(com.holo.storage.service.container.vo.ContainerVO, com.holo.storage.service.locator.vo.LocatorVO, com.holo.wcs.service.device.instance.device.device.domain.Device, com.holo.wcs.service.device.instance.device.connector.enums.DeviceConnectorTypeEnum)', timeout=60000}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:01.987  INFO --- [ServerHandlerThread_1_6_500] [org.apache.seata.server.coordinator.DefaultCoordinator] [doGlobalBegin] [192.168.12.222:8091:5576031298779548712]: Begin new global transaction applicationId: holo-wcs-service,transactionServiceGroup: default_tx_group, transactionName: insertTask(com.holo.storage.service.container.vo.ContainerVO, com.holo.storage.service.locator.vo.LocatorVO, com.holo.wcs.service.device.instance.device.device.domain.Device, com.holo.wcs.service.device.instance.device.connector.enums.DeviceConnectorTypeEnum),timeout:60000,xid:192.168.12.222:8091:5576031298779548712
2024-10-18 18:23:01.992  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalBeginResponse{xid='192.168.12.222:8091:5576031298779548712', extraData='null', resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:01.997  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalCommitRequest{xid='192.168.12.222:8091:5576031298779548709', extraData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:01.997  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalCommitResponse{globalStatus=Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.006  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548712', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='container:19871', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.011  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.016  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548712', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='locator:9782,9785', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.016  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.018  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548712]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548712, branchId = 5576031298779548713, resourceId = jdbc:mysql://192.168.12.222:3306/holo_storage ,lockKeys = container:19871;locator:9782;locator:9785
2024-10-18 18:23:02.021  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: BranchRegisterRequest{xid='192.168.12.222:8091:5576031298779548712', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='container:19871;locator:9782;locator:9785', applicationData='{"autoCommit":false}'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.021  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548713, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.113  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalBeginRequest{transactionName='update(com.holo.wcs.service.device.transport_task.bo.TransportTaskUpdateReqBO)', timeout=60000}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.113  INFO --- [ServerHandlerThread_1_1_500] [org.apache.seata.server.coordinator.DefaultCoordinator] [doGlobalBegin] [192.168.12.222:8091:5576031298779548714]: Begin new global transaction applicationId: holo-wcs-service,transactionServiceGroup: default_tx_group, transactionName: update(com.holo.wcs.service.device.transport_task.bo.TransportTaskUpdateReqBO),timeout:60000,xid:192.168.12.222:8091:5576031298779548714
2024-10-18 18:23:02.118  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalBeginResponse{xid='192.168.12.222:8091:5576031298779548714', extraData='null', resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.118  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548714', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212474', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.118  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.118  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: BranchRegisterRequest{xid='192.168.12.222:8091:5576031298779548714', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212474', applicationData='{"autoCommit":false}'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.133  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548714]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548714, branchId = 5576031298779548715, resourceId = jdbc:mysql://192.168.12.222:3306/holo_wcs ,lockKeys = transport_task:1212474
2024-10-18 18:23:02.133  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548715, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.138  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: BranchRegisterRequest{xid='192.168.12.222:8091:5576031298779548712', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212475', applicationData='{"autoCommit":false,"skipCheckLock":true}'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.139  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548712]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548712, branchId = 5576031298779548716, resourceId = jdbc:mysql://192.168.12.222:3306/holo_wcs ,lockKeys = transport_task:1212475
2024-10-18 18:23:02.144  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548716, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.205  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalCommitRequest{xid='192.168.12.222:8091:5576031298779548714', extraData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.210  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalCommitResponse{globalStatus=Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.247  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalCommitRequest{xid='192.168.12.222:8091:5576031298779548712', extraData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.260  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: BranchCommitResponse{xid='192.168.12.222:8091:5576031298779548709', branchId=5576031298779548710, branchStatus=PhaseTwo_Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.261  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: BranchCommitResponse{xid='192.168.12.222:8091:5576031298779548712', branchId=5576031298779548713, branchStatus=PhaseTwo_Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.261  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: BranchCommitResponse{xid='192.168.12.222:8091:5576031298779548714', branchId=5576031298779548715, branchStatus=PhaseTwo_Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.262  INFO --- [AsyncCommitting_1_1] [org.apache.seata.server.coordinator.DefaultCore] [lambda$doGlobalCommit$1] [192.168.12.222:8091:5576031298779548712]: Commit branch transaction successfully, xid = 192.168.12.222:8091:5576031298779548712 branchId = 5576031298779548713
2024-10-18 18:23:02.262  INFO --- [ForkJoinPool.commonPool-worker-262] [org.apache.seata.server.coordinator.DefaultCore] [lambda$doGlobalCommit$1] [192.168.12.222:8091:5576031298779548714]: Commit branch transaction successfully, xid = 192.168.12.222:8091:5576031298779548714 branchId = 5576031298779548715
2024-10-18 18:23:02.262  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.DefaultCore] [lambda$doGlobalCommit$1] [192.168.12.222:8091:5576031298779548709]: Commit branch transaction successfully, xid = 192.168.12.222:8091:5576031298779548709 branchId = 5576031298779548710
2024-10-18 18:23:02.262  INFO --- [ForkJoinPool.commonPool-worker-262] [org.apache.seata.server.coordinator.DefaultCore] [doGlobalCommit] [192.168.12.222:8091:5576031298779548714]: Committing global transaction is successfully done, xid = 192.168.12.222:8091:5576031298779548714.
2024-10-18 18:23:02.263  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.DefaultCore] [lambda$doGlobalCommit$1] [192.168.12.222:8091:5576031298779548709]: Commit branch transaction successfully, xid = 192.168.12.222:8091:5576031298779548709 branchId = 5576031298779548711
2024-10-18 18:23:02.263  INFO --- [AsyncCommitting_1_1] [org.apache.seata.server.coordinator.DefaultCore] [lambda$doGlobalCommit$1] [192.168.12.222:8091:5576031298779548712]: Commit branch transaction successfully, xid = 192.168.12.222:8091:5576031298779548712 branchId = 5576031298779548716
2024-10-18 18:23:02.263  INFO --- [AsyncCommitting_1_1] [org.apache.seata.server.coordinator.DefaultCore] [doGlobalCommit] [192.168.12.222:8091:5576031298779548712]: Committing global transaction is successfully done, xid = 192.168.12.222:8091:5576031298779548712.
2024-10-18 18:23:02.263  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.DefaultCore] [doGlobalCommit] [192.168.12.222:8091:5576031298779548709]: Committing global transaction is successfully done, xid = 192.168.12.222:8091:5576031298779548709.
2024-10-18 18:23:02.266  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalCommitResponse{globalStatus=Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.266  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: BranchCommitResponse{xid='192.168.12.222:8091:5576031298779548709', branchId=5576031298779548711, branchStatus=PhaseTwo_Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.266  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: BranchCommitResponse{xid='192.168.12.222:8091:5576031298779548712', branchId=5576031298779548716, branchStatus=PhaseTwo_Committed, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.505  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='null', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='container:17621', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.506  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.511  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='null', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='locator:9773,10244', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.511  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.516  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='null', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='locator:10244;container:17621;locator:9773', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.521  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.753  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalBeginRequest{transactionName='insertTask(com.holo.storage.service.container.vo.ContainerVO, com.holo.storage.service.locator.vo.LocatorVO, com.holo.wcs.service.device.instance.device.device.domain.Device, com.holo.wcs.service.device.instance.device.connector.enums.DeviceConnectorTypeEnum)', timeout=60000}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.754  INFO --- [ServerHandlerThread_1_27_500] [org.apache.seata.server.coordinator.DefaultCoordinator] [doGlobalBegin] [192.168.12.222:8091:5576031298779548717]: Begin new global transaction applicationId: holo-wcs-service,transactionServiceGroup: default_tx_group, transactionName: insertTask(com.holo.storage.service.container.vo.ContainerVO, com.holo.storage.service.locator.vo.LocatorVO, com.holo.wcs.service.device.instance.device.device.domain.Device, com.holo.wcs.service.device.instance.device.connector.enums.DeviceConnectorTypeEnum),timeout:60000,xid:192.168.12.222:8091:5576031298779548717
2024-10-18 18:23:02.759  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalBeginResponse{xid='192.168.12.222:8091:5576031298779548717', extraData='null', resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.770  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548717', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='container:17621', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.775  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.775  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548717', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='locator:9773,10244', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.775  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=true, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.780  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: BranchRegisterRequest{xid='192.168.12.222:8091:5576031298779548717', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='locator:10244;container:17621;locator:9773', applicationData='{"autoCommit":false}'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.780  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548717]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548717, branchId = 5576031298779548718, resourceId = jdbc:mysql://192.168.12.222:3306/holo_storage ,lockKeys = locator:10244;container:17621;locator:9773
2024-10-18 18:23:02.785  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548718, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.813  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalBeginRequest{transactionName='update(com.holo.wcs.service.device.transport_task.bo.TransportTaskUpdateReqBO)', timeout=60000}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.829  INFO --- [ServerHandlerThread_1_35_500] [org.apache.seata.server.coordinator.DefaultCoordinator] [doGlobalBegin] [192.168.12.222:8091:5576031298779548719]: Begin new global transaction applicationId: holo-wcs-service,transactionServiceGroup: default_tx_group, transactionName: update(com.holo.wcs.service.device.transport_task.bo.TransportTaskUpdateReqBO),timeout:60000,xid:192.168.12.222:8091:5576031298779548719
2024-10-18 18:23:02.829  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalBeginResponse{xid='192.168.12.222:8091:5576031298779548719', extraData='null', resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.831  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779548719]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 18:23:02.834  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548719', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212475', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.834  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=false, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.853  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548719', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212475', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.853  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779548719]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 18:23:02.858  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=false, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.865  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: BranchRegisterRequest{xid='192.168.12.222:8091:5576031298779548717', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212476', applicationData='{"autoCommit":false,"skipCheckLock":true}'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.865  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548717]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548717, branchId = 5576031298779548720, resourceId = jdbc:mysql://192.168.12.222:3306/holo_wcs ,lockKeys = transport_task:1212476
2024-10-18 18:23:02.870  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548720, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.875  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779548719]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 18:23:02.875  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548719', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212475', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.875  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=false, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.896  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548719', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212475', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.896  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779548719]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 18:23:02.902  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=false, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.918  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548719', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212475', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:02.918  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779548719]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 18:23:02.924  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: GlobalLockQueryResponse{lockable=false, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.011  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalCommitRequest{xid='192.168.12.222:8091:5576031298779548717', extraData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group

seata客户端日志如下：

INFO  2024-10-18 18:23:01.987 [] o.a.s.tm.api.DefaultGlobalTransaction: Begin new global transaction [192.168.12.222:8091:5576031298779548712]
INFO  2024-10-18 18:23:01.987 [] o.a.s.tm.api.TransactionalTemplate: join into a existing global transaction,xid=192.168.12.222:8091:5576031298779548712
INFO  2024-10-18 18:23:01.996 [] o.a.s.tm.api.DefaultGlobalTransaction: transaction 192.168.12.222:8091:5576031298779548709 will be commit
INFO  2024-10-18 18:23:01.996 [] o.a.s.tm.api.DefaultGlobalTransaction: transaction end, xid = 192.168.12.222:8091:5576031298779548709
INFO  2024-10-18 18:23:01.996 [] o.a.s.tm.api.DefaultGlobalTransaction: [192.168.12.222:8091:5576031298779548709] commit status: Committed
INFO  2024-10-18 18:23:02.113 [] o.a.s.tm.api.DefaultGlobalTransaction: Begin new global transaction [192.168.12.222:8091:5576031298779548714]
INFO  2024-10-18 18:23:02.133 [] o.a.s.rm.AbstractResourceManager: branch register success, xid:192.168.12.222:8091:5576031298779548714, branchId:5576031298779548715, lockKeys:transport_task:1212474
INFO  2024-10-18 18:23:02.139 [] o.a.s.rm.AbstractResourceManager: branch register success, xid:192.168.12.222:8091:5576031298779548712, branchId:5576031298779548716, lockKeys:transport_task:1212475
INFO  2024-10-18 18:23:02.205 [] o.a.s.tm.api.DefaultGlobalTransaction: transaction 192.168.12.222:8091:5576031298779548714 will be commit
INFO  2024-10-18 18:23:02.205 [] o.a.s.tm.api.DefaultGlobalTransaction: transaction end, xid = 192.168.12.222:8091:5576031298779548714
INFO  2024-10-18 18:23:02.205 [] o.a.s.tm.api.DefaultGlobalTransaction: [192.168.12.222:8091:5576031298779548714] commit status: Committed
INFO  2024-10-18 18:23:02.246 [] o.a.s.tm.api.DefaultGlobalTransaction: transaction 192.168.12.222:8091:5576031298779548712 will be commit
INFO  2024-10-18 18:23:02.260 [rpcDispatch_RMROLE_1_32_64] o.a.s.core.rpc.processor.client.RmBranchCommitProcessor: rm client handle branch commit process:BranchCommitRequest{xid='192.168.12.222:8091:5576031298779548714', branchId=5576031298779548715, branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', applicationData='{"autoCommit":false}'}
INFO  2024-10-18 18:23:02.260 [rpcDispatch_RMROLE_1_32_64] o.a.s.rm.AbstractRMHandler: Branch committing: 192.168.12.222:8091:5576031298779548714 5576031298779548715 jdbc:mysql://192.168.12.222:3306/holo_wcs {"autoCommit":false}
INFO  2024-10-18 18:23:02.260 [rpcDispatch_RMROLE_1_32_64] o.a.s.rm.AbstractRMHandler: Branch commit result: PhaseTwo_Committed
INFO  2024-10-18 18:23:02.262 [] o.a.s.tm.api.DefaultGlobalTransaction: transaction end, xid = 192.168.12.222:8091:5576031298779548712
INFO  2024-10-18 18:23:02.262 [] o.a.s.tm.api.DefaultGlobalTransaction: [192.168.12.222:8091:5576031298779548712] commit status: Committed
INFO  2024-10-18 18:23:02.262 [rpcDispatch_RMROLE_1_61_64] o.a.s.core.rpc.processor.client.RmBranchCommitProcessor: rm client handle branch commit process:BranchCommitRequest{xid='192.168.12.222:8091:5576031298779548712', branchId=5576031298779548716, branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', applicationData='{"autoCommit":false,"skipCheckLock":true}'}
INFO  2024-10-18 18:23:02.262 [rpcDispatch_RMROLE_1_56_64] o.a.s.core.rpc.processor.client.RmBranchCommitProcessor: rm client handle branch commit process:BranchCommitRequest{xid='192.168.12.222:8091:5576031298779548709', branchId=5576031298779548711, branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', applicationData='{"autoCommit":false}'}
INFO  2024-10-18 18:23:02.262 [rpcDispatch_RMROLE_1_61_64] o.a.s.rm.AbstractRMHandler: Branch committing: 192.168.12.222:8091:5576031298779548712 5576031298779548716 jdbc:mysql://192.168.12.222:3306/holo_wcs {"autoCommit":false,"skipCheckLock":true}
INFO  2024-10-18 18:23:02.262 [rpcDispatch_RMROLE_1_61_64] o.a.s.rm.AbstractRMHandler: Branch commit result: PhaseTwo_Committed
INFO  2024-10-18 18:23:02.262 [rpcDispatch_RMROLE_1_56_64] o.a.s.rm.AbstractRMHandler: Branch committing: 192.168.12.222:8091:5576031298779548709 5576031298779548711 jdbc:mysql://192.168.12.222:3306/holo_wcs {"autoCommit":false}
INFO  2024-10-18 18:23:02.262 [rpcDispatch_RMROLE_1_56_64] o.a.s.rm.AbstractRMHandler: Branch commit result: PhaseTwo_Committed

Self-revolution avatar Oct 18 '24 13:10 Self-revolution