logging - What would cause a planSummary to be repeated in the MongoDB log when no query is made? -


i'm working on database, , we're having slowdowns lately. looking through mongodb logs noticed these entries. show plansummary query don't think i'm making. interesting part howntoreturn 1000 , ntoskip increments 1000, kind of paging going on.

my question is, cause logs show up? mongodb kind of paging on collections without queries taking place?

2017-08-10t07:35:34.811+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1088547704529 ntoreturn:1000 ntoskip:271000 keysexamined:0 docsexamined:272000 keyupdates:0 writeconflicts:0 numyields:2125 nreturned:1000 reslen:507268 locks:{ global: { acquirecount: { r: 4252 } }, mmapv1journal: { acquirecount: { r: 2126 } }, database: { acquirecount: { r: 2126 } }, collection: { acquirecount: { r: 2126 } } } 105ms 2017-08-10t07:35:35.006+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1090571237876 ntoreturn:1000 ntoskip:272000 keysexamined:0 docsexamined:273000 keyupdates:0 writeconflicts:0 numyields:2132 nreturned:1000 reslen:510703 locks:{ global: { acquirecount: { r: 4266 } }, mmapv1journal: { acquirecount: { r: 2133 } }, database: { acquirecount: { r: 2133 } }, collection: { acquirecount: { r: 2133 } } } 131ms 2017-08-10t07:35:35.186+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1087890895561 ntoreturn:1000 ntoskip:273000 keysexamined:0 docsexamined:274000 keyupdates:0 writeconflicts:0 numyields:2140 nreturned:1000 reslen:512881 locks:{ global: { acquirecount: { r: 4282 } }, mmapv1journal: { acquirecount: { r: 2141 } }, database: { acquirecount: { r: 2141 } }, collection: { acquirecount: { r: 2141 } } } 109ms 2017-08-10t07:35:35.360+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1089324896392 ntoreturn:1000 ntoskip:274000 keysexamined:0 docsexamined:275000 keyupdates:0 writeconflicts:0 numyields:2150 nreturned:1000 reslen:511902 locks:{ global: { acquirecount: { r: 4302 } }, mmapv1journal: { acquirecount: { r: 2151 } }, database: { acquirecount: { r: 2151 } }, collection: { acquirecount: { r: 2151 } } } 124ms 2017-08-10t07:35:35.528+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1087370660613 ntoreturn:1000 ntoskip:275000 keysexamined:0 docsexamined:276000 keyupdates:0 writeconflicts:0 numyields:2161 nreturned:1000 reslen:503563 locks:{ global: { acquirecount: { r: 4324 } }, mmapv1journal: { acquirecount: { r: 2162 } }, database: { acquirecount: { r: 2162 } }, collection: { acquirecount: { r: 2162 } } } 106ms 2017-08-10t07:35:35.683+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1087239914658 ntoreturn:1000 ntoskip:276000 keysexamined:0 docsexamined:277000 keyupdates:0 writeconflicts:0 numyields:2168 nreturned:1000 reslen:528128 locks:{ global: { acquirecount: { r: 4338 } }, mmapv1journal: { acquirecount: { r: 2169 } }, database: { acquirecount: { r: 2169 } }, collection: { acquirecount: { r: 2169 } } } 106ms 2017-08-10t07:35:35.873+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1089357591808 ntoreturn:1000 ntoskip:277000 keysexamined:0 docsexamined:278000 keyupdates:0 writeconflicts:0 numyields:2175 nreturned:1000 reslen:523499 locks:{ global: { acquirecount: { r: 4352 } }, mmapv1journal: { acquirecount: { r: 2176 } }, database: { acquirecount: { r: 2176 } }, collection: { acquirecount: { r: 2176 } } } 125ms 2017-08-10t07:35:36.048+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1089120593728 ntoreturn:1000 ntoskip:278000 keysexamined:0 docsexamined:279000 keyupdates:0 writeconflicts:0 numyields:2184 nreturned:1000 reslen:511897 locks:{ global: { acquirecount: { r: 4370 } }, mmapv1journal: { acquirecount: { r: 2185 } }, database: { acquirecount: { r: 2185 } }, collection: { acquirecount: { r: 2185 } } } 111ms 2017-08-10t07:35:36.222+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1086697691506 ntoreturn:1000 ntoskip:279000 keysexamined:0 docsexamined:280000 keyupdates:0 writeconflicts:0 numyields:2191 nreturned:1000 reslen:518537 locks:{ global: { acquirecount: { r: 4384 } }, mmapv1journal: { acquirecount: { r: 2192 } }, database: { acquirecount: { r: 2192 } }, collection: { acquirecount: { r: 2192 } } } 125ms 2017-08-10t07:35:36.407+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1087781463235 ntoreturn:1000 ntoskip:280000 keysexamined:0 docsexamined:281000 keyupdates:0 writeconflicts:0 numyields:2200 nreturned:1000 reslen:529410 locks:{ global: { acquirecount: { r: 4402 } }, mmapv1journal: { acquirecount: { r: 2201 } }, database: { acquirecount: { r: 2201 } }, collection: { acquirecount: { r: 2201 } } } 111ms 2017-08-10t07:35:36.567+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1090035725548 ntoreturn:1000 ntoskip:281000 keysexamined:0 docsexamined:282000 keyupdates:0 writeconflicts:0 numyields:2206 nreturned:1000 reslen:512317 locks:{ global: { acquirecount: { r: 4414 } }, mmapv1journal: { acquirecount: { r: 2207 } }, database: { acquirecount: { r: 2207 } }, collection: { acquirecount: { r: 2207 } } } 108ms 2017-08-10t07:35:36.743+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1088574051757 ntoreturn:1000 ntoskip:282000 keysexamined:0 docsexamined:283000 keyupdates:0 writeconflicts:0 numyields:2216 nreturned:1000 reslen:514762 locks:{ global: { acquirecount: { r: 4434 } }, mmapv1journal: { acquirecount: { r: 2217 } }, database: { acquirecount: { r: 2217 } }, collection: { acquirecount: { r: 2217 } } } 111ms 2017-08-10t07:35:36.903+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1089415045908 ntoreturn:1000 ntoskip:283000 keysexamined:0 docsexamined:284000 keyupdates:0 writeconflicts:0 numyields:2222 nreturned:1000 reslen:512749 locks:{ global: { acquirecount: { r: 4446 } }, mmapv1journal: { acquirecount: { r: 2223 } }, database: { acquirecount: { r: 2223 } }, collection: { acquirecount: { r: 2223 } } } 111ms 2017-08-10t07:35:37.087+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1088725745389 ntoreturn:1000 ntoskip:284000 keysexamined:0 docsexamined:285000 keyupdates:0 writeconflicts:0 numyields:2231 nreturned:1000 reslen:520508 locks:{ global: { acquirecount: { r: 4464 } }, mmapv1journal: { acquirecount: { r: 2232 } }, database: { acquirecount: { r: 2232 } }, collection: { acquirecount: { r: 2232 } } } 119ms 2017-08-10t07:35:37.271+0000 command  [conn13007] query qauser plansummary: collscan cursorid:1087696843766 ntoreturn:1000 ntoskip:285000 keysexamined:0 docsexamined:286000 keyupdates:0 writeconflicts:0 numyields:2239 nreturned:1000 reslen:526705 locks:{ global: { acquirecount: { r: 4480 } }, mmapv1journal: { acquirecount: { r: 2240 } }, database: { acquirecount: { r: 2240 } }, collection: { acquirecount: { r: 2240 } } } 106ms 2017-08-10t07:35:37.463+0000 command  [conn13012] query qauser plansummary: collscan cursorid:1089941189919 ntoreturn:1000 ntoskip:286000 keysexamined:0 docsexamined:287000 keyupdates:0 writeconflicts:0 numyields:2246 nreturned:1000 reslen:516773 locks:{ global: { acquirecount: { r: 4494 } }, mmapv1journal: { acquirecount: { r: 2247 } }, database: { acquirecount: { r: 2247 } }, collection: { acquirecount: { r: 2247 } } } 122ms 2017-08-10t07:35:38.178+0000 command  [conn13003] query qauser plansummary: collscan cursorid:1090662777977 ntoreturn:1000 ntoskip:287000 keysexamined:0 docsexamined:288000 keyupdates:0 writeconflicts:0 numyields:2252 nreturned:1000 reslen:514494 locks:{ global: { acquirecount: { r: 4506 } }, mmapv1journal: { acquirecount: { r: 2253 } }, database: { acquirecount: { r: 2253 } }, collection: { acquirecount: { r: 2253 } } } 150ms 2017-08-10t07:35:38.605+0000 command  [conn13007] query qauser plansummary: collscan cursorid:1088487204752 ntoreturn:1000 ntoskip:288000 keysexamined:0 docsexamined:289000 keyupdates:0 writeconflicts:0 numyields:2263 nreturned:1000 reslen:509976 locks:{ global: { acquirecount: { r: 4528 } }, mmapv1journal: { acquirecount: { r: 2264 } }, database: { acquirecount: { r: 2264 } }, collection: { acquirecount: { r: 2264 } } } 143ms 2017-08-10t07:35:38.781+0000 command  [conn13014] query qauser plansummary: collscan cursorid:1086975508153 ntoreturn:1000 ntoskip:289000 keysexamined:0 docsexamined:290000 keyupdates:0 writeconflicts:0 numyields:2269 nreturned:1000 reslen:505619 locks:{ global: { acquirecount: { r: 4540 } }, mmapv1journal: { acquirecount: { r: 2270 } }, database: { acquirecount: { r: 2270 } }, collection: { acquirecount: { r: 2270 } } } 113ms 2017-08-10t07:35:38.950+0000 command  [conn13014] query qauser plansummary: collscan cursorid:1089384454002 ntoreturn:1000 ntoskip:290000 keysexamined:0 docsexamined:291000 keyupdates:0 writeconflicts:0 numyields:2277 nreturned:1000 reslen:503889 locks:{ global: { acquirecount: { r: 4556 } }, mmapv1journal: { acquirecount: { r: 2278 } }, database: { acquirecount: { r: 2278 } }, collection: { acquirecount: { r: 2278 } } } 115ms 2017-08-10t07:35:39.128+0000 command  [conn13014] query qauser plansummary: collscan cursorid:1090361212172 ntoreturn:1000 ntoskip:291000 keysexamined:0 docsexamined:292000 keyupdates:0 writeconflicts:0 numyields:2286 nreturned:1000 reslen:508949 locks:{ global: { acquirecount: { r: 4574 } }, mmapv1journal: { acquirecount: { r: 2287 } }, database: { acquirecount: { r: 2287 } }, collection: { acquirecount: { r: 2287 } } } 130ms 2017-08-10t07:35:39.312+0000 command  [conn13014] query qauser plansummary: collscan cursorid:1089126816105 ntoreturn:1000 ntoskip:292000 keysexamined:0 docsexamined:293000 keyupdates:0 writeconflicts:0 numyields:2292 nreturned:1000 reslen:507695 locks:{ global: { acquirecount: { r: 4586 } }, mmapv1journal: { acquirecount: { r: 2293 } }, database: { acquirecount: { r: 2293 } }, collection: { acquirecount: { r: 2293 } } } 115ms 2017-08-10t07:35:39.498+0000 command  [conn13014] query qauser plansummary: collscan cursorid:1090089337158 ntoreturn:1000 ntoskip:293000 keysexamined:0 docsexamined:294000 keyupdates:0 writeconflicts:0 numyields:2303 nreturned:1000 reslen:513197 locks:{ global: { acquirecount: { r: 4608 } }, mmapv1journal: { acquirecount: { r: 2304 } }, database: { acquirecount: { r: 2304 } }, collection: { acquirecount: { r: 2304 } } } 131ms 2017-08-10t07:35:39.723+0000 command  [conn13014] query qauser plansummary: collscan cursorid:1089157970943 ntoreturn:1000 ntoskip:294000 keysexamined:0 docsexamined:295000 keyupdates:0 writeconflicts:0 numyields:2308 nreturned:1000 reslen:507670 locks:{ global: { acquirecount: { r: 4618 } }, mmapv1journal: { acquirecount: { r: 2309 } }, database: { acquirecount: { r: 2309 } }, collection: { acquirecount: { r: 2309 } } } 144ms


Comments

Post a Comment

Popular posts from this blog

android - InAppBilling registering BroadcastReceiver in AndroidManifest -

i'm implementing inapp billing in android app. works well, however, i'm trying decouple broadcast receiver activity manifest. suggestion in android's trivialdrive sample: // important: dynamically register broadcast messages updated purchases. // register receiver here instead of <receiver> in manifest // because call getpurchases() @ startup, therefore can ignore // broadcasts sent while app isn't running. // note: registering listener in activity bad idea, done here // because sample. regardless, receiver must registered after // iabhelper setup, before first call getpurchases(). currently there's class extends broadcastreceiver : public class iabbroadcastreceiver extends broadcastreceiver { /** * intent action receiver should filter for. */ public static final string action = "com.android.vending.billing.purchases_updated"; private final iabbroadcastlistener mlistener; public iabbroadcastreceiver(iabbroadcastlistener listener) { m...
Read more

python Tkinter Capturing keyboard events save as one single string -

is possible store keyboard events 1 string? code below stores 1 char , prints it. card reader or bar code reader, contains collection of character/ string not 1 character @ time. goal save char pressed text variable. from tkinter import * root = tk() def key(event): text= event.char text+= event.char print ("pressed", text) def callback(event): frame.focus_set() print ("clicked at", event.x, event.y) frame = frame(root, width=100, height=100) frame.bind("<key>", key) frame.bind("<button-1>", callback) frame.pack() root.mainloop() currently, creating text variable , everytime key() function gets called, , text stores last character typed. you can define text module level variable , , use module level text inside key function - text = '' def key(event): global text text+= event.char print("pressed", text)
Read more

sql server - Why does Linq-to-SQL add unnecessary COUNT()? -

here tables: create table customer ( cid int primary key, name varchar(32) ) create table ord ( oid int primary key, cid int foreign key references customer, address varchar(20) ) here's linq-to-sql statement: var aaa = c in db.customer select new { c, o = c.ord.tolist() }; and here's inexplicable query generated linq-to-sql: select [t0].[cid] [cid], [t0].[name] [name], [t1].[oid] [oid], [t1].[cid] [cid2], [t1].[address] [address], (select count(*) [dbo].[ord] [t2] [t2].[cid] = [t0].[cid]) [value] [dbo].[customer] [t0] left outer join [dbo].[ord] [t1] on [t1].[cid] = [t0].[cid] order [t0].[cid], [t1].[oid] i want understand how rid of count(*) part. it's entirely uncalled for! linq needs count(*) in order determine number of entries returned o = c.ord.tolist() .
Read more