OE Explorer to Manage and Monitor Scripted Databases

Posted by dbeavon on 19-Apr-2017 09:09

Does anyone have experience using OE Explorer to monitor a scripted database?

We are having quite a bit of difficultly with this product.  More information can be found here: https://community.progress.com/community_groups/openedge_deployment/f/21/t/30883

I'm not seeing a lot of complaints from others about OE Explorer issues related to CPU so either we are doing something wrong or nobody is using the product (or at least not for a scripted database on HP-UX).

The problems seem to be primarily related to when we investigate "User Details" (ie. database connectivity information).  Some of those web pages appear to operate by asynchronously pulling incremental information.  We see that some of these screens seem to open up quickly, but then there is a ton of follow-up CPU activity in the java admin server process and it goes on for several minutes afterwards.  Subsequent clicks in the OE Explorer pages are all extremely slow.

Please let me know if any of this sounds familiar and/or if there is a way to troubleshoot whatever that java admin server process is doing with our CPU.  This is reproducible now in two different HP-UX environments, although nothing like this had happened in Windows (although they were managed database on that side of things).  Any help would be very much appreciated.

Posted by dbeavon on 26-Oct-2017 14:46

In case anyone else wants to attempt to use OEE for OpenEdge, the fix appears to be available now as part of the 11.7.1 service pack (look for PSC00357066.). It points to resource usage issues in the underlying admin server.

Aside from applying a Progress fix, the best workaround is to turn off usage statistics altogether.  I've discovered that most of the work that is done when clicking on a database connection is related to compiling "per-user" usage statistics for display.  I'm not really sure why this is such a challenging operation but it seems to make OEE almost unusable for managing databases.

All Replies

Posted by cjbrandt on 19-Apr-2017 12:16

I have used OEE and OEM for several years.  We use OEE in dev, environments just to provide a GUI interface to the databases or app servers.  We use OEM in prod to monitor and trend performance data.

OEM is for monitoring

OEE is really just a GUI interface because it lacks the features that OEM uses to monitor environments and trend data.

Are you using OEE trying to monitor something , or you just want to see some stats ?  Also the version of OEM or OEE you are using is very important so include that info.

Our current version of OEM is 11.5.1 and it has a known issue with performance.  11.6 is supposed to address that.  We had the best success when we were using 10.2B06 for the databases and OEM.  Since upgrading the performance has gone down.

You are correct, there isn't much information available from clients that use OEM, although there seems to be more in the last year or so.

Posted by Paul Koufalis on 19-Apr-2017 13:01

I'm going to put on my marketing hat here, but you really should look at the free version of ProTop. I expect the real-time ChUI interface will give you everything you need and more with very little CPU usage. You can zoom in on any user and see their table and index activity, transaction status, I/O, and even the program and line of code they're running if you activate Client Statement Cache. There's a blocked process screen showing you blockers and blockees...the list goes on and on.

Posted by dbeavon on 19-Apr-2017 13:29

Thanks for the reply.  We use OEE ("free" version) and it has some very minimal features for monitoring a database.  The main things we'd like to see are locks, and user sessions, and possibly the table activity for individual user numbers.

The version of OE we are on is 11.6.3.013.  That is the version that is demonstrating performance issues on both of the HP-UX environments where we have tried to get OEE running .   As soon as we start using the "User Details" views, it sends some work off to the "admin server" java process and it consumes quite a lot of CPU.  I'm not doing much more than just clicking on a single client user number (17 or whatever) and seeing the details for it.  That alone is enough to swamp CPU on two cores for a couple minutes.

It seems to be gathering a couple parts of the page asynchronously (eg. the "Table Activity" and "Index Activity" for the user 17).  Even if you click away to a different page, the CPU continues to be pegged on the server and slows down subsequent U/I interaction as well.

I'm not sure why this takes so long, or why there is so much CPU-intensive work within a java-related admin server process ... instead of in the _dbagent itself, or why none of these performance issues were occurring in Windows.  I'd also like to know whether there are preferences to *disable* these types of OEE behaviors that are consuming so much CPU on the server.  There are quite a lot of benefits in having some visibility on the database in OEE... however we don't want the use of this to generate an entirely new set of problems for us.

If I had to guess, I'd say that the java admin server process is waiting on data from _dbagent and is passing the time in an extremely high CPU-spinning loop.  Would you please click on one of the connections in the "Database Connections" tab and see if it caps out your CPU on the server's java process for long periods of time?  As I said, my Windows installation is better behaved, and it is possible that I'm dealing with yet another instance of HP-UX-specific problems that don't affect the more common OE platforms like Windows and Linux.

Posted by dbeavon on 19-Apr-2017 13:57

Thanks for the feedback.  How long has the free version of ProTop been available?  Some of our OE administration scripts and tools are 15-20 years old ; we do quite a lot with promon, and "glance" in hp-ux.  It wouldn't surprise me that we haven't  looked at ProTop yet, if it is a new offering (less than 5 years old).  

Our goal is to make simple problems easier to troubleshoot (the simple problems are the vast majority of the types of problems that "bite" us regularly in OpenEdge).  Examples of relatively simple problems are  long running transactions, high connection counts, high I/O, record locking contention, etc.  We'd like these types of things to be highly visible ("point-and-click") for "non-DBA" types of people.  

I think that one of the challenges we're running into with OEE is the use of its internal VST's.  We've never had much luck using them directly, and I thought that OEE would do a better job of "making sense of them" and it would be able to extract real information from them in a reasonable way.  However, it is possible that the underlying problem wasn't *only* to do with the VST's; but perhaps there were also some HP-UX specific factors involved.  I find it hard to believe that ProTop would do a better job with the OE VST's than OEE does (unless it simple knows to avoid certaion of them as much as possible).

I suspect your ProTop is a great product, and we might take a look at it.  At this point it won't take much to convince me that your stuff will work better than OEE.  It is somewhat of a surprise that Progress can't provide a better management experience for its database.  I understand that a number of years ago we had purchased "OEM" and tried to use it for a while (on HP-UX), only to find that it created a lot more problems than it solved.  So we had to give up on it and uninstalled/discontinued using it.  I was hoping that some of those "kinks" were ironed out by now, or that they didn't apply to the "free" OEE.

Posted by Paul Koufalis on 19-Apr-2017 14:14

ProTop has been around for 14 years. The free version gives you a real-time view in the local character UI and an 8-hour trend view in the web portal. The paid version will alert you to all of those "relatively simple problems" like long running transactions, record locking contention, plus about 200 other metrics and gives you a LOT of cool toys in the web portal.

I think it's time for you to contact me offline. :-) I'll help get you setup on the free version.

Paul

Posted by Rob Fitzpatrick on 19-Apr-2017 14:20

> I think that one of the challenges we're running into with OEE is the use of its internal VST's

I don't use OEE much and I don't use OEM at all, but I doubt this is enough to cause a simple query to take a couple of minutes to complete.  

The most egregious thing you can do with the VSTs is write a bad _Lock query and in 11.6.3 even that should be quick.  If you have a large schema and a large user count then you can have a lot of records in _UserTableStat and _UserIndexStat.  It's certainly possible to write inefficient queries on these tables that do table scans, but even for that two minutes sounds like an awful lot.  So if you run ProTop (which I recommend) or your own queries against the VSTs then you won't have a performance problem.

Posted by ChUIMonster on 19-Apr-2017 14:59

ProTop has always focused on taking data from the VSTs and meta schema and many other sources and presenting more meaningful insight than a simple "vst browser".

In the past some people have been turned off by the character interface.  Some people probably still are turned off by that.

FWIW there is actually a 10.2A Dot Net GUI client but it turned out to be a major PITA to coordinate all of the Dot Net gunk for new builds as new OE releases came out.  It was pretty.  But it was also much more work to support than it was worth.

There is still a character interface for working in real time on the server.  Kind of like a cross between PROMON and the UNIX "top" command. This is extremely powerful.  And free.  Character interfaces are not as bad as some people think they are.

There is also an optional background agent process that feeds a browser based view of collected data with lots of pretty charts and stuff.  The free version shows you up to 8 hours of history.  Paid versions show lots more history and include features like alerting.  The agent process is very lightweight and stores nothing locally -- so you don't have to manage a local database and you don't need to worry about the performance impact of the monitoring tool.

Posted by dbeavon on 20-Apr-2017 10:23

So in my effort to troubleshoot high cpu in the admin server (related primarily to our attempts to start using OEE), I've turned up the logging level to 5 as explained here:

https://documentation.progress.com/output/ua/OpenEdge_latest/index.html#page/oemgs/setting-the-log-level-for-the-adminserver-log-fi.html

The logs definitely pump out lot of additional messaging for the entire time that the java process is swamping CPU.  Here is a sample showing the beginning and ending (omitting intermediate stuff).  

Keep in mind that this is only the admin server activity from clicking on a single user ID in OEE, on a relatively quiet testing database that has only been up for about 2 hours.  Depending on the user I click on, and the length of time the database has been up, the duration of these clicks can cause a much bigger problem in admin server CPU.  I'm not trying to mislead anyone or exagerate.  I would love for this to work better on HP-UX.  I am starting to suspect there aren't many who are using this, or at least not on HP-UX.

[2017/04/20@11:02:24.147-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _Database-Feature start
[2017/04/20@11:02:24.148-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] ----> agent message: time: 1492700544147 name : QRY_VST_TBL code: 0 length: 74 sequence: 0 type: 1 session: 0 status: 0
[2017/04/20@11:02:24.148-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: queue write: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.148-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: writing buffer: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.149-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: wrote 74 bytes
[2017/04/20@11:02:24.155-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=68 cap=68]
[2017/04/20@11:02:24.155-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 68 total bytes read
[2017/04/20@11:02:24.155-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=1111 cap=1111]
[2017/04/20@11:02:24.155-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 1111 total bytes read
[2017/04/20@11:02:24.156-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] <---- agent message: time: 1492700544155 name : QRY_VST_TBL_ACK code: 1101 length: 1179 sequence: 1 type: 5 session: 463 status: 0
[2017/04/20@11:02:24.156-0400] [5] [DatabaseAgent] Received message agent message: time: 1492700544155 name : QRY_VST_TBL_ACK code: 1101 length: 1179 sequence: 1 type: 5 session: 463 status: 0 from client 1. (8492)
[2017/04/20@11:02:24.157-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _Database-Feature end time(ms): 10
[2017/04/20@11:02:24.171-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _Connect start
[2017/04/20@11:02:24.171-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] ----> agent message: time: 1492700544171 name : QRY_VST_TBL code: 0 length: 74 sequence: 0 type: 1 session: 0 status: 0
[2017/04/20@11:02:24.171-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: queue write: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.172-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: writing buffer: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.172-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: wrote 74 bytes
[2017/04/20@11:02:24.175-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=68 cap=68]
[2017/04/20@11:02:24.175-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 68 total bytes read
[2017/04/20@11:02:24.176-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=14463 cap=14463]
[2017/04/20@11:02:24.176-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 14463 total bytes read
[2017/04/20@11:02:24.185-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] <---- agent message: time: 1492700544175 name : QRY_VST_TBL_ACK code: 1101 length: 14531 sequence: 1 type: 5 session: 463 status: 0
[2017/04/20@11:02:24.185-0400] [5] [DatabaseAgent] Received message agent message: time: 1492700544175 name : QRY_VST_TBL_ACK code: 1101 length: 14531 sequence: 1 type: 5 session: 463 status: 0 from client 1. (8492)
[2017/04/20@11:02:24.186-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _Connect end time(ms): 15
[2017/04/20@11:02:24.689-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _sec-authentication-domain start
[2017/04/20@11:02:24.689-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] ----> agent message: time: 1492700544689 name : QRY_VST_TBL code: 0 length: 72 sequence: 0 type: 1 session: 0 status: 0
[2017/04/20@11:02:24.690-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: queue write: java.nio.HeapByteBuffer[pos=0 lim=72 cap=72]
[2017/04/20@11:02:24.723-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: writing buffer: java.nio.HeapByteBuffer[pos=0 lim=72 cap=72]
[2017/04/20@11:02:24.723-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: wrote 72 bytes
[2017/04/20@11:02:24.724-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=68 cap=68]
[2017/04/20@11:02:24.724-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 68 total bytes read
[2017/04/20@11:02:24.724-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=760 cap=760]
[2017/04/20@11:02:24.724-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 760 total bytes read
[2017/04/20@11:02:24.725-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] <---- agent message: time: 1492700544724 name : QRY_VST_TBL_ACK code: 1101 length: 828 sequence: 1 type: 5 session: 463 status: 0
[2017/04/20@11:02:24.725-0400] [5] [DatabaseAgent] Received message agent message: time: 1492700544724 name : QRY_VST_TBL_ACK code: 1101 length: 828 sequence: 1 type: 5 session: 463 status: 0 from client 1. (8492)
[2017/04/20@11:02:24.725-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _sec-authentication-domain end time(ms): 36
[2017/04/20@11:02:24.736-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _UserIO start
[2017/04/20@11:02:24.736-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] ----> agent message: time: 1492700544736 name : QRY_VST_TBL code: 0 length: 74 sequence: 0 type: 1 session: 0 status: 0
[2017/04/20@11:02:24.737-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: queue write: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.756-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: writing buffer: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.756-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: wrote 74 bytes
[2017/04/20@11:02:24.759-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=68 cap=68]
[2017/04/20@11:02:24.759-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 68 total bytes read
[2017/04/20@11:02:24.759-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=4047 cap=4047]
[2017/04/20@11:02:24.759-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 4047 total bytes read
[2017/04/20@11:02:24.762-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] <---- agent message: time: 1492700544759 name : QRY_VST_TBL_ACK code: 1101 length: 4115 sequence: 1 type: 5 session: 463 status: 0
[2017/04/20@11:02:24.762-0400] [5] [DatabaseAgent] Received message agent message: time: 1492700544759 name : QRY_VST_TBL_ACK code: 1101 length: 4115 sequence: 1 type: 5 session: 463 status: 0 from client 1. (8492)
[2017/04/20@11:02:24.762-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _UserIO end time(ms): 26
[2017/04/20@11:02:24.792-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _LockReq start
[2017/04/20@11:02:24.793-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] ----> agent message: time: 1492700544792 name : QRY_VST_TBL code: 0 length: 74 sequence: 0 type: 1 session: 0 status: 0
[2017/04/20@11:02:24.793-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: queue write: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.793-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: writing buffer: java.nio.HeapByteBuffer[pos=0 lim=74 cap=74]
[2017/04/20@11:02:24.793-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: wrote 74 bytes
[2017/04/20@11:02:24.794-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=68 cap=68]
[2017/04/20@11:02:24.794-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 68 total bytes read
[2017/04/20@11:02:24.795-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=4038 cap=4038]
[2017/04/20@11:02:24.795-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 4038 total bytes read
[2017/04/20@11:02:24.797-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] <---- agent message: time: 1492700544795 name : QRY_VST_TBL_ACK code: 1101 length: 4106 sequence: 1 type: 5 session: 463 status: 0
[2017/04/20@11:02:24.797-0400] [5] [DatabaseAgent] Received message agent message: time: 1492700544795 name : QRY_VST_TBL_ACK code: 1101 length: 4106 sequence: 1 type: 5 session: 463 status: 0 from client 1. (8492)

... SKIP A WHOLE BUNCH OF SIMILAR STUFF ....

[2017/04/20@11:03:37.833-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _StorageObject start
[2017/04/20@11:03:37.833-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] ----> agent message: time: 1492700617833 name : QRY_VST_TBL code: 0 length: 79 sequence: 0 type: 1 session: 0 status: 0
[2017/04/20@11:03:37.833-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: queue write: java.nio.HeapByteBuffer[pos=0 lim=79 cap=79]
[2017/04/20@11:03:37.834-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: writing buffer: java.nio.HeapByteBuffer[pos=0 lim=79 cap=79]
[2017/04/20@11:03:37.834-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: wrote 79 bytes
[2017/04/20@11:03:37.859-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=68 cap=68]
[2017/04/20@11:03:37.859-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 68 total bytes read
[2017/04/20@11:03:37.859-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: reading into buffer: java.nio.HeapByteBuffer[pos=0 lim=14226 cap=14226]
[2017/04/20@11:03:37.859-0400] [5] [DatabaseAgent] uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679]: returning with 14226 total bytes read
[2017/04/20@11:03:37.860-0400] [5] [DatabaseAgent] id: 1 nioendpoint: uuid:9784fb00-1a10-4d64-a6b2-f047fcef41da channel: java.nio.channels.SocketChannel[connected local=localhost/127.0.0.1:8845 remote=/172.31.18.79:60679] <---- agent message: time: 1492700617859 name : QRY_VST_TBL_ACK code: 1101 length: 14294 sequence: 1 type: 5 session: 463 status: 0
[2017/04/20@11:03:37.860-0400] [5] [DatabaseAgent] Received message agent message: time: 1492700617859 name : QRY_VST_TBL_ACK code: 1101 length: 14294 sequence: 1 type: 5 session: 463 status: 0 from client 1. (8492)
[2017/04/20@11:03:37.860-0400] [5] [DatabaseAgent] VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 27

Posted by Paul Koufalis on 20-Apr-2017 10:32

It looks like the java process is querying EVERY VST table for the user. Grep out all the "VST query for agent _SMDatabase_etest table ..."  and you'll get the start/end time for each table.

Posted by dbeavon on 20-Apr-2017 10:40

Here is the entire log from that period of time, filtered on lines saying "VST query for agent".  Note that the start/end times are misleading (smaller ms shown than actual duration) as is apparent if you investigate the gps in the absolute times listed on each output line.

[11:02:24.147-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Database-Feature start
[11:02:24.157-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Database-Feature end time(ms): 10
[11:02:24.171-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Connect start
[11:02:24.186-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Connect end time(ms): 15
[11:02:24.689-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _sec-authentication-domain start
[11:02:24.725-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _sec-authentication-domain end time(ms): 36
[11:02:24.736-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIO start
[11:02:24.762-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIO end time(ms): 26
[11:02:24.792-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _LockReq start
[11:02:24.830-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _LockReq end time(ms): 38
[11:02:24.880-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:25.015-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 135
[11:02:25.272-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:25.341-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 69
[11:02:25.590-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:25.676-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 86
[11:02:25.915-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:26.029-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 110
[11:02:26.262-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:26.357-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 95
[11:02:26.621-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:26.685-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 64
[11:02:26.921-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:26.936-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 15
[11:02:27.188-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:27.208-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 20
[11:02:27.476-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:27.488-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 12
[11:02:27.726-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:27.739-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 13
[11:02:27.974-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:27.986-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 12
[11:02:28.224-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:28.268-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 44
[11:02:28.535-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock start
[11:02:28.542-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserLock end time(ms): 7
[11:02:28.755-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Tenant start
[11:02:28.758-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Tenant end time(ms): 3
[11:02:28.759-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Trans start
[11:02:28.763-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Trans end time(ms): 4
[11:02:28.785-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:28.808-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:28.809-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 24
[11:02:28.895-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:28.921-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 113
[11:02:28.931-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 36
[11:02:29.016-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.018-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.034-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 18
[11:02:29.073-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 55
[11:02:29.137-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.153-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.170-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 33
[11:02:29.198-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 45
[11:02:29.239-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.286-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 47
[11:02:29.319-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.330-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:29.376-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.384-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:29.408-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.424-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:29.506-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.516-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 10
[11:02:29.546-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.590-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 44
[11:02:29.593-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.621-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 28
[11:02:29.662-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.686-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 24
[11:02:29.690-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.756-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.759-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 69
[11:02:29.803-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 47
[11:02:29.828-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:29.874-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:29.882-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 54
[11:02:30.010-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.016-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 142
[11:02:30.023-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 13
[11:02:30.092-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.095-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.175-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 83
[11:02:30.177-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 82
[11:02:30.248-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.249-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.259-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:30.264-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 15
[11:02:30.331-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.340-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:30.344-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.363-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 19
[11:02:30.411-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.432-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.441-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 30
[11:02:30.449-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 17
[11:02:30.550-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.580-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.590-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 40
[11:02:30.596-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 17
[11:02:30.661-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.672-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.681-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 20
[11:02:30.690-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 19
[11:02:30.754-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.765-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.775-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 21
[11:02:30.783-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 18
[11:02:30.848-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.859-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.861-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:30.868-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:30.934-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:30.944-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:30.945-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:30.955-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 11
[11:02:31.081-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.096-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.107-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 26
[11:02:31.114-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 18
[11:02:31.178-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.190-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.203-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 24
[11:02:31.216-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 26
[11:02:31.276-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.291-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.301-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 26
[11:02:31.310-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 19
[11:02:31.387-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.387-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.406-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:31.411-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 24
[11:02:31.534-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.542-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.551-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 17
[11:02:31.623-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 81
[11:02:31.652-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.694-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 41
[11:02:31.697-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.706-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:31.766-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.775-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:31.783-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.792-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:31.847-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:31.859-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:31.868-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:31.878-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 10
[11:02:31.988-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.008-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.011-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 23
[11:02:32.023-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 15
[11:02:32.092-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.100-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.111-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 11
[11:02:32.112-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 20
[11:02:32.187-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.191-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.197-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:32.205-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:32.269-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.282-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.290-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 21
[11:02:32.303-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 22
[11:02:32.468-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.476-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:32.487-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.513-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 26
[11:02:32.549-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.581-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 32
[11:02:32.586-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.629-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 44
[11:02:32.652-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.662-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:32.703-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.734-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.742-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 39
[11:02:32.750-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:32.892-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.900-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:32.913-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 21
[11:02:32.922-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 22
[11:02:32.987-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:32.994-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.003-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 16
[11:02:33.012-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:33.087-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.096-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:33.099-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.108-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:33.172-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.184-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 12
[11:02:33.188-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.196-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:33.322-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.331-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.342-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 20
[11:02:33.347-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:33.418-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.419-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.430-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 12
[11:02:33.437-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:33.506-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.515-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:33.521-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.531-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:33.589-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.603-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.613-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 24
[11:02:33.624-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 22
[11:02:33.742-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.746-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.755-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 12
[11:02:33.766-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 20
[11:02:33.829-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.843-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:33.850-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.861-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:33.918-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:33.933-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:33.935-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 17
[11:02:33.944-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:34.059-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.065-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.068-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:34.080-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:02:34.137-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.152-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.155-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 18
[11:02:34.167-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:02:34.225-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.237-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 12
[11:02:34.245-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.254-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:34.306-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.321-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 15
[11:02:34.380-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.392-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:34.437-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.456-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 19
[11:02:34.470-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.480-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:34.526-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.541-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 15
[11:02:34.558-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.567-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:34.611-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.624-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 13
[11:02:34.727-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.737-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:34.770-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.782-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 13
[11:02:34.815-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.827-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:34.853-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.861-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 8
[11:02:34.906-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:34.916-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:34.931-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:34.940-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:35.070-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.071-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.081-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:35.090-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 19
[11:02:35.161-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.183-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 22
[11:02:35.209-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.225-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:35.251-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.265-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:35.303-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.314-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:35.335-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.344-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:35.456-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.468-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:35.477-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.491-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:35.547-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.560-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:35.566-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.575-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:35.639-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.647-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.651-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:35.659-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 12
[11:02:35.790-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.790-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.804-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:35.829-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 39
[11:02:35.874-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.883-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:35.907-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:35.917-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:35.959-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:35.973-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:36.098-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.107-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:36.147-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.160-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 13
[11:02:36.185-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.194-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:36.236-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.245-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 8
[11:02:36.272-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.286-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:36.320-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.334-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:36.443-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.452-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:36.469-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.500-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 31
[11:02:36.529-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.567-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 38
[11:02:36.574-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.586-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 12
[11:02:36.646-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.663-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.664-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:36.744-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 82
[11:02:36.817-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.821-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.834-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 17
[11:02:36.847-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 26
[11:02:36.913-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:36.924-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:36.935-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 22
[11:02:36.941-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 17
[11:02:37.075-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.079-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:37.097-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 22
[11:02:37.103-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 24
[11:02:37.176-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.189-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:37.190-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:37.200-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 10
[11:02:37.270-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.277-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:37.285-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:02:37.294-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 17
[11:02:37.431-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.438-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:37.445-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:37.470-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 32
[11:02:37.524-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.538-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:37.547-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:37.560-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 13
[11:02:37.668-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.685-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 17
[11:02:37.688-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:37.761-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 73
[11:02:37.763-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.789-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 27
[11:02:37.838-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:37.848-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 10
[11:02:37.885-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:37.899-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:37.993-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:38.021-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 27
[11:02:38.047-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.059-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:38.136-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.145-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:38.226-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.230-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:38.236-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:38.252-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 22
[11:02:38.378-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.390-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:38.406-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:38.453-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 47
[11:02:38.467-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.480-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:38.531-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:38.540-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:38.560-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.662-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 102
[11:02:38.696-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:38.706-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 10
[11:02:38.742-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.751-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:38.786-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:38.795-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:38.832-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:38.854-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 22
[11:02:38.935-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:38.947-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 12
[11:02:39.002-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.017-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:02:39.038-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:39.074-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 36
[11:02:39.096-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.149-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:39.155-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 59
[11:02:39.254-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 105
[11:02:39.290-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.303-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:39.347-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:39.368-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 20
[11:02:39.394-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.406-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:39.501-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.514-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:39.636-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:39.642-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.653-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 17
[11:02:39.662-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 20
[11:02:39.744-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.748-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:39.753-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:39.833-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:39.841-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 94
[11:02:39.848-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:02:40.007-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:40.021-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 14
[11:02:40.056-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:40.071-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:02:40.108-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:40.117-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:40.184-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:40.197-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 13
[11:02:40.292-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:40.314-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 22
[11:02:40.325-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:40.391-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:40.431-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 106
[11:02:40.437-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 46
[11:02:40.502-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:40.619-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:40.626-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 124
[11:02:40.652-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 33
[11:02:40.700-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:40.721-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 21
[11:02:40.761-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:40.770-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:40.792-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:40.936-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 144
[11:02:41.063-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:41.074-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:41.108-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat start
[11:02:41.117-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserTableStat end time(ms): 9
[11:02:41.154-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:41.168-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:41.273-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:41.361-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 88
[11:02:41.438-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:41.454-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:41.541-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:41.644-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 102
[11:02:41.805-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:41.842-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 37
[11:02:42.309-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:42.324-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:02:42.528-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:42.623-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 96
[11:02:42.701-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:42.710-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:42.916-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:42.929-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:43.007-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:43.040-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 33
[11:02:43.148-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:43.168-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 20
[11:02:43.369-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:43.388-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 19
[11:02:43.480-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:43.489-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:43.773-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:43.815-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:43.878-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 63
[11:02:43.885-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:43.887-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 114
[11:02:43.962-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 77
[11:02:43.971-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.005-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 34
[11:02:44.023-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.026-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.035-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:44.113-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.119-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 93
[11:02:44.123-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.131-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:44.151-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 28
[11:02:44.154-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.204-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 50
[11:02:44.208-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.211-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.220-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 12
[11:02:44.224-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.229-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:44.305-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 81
[11:02:44.308-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.320-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 12
[11:02:44.324-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.331-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 7
[11:02:44.335-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.344-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:02:44.348-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.357-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:02:44.361-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.370-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:02:44.374-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.379-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.383-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:02:44.386-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:02:44.389-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:44.432-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 46
[11:02:44.464-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Tenant start
[11:02:44.469-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.494-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Tenant end time(ms): 30
[11:02:44.494-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:44.501-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 33
[11:02:44.508-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 14
[11:02:44.648-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.659-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:44.689-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:44.718-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 29
[11:02:44.740-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.751-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:44.816-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:44.833-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:44.834-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 18
[11:02:44.858-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 25
[11:02:44.983-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:44.994-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:45.016-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 33
[11:02:45.025-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 31
[11:02:45.106-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:45.116-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:45.119-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:45.196-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:45.222-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 104
[11:02:45.245-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 49
[11:02:45.404-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:45.414-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:45.460-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 57
[11:02:45.466-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 52
[11:02:45.551-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:45.552-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:45.589-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 38
[11:02:45.598-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 46
[11:02:45.690-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:45.694-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:45.718-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 28
[11:02:45.746-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 52
[11:02:45.914-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:45.934-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:45.974-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 40
[11:02:45.996-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 82
[11:02:46.057-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.066-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:46.098-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:02:46.134-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 36
[11:02:46.176-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.186-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:46.336-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.345-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:46.425-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.482-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 57
[11:02:46.560-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.570-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:46.716-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.728-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:46.807-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.816-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:46.897-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.906-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:46.985-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:46.994-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:47.128-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:47.137-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:47.216-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:47.226-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:47.305-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:47.315-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:47.396-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.281-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 1885
[11:02:49.366-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.376-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:49.456-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.465-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:49.545-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.554-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:49.678-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.688-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:49.767-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.781-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:49.861-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.870-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:49.950-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:49.959-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:50.080-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.093-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:50.174-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.183-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:50.264-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.300-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 36
[11:02:50.458-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.468-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:50.544-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.553-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:50.627-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.636-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:50.711-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.721-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:50.842-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.855-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:50.936-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:50.945-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:51.026-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.035-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:51.165-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.174-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:51.255-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.268-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:51.348-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.360-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:51.436-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.445-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:51.568-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.577-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:51.652-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.661-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:51.737-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.745-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:51.820-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.829-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:51.946-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:51.955-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:52.031-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.039-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:52.115-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.123-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:52.241-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.249-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:52.325-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.335-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:52.410-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.419-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:52.495-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.504-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:52.622-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.631-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:52.706-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.717-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:52.794-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.803-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:52.923-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:52.936-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:02:53.012-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.020-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:53.096-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.108-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:53.187-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.195-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:53.331-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.340-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:53.416-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.425-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:53.505-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.538-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 33
[11:02:53.664-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.682-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:53.761-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.803-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 42
[11:02:53.880-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:53.898-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:54.040-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.049-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:54.125-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.134-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:54.210-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.219-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:54.296-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.304-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:54.447-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.459-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:54.536-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.545-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:54.622-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.631-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:54.751-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.761-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:54.837-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.846-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:54.924-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:54.932-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:55.054-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.063-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:55.141-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.157-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:55.237-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.255-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 18
[11:02:55.398-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.408-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:55.491-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.501-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:55.582-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.591-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:55.717-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.727-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:55.805-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.813-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:55.891-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:55.903-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:56.027-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.036-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:56.114-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.122-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:02:56.199-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.208-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:56.353-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.364-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:56.442-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.453-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:56.532-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.541-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:56.672-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.681-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:56.765-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.774-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:56.858-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:56.869-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:56.993-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.002-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:57.087-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.098-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:57.183-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.192-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:57.322-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.332-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:57.415-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.425-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:57.554-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.563-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:57.647-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.656-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:57.739-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.751-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:57.890-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.899-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:57.985-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:57.994-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:58.079-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.090-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:58.247-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.257-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:58.343-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.352-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:58.437-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.498-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 62
[11:02:58.584-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.593-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:58.678-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.687-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:58.820-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.836-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:58.924-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:58.935-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:02:59.024-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.038-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:02:59.191-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.206-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:02:59.292-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.301-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:59.469-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.478-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:59.566-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.575-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:02:59.661-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.672-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:02:59.824-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.834-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:02:59.924-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:02:59.934-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:00.069-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.078-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:00.166-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.176-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:00.264-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.338-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 74
[11:03:00.426-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.435-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:00.521-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.536-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:03:00.679-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.689-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:00.777-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.786-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:00.921-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:00.942-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 21
[11:03:01.029-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.041-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:03:01.126-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.135-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:01.276-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.286-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:01.371-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.380-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:01.519-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.529-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:01.615-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.625-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:01.773-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.783-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:01.869-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:01.878-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:02.049-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.059-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:02.150-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.159-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:02.245-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.255-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:02.391-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.401-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:02.492-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.504-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:03:02.684-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.693-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:02.783-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.792-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:02.925-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:02.935-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:03.022-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.031-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:03.167-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.210-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 43
[11:03:03.314-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.324-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:03.412-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.422-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:03.564-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.574-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:03.662-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.671-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:03.808-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.818-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:03.910-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:03.922-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:03:04.067-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:04.084-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 17
[11:03:04.176-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:04.186-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:04.329-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:04.345-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:03:04.434-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:04.449-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:03:04.591-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:04.608-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 17
[11:03:04.698-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:04.725-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 27
[11:03:04.924-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:04.934-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:05.024-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:05.048-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 24
[11:03:05.237-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:05.274-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 37
[11:03:05.366-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:05.382-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:03:05.548-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:05.559-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:03:05.653-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:05.662-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:05.835-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:05.846-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:03:05.940-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:05.955-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:03:06.128-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:06.141-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:03:06.230-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:06.239-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:06.386-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:06.395-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:06.486-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:06.498-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:03:06.643-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:06.656-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:03:06.747-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:06.757-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:06.910-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:06.925-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:03:07.019-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.029-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:07.161-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.169-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:03:07.254-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.265-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:03:07.405-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.414-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:07.510-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.520-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:07.665-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.675-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:07.766-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.775-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:07.916-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:07.924-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:03:08.053-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.062-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:08.147-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.157-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:08.316-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.324-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:03:08.410-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.423-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 13
[11:03:08.565-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.574-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:08.727-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.738-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:03:08.889-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.898-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:08.990-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:08.999-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:09.148-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:09.157-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:09.243-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:09.255-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:03:09.429-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:09.443-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 14
[11:03:09.607-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:09.618-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:03:09.712-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:09.728-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 16
[11:03:09.898-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:09.908-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:10.017-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.026-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:10.166-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.176-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 10
[11:03:10.265-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.273-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:10.434-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.443-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:10.530-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.592-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 62
[11:03:10.679-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.688-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:10.826-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.837-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 11
[11:03:10.925-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:10.937-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 12
[11:03:11.081-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.089-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 8
[11:03:11.175-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.184-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:11.350-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.365-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:03:11.492-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.507-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 15
[11:03:11.591-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.601-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:11.742-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.751-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:11.842-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.851-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 9
[11:03:11.986-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat start
[11:03:11.991-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _UserIndexStat end time(ms): 4
[11:03:30.462-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:30.467-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 5
[11:03:30.471-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:30.475-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 5
[11:03:30.479-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:30.496-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 17
[11:03:31.880-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.892-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 12
[11:03:31.895-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.902-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 7
[11:03:31.905-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.911-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 6
[11:03:31.915-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.922-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 6
[11:03:31.925-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.933-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 8
[11:03:31.936-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.943-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 7
[11:03:31.946-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.955-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:03:31.958-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.967-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:03:31.970-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.979-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:03:31.983-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:31.993-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 11
[11:03:31.996-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:32.005-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 9
[11:03:32.009-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File start
[11:03:32.017-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _File end time(ms): 8
[11:03:32.042-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:32.050-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 8
[11:03:32.256-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:32.263-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 7
[11:03:32.429-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:32.440-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 11
[11:03:32.605-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:32.615-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 11
[11:03:32.811-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:32.826-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 15
[11:03:32.990-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:33.006-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 16
[11:03:33.171-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:33.182-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 11
[11:03:33.347-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:33.358-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 11
[11:03:33.523-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:33.535-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 12
[11:03:33.699-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:33.716-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 17
[11:03:33.910-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:33.925-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 15
[11:03:34.091-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:34.106-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 15
[11:03:34.269-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:34.286-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 17
[11:03:34.451-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:34.471-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 20
[11:03:34.634-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:34.657-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 23
[11:03:34.859-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:34.880-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 22
[11:03:35.047-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:35.068-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 21
[11:03:35.275-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:35.310-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 35
[11:03:35.474-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:35.500-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 26
[11:03:35.664-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:35.693-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 29
[11:03:35.895-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:35.934-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 39
[11:03:36.139-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:36.171-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 32
[11:03:36.356-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:36.385-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 29
[11:03:36.549-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index start
[11:03:36.577-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Index end time(ms): 28
[11:03:36.725-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Tenant start
[11:03:36.731-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _Tenant end time(ms): 6
[11:03:36.731-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:36.741-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 10
[11:03:36.863-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:36.875-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 12
[11:03:36.963-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:36.977-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 14
[11:03:37.064-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:37.083-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 19
[11:03:37.169-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:37.191-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 22
[11:03:37.317-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:37.345-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 28
[11:03:37.472-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:37.501-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 30
[11:03:37.587-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:37.622-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 35
[11:03:37.721-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:37.750-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 29
[11:03:37.833-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject start
[11:03:37.860-0400] [5] [DatabaseAgent]         VST query for agent _SMDatabase_etest table _StorageObject end time(ms): 27

Posted by Paul Koufalis on 20-Apr-2017 11:54

Wow.

Posted by gus bjorklund on 20-Apr-2017 12:04

> you really should look at the free version of ProTop

Paul, you took the words right out of my mouth!

Also you can use promon for some monitoring tasks and you can get database activity and status information from the VST’s with some simple, straightforward 4GL code (that’s how protop does its thing).

Posted by dbeavon on 20-Apr-2017 12:05

I assume this is standard behavior, only much slower on HP-UX than other platforms?  I can't imagine anyone would use this OEE stuff otherwise (on HP-UX or any other platform).

Even though the java admin server process is talking to a ***local*** _dbagent on the same server, it seems to be doing so inefficiently via TCP.  If the delay was simply the sum of the actual VST query times (IE. "end time(ms): 26" ) then it might not be so bad.  But the *actual* total duration is obviously much, much longer.

I think there are differences in the networking behavior between the various OE platforms that Progress supports.  I expect the code in the _dbagent (or in the network api config in java) is doing something on HP-UX that is different than what it does on Windows.  In the past, for example, we had seen network delays that were HP-UX-specific because the Nagle delay algorithm wasn't being disabled.  Such a thing as that should certainly not be coming into effect when two processes are talking to each other on the same server.

Posted by ChUIMonster on 20-Apr-2017 12:14

It is standard behavior and not any better or worse on HPUX than any other platform.

Posted by Matt Baker on 20-Apr-2017 19:43

Thanks for the log file dump.  

dbagent has no ability to filter records on the server.  If OEM queries a VST table, it sends back every row, at which point it eventually gets filtered on the OEM.

Can you answer a couple of questions for me so I can look into this?

Have you adjusted indexrangesize and tablerangesize for your database to be large enough for all your tables/indexes?  If so you can let me know how many tables and indexes you have?

I can't tell from the log if this is multiple overlapping requests, or single request for one user connection.

Posted by dbeavon on 20-Apr-2017 20:59

Tom,  in my original posting I pointed out that I never saw this type of performance issue in my experience with a 64 bit Windows database.  Although I should also say that there were other differences aside from just the platform itself.  On the windows side I am using a *managed* local database (not a "scripted" local database) , and it was "workgroup" license not "enterprise".

On both sides, my tests involve an extremely trivial number of users and activity (20 user connections, database running for a couple hours).

That's why I was hoping for some feedback from other OEE users to see if their experiences with the product (clicking on a user connection number) were good or bad (like I see in windows or HP-UX, respectively).  I was interested to hear that a number of people prefer third-party tools for a better management experience than you get with OE's own tooling. But I'd really like to give OEE a fair shot.  Especially since it is hard to explain to people (especially people that don't have their own personal experiences in OE ) that a third-party ProTop tool should be purchased rather than using something that comes with the shrink-wrapped product from Progress.

Posted by dbeavon on 20-Apr-2017 21:22

Matt, it was just a single click on a user number from the "Database Connections" tab.  It hyperlinks over to the "User Details" and shows connectivity information for that user. 

The table and index stats aren't always important to us so it is surprising that it caps out cores on the HP-UX server for about two minutes.  This screenshot is another attempt.  Note that the top of the screen comes back quickly in about 2 seconds (everything down thru "Active Transactions") but the "Table Activity" and "Index Activity" take another one or two minutes. 

Below you can see the complete (filtered table and index activity).  This refresh took two minutes.

The database is proserv'ed with -tablerangesize 900 -indexrangesize 2850

If I run the following, I get 1215.  Let me know what other info you may need.

    SELECT COUNT(*) FROM _file

Posted by Rob Fitzpatrick on 20-Apr-2017 22:16

> The database is proserv'ed with -tablerangesize 900 -indexrangesize 2850

> If I run the following, I get 1215.  Let me know what other info you may need.

>    SELECT COUNT(*) FROM _file

I think your -tablerangesize is not high enough.  You haven't specified your -basetable/-baseindex so I assume they are set to 1.

An empty 11.6.3 database contains 175 _File records.  So assuming you don't have extra system records for optional features (e.g. auditing or TDE) then I'd guess you have 1,040 application tables.  So with TRS of 900 (assuming -basetable 1) you would be missing stats for at least your uppermost 140 tables ("at least" because there could be gaps in the object numbers).

You can set reasonable stats parameter values like this:

find last dictdb._file no-lock where _file._tbl-type = "T" use-index _file-number.
find last dictdb._index no-lock where not _index._index-name begins "_" use-index _index-number.
 
display
  "Highest table number: " _file._file-number skip
  "Highest index number: " _index._idx-num    skip
with no-labels.

Sample output:
Highest table number:     468
Highest index number:     904

If you set -basetable to a value other than 1 then set -tablerangesize to (highest table # - basetable + 1).
If you set -baseindex to a value other than 1 then set -indexrangesize to (highest index # - baseindex + 1).

This approach provides minimum values. I like to pad these numbers by about 20 for tables and 40 for indexes, depending on how often the schema may change online.

Posted by dbeavon on 28-Apr-2017 09:43

I think I'm going to have to open a support case for HP-UX's admin server, and see if they can fix this stuff.

Today, once again, I unwittingly clicked on a user connection to see that user's connection details and the consequence is that now the java/adminserver process is now swamping on *five* cores at the same time and it has been over ten minutes.  As far as I know, the only way to recover from this is to stop and restart admin server altogether. (I closed the webpage in which I made the mouse-click on the user, but that did not cancel whatever work is being done by admin server).

Posted by dbeavon on 26-Oct-2017 14:46

In case anyone else wants to attempt to use OEE for OpenEdge, the fix appears to be available now as part of the 11.7.1 service pack (look for PSC00357066.). It points to resource usage issues in the underlying admin server.

Aside from applying a Progress fix, the best workaround is to turn off usage statistics altogether.  I've discovered that most of the work that is done when clicking on a database connection is related to compiling "per-user" usage statistics for display.  I'm not really sure why this is such a challenging operation but it seems to make OEE almost unusable for managing databases.

Posted by Eddie Hoareau on 26-Oct-2017 16:00

Please stop send me email.
I have unsubscribed
 
Thanks
Eddie
 

Posted by dbeavon on 15-Jan-2019 15:18

FYI, OEE is now working well on OE version 11.7.4.  In prior versions when we were monitoring the individual user connections on an OE database, it was always causing the adminserver java process to lock up on CPU for *very* long periods of time (sometimes at 400% or higher).  That problem was related to the gathering of the table and index statistics for that user (as explained in prior messages).

But we have now upgraded from 11.6.3 to 11.7.4 and I'm finally able to click on my database user connection details in OEE, and the results come back in a few seconds...  That is a significant improvement.  Otherwise anyone using OEE for database monitoring would cause massive problems in the OE adminserver, and it was something we had to avoid doing in production!  Now we can click the user detail links without the risk of so many problems.

Thanks for fixing this.  It would be nice if Progress would consider making *all* of OEM available to customers of the OE enterprise database (perhaps in v 12?).  It is odd to be paying so much for an enterprise database without having any modern management or monitoring U/I for it.  The promon tool may have been state-of-the-art 20 years ago, but these days it leaves a lot to be desired.

Posted by dbeavon on 15-Jan-2019 15:18

FYI, OEE is now working well on OE version 11.7.4.  In prior versions when we were monitoring the individual user connections on an OE database, it was always causing the adminserver java process to lock up on CPU for *very* long periods of time (sometimes at 400% or higher).  That problem was related to the gathering of the table and index statistics for that user (as explained in prior messages).

But we have now upgraded from 11.6.3 to 11.7.4 and I'm finally able to click on my database user connection details in OEE, and the results come back in a few seconds...  That is a significant improvement.  Otherwise anyone using OEE for database monitoring would cause massive problems in the OE adminserver, and it was something we had to avoid doing in production!  Now we can click the user detail links without the risk of so many problems.

Thanks for fixing this.  It would be nice if Progress would consider making *all* of OEM available to customers of the OE enterprise database (perhaps in v 12?).  It is odd to be paying so much for an enterprise database without having any modern management or monitoring U/I for it.  The promon tool may have been state-of-the-art 20 years ago, but these days it leaves a lot to be desired.

Posted by rkumar on 15-Jan-2019 15:30

It is nice to hear that you are happy with the solution for the high CPU usage while monitoring databases from OEE.

I will forward your question regarding the licensing request to Product Management.

This thread is closed