Change pass name printing to info. Print pass runtime.

Change default print level to info.

2 files changed, 8 insertions, 4 deletions

diff --git a/src/main/resources/logback.xml b/src/main/resources/logback.xml
index d2f8beae..8c9304c2 100644
--- a/src/main/resources/logback.xml
+++ b/src/main/resources/logback.xml
@@ -30,7 +30,7 @@ MODIFICATIONS.
             <pattern>[%-4level] %msg%n</pattern>
         </encoder>
     </appender>
-    <root level="warn">
+    <root level="info">
         <appender-ref ref="STDOUT" />
     </root>
 </configuration>
diff --git a/src/main/scala/firrtl/passes/Passes.scala b/src/main/scala/firrtl/passes/Passes.scala
index 1d7aabcd..6829d48c 100644
--- a/src/main/scala/firrtl/passes/Passes.scala
+++ b/src/main/scala/firrtl/passes/Passes.scala
@@ -82,14 +82,18 @@ object PassUtils extends LazyLogging {
   lazy val mapNameToPass: Map[String, Pass] = listOfPasses.map(p => p.name -> p).toMap
 
   def executePasses(c: Circuit, passes: Seq[Pass]): Circuit = { 
-    if (passes.isEmpty) {logger.debug(s"Done!"); c}
+    if (passes.isEmpty) {logger.info(s"Done!"); c}
     else {
        val p = passes.head
        val name = p.name
-       logger.debug(s"Starting ${name}")
+       logger.info(s"Starting ${name}")
+       val start = System.nanoTime
        val x = p.run(c)
+       val end = System.nanoTime
        logger.debug(x.serialize)
-       logger.debug(s"Finished ${name}")
+       logger.info(s"Finished ${name}")
+       val timeMillis = (end - start) / 1000000.0
+       logger.info(f"$name took $timeMillis%.1f ms\n")
        executePasses(x, passes.tail)
     }
   }