From issues-return-98643-archive-asf-public=cust-asf.ponee.io@ignite.apache.org Fri Sep 13 11:27:02 2019 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [207.244.88.153]) by mx-eu-01.ponee.io (Postfix) with SMTP id 64E8A180657 for ; Fri, 13 Sep 2019 13:27:02 +0200 (CEST) Received: (qmail 80714 invoked by uid 500); 13 Sep 2019 11:27:01 -0000 Mailing-List: contact issues-help@ignite.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@ignite.apache.org Delivered-To: mailing list issues@ignite.apache.org Received: (qmail 80654 invoked by uid 99); 13 Sep 2019 11:27:01 -0000 Received: from mailrelay1-us-west.apache.org (HELO mailrelay1-us-west.apache.org) (209.188.14.139) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 13 Sep 2019 11:27:01 +0000 Received: from jira-he-de.apache.org (static.172.67.40.188.clients.your-server.de [188.40.67.172]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id B4B44E3154 for ; Fri, 13 Sep 2019 11:27:00 +0000 (UTC) Received: from jira-he-de.apache.org (localhost.localdomain [127.0.0.1]) by jira-he-de.apache.org (ASF Mail Server at jira-he-de.apache.org) with ESMTP id 22CC9780547 for ; Fri, 13 Sep 2019 11:27:00 +0000 (UTC) Date: Fri, 13 Sep 2019 11:27:00 +0000 (UTC) From: "Denis Chudov (Jira)" To: issues@ignite.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Updated] (IGNITE-12165) Negative time in Transaction time dump MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/IGNITE-12165?page=3Dcom.atlass= ian.jira.plugin.system.issuetabpanels:all-tabpanel ] Denis Chudov updated IGNITE-12165: ---------------------------------- Issue Type: Bug (was: Improvement) > Negative time in Transaction time dump > -------------------------------------- > > Key: IGNITE-12165 > URL: https://issues.apache.org/jira/browse/IGNITE-12165 > Project: Ignite > Issue Type: Bug > Reporter: Denis Chudov > Assignee: Denis Chudov > Priority: Major > > After implementing ticket=C2=A0IGNITE-12063=C2=A0we have transaction dump= s in the logs. > there are some issues with information in these dumps: > {code:java} > [11:53:36,154][INFO][snapshot-scheduler-restats-#69][GridNearTxLocal] Tra= nsaction time dump [startTime=3D11:53:36.081, totalTime=3D65, systemTime=3D= 3, userTime=3D62, cacheOperationsTime=3D-41943785508, rollbackTime=3D419437= 85512, tx=3DGridNearTxLocal [mappings=3DIgniteTxMappingsImpl [], nearLocall= yMapped=3Dfalse, colocatedLocallyMapped=3Dfalse, needCheckBackup=3Dnull, ha= sRemoteLocks=3Dtrue, trackTimeout=3Dfalse, systemTime=3D3953576, systemStar= tTime=3D0, prepareStartTime=3D0, prepareTime=3D0, commitOrRollbackStartTime= =3D0, commitOrRollbackTime=3D41943785512318555, txDumpsThrottling=3Dorg.apa= che.ignite.internal.processors.cache.transactions.IgniteTxManager$TxDumpsTh= rottling@760d8025, lb=3Dnull, thread=3Dsnapshot-scheduler-restats-#69, mapp= ings=3DIgniteTxMappingsImpl [], super=3DGridDhtTxLocalAdapter [nearOnOrigin= atingNode=3Dfalse, nearNodes=3DKeySetView [], dhtNodes=3DKeySetView [], exp= licitLock=3Dfalse, super=3DIgniteTxLocalAdapter [completedBase=3Dnull, sndT= ransformedVals=3Dfalse, depEnabled=3Dfalse, txState=3DIgniteTxStateImpl [ac= tiveCacheIds=3D[-2100569601], recovery=3Dfalse, txMap=3DHashSet [IgniteTxEn= try [key=3DKeyCacheObjectImpl [part=3D2, val=3DSnapshotScheduleKey [id=3D_S= CHEDULES_], hasValBytes=3Dtrue], cacheId=3D-2100569601, txKey=3DIgniteTxKey= [key=3DKeyCacheObjectImpl [part=3D2, val=3DSnapshotScheduleKey [id=3D_SCHE= DULES_], hasValBytes=3Dtrue], cacheId=3D-2100569601], val=3D[op=3DREAD, val= =3Dnull], prevVal=3D[op=3DNOOP, val=3Dnull], oldVal=3D[op=3DNOOP, val=3Dnul= l], entryProcessorsCol=3Dnull, ttl=3D-1, conflictExpireTime=3D-1, conflictV= er=3Dnull, explicitVer=3Dnull, dhtVer=3Dnull, filters=3Dnull, filtersPassed= =3Dfalse, filtersSet=3Dtrue, entry=3DGridCacheMapEntry [key=3DKeyCacheObjec= tImpl [part=3D2, val=3DSnapshotScheduleKey [id=3D_SCHEDULES_], hasValBytes= =3Dtrue], val=3Dnull, ver=3DGridCacheVersion [topVer=3D0, order=3D0, nodeOr= der=3D0], hash=3D886348002, extras=3Dnull, flags=3D0]GridDistributedCacheEn= try [super=3D]GridDhtDetachedCacheEntry [super=3D], prepared=3D0, locked=3D= true, nodeId=3D1adbae78-40fe-480d-803d-4d498919ae63, locMapped=3Dfalse, exp= iryPlc=3Dnull, transferExpiryPlc=3Dfalse, flags=3D0, partUpdateCntr=3D0, se= rReadVer=3Dnull, xidVer=3DGridCacheVersion [topVer=3D179672005, order=3D156= 8192005565, nodeOrder=3D1]]]], super=3DIgniteTxAdapter [xidVer=3DGridCacheV= ersion [topVer=3D179672005, order=3D1568192005565, nodeOrder=3D1], writeVer= =3Dnull, implicit=3Dfalse, loc=3Dtrue, threadId=3D125, startTime=3D15681920= 16081, nodeId=3D9e00ae45-4084-4f29-949f-c19c933f4299, startVer=3DGridCacheV= ersion [topVer=3D179672005, order=3D1568192005565, nodeOrder=3D1], endVer= =3Dnull, isolation=3DREPEATABLE_READ, concurrency=3DPESSIMISTIC, timeout=3D= 0, sysInvalidate=3Dfalse, sys=3Dtrue, plc=3D5, commitVer=3Dnull, finalizing= =3DNONE, invalidParts=3Dnull, state=3DROLLED_BACK, timedOut=3Dfalse, topVer= =3DAffinityTopologyVersion [topVer=3D4, minorTopVer=3D1], duration=3D60ms, = onePhaseCommit=3Dfalse], size=3D1]]]] > {code} > For example: > # Negative time: cacheOperationsTime=3D-41943785508, > # Huge times: rollbackTime=3D41943785512 -- This message was sent by Atlassian Jira (v8.3.2#803003)