Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

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

Open
Self-revolution opened this issue Oct 18, 2024 · 19 comments
Open

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

Self-revolution opened this issue Oct 18, 2024 · 19 comments

Comments

@Self-revolution
Copy link

线上偶发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
@funky-eyes
Copy link
Contributor

server store mode是什么?

@funky-eyes
Copy link
Contributor

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
日志提供的有缺漏,5576031298779548712在这行日志后再也没出现过

@Self-revolution
Copy link
Author

服务端获取锁失败的日志在后面,我在上面的内容中提供了4次,后面也一直在报

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.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

store.mode=db,seata配置文件如下:

#Transport configuration, for client and server
transport.type=TCP
transport.server=NIO
transport.heartbeat=true
transport.enableTmClientBatchSendRequest=false
transport.enableRmClientBatchSendRequest=true
transport.enableTcServerBatchSendResponse=false
transport.rpcRmRequestTimeout=30000
transport.rpcTmRequestTimeout=30000
transport.rpcTcRequestTimeout=30000
transport.threadFactory.bossThreadPrefix=NettyBoss
transport.threadFactory.workerThreadPrefix=NettyServerNIOWorker
transport.threadFactory.serverExecutorThreadPrefix=NettyServerBizHandler
transport.threadFactory.shareBossWorker=false
transport.threadFactory.clientSelectorThreadPrefix=NettyClientSelector
transport.threadFactory.clientSelectorThreadSize=1
transport.threadFactory.clientWorkerThreadPrefix=NettyClientWorkerThread
transport.threadFactory.bossThreadSize=1
transport.threadFactory.workerThreadSize=default
transport.shutdown.wait=3
transport.serialization=seata
transport.compressor=none

#Transaction routing rules configuration, only for the client
service.vgroupMapping.default_tx_group=default
#If you use a registry, you can ignore it
service.default.grouplist=127.0.0.1:8091
service.disableGlobalTransaction=false

client.metadataMaxAgeMs=30000
#Transaction rule configuration, only for the client
client.rm.asyncCommitBufferLimit=10000
client.rm.lock.retryInterval=20
client.rm.lock.retryTimes=50
client.rm.lock.retryPolicyBranchRollbackOnConflict=true
client.rm.reportRetryCount=5
client.rm.tableMetaCheckEnable=true
client.rm.tableMetaCheckerInterval=60000
client.rm.sqlParserType=druid
client.rm.reportSuccessEnable=false
client.rm.sagaBranchRegisterEnable=false
client.rm.sagaJsonParser=fastjson
client.rm.tccActionInterceptorOrder=-2147482648
client.rm.sqlParserType=druid
client.tm.commitRetryCount=5
client.tm.rollbackRetryCount=5
client.tm.defaultGlobalTransactionTimeout=60000
client.tm.degradeCheck=false
client.tm.degradeCheckAllowTimes=10
client.tm.degradeCheckPeriod=2000
client.tm.interceptorOrder=-2147482648
client.undo.dataValidation=true
client.undo.logSerialization=jackson
client.undo.onlyCareUpdateColumns=true
server.undo.logSaveDays=7
server.undo.logDeletePeriod=86400000
client.undo.logTable=undo_log
client.undo.compress.enable=true
client.undo.compress.type=zip
client.undo.compress.threshold=64k

#Log rule configuration, for client and server
log.exceptionRate=100

#Transaction storage configuration, only for the server. The file, db, and redis configuration values are optional.
store.mode=db
store.lock.mode=db
store.session.mode=db

#These configurations are required if the `store mode` is `db`. If `store.mode,store.lock.mode,store.session.mode` are not equal to `db`, you can remove the configuration block.
store.db.datasource=druid
store.db.dbType=mysql
store.db.driverClassName=com.mysql.cj.jdbc.Driver
store.db.url=******
store.db.user=******
store.db.password=******
store.db.minConn=1
store.db.maxConn=30
store.db.globalTable=global_table
store.db.branchTable=branch_table
store.db.distributedLockTable=distributed_lock
store.db.queryLimit=100
store.db.lockTable=lock_table
store.db.maxWait=5000

#Transaction rule configuration, only for the server
server.recovery.committingRetryPeriod=1000
server.recovery.asynCommittingRetryPeriod=1000
server.recovery.rollbackingRetryPeriod=1000
server.recovery.timeoutRetryPeriod=1000
server.maxCommitRetryTimeout=-1
server.maxRollbackRetryTimeout=-1
server.rollbackRetryTimeoutUnlockEnable=false
server.distributedLockExpireTime=10000
server.session.branchAsyncQueueSize=5000
server.session.enableBranchAsyncRemove=false
server.enableParallelRequestHandle=true
server.enableParallelHandleBranch=false
server.applicationDataLimit=64000
server.applicationDataLimitCheck=false

#Metrics configuration, only for the server
metrics.enabled=false
metrics.registryType=compact
metrics.exporterList=prometheus
metrics.exporterPrometheusPort=9898

@funky-eyes
Copy link
Contributor

5576031298779548712 这个事务的所有日志贴上来

@Self-revolution
Copy link
Author

后面一直在申请[transport_task:1212475]的锁,一直申请不到,我查看了mysql中的seata数据,没有看到这个锁,后来我手动修改了业务数据,没有继续申请这个锁
服务端日志:

2024-10-18 18:23:01.950  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548709]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548709, branchId = 5576031298779548711, resourceId = jdbc:mysql://192.168.12.222:3306/holo_wcs ,lockKeys = transport_task:1212471
2024-10-18 18:23:01.955  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548711, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
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
2024-10-18 18:23:03.012  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:03.016  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:03.016  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:03.016  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.034  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:03.034  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:03.039  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.056  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:03.056  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:03.061  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.078  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:03.078  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:03.084  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.100  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:03.100  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:03.106  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.108  INFO --- [ServerHandlerThread_1_46_500] [org.apache.seata.server.coordinator.DefaultCoordinator] [doGlobalBegin] [192.168.12.222:8091:5576031298779548721]: Begin new global transaction applicationId: holo-wcs-service,transactionServiceGroup: default_tx_group, transactionName: updateBatch(com.holo.wcs.service.device.transport_task.bo.TransportTaskUpdateBatchReqBO),timeout:60000,xid:192.168.12.222:8091:5576031298779548721
2024-10-18 18:23:03.111  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalBeginRequest{transactionName='updateBatch(com.holo.wcs.service.device.transport_task.bo.TransportTaskUpdateBatchReqBO)', timeout=60000}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.111  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalBeginResponse{xid='192.168.12.222:8091:5576031298779548721', extraData='null', resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.111  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548721', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212421', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.111  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:03.122  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:03.122  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:03.128  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.128  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548721', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='container:13639', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.128  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:03.133  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548721', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='locator:9951,10218', applicationData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.133  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:03.138  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: BranchRegisterRequest{xid='192.168.12.222:8091:5576031298779548721', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_storage', lockKey='locator:9951;locator:10218;container:13639', applicationData='{"autoCommit":false}'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.139  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548721]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548721, branchId = 5576031298779548722, resourceId = jdbc:mysql://192.168.12.222:3306/holo_storage ,lockKeys = locator:9951;locator:10218;container:13639
2024-10-18 18:23:03.144  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548722, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.145  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:03.149  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:03.149  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.167  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:03.167  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:03.172  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.185  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: BranchRegisterRequest{xid='192.168.12.222:8091:5576031298779548721', branchType=AT, resourceId='jdbc:mysql://192.168.12.222:3306/holo_wcs', lockKey='transport_task:1212421', applicationData='{"autoCommit":false}'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.185  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.server.coordinator.AbstractCore] [lambda$branchRegister$0] [192.168.12.222:8091:5576031298779548721]: Register branch successfully, xid = 192.168.12.222:8091:5576031298779548721, branchId = 5576031298779548723, resourceId = jdbc:mysql://192.168.12.222:3306/holo_wcs ,lockKeys = transport_task:1212421
2024-10-18 18:23:03.189  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:03.190  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[merged]: BranchRegisterResponse{branchId=5576031298779548723, resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.190  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:03.190  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.211  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:03.211  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:03.216  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.226  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:03.226  INFO --- [ServerHandlerThread_1_28_500] [org.apache.seata.server.coordinator.DefaultCoordinator] [doGlobalBegin] [192.168.12.222:8091:5576031298779548724]: 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:5576031298779548724
2024-10-18 18:23:03.228  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779548724]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 18:23:03.231  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: result msg[single]: GlobalBeginResponse{xid='192.168.12.222:8091:5576031298779548724', extraData='null', resultCode=Success, msg='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.231  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548724', 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:03.231  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.233  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:03.236  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:03.236  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.241  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[single]: GlobalCommitRequest{xid='192.168.12.222:8091:5576031298779548721', extraData='null'}, clientIp: 192.168.12.222, vgroup: default_tx_group
2024-10-18 18:23:03.242  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:03.250  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779548724', 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:03.250  INFO --- [ForkJoinPool.commonPool-worker-246] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779548724]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 18:23:03.255  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 19:34:10.844  INFO --- [ForkJoinPool.commonPool-worker-261] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779562293]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 19:34:10.849  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 19:34:10.866  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779562293', 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 19:34:10.866  INFO --- [ForkJoinPool.commonPool-worker-261] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779562293]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 19:34:10.871  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 19:34:10.888  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779562293', 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 19:34:10.888  INFO --- [ForkJoinPool.commonPool-worker-261] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779562293]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 19:34:10.893  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 19:34:10.909  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779562293', 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 19:34:10.910  INFO --- [ForkJoinPool.commonPool-worker-261] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779562293]: Global lock on [transport_task:1212475] is holding by 5576031298779548712
2024-10-18 19:34:10.915  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 19:34:10.932  INFO --- [batchLoggerPrint_1_1] [org.apache.seata.core.rpc.processor.server.BatchLogHandler] [run] []: receive msg[merged]: GlobalLockQueryRequest{xid='192.168.12.222:8091:5576031298779562293', 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 19:34:10.932  INFO --- [ForkJoinPool.commonPool-worker-261] [org.apache.seata.core.lock.AbstractLocker] [isLockable] [192.168.12.222:8091:5576031298779562293]: Global lock on [transport_task:1212475] is holding by 5576031298779548712

@funky-eyes
Copy link
Contributor

通过5576031298779548712 这个xid去lock table中查询锁是否被持有
如果你的数据库是通过proxy做到了读写分离的话,可能是由于从库数据不一致导致一直被误认为锁被持有没释放

@Self-revolution
Copy link
Author

我当时查到数据库中没有任何锁,我的mysql是单机部署的,没有从库

@funky-eyes
Copy link
Contributor

数据库没有任何锁,是不可能出现这个异常日志的
The database does not have any locks, so this exception log cannot appear

@Self-revolution
Copy link
Author

我也感觉很奇怪,但是确实是这样,之前从来没发生过,就这出现过一次

@Self-revolution
Copy link
Author

今天再次发生了同样的问题,这次我直接重启seata-server,然后自动恢复正常了,我的服务端已升级至apache/seata-server:2.2.0.jre17

@funky-eyes
Copy link
Contributor

今天再次发生了同样的问题,这次我直接重启seata-server,然后自动恢复正常了,我的服务端已升级至apache/seata-server:2.2.0.jre17

能确认store.mode一定是db?而不是file?启动时会有store.mode的实际值输出

@funky-eyes
Copy link
Contributor

你确定出现该问题的时候,去数据库lock table能搜到这个lockkey?

@Self-revolution
Copy link
Author

感谢提醒,由于某些原因,我的服务器在5点多进行了重启
我的所有服务部署在docker中,所有容器包括seata-server、nacos和MySQL的容器同时进行了重启
seata的配置是放在nacos中的(包括MySQL配置),nacos的配置是放在MySQL中的,因为系统重启时docker容器的启动顺序无法调整,从日志中看到,可能是seata-server启动时,与MySQL建立连接失败,但是不知道为什么自动将mode从db改为了file
关于mode部分日志如下,完整日志见附件:
seata-server.8091.all.log

2024-11-28 05:12:26.655  INFO --- [main] [org.apache.seata.server.ServerApplication] [logStarted] []: Started ServerApplication in 51.359 seconds (JVM running for 86.481)
2024-11-28 05:12:43.594  INFO --- [main] [org.apache.seata.server.lock.LockerManagerFactory] [init] []: use lock store mode: db
2024-11-28 05:12:43.628  INFO --- [main] [org.apache.seata.server.session.SessionHolder] [init] []: use session store mode: db
2024-11-28 05:13:06.882  INFO --- [main] [com.alibaba.druid.pool.DruidDataSource] [init] []: {dataSource-1} inited
2024-11-28 05:13:19.326  INFO --- [main] [org.apache.seata.core.rpc.netty.NettyServerBootstrap] [start] []: Server started, service listen port: 8091
2024-11-28 05:13:19.346  INFO --- [main] [org.apache.seata.discovery.registry.nacos.NacosRegistryServiceImpl] [initNacosAuthProperties] []: Nacos check auth with userName/password.
2024-11-28 05:13:29.813  INFO --- [main] [org.apache.seata.discovery.registry.nacos.NacosRegistryServiceImpl] [initNacosAuthProperties] []: Nacos check auth with userName/password.
2024-11-28 05:13:30.035 ERROR --- [main] [org.apache.seata.server.ServerRunner] [run] []: seata server start error: Server start failed 
==>
java.lang.RuntimeException: Server start failed
	at org.apache.seata.core.rpc.netty.NettyServerBootstrap.start(NettyServerBootstrap.java:179) ~[seata-core-2.2.0.jar:2.2.0]
	at org.apache.seata.core.rpc.netty.AbstractNettyRemotingServer.init(AbstractNettyRemotingServer.java:56) ~[seata-core-2.2.0.jar:2.2.0]
	at org.apache.seata.core.rpc.netty.NettyRemotingServer.init(NettyRemotingServer.java:58) ~[seata-core-2.2.0.jar:2.2.0]
	at org.apache.seata.server.Server.start(Server.java:112) ~[classes/:na]
	at org.apache.seata.server.ServerRunner.run(ServerRunner.java:64) ~[classes/:na]
	at org.springframework.boot.SpringApplication.callRunner(SpringApplication.java:765) ~[spring-boot-2.7.18.jar:2.7.18]
	at org.springframework.boot.SpringApplication.lambda$callRunners$2(SpringApplication.java:749) ~[spring-boot-2.7.18.jar:2.7.18]
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183) ~[na:na]
	at java.base/java.util.stream.SortedOps$SizedRefSortingSink.end(SortedOps.java:357) ~[na:na]
	at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:510) ~[na:na]
	at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:499) ~[na:na]
	at java.base/java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150) ~[na:na]
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173) ~[na:na]
	at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) ~[na:na]
	at java.base/java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:596) ~[na:na]
	at org.springframework.boot.SpringApplication.callRunners(SpringApplication.java:744) ~[spring-boot-2.7.18.jar:2.7.18]
	at org.springframework.boot.SpringApplication.run(SpringApplication.java:315) ~[spring-boot-2.7.18.jar:2.7.18]
	at org.springframework.boot.SpringApplication.run(SpringApplication.java:1300) ~[spring-boot-2.7.18.jar:2.7.18]
	at org.springframework.boot.SpringApplication.run(SpringApplication.java:1289) ~[spring-boot-2.7.18.jar:2.7.18]
	at org.apache.seata.server.ServerApplication.main(ServerApplication.java:30) ~[classes/:na]
Caused by: com.alibaba.nacos.api.exception.NacosException: failed to req API:/nacos/v1/ns/instance after all servers([192.168.12.222:8848]) tried: java.net.ConnectException: Connection refused
	at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:565) ~[nacos-client-1.4.6.jar:na]
	at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:507) ~[nacos-client-1.4.6.jar:na]
	at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:502) ~[nacos-client-1.4.6.jar:na]
	at com.alibaba.nacos.client.naming.net.NamingProxy.registerService(NamingProxy.java:255) ~[nacos-client-1.4.6.jar:na]
	at com.alibaba.nacos.client.naming.NacosNamingService.registerInstance(NacosNamingService.java:218) ~[nacos-client-1.4.6.jar:na]
	at com.alibaba.nacos.client.naming.NacosNamingService.registerInstance(NacosNamingService.java:202) ~[nacos-client-1.4.6.jar:na]
	at org.apache.seata.discovery.registry.nacos.NacosRegistryServiceImpl.register(NacosRegistryServiceImpl.java:116) ~[seata-discovery-nacos-2.2.0.jar:2.2.0]
	at org.apache.seata.core.rpc.netty.NettyServerBootstrap.start(NettyServerBootstrap.java:173) ~[seata-core-2.2.0.jar:2.2.0]
	... 19 common frames omitted
<==

2024-11-28 05:13:30.037  WARN --- [Thread-19] [com.alibaba.nacos.common.notify.NotifyCenter] [shutdown] []: [NotifyCenter] Start destroying Publisher
2024-11-28 05:13:30.037  WARN --- [Thread-19] [com.alibaba.nacos.common.notify.NotifyCenter] [shutdown] []: [NotifyCenter] Destruction of the end
2024-11-28 05:13:30.038  WARN --- [Thread-6] [com.alibaba.nacos.common.http.HttpClientBeanHolder] [shutdown] []: [HttpClientBeanHolder] Start destroying common HttpClient
2024-11-28 05:13:30.039  WARN --- [Thread-6] [com.alibaba.nacos.common.http.HttpClientBeanHolder] [shutdown] []: [HttpClientBeanHolder] Destruction of the end
2024-11-28 05:13:30.998  INFO --- [SpringApplicationShutdownHook] [org.apache.seata.core.rpc.netty.NettyServerBootstrap] [shutdown] []: Shutting server down, the listen port: 8091
2024-11-28 05:13:49.276  INFO --- [main] [org.apache.seata.server.ServerApplication] [logStarted] []: Started ServerApplication in 11.333 seconds (JVM running for 13.178)
2024-11-28 05:13:51.612  INFO --- [main] [org.apache.seata.server.lock.LockerManagerFactory] [init] []: use lock store mode: file
2024-11-28 05:13:51.776  INFO --- [main] [org.apache.seata.server.session.SessionHolder] [init] []: use session store mode: file
2024-11-28 05:13:53.676  INFO --- [main] [org.apache.seata.core.rpc.netty.NettyServerBootstrap] [start] []: Server started, service listen port: 8091
2024-11-28 05:13:53.709  INFO --- [main] [org.apache.seata.discovery.registry.nacos.NacosRegistryServiceImpl] [initNacosAuthProperties] []: Nacos check auth with userName/password.
2024-11-28 05:14:03.789  INFO --- [main] [org.apache.seata.discovery.registry.nacos.NacosRegistryServiceImpl] [initNacosAuthProperties] []: Nacos check auth with userName/password.
2024-11-28 05:14:03.926  INFO --- [main] [org.apache.seata.server.ServerRunner] [run] []: 
 you can visit seata console UI on http://127.0.0.1:7091. 
 log path: /root/logs/seata.
2024-11-28 05:14:03.927  INFO --- [main] [org.apache.seata.server.ServerRunner] [run] []: seata server started in 14638 millSeconds
2024-11-28 05:14:07.078  INFO --- [NettyServerNIOWorker_1_1_64] [org.apache.seata.core.rpc.processor.server.RegTmProcessor] [onRegTmMessage] []: TM register success,message:RegisterTMRequest{version='2.1.0', applicationId='holo-user-service', transactionServiceGroup='default_tx_group', extraData='ak=null
digest=default_tx_group,10.8.185.101,1732742046609
timestamp=1732742046609
authVersion=V4
vgroup=default_tx_group
ip=10.8.185.101
'},channel:[id: 0x5a7596b4, L:/192.168.12.222:8091 - R:/192.168.12.222:49590],client version:2.1.0,client protocol-version:null

@funky-eyes
Copy link
Contributor

file和raft模式有你说的问题,这将在2.3修复,其它模式没问题。
#6932

@Self-revolution
Copy link
Author

好的,但我现在比较好奇为什么seata-server会自动变成file模式

@funky-eyes
Copy link
Contributor

跟你的docker自动拉起有关,看起来nacos报错后,拉起的容器用的是非挂载目录的配置文件,也就是默认配置文件

@Self-revolution
Copy link
Author

我在执行docker run命令时配置环境变量-e STORE_MODE=db是不是可以解决这个问题

@funky-eyes
Copy link
Contributor

我在执行docker run命令时配置环境变量-e STORE_MODE=db是不是可以解决这个问题

不一定,因为看起来你的配置挂载是失效了的,即便你指定了db,那么链接地址又从何而来呢?
Not necessarily, because it looks like your configuration mounting is invalid, even if you specify db, where does the link address come from?

@Self-revolution
Copy link
Author

强制指定了db模式,如果读取不到地址,docker容器就会重启,直到获取到地址,而不是以file模式运行

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants