From 503fc4883cf8e6513eb280432c21a78f407a8b86 Mon Sep 17 00:00:00 2001
From: Dmitriy Dolovov <Dmitriy.Dolovov@jetbrains.com>
Date: Wed, 11 Dec 2019 11:30:35 +0300
Subject: [PATCH] [Commonizer] Log duration of each CLI phase

---
 .../kotlin/descriptors/commonizer/cli.kt        | 17 +++++++++++++++++
 1 file changed, 17 insertions(+)

diff --git a/konan/commonizer/src/org/jetbrains/kotlin/descriptors/commonizer/cli.kt b/konan/commonizer/src/org/jetbrains/kotlin/descriptors/commonizer/cli.kt
index 95658eaed92..5982a4707f8 100644
--- a/konan/commonizer/src/org/jetbrains/kotlin/descriptors/commonizer/cli.kt
+++ b/konan/commonizer/src/org/jetbrains/kotlin/descriptors/commonizer/cli.kt
@@ -28,8 +28,11 @@ import org.jetbrains.kotlin.serialization.konan.impl.KlibResolvedModuleDescripto
 import org.jetbrains.kotlin.storage.LockBasedStorageManager
 import java.io.File
 import kotlin.system.exitProcess
+import kotlin.time.ExperimentalTime
+import kotlin.time.MonoClock
 import org.jetbrains.kotlin.konan.file.File as KFile
 
+@ExperimentalTime
 fun main(args: Array<String>) {
     if (args.isEmpty()) printUsageAndExit()
 
@@ -52,9 +55,23 @@ fun main(args: Array<String>) {
         destination.walkTopDown().any { it != destination } -> printErrorAndExit("output is not empty: $destination")
     }
 
+    val startMark = MonoClock.markNow()
     val modulesByTargets = loadModules(repository, targets)
+    val loadDuration = startMark.elapsedNow()
+
+    println("Loaded lazy (uninitialized) libraries in $loadDuration")
+
     val result = commonize(modulesByTargets)
+    val commonizationDuration = startMark.elapsedNow() - loadDuration
+
+    println("Commonization performed in $commonizationDuration")
+
     saveModules(modulesByTargets, destination, result)
+    val totalDuration = startMark.elapsedNow()
+    val writeDuration = totalDuration - commonizationDuration - loadDuration
+
+    println("Written libraries in $writeDuration")
+    println("TOTAL: $totalDuration")
 
     println("Done.")
     println()