src/Pure/General/timing.scala
author wenzelm
Fri, 28 Apr 2017 17:17:23 +0200
changeset 65613 cfcafe9824d1
parent 65597 b408ca224954
child 65617 823bbc467dfa
permissions -rw-r--r--
clarified database layout;
Ignore whitespace changes - Everywhere: Within whitespace: At end of lines:
40393
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     1
/*  Title:      Pure/General/timing.scala
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     2
    Author:     Makarius
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     3
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     4
Basic support for time measurement.
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     5
*/
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     6
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     7
package isabelle
2bb7ec08574a somewhat more uniform timing in ML vs. Scala;
wenzelm
parents:
diff changeset
     8
45664
ac6e704dcd12 tuned signature (according to ML version);
wenzelm
parents: 45249
diff changeset
     9
62571
2fd90993a928 print timing like lib/scripts/timestop.bash;
wenzelm
parents: 57912
diff changeset
    10
import java.util.Locale
2fd90993a928 print timing like lib/scripts/timestop.bash;
wenzelm
parents: 57912
diff changeset
    11
2fd90993a928 print timing like lib/scripts/timestop.bash;
wenzelm
parents: 57912
diff changeset
    12
45664
ac6e704dcd12 tuned signature (according to ML version);
wenzelm
parents: 45249
diff changeset
    13
object Timing
ac6e704dcd12 tuned signature (according to ML version);
wenzelm
parents: 45249
diff changeset
    14
{
51587
7050c4656fd8 more operations on Time, Timing;
wenzelm
parents: 47653
diff changeset
    15
  val zero = Timing(Time.zero, Time.zero, Time.zero)
7050c4656fd8 more operations on Time, Timing;
wenzelm
parents: 47653
diff changeset
    16
47653
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    17
  def timeit[A](message: String, enabled: Boolean = true)(e: => A) =
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    18
    if (enabled) {
56691
ad5d7461b370 tuned signature, in accordance to ML version;
wenzelm
parents: 55618
diff changeset
    19
      val start = Time.now()
47653
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    20
      val result = Exn.capture(e)
56691
ad5d7461b370 tuned signature, in accordance to ML version;
wenzelm
parents: 55618
diff changeset
    21
      val stop = Time.now()
46768
46acd255810d relevant timing as in ML;
wenzelm
parents: 45674
diff changeset
    22
56691
ad5d7461b370 tuned signature, in accordance to ML version;
wenzelm
parents: 55618
diff changeset
    23
      val timing = stop - start
47653
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    24
      if (timing.is_relevant)
56782
433cf57550fa more systematic Isabelle output, like in classic Isabelle/ML (without markup);
wenzelm
parents: 56691
diff changeset
    25
        Output.warning(
47653
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    26
          (if (message == null || message.isEmpty) "" else message + ": ") +
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    27
            timing.message + " elapsed time")
46768
46acd255810d relevant timing as in ML;
wenzelm
parents: 45674
diff changeset
    28
47653
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    29
      Exn.release(result)
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    30
    }
4605d4341b8b some builtin session timing;
wenzelm
parents: 46768
diff changeset
    31
    else e
65597
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    32
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    33
  val encode: XML.Encode.T[Timing] = (t: Timing) =>
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    34
  {
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    35
    import XML.Encode._
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    36
    triple(Time.encode, Time.encode, Time.encode)(t.elapsed, t.cpu, t.gc)
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    37
  }
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    38
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    39
  val decode: XML.Decode.T[Timing] = (body: XML.Body) =>
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    40
  {
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    41
    import XML.Decode._
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    42
    val (elapsed, cpu, gc) = triple(Time.decode, Time.decode, Time.decode)(body)
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    43
    Timing(elapsed, cpu, gc)
b408ca224954 more encode/decode operations;
wenzelm
parents: 64089
diff changeset
    44
  }
65613
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    45
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    46
  def write(t: Timing): Bytes =
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    47
    if (t.is_zero) Bytes.empty
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    48
    else Bytes(YXML.string_of_body(encode(t)))
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    49
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    50
  def read(bs: Bytes): Timing =
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    51
    if (bs.isEmpty) zero
cfcafe9824d1 clarified database layout;
wenzelm
parents: 65597
diff changeset
    52
    else decode(YXML.parse_body(bs.text))
45664
ac6e704dcd12 tuned signature (according to ML version);
wenzelm
parents: 45249
diff changeset
    53
}
ac6e704dcd12 tuned signature (according to ML version);
wenzelm
parents: 45249
diff changeset
    54
51587
7050c4656fd8 more operations on Time, Timing;
wenzelm
parents: 47653
diff changeset
    55
sealed case class Timing(elapsed: Time, cpu: Time, gc: Time)
40848
8662b9b1f123 more abstract/uniform handling of time, preferring seconds as Double;
wenzelm
parents: 40393
diff changeset
    56
{
64089
10d719dbb3ee more permissive timing data;
wenzelm
parents: 64084
diff changeset
    57
  def is_zero: Boolean = elapsed.is_zero && cpu.is_zero && gc.is_zero
46768
46acd255810d relevant timing as in ML;
wenzelm
parents: 45674
diff changeset
    58
  def is_relevant: Boolean = elapsed.is_relevant || cpu.is_relevant || gc.is_relevant
46acd255810d relevant timing as in ML;
wenzelm
parents: 45674
diff changeset
    59
64084
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    60
  def resources: Time = cpu + gc
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    61
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    62
  def factor: Option[Double] =
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    63
  {
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    64
    val t1 = elapsed.seconds
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    65
    val t2 = resources.seconds
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    66
    if (t1 >= 3.0 && t2 >= 3.0) Some(t2 / t1) else None
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    67
  }
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    68
51587
7050c4656fd8 more operations on Time, Timing;
wenzelm
parents: 47653
diff changeset
    69
  def + (t: Timing): Timing = Timing(elapsed + t.elapsed, cpu + t.cpu, gc + t.gc)
7050c4656fd8 more operations on Time, Timing;
wenzelm
parents: 47653
diff changeset
    70
62587
e31bf8ed5397 clarified messages, notably on Windows where CPU time of poly.exe is not measured;
wenzelm
parents: 62571
diff changeset
    71
  def message: String =
e31bf8ed5397 clarified messages, notably on Windows where CPU time of poly.exe is not measured;
wenzelm
parents: 62571
diff changeset
    72
    elapsed.message + " elapsed time, " + cpu.message + " cpu time, " + gc.message + " GC time"
e31bf8ed5397 clarified messages, notably on Windows where CPU time of poly.exe is not measured;
wenzelm
parents: 62571
diff changeset
    73
62571
2fd90993a928 print timing like lib/scripts/timestop.bash;
wenzelm
parents: 57912
diff changeset
    74
  def message_resources: String =
2fd90993a928 print timing like lib/scripts/timestop.bash;
wenzelm
parents: 57912
diff changeset
    75
  {
64084
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    76
    val factor_text =
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    77
      factor match {
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    78
        case Some(f) => String.format(Locale.ROOT, ", factor %.2f", new java.lang.Double(f))
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    79
        case None => ""
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    80
      }
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    81
    if (resources.seconds >= 3.0)
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    82
      elapsed.message_hms + " elapsed time, " + resources.message_hms + " cpu time" + factor_text
62587
e31bf8ed5397 clarified messages, notably on Windows where CPU time of poly.exe is not measured;
wenzelm
parents: 62571
diff changeset
    83
    else
64084
bca58a11efde more operations;
wenzelm
parents: 62587
diff changeset
    84
      elapsed.message_hms + " elapsed time" + factor_text
62571
2fd90993a928 print timing like lib/scripts/timestop.bash;
wenzelm
parents: 57912
diff changeset
    85
  }
2fd90993a928 print timing like lib/scripts/timestop.bash;
wenzelm
parents: 57912
diff changeset
    86
57912
wenzelm
parents: 56782
diff changeset
    87
  override def toString: String = message
40848
8662b9b1f123 more abstract/uniform handling of time, preferring seconds as Double;
wenzelm
parents: 40393
diff changeset
    88
}