Confused with Time showing on the report

Mar 10, 2010 at 5:12 PM

I ran a system performance monitor for a week from 3/1/10 to 3/8/10.  I analyzed the log file with PAL tool. Version 1.3.6.1,  I used Auto for the time interval.  The report generated a lot good information for me; however, I am a little confused with time showing on the report.  The time interval is showing from 3/8/10 11:38 AM to 3/8/10 12:13 PM.  This is the time interval when report is running,  It is not actual time of events are occuring on the server, because, the log was generated before the time indicated on reports.  Can someone explain this too me ?  thanks

Time            
3/8/2010 11:38:42 AM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
3/8/2010 11:39:51 AM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,142 5,143 5,145 52.1739130434783
3/8/2010 11:41:00 AM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(java)\Handle Count 33,876 33,876 33,876 52.1739130434783
3/8/2010 11:42:09 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.006 0.04 0.068
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
  Aggressive Handle Leak Suspected - more than 10,000 handles and a trend of more than 100 handles per hour \\SOPWKFORCE\Process(java)\Handle Count 33,878 33,883 33,884 151.552795031002
3/8/2010 11:43:18 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.003 0.036 0.011
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(java)\Handle Count 33,878 33,878 33,878 52.1739130434783
3/8/2010 11:44:27 AM Condition Counter Min Avg Max Hourly Trend
  Average disk responsiveness is slow - more than 15ms \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.011 0.041 0.052
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.012 0.044 0.057
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
3/8/2010 11:45:36 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.005 0.034 -0.013
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.005 0.034 -0.011
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
3/8/2010 11:46:45 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.009 0.069 0.022
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.009 0.069 0.024
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
  Memory: an increasing trend of 5MB's per hour detected \\SOPWKFORCE\Process(java)\Private Bytes 22,625,185,792 22,626,319,799 22,636,863,488 8,452,225.02218685
3/8/2010 11:47:54 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.007 0.042 0.006
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.007 0.042 0.008
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
3/8/2010 11:49:03 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.004 0.031 -0.015
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.004 0.031 -0.014
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
3/8/2010 11:50:12 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.01 0.028 0.019
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.011 0.028 0.023
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
3/8/2010 11:51:21 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.008 0.037 0.007
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.008 0.037 0.008
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
3/8/2010 11:52:30 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.008 0.042 0.026
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,735 12,735 12,735 0
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(java)\Handle Count 33,867 33,892 33,894 77.9503105590107
3/8/2010 11:53:39 AM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,734 12,735 -2
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(java)\Handle Count 33,891 33,891 33,894 69.9474438604915
3/8/2010 11:54:48 AM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -7
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(java)\Handle Count 33,889 33,890 33,891 60.1597160603294
3/8/2010 11:55:57 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.005 0.035 -0.003
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.006 0.035 -0.002
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -7
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,151 5,162 5,177 67.959866220737
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(java)\Handle Count 33,889 33,893 33,897 64.4816053511686
  More then 10 page file reads per second \\SOPWKFORCE\Memory\Pages Input/sec 0 2 26 6
3/8/2010 11:57:06 AM Condition Counter Min Avg Max Hourly Trend
  Significant Spike in Processor Usage - spike of more than 80% CPU utilization \\SOPWKFORCE\Process(java)\% Processor Time 0 27 108 89
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.005 0.051 0.01
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -7
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,172 5,173 5,177 102.48447204969
3/8/2010 11:58:15 AM Condition Counter Min Avg Max Hourly Trend
  Significant Spike in Processor Usage - spike of more than 80% CPU utilization \\SOPWKFORCE\Process(java)\% Processor Time 0 20 99 61
  Average disk responsiveness is slow - more than 15ms \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.012 0.064 0.016
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.008 0.044 0.006
  Average disk responsiveness is very slow - more than 25ms \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.034 0.441 0.099
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -6
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,170 5,172 5,174 91.6331750091349
3/8/2010 11:59:24 AM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.007 0.031 0.003
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.005 0.038 -0.004
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0.001 0.011 0.048 0.026
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -6
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,169 5,170 5,170 80.9523809523813
3/8/2010 12:00:33 PM Condition Counter Min Avg Max Hourly Trend
  Significant Spike in Processor Usage - spike of more than 80% CPU utilization \\SOPWKFORCE\Process(java)\% Processor Time 0 17 100 47
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.008 0.049 0.017
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -5
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,162 5,168 5,172 72.1804511278206
  More then 10 page file reads per second \\SOPWKFORCE\Memory\Pages Input/sec 0 2 32 5
3/8/2010 12:01:42 PM Condition Counter Min Avg Max Hourly Trend
  Significant Spike in Processor Usage - spike of more than 80% CPU utilization \\SOPWKFORCE\Process(java)\% Processor Time 0 50 110 131
  Average disk responsiveness is slow - more than 15ms \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.002 0.011 0.022 0.011
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0.001 0.013 0.036 0.029
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -5
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,161 5,162 5,162 51.5719063545143
3/8/2010 12:02:51 PM Condition Counter Min Avg Max Hourly Trend
  Significant Spike in Processor Usage - spike of more than 80% CPU utilization \\SOPWKFORCE\Process(java)\% Processor Time 0 22 103 55
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.008 0.085 0.014
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -5
  Memory: an increasing trend of 5MB's per hour detected \\SOPWKFORCE\Process(java)\Private Bytes 22,624,952,320 22,627,747,840 22,634,483,712 6,365,336.64596273
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,161 5,163 5,164 51.4640638864232
3/8/2010 12:04:00 PM Condition Counter Min Avg Max Hourly Trend
  Significant Spike in Processor Usage - spike of more than 80% CPU utilization \\SOPWKFORCE\Process(java)\% Processor Time 0 20 91 47
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.006 0.038 0
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.004 0.027 -0.004
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0.001 0.009 0.086 0.016
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -5
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,161 5,164 5,165 52.0045172219088
3/8/2010 12:05:09 PM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.008 0.029 0.004
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.007 0.034 0.002
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.009 0.05 0.015
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -5
3/8/2010 12:06:18 PM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -4
  More then 10 page file reads per second \\SOPWKFORCE\Memory\Pages Input/sec 0 2 20 3
3/8/2010 12:07:27 PM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -4
3/8/2010 12:08:36 PM Condition Counter Min Avg Max Hourly Trend
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -4
3/8/2010 12:09:45 PM Condition Counter Min Avg Max Hourly Trend
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.006 0.041 -0.001
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.006 0.041 -0.001
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -4
3/8/2010 12:10:54 PM Condition Counter Min Avg Max Hourly Trend
  Spike of more than 30% CPU in Privileged Mode CPU - This may indicate a spike of high I/O \\SOPWKFORCE\Processor(11)\% Privileged Time 0 8 65 15
  Significant Spike in Processor Usage - spike of more than 80% CPU utilization \\SOPWKFORCE\Process(java)\% Processor Time 0 23 104 43
  Average disk responsiveness is slow - more than 15ms \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Write 0.001 0.011 0.055 0.01
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Write 0.001 0.008 0.036 0.004
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.015 0.078 0.025
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -4
  More then 10 page file reads per second \\SOPWKFORCE\Memory\Pages Input/sec 0 77 622 142
3/8/2010 12:12:03 PM Condition Counter Min Avg Max Hourly Trend
  More than 30% CPU in Privileged Mode CPU - This may indicate a high I/O issue \\SOPWKFORCE\Processor(11)\% Privileged Time 39 57 68 103
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\PhysicalDisk(1 C: D:)\Avg. Disk sec/Read 0 0.009 0.041 0.016
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(C:)\Avg. Disk sec/Read 0 0.009 0.041 0.016
  Disk responsiveness is very slow (spike of more than 25ms) \\SOPWKFORCE\LogicalDisk(D:)\Avg. Disk sec/Write 0 0.003 0.036 0.002
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -4
  More then 10 page file reads per second \\SOPWKFORCE\Memory\Pages Input/sec 0 33 342 59
3/8/2010 12:13:12 PM Condition Counter Min Avg Max Hourly Trend
  Spike of more than 30% CPU in Privileged Mode CPU - This may indicate a spike of high I/O \\SOPWKFORCE\Processor(11)\% Privileged Time 0 41 61 71
  Not enough disk space on C: drive for a kernel dump (blue screen) \\SOPWKFORCE\LogicalDisk(C:)\Free Megabytes 12,733 12,733 12,733 -3
  Handle Leak Suspected - more than 5000 handles and a trend of more than 50 handles per hour \\SOPWKFORCE\Process(csrss)\Handle Count 5,173 5,174 5,174 55.4589371980673
  More then 10 page file reads per second \\SOPWKFORCE\Memory\Pages Input/sec 0 6 17 9
Coordinator
Mar 11, 2010 at 9:31 AM

This can happen sometimes if the log was captured in a Binary Circular log file format. When in a circular format, the data is written to a buffer and that buffer is continuously overwritten with the latest data. Other than that, it should have worked or there is data missing or corrupted in the log. Try opening the log in Perfmon and see if you can see these counters and their respective data all from March 1st.