ll_prof: Output tick percentage by symbol/library.

tools/ll_prof.py

 #!/usr/bin/env python
 #
 # Copyright 2010 the V8 project authors. All rights reserved.
 # Redistribution and use in source and binary forms, with or without
 # modification, are permitted provided that the following conditions are
 # met:
 #
 #     * Redistributions of source code must retain the above copyright
 #       notice, this list of conditions and the following disclaimer.
 #     * Redistributions in binary form must reproduce the above
@@ skipping 762 matching lines @@
         start_address = int(match.group(1), 16)
         end_address = start_address
         name = match.group(2)
         if code:
           code.end_address = start_address
           code_map.Add(code, 16)
         code = Code(name, start_address, end_address, "kernel", 0)
     return True
 
 
-def PrintReport(code_map, library_repo, arch, options):
+def PrintReport(code_map, library_repo, arch, ticks, options):
   print "Ticks per symbol:"
   used_code = [code for code in code_map.UsedCode()]
   used_code.sort(key=lambda x: x.self_ticks, reverse=True)
   for i, code in enumerate(used_code):
-    print "%10d %s [%s]" % (code.self_ticks, code.FullName(), code.origin)
+    code_ticks = code.self_ticks
+    print "%10d %5.1f%% %s [%s]" % (code_ticks, 100. * code_ticks / ticks,
+                                    code.FullName(), code.origin)
     if options.disasm_all or i < options.disasm_top:
       code.PrintAnnotated(arch, options)
   print
   print "Ticks per library:"
   mmap_infos = [m for m in library_repo.infos]
   mmap_infos.sort(key=lambda m: m.ticks, reverse=True)
   for mmap_info in mmap_infos:
-    print "%10d %s" % (mmap_info.ticks, mmap_info.unique_name)
+    mmap_ticks = mmap_info.ticks
+    print "%10d %5.1f%% %s" % (mmap_ticks, 100. * mmap_ticks / ticks,
+                               mmap_info.unique_name)
 
 
 def PrintDot(code_map, options):
   print "digraph G {"
   for code in code_map.UsedCode():
     if code.self_ticks < 10:
       continue
     print "n%d [shape=box,label=\"%s\"];" % (code.id, code.name)
     if code.callee_ticks:
       for callee, ticks in code.callee_ticks.iteritems():
@@ skipping 64 matching lines @@
     snapshot_name_map = snapshot_log_reader.ReadNameMap()
 
   # Initialize the log reader.
   code_map = CodeMap()
   log_reader = LogReader(log_name=options.log + ".ll",
                          code_map=code_map,
                          snapshot_pos_to_name=snapshot_name_map)
   if not options.quiet:
     print "Generated code architecture: %s" % log_reader.arch
     print
+    sys.stdout.flush()
 
   # Process the code and trace logs.
   library_repo = LibraryRepo()
   log_reader.ReadUpToGC()
   trace_reader = TraceReader(options.trace)
   while True:
     header, offset = trace_reader.ReadEventHeader()
     if not header:
       break
     events += 1
@@ skipping 21 matching lines @@
           caller_code = code_map.Find(ip)
           if caller_code:
             if code:
               caller_code.CalleeTick(code)
             code = caller_code
       sample_time += time.time() - start
 
   if options.dot:
     PrintDot(code_map, options)
   else:
-    PrintReport(code_map, library_repo, log_reader.arch, options)
+    PrintReport(code_map, library_repo, log_reader.arch, ticks, options)
 
     if not options.quiet:
       print
       print "Stats:"
       print "%10d total trace events" % events
       print "%10d total ticks" % ticks
       print "%10d ticks not in symbols" % missed_ticks
       print "%10d unaccounted ticks" % really_missed_ticks
       print "%10d total symbols" % len([c for c in code_map.AllCode()])
       print "%10d used symbols" % len([c for c in code_map.UsedCode()])
       print "%9.2fs library processing time" % mmap_time
       print "%9.2fs tick processing time" % sample_time
 
   log_reader.Dispose()
   trace_reader.Dispose()