Class: Analyzer

Inherits:
Object
  • Object
show all
Defined in:
lib/production_log/analyzer.rb

Overview

Calculates statistics for production logs.

Instance Attribute Summary collapse

Class Method Summary collapse

Instance Method Summary collapse

Constructor Details

#initialize(logfile_name) ⇒ Analyzer

Creates a new Analyzer that will read data from logfile_name.



168
169
170
171
172
173
174
 
# File 'lib/production_log/analyzer.rb', line 168

def initialize(logfile_name)
    @logfile_name  = logfile_name
    @request_times = Hash.new { |h,k| h[k] = [] }
    @db_times      = Hash.new { |h,k| h[k] = [] }
    @render_times  = Hash.new { |h,k| h[k] = [] }
#        @query_times   = Hash.new { |h,k| h[k] = [] }
end

Instance Attribute Details

#db_times ⇒ Object (readonly)

An Array of all the request database times for the log file.



127
128
129
 
# File 'lib/production_log/analyzer.rb', line 127

def db_times
  @db_times
end

#logfile_name ⇒ Object (readonly)

The logfile being read by the Analyzer.



117
118
119
 
# File 'lib/production_log/analyzer.rb', line 117

def logfile_name
  @logfile_name
end

#render_times ⇒ Object (readonly)

An Array of all the request render times for the log file.



132
133
134
 
# File 'lib/production_log/analyzer.rb', line 132

def render_times
  @render_times
end

#request_times ⇒ Object (readonly)

An Array of all the request total times for the log file.



122
123
124
 
# File 'lib/production_log/analyzer.rb', line 122

def request_times
  @request_times
end

Class Method Details

.email(file_name, recipient, subject, count = 10) ⇒ Object

Generates and sends an email report with lots of fun stuff in it. This way, Mail.app will behave when given tabs.



138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
 
# File 'lib/production_log/analyzer.rb', line 138

def self.email(file_name, recipient, subject, count = 10)
    analyzer = self.new file_name
    analyzer.process
    body = analyzer.report count

    email = self.envelope(recipient, subject)
    email << nil
    email << "<pre>#{body}</pre>"
    email = email.join($/) << $/

    return email if $TESTING

    IO.popen("/usr/sbin/sendmail -i -t", "w+") do |sm|
        sm.print email
        sm.flush
    end
end

.envelope(recipient, subject = nil) ⇒ Object

:nodoc:



156
157
158
159
160
161
162
163
 
# File 'lib/production_log/analyzer.rb', line 156

def self.envelope(recipient, subject = nil) # :nodoc:
    envelope = {}
    envelope['To'] = recipient
    envelope['Subject'] = subject || "pl_analyze"
    envelope['Content-Type'] = "text/html"

    return envelope.map { |(k,v)| "#{k}: #{v}" }
end

Instance Method Details

#average_db_time ⇒ Object

The average total database time for all requests.



214
215
216
 
# File 'lib/production_log/analyzer.rb', line 214

def average_db_time
    return time_average(@db_times)
end

#average_render_time ⇒ Object

The average total render time for all requests.



235
236
237
 
# File 'lib/production_log/analyzer.rb', line 235

def average_render_time
    return time_average(@render_times)
end

#average_request_time ⇒ Object

The average total request time for all requests.



193
194
195
 
# File 'lib/production_log/analyzer.rb', line 193

def average_request_time
    return time_average(@request_times)
end

#db_time_std_dev ⇒ Object

The standard deviation of the total database time for all requests.



221
222
223
 
# File 'lib/production_log/analyzer.rb', line 221

def db_time_std_dev
    return time_std_dev(@db_times)
end

#db_times_summary ⇒ Object

A list of count/min/max/avg/std dev for database times.



263
264
265
 
# File 'lib/production_log/analyzer.rb', line 263

def db_times_summary
    return summarize("DB Times", @db_times)
end

#longest_request_name ⇒ Object

:nodoc:



367
368
369
370
371
372
373
374
375
376
377
 
# File 'lib/production_log/analyzer.rb', line 367

def longest_request_name # :nodoc:
    return @longest_req if defined? @longest_req

    names = @request_times.keys.map do |name|
        (name||'Unknown').length + 1 # + : - HACK where does nil come from?
    end

    @longest_req = names.max

    return @longest_req
end

#pad_request_name(name) ⇒ Object

:nodoc:



379
380
381
382
 
# File 'lib/production_log/analyzer.rb', line 379

def pad_request_name(name) # :nodoc:
    name = (name||'Unknown') + ':' # HACK where does nil come from?
    name += (' ' * (longest_request_name - name.length))
end

#process ⇒ Object

Processes the log file collecting statistics from each found LogEntry.



179
180
181
182
183
184
185
186
187
188
 
# File 'lib/production_log/analyzer.rb', line 179

def process
    File.open @logfile_name do |fp|
        LogParser.parse fp do |entry|
            entry_page = entry.page
            @request_times[entry_page] << entry.request_time
            @db_times[entry_page] << entry.db_time
            @render_times[entry_page] << entry.render_time
        end
    end
end

#render_time_std_dev ⇒ Object

The standard deviation of the total render time for all requests.



242
243
244
 
# File 'lib/production_log/analyzer.rb', line 242

def render_time_std_dev
    return time_std_dev(@render_times)
end

#render_times_summary ⇒ Object

A list of count/min/max/avg/std dev for request times.



270
271
272
 
# File 'lib/production_log/analyzer.rb', line 270

def render_times_summary
    return summarize("Render Times", @render_times)
end

#report(count) ⇒ Object

Builds a report containing count slow items.



277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
297
298
299
300
301
302
303
304
305
306
307
308
309
 
# File 'lib/production_log/analyzer.rb', line 277

def report(count)
    text = []

    text << request_times_summary
    text << nil
    text << "Slowest Request Times:"
    slowest_request_times(count).each do |time, name|
        text << "\t#{name} took #{'%0.3f' % time}s"
    end
    text << nil
    text << "-" * 72
    text << nil

    text << db_times_summary
    text << nil
    text << "Slowest Total DB Times:"
    slowest_db_times(count).each do |time, name|
        text << "\t#{name} took #{'%0.3f' % time}s"
    end
    text << nil
    text << "-" * 72
    text << nil

    text << render_times_summary
    text << nil
    text << "Slowest Total Render Times:"
    slowest_render_times(count).each do |time, name|
        text << "\t#{name} took #{'%0.3f' % time}s"
    end
    text << nil

    return text.join($/)
end

#request_time_std_dev ⇒ Object

The standard deviation of the total request time for all requests.



200
201
202
 
# File 'lib/production_log/analyzer.rb', line 200

def request_time_std_dev
    return time_std_dev(@request_times)
end

#request_times_summary ⇒ Object

A list of count/min/max/avg/std dev for request times.



256
257
258
 
# File 'lib/production_log/analyzer.rb', line 256

def request_times_summary
    return summarize("Request Times", @request_times)
end

#slowest_db_times(limit = 10) ⇒ Object

The limit slowest total database times.



228
229
230
 
# File 'lib/production_log/analyzer.rb', line 228

def slowest_db_times(limit = 10)
    return slowest_times(@db_times, limit)
end

#slowest_render_times(limit = 10) ⇒ Object

The limit slowest total render times for all requests.



249
250
251
 
# File 'lib/production_log/analyzer.rb', line 249

def slowest_render_times(limit = 10)
    return slowest_times(@render_times, limit)
end

#slowest_request_times(limit = 10) ⇒ Object

The limit slowest total request times.



207
208
209
 
# File 'lib/production_log/analyzer.rb', line 207

def slowest_request_times(limit = 10)
    return slowest_times(@request_times, limit)
end

#slowest_times(records, limit) ⇒ Object

:nodoc:



343
344
345
346
347
348
349
350
351
352
353
 
# File 'lib/production_log/analyzer.rb', line 343

def slowest_times(records, limit) # :nodoc:
    slowest_times = SlowestTimes.new limit

    records.each do |name, times|
        times.each do |time|
            slowest_times << [time, name]
        end
    end

    return slowest_times.sort_by { |time, name| time }.reverse
end

#summarize(title, records) ⇒ Object

:nodoc:



313
314
315
316
317
318
319
320
321
322
323
324
325
326
327
328
329
330
331
332
333
334
335
336
337
338
339
340
341
 
# File 'lib/production_log/analyzer.rb', line 313

def summarize(title, records) # :nodoc:
    record = nil
    list = []

    # header
    record = [pad_request_name("#{title} Summary"), 'Count', 'Avg',
              'Std Dev', 'Min', 'Max']
    list << record.join("\t")

    # all requests
    times = records.values.flatten
    record = [times.average, times.standard_deviation, times.min, times.max]
    record.map! { |v| "%0.3f" % v }
    record.unshift [pad_request_name('ALL REQUESTS'), times.size]
    list << record.join("\t")

    # spacer
    list << nil

    records.sort_by { |k,v| v.size}.reverse_each do |req, times|
        record = [times.average, times.standard_deviation,
                  times.min, times.max]
        record.map! { |v| "%0.3f" % v }
        record.unshift ["#{pad_request_name req}", times.size]
        list << record.join("\t")
    end

    return list.join("\n")
end

#time_average(records) ⇒ Object

:nodoc:



355
356
357
358
359
 
# File 'lib/production_log/analyzer.rb', line 355

def time_average(records) # :nodoc:
    times = records.values.flatten
    times.delete 0
    return times.average
end

#time_std_dev(records) ⇒ Object

:nodoc:



361
362
363
364
365
 
# File 'lib/production_log/analyzer.rb', line 361

def time_std_dev(records) # :nodoc:
    times = records.values.flatten
    times.delete 0
    return times.standard_deviation
end