Account Options

  1. Sign in
The old Google Groups will be going away soon, but your browser is incompatible with the new version.
Google Groups Home
« Groups Home
timeAcquiringMicros and timeLockedMicros explained
There are currently too many topics in this group that display first. To make this topic appear first, remove this option from another topic.
There was an error processing your request. Please try again.
flag
  4 messages - Collapse all  -  Translate all to Translated (View all originals)
The group you are posting to is a Usenet group. Messages posted to this group will make your email address visible to anyone on the Internet.
Your reply message has not been sent.
Your post was successful
 
From:
To:
Cc:
Followup To:
Add Cc | Add Followup-to | Edit Subject
Subject:
Validation:
For verification purposes please type the characters you see in the picture below or the numbers you hear by clicking the accessibility icon. Listen and type the numbers you hear
 
FAQUser  
View profile  
 More options Sep 25 2012, 5:04 am
From: FAQUser <xritc...@gmail.com>
Date: Tue, 25 Sep 2012 02:04:37 -0700 (PDT)
Local: Tues, Sep 25 2012 5:04 am
Subject: timeAcquiringMicros and timeLockedMicros explained

Anyone mind explaining to me these 2 fields as my performance degraded
significantly these past 2 days i', suspecting it has something to do with
them.

{
   "ts": ISODate("2012-09-25T08: 58: 43.959Z"),
   "op": "command",
   "ns": "CrawledCompanies.$cmd",
   "command": {
     "count": "CompanyBucketCollection",
     "query": {
       "Data.Data.GramIdentifier": "431F5CF11E9AF6F474A8F67EFAAA0FEA",
       "Data.Data.Grams": {
         "0": "restructuring"
      }
    }
  },
   "ntoreturn": 1,
   "keyUpdates": 0,
   "numYield": 2,
   "lockStats": {
     "timeLockedMicros"▼<http://127.0.0.1:8081/rockmongo-on-windows/web/rockmongo/index.php?ac...>
: {
       "r": 249964,
       "w": 0
    },
     "timeAcquiringMicros": {
       "r": 121123,
       "w": 11061
    }
  },
   "responseLength": 48,
   "millis": 124,
   "client": "172.21.13.5",
   "user": "root"

}

Thanks in Advance :D

 
You must Sign in before you can post messages.
To post a message you must first join this group.
Please update your nickname on the subscription settings page before posting.
You do not have the permission required to post.
FAQUser  
View profile  
 More options Sep 25 2012, 5:35 am
From: FAQUser <xritc...@gmail.com>
Date: Tue, 25 Sep 2012 02:35:49 -0700 (PDT)
Local: Tues, Sep 25 2012 5:35 am
Subject: Re: timeAcquiringMicros and timeLockedMicros explained

Another Example

From what I read millis is the number of milli seconds for the operation to
complete which in this case is 140
while timeLockedMicros is 146 which is suppose to mean time locked how can
this be?

{
   "ts": ISODate("2012-09-25T09: 25: 44.724Z"),
   "op": "update",
   "ns": "CrawledCompanies.CompanyBucketCollection",
   "query": {
     "_id": ObjectId("505873199fd1870780ba566e")
  },
   "updateobj": {
     "_id": ObjectId("505873199fd1870780ba566e"),
     "DateCreated": ISODate("2012-09-18T13: 11: 53.934Z"),
     "Type": "ZeepexManager.BuisnessObjects.ERModel.Bucketing.Bucket",
     "Data": {
       "_t": "Bucket",
       "ParentBucket": ObjectId("000000000000000000000000"),
       "CreatedOn": ISODate("2012-09-18T13: 11: 53.934Z"),
       "Data": {
         "_t": "GramSearch",
         "Grams": {
           "0": "Juliet"
        },
         "GramIdentifier": "AE02C75A75E13ED8328C91FBCAD2E4A0"
      },
       "Type": "ZeepexManager.BuisnessObjects.GramBucket.GramSearch",
       "Buckets": {
         "0": {
           "_t": "ObjectReference",
           "RefId": ObjectId("4fbf39b59fd18712a49ad0b1"),
           "OccuranceCount": 1
        },
         "1": {
           "_t": "ObjectReference",
           "RefId": ObjectId("4fbf3f789fd18712a49c4cea"),
           "OccuranceCount": 1
        },
         "2": {
           "_t": "ObjectReference",
           "RefId": ObjectId("4fbfa63f9fd18718b8fe3433"),
           "OccuranceCount": 1
        },
         "3": {
           "_t": "ObjectReference",
           "RefId": ObjectId("4fbfa6559fd18718b8fe3bda"),
           "OccuranceCount": 1
        },
         "4": {
           "_t": "ObjectReference",
           "RefId": ObjectId("4fbfa6b79fd18718b8fe5279"),
           "OccuranceCount": 1
        },
         "5": {
           "_t": "ObjectReference",
           "RefId": ObjectId("4fbfb2ef9fd18718b800b88d"),
           "OccuranceCount": 2

} ...

         "99": {
           "_t": "ObjectReference",
           "RefId": ObjectId("000000000000000000000000"),
           "OccuranceCount": 0
        }
      },
       "BucketType":
"ZeepexManager.BuisnessObjects.GramBucket.ObjectReference",
       "BucketSize": 11,
       "NeighbourBuckets": ObjectId("000000000000000000000000")
    }
  },
   "idhack": true,
   "nupdated": 1,
   "keyUpdates": 0,
   "numYield": 0,
   "lockStats": {
     "timeLockedMicros": {
       "r": 0,
       "w": 146
    },
     "timeAcquiringMicros": {
       "r": 0,
       "w": 152020
    }
  },
   "millis": 140,
   "client": "172.21.13.5",
   "user": "root"


 
You must Sign in before you can post messages.
To post a message you must first join this group.
Please update your nickname on the subscription settings page before posting.
You do not have the permission required to post.
FAQUser  
View profile  
 More options Sep 25 2012, 9:57 am
From: FAQUser <xritc...@gmail.com>
Date: Tue, 25 Sep 2012 06:57:47 -0700 (PDT)
Local: Tues, Sep 25 2012 9:57 am
Subject: Re: timeAcquiringMicros and timeLockedMicros explained

Spent all day playing around with the profiler and stuff and the only
reason i can think of why the locking is taking so long is that the index
does not fit on ram and making index updates for inserts slow.


 
You must Sign in before you can post messages.
To post a message you must first join this group.
Please update your nickname on the subscription settings page before posting.
You do not have the permission required to post.
William Zola  
View profile  
 More options Sep 27 2012, 3:55 pm
From: William Zola <william.z...@10gen.com>
Date: Thu, 27 Sep 2012 12:55:40 -0700 (PDT)
Local: Thurs, Sep 27 2012 3:55 pm
Subject: Re: timeAcquiringMicros and timeLockedMicros explained

Hi FAQ!

The 'timeLockedMicros' field indicates how many microseconds this operation
spent holding a particular lock.  

There are two locks for each collection: the 'r' lock indicates how long
this operation spent holding the read lock, and the 'w' lock indicates how
long it spent holding the write lock.

The 'timeAcquiringMicros' field indicates how many microseconds this
operation spent waiting for the locks.  

Reference:
http://docs.mongodb.org/manual/reference/current-op/#output-reference

If you're looking to diagnose general performance slowdown, I'll need
additional information in order to do that.  

If these systems are in MMS, then please post a link to the correct MMS
group.  If they're not please post the following in a gist/pastebing/pastie
and post a link:

 - Output of 'mongostat --all -n 20'
 - Output of 'db.serverStatus()'
 - Output of 'db.serverBuildInfo()'

I'll be able to use this information to move forward with my diagnosis.

 Ref:
http://docs.mongodb.org/manual/administration/monitoring/#diagnosing-...

Let me know if you have further questions.

 -William


 
You must Sign in before you can post messages.
To post a message you must first join this group.
Please update your nickname on the subscription settings page before posting.
You do not have the permission required to post.
End of messages
« Back to Discussions « Newer topic     Older topic »