SuperIllidan commented on issue #2265:
URL: https://github.com/apache/rocketmq/issues/2265#issuecomment-679912515
> can you provide some log?
> eg: ${user.home}/logs/rocketmqlogs/transaction.log
Here is part of the transaction.log file:
2020-08-25 17:19:20 INFO TransactionalMessageCheckService - Begin to check
prepare message, begin time:1598347160220
2020-08-25 17:19:20 INFO TransactionalMessageCheckService - Check
topic=RMQ_SYS_TRANS_HALF_TOPIC, queues=[MessageQueue
[topic=RMQ_SYS_TRANS_HALF_TOPIC, brokerName=DESKTOP-2LSPLD5, queueId=0]]
2020-08-25 17:19:20 INFO TransactionalMessageCheckService - Before check,
the queue=MessageQueue [topic=RMQ_SYS_TRANS_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0] msgOffset=1 opOffset=1
2020-08-25 17:19:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_OP_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=1
2020-08-25 17:19:20 WARN TransactionalMessageCheckService - The miss op
offset=1 in queue=MessageQueue [topic=RMQ_SYS_TRANS_OP_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0] is illegal, pullResult=PullResult
[pullStatus=OFFSET_ILLEGAL, nextBeginOffset=1, minOffset=0, maxOffset=1,
msgFoundList=0]
2020-08-25 17:19:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=1
2020-08-25 17:19:20 INFO TransactionalMessageCheckService - Illegal offset,
the miss offset=1 in=MessageQueue [topic=RMQ_SYS_TRANS_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0], continue check=2, pull
result=PullResult [pullStatus=OFFSET_ILLEGAL, nextBeginOffset=1, minOffset=0,
maxOffset=1, msgFoundList=0]
2020-08-25 17:19:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=1
2020-08-25 17:19:20 INFO TransactionalMessageCheckService - End to check
prepare message, consumed time:1
2020-08-25 17:19:21 INFO EndTransactionThread_2 - Transaction
request:EndTransactionRequestHeader{producerGroup='order_trans_msg_producer_group',
tranStateTableOffset=1, commitLogOffset=687, commitOrRollback=0,
fromTransactionCheck=false, msgId='C0A80AC24E2C18B4AAC27F5031760000',
transactionId='null'}
2020-08-25 17:19:21 WARN EndTransactionThread_2 - The
producer[172.16.11.187:64021] end transaction in sending message, and it's
pending status.RequestHeader:
EndTransactionRequestHeader{producerGroup='order_trans_msg_producer_group',
tranStateTableOffset=1, commitLogOffset=687, commitOrRollback=0,
fromTransactionCheck=false, msgId='C0A80AC24E2C18B4AAC27F5031760000',
transactionId='null'} Remark: null
2020-08-25 17:20:20 INFO TransactionalMessageCheckService - Begin to check
prepare message, begin time:1598347220222
2020-08-25 17:20:20 INFO TransactionalMessageCheckService - Check
topic=RMQ_SYS_TRANS_HALF_TOPIC, queues=[MessageQueue
[topic=RMQ_SYS_TRANS_HALF_TOPIC, brokerName=DESKTOP-2LSPLD5, queueId=0]]
2020-08-25 17:20:20 INFO TransactionalMessageCheckService - Before check,
the queue=MessageQueue [topic=RMQ_SYS_TRANS_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0] msgOffset=1 opOffset=1
2020-08-25 17:20:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_OP_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=1
2020-08-25 17:20:20 WARN TransactionalMessageCheckService - The miss op
offset=1 in queue=MessageQueue [topic=RMQ_SYS_TRANS_OP_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0] is illegal, pullResult=PullResult
[pullStatus=OFFSET_ILLEGAL, nextBeginOffset=1, minOffset=0, maxOffset=1,
msgFoundList=0]
2020-08-25 17:20:20 INFO TransactionalMessageCheckService - Send check
message, the offset=1 restored in queueOffset=2 commitLogOffset=963
newMsgId=AC100BBB00002A9F00000000000003C3
realMsgId=C0A80AC24E2C18B4AAC27F5031760000 topic=RMQ_SYS_TRANS_HALF_TOPIC
2020-08-25 17:20:20 INFO TransactionalMessageCheckService - Fresh stored.
the miss offset=2, check it later, store=Tue Aug 25 17:20:20 CST 2020
2020-08-25 17:20:20 INFO TransactionalMessageCheckService - End to check
prepare message, consumed time:22
2020-08-25 17:20:20 INFO EndTransactionThread_3 - Transaction
request:EndTransactionRequestHeader{producerGroup='order_trans_msg_producer_group',
tranStateTableOffset=2, commitLogOffset=963, commitOrRollback=8,
fromTransactionCheck=true, msgId='C0A80AC24E2C18B4AAC27F5031760000',
transactionId='C0A80AC24E2C18B4AAC27F5031760000'}
2020-08-25 17:20:20 WARN EndTransactionThread_3 - Check
producer[172.16.11.187:64021] transaction state, the producer commit the
message.RequestHeader:
EndTransactionRequestHeader{producerGroup='order_trans_msg_producer_group',
tranStateTableOffset=2, commitLogOffset=963, commitOrRollback=8,
fromTransactionCheck=true, msgId='C0A80AC24E2C18B4AAC27F5031760000',
transactionId='C0A80AC24E2C18B4AAC27F5031760000'} Remark: null
2020-08-25 17:20:20 INFO EndTransactionThread_3 - Transaction op message
write successfully. messageId=AC100BBB00002A9F00000000000003C3, queueId=0
msgExt:MessageExt [queueId=0, storeSize=302, queueOffset=2, sysFlag=0,
bornTimestamp=1598347161974, bornHost=/172.16.11.187:64022,
storeTimestamp=1598347220226, storeHost=/172.16.11.187:10911,
msgId=AC100BBB00002A9F00000000000003C3, commitLogOffset=963, bodyCRC=222957957,
reconsumeTimes=0, preparedTransactionOffset=0,
toString()=Message{topic='RMQ_SYS_TRANS_HALF_TOPIC', flag=0,
properties={TRANSACTION_CHECK_TIMES=1, TRAN_MSG=true,
UNIQ_KEY=C0A80AC24E2C18B4AAC27F5031760000, WAIT=true,
PGROUP=order_trans_msg_producer_group}, body=[104, 101, 108, 108, 111, 32, 119,
111, 114, 108, 100], transactionId='null'}]
2020-08-25 17:21:20 INFO TransactionalMessageCheckService - Begin to check
prepare message, begin time:1598347280246
2020-08-25 17:21:20 INFO TransactionalMessageCheckService - Check
topic=RMQ_SYS_TRANS_HALF_TOPIC, queues=[MessageQueue
[topic=RMQ_SYS_TRANS_HALF_TOPIC, brokerName=DESKTOP-2LSPLD5, queueId=0]]
2020-08-25 17:21:20 INFO TransactionalMessageCheckService - Before check,
the queue=MessageQueue [topic=RMQ_SYS_TRANS_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0] msgOffset=2 opOffset=1
2020-08-25 17:21:20 INFO TransactionalMessageCheckService - Topic:
RMQ_SYS_TRANS_OP_HALF_TOPIC tags: d, OpOffset: 1, HalfOffset: 2
2020-08-25 17:21:20 INFO TransactionalMessageCheckService - Half offset 2
has been committed/rolled back
2020-08-25 17:21:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=3
2020-08-25 17:21:20 INFO TransactionalMessageCheckService - Illegal offset,
the miss offset=3 in=MessageQueue [topic=RMQ_SYS_TRANS_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0], continue check=2, pull
result=PullResult [pullStatus=OFFSET_ILLEGAL, nextBeginOffset=3, minOffset=0,
maxOffset=3, msgFoundList=0]
2020-08-25 17:21:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=3
2020-08-25 17:21:20 INFO TransactionalMessageCheckService - End to check
prepare message, consumed time:1
2020-08-25 17:22:20 INFO TransactionalMessageCheckService - Begin to check
prepare message, begin time:1598347340249
2020-08-25 17:22:20 INFO TransactionalMessageCheckService - Check
topic=RMQ_SYS_TRANS_HALF_TOPIC, queues=[MessageQueue
[topic=RMQ_SYS_TRANS_HALF_TOPIC, brokerName=DESKTOP-2LSPLD5, queueId=0]]
2020-08-25 17:22:20 INFO TransactionalMessageCheckService - Before check,
the queue=MessageQueue [topic=RMQ_SYS_TRANS_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0] msgOffset=3 opOffset=1
2020-08-25 17:22:20 INFO TransactionalMessageCheckService - Topic:
RMQ_SYS_TRANS_OP_HALF_TOPIC tags: d, OpOffset: 1, HalfOffset: 2
2020-08-25 17:22:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=3
2020-08-25 17:22:20 INFO TransactionalMessageCheckService - Illegal offset,
the miss offset=3 in=MessageQueue [topic=RMQ_SYS_TRANS_HALF_TOPIC,
brokerName=DESKTOP-2LSPLD5, queueId=0], continue check=2, pull
result=PullResult [pullStatus=OFFSET_ILLEGAL, nextBeginOffset=3, minOffset=0,
maxOffset=3, msgFoundList=0]
2020-08-25 17:22:20 WARN TransactionalMessageCheckService - Offset illegal.
GetMessageStatus=OFFSET_OVERFLOW_ONE, topic=RMQ_SYS_TRANS_HALF_TOPIC,
groupId=CID_RMQ_SYS_TRANS, requestOffset=3
2020-08-25 17:22:20 INFO TransactionalMessageCheckService - End to check
prepare message, consumed time:0
----------------------------------------------------------------
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
For queries about this service, please contact Infrastructure at:
[email protected]
