feat: implement profile, profileEnd and timeStamp console methods wit…

[sajdakabir]

#180

[github-actions]

Performance Comparison →

Total Elapsed Time: 45.86ms → 47.18ms (+2.9%)

Profiling Mode: timing - Execution duration of functions.

+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| Function                            | Calls                | Avg                              | P50                              | P95                              | P99                             | Total                            | % Total                      |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| andromeda::main                     | 1 → 1 (+0.0%)        | 45.19ms → 46.50ms (+2.9%)        | 45.22ms → 46.53ms (+2.9%)        | 45.22ms → 46.53ms (+2.9%)        | 45.22ms → 46.53ms (+2.9%)       | 45.19ms → 46.50ms (+2.9%)        | 100.00% → 100.00% (+0.0%)    |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| andromeda::run_main                 | 1 → 1 (+0.0%)        | 45.17ms → 46.48ms (+2.9%)        | 45.19ms → 46.50ms (+2.9%)        | 45.19ms → 46.50ms (+2.9%)        | 45.19ms → 46.50ms (+2.9%)       | 45.17ms → 46.48ms (+2.9%)        | 99.95% → 99.95% (+0.0%)      |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| run::run                            | 1 → 1 (+0.0%)        | 44.59ms → 45.91ms (+3.0%)        | 44.60ms → 45.94ms (+3.0%)        | 44.60ms → 45.94ms (+3.0%)        | 44.60ms → 45.94ms (+3.0%)       | 44.59ms → 45.91ms (+3.0%)        | 98.66% → 98.72% (+0.1%)      |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| run::create_runtime_files           | 1 → 1 (+0.0%)        | 44.59ms → 45.91ms (+3.0%)        | 44.60ms → 45.91ms (+2.9%)        | 44.60ms → 45.91ms (+2.9%)        | 44.60ms → 45.91ms (+2.9%)       | 44.59ms → 45.91ms (+3.0%)        | 98.65% → 98.71% (+0.1%)      |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| runtime::run                        | 1 → 1 (+0.0%)        | 23.64ms → 25.13ms (+6.3%)        | 23.64ms → 25.13ms (+6.3%)        | 23.64ms → 25.13ms (+6.3%)        | 23.64ms → 25.13ms (+6.3%)       | 23.64ms → 25.13ms (+6.3%)        | 52.31% → 54.03% (+3.3%)      |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| runtime::new                        | 1 → 1 (+0.0%)        | 19.43ms → 19.00ms (-2.3%)        | 19.45ms → 19.01ms (-2.3%)        | 19.45ms → 19.01ms (-2.3%)        | 19.45ms → 19.01ms (-2.3%)       | 19.43ms → 19.00ms (-2.3%)        | 42.99% → 40.84% (-5.0%)      |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| extension::load                     | 22 → 22 (+0.0%)      | 851.66µs → 829.94µs (-2.6%)      | 302.34µs → 302.59µs (+0.1%)      | 3.11ms → 2.97ms (-4.6%)          | 5.67ms → 5.69ms (+0.3%)         | 18.74ms → 18.26ms (-2.6%)        | 41.45% → 39.26% (-5.3%)      |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| console::internal_print             | 180 → 180 (+0.0%)    | 3.68µs → 4.51µs (+22.8%) ⚠️      | 3.60µs → 3.90µs (+8.3%)          | 4.82µs → 7.42µs (+53.7%) ⚠️      | 15.26µs → 14.54µs (-4.7%)       | 661.59µs → 812.44µs (+22.8%) ⚠️  | 1.46% → 1.74% (+19.2%)       |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| recommended::recommended_extensions | 1 → 1 (+0.0%)        | 52.21µs → 59.19µs (+13.4%)       | 52.22µs → 59.20µs (+13.4%)       | 52.22µs → 59.20µs (+13.4%)       | 52.22µs → 59.20µs (+13.4%)      | 52.21µs → 59.19µs (+13.4%)       | 0.11% → 0.12% (+9.1%)        |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+
| console::get_group_indent           | 180 → 180 (+0.0%)    | 107.00ns → 149.00ns (+39.3%) ⚠️  | 100.00ns → 140.00ns (+40.0%) ⚠️  | 240.00ns → 301.00ns (+25.4%) ⚠️  | 481.00ns → 551.00ns (+14.6%)    | 19.31µs → 26.98µs (+39.7%) ⚠️    | 0.04% → 0.05% (+25.0%) ⚠️    |
+-------------------------------------+----------------------+----------------------------------+----------------------------------+----------------------------------+---------------------------------+----------------------------------+------------------------------+

---

Generated with hotpath

📊 View Raw JSON Metrics

PR Metrics

{
  "hotpath_profiling_mode": "timing",
  "total_elapsed": 47182027,
  "description": "Execution duration of functions.",
  "caller_name": "andromeda::main",
  "output": {
    "runtime::new": {
      "calls": 1,
      "avg": 18995488,
      "p50": 19005439,
      "p95": 19005439,
      "p99": 19005439,
      "total": 18995488,
      "percent_total": 4084
    },
    "console::get_group_indent": {
      "calls": 180,
      "avg": 149,
      "p50": 140,
      "p95": 301,
      "p99": 551,
      "total": 26983,
      "percent_total": 5
    },
    "andromeda::main": {
      "calls": 1,
      "avg": 46501441,
      "p50": 46530559,
      "p95": 46530559,
      "p99": 46530559,
      "total": 46501441,
      "percent_total": 10000
    },
    "extension::load": {
      "calls": 22,
      "avg": 829936,
      "p50": 302591,
      "p95": 2967551,
      "p99": 5689343,
      "total": 18258609,
      "percent_total": 3926
    },
    "run::create_runtime_files": {
      "calls": 1,
      "avg": 45905415,
      "p50": 45907967,
      "p95": 45907967,
      "p99": 45907967,
      "total": 45905415,
      "percent_total": 9871
    },
    "run::run": {
      "calls": 1,
      "avg": 45910605,
      "p50": 45940735,
      "p95": 45940735,
      "p99": 45940735,
      "total": 45910605,
      "percent_total": 9872
    },
    "andromeda::run_main": {
      "calls": 1,
      "avg": 46478918,
      "p50": 46497791,
      "p95": 46497791,
      "p99": 46497791,
      "total": 46478918,
      "percent_total": 9995
    },
    "console::internal_print": {
      "calls": 180,
      "avg": 4513,
      "p50": 3897,
      "p95": 7415,
      "p99": 14543,
      "total": 812442,
      "percent_total": 174
    },
    "runtime::run": {
      "calls": 1,
      "avg": 25126925,
      "p50": 25133055,
      "p95": 25133055,
      "p99": 25133055,
      "total": 25126925,
      "percent_total": 5403
    },
    "recommended::recommended_extensions": {
      "calls": 1,
      "avg": 59191,
      "p50": 59199,
      "p95": 59199,
      "p99": 59199,
      "total": 59191,
      "percent_total": 12
    }
  }
}

Main Branch Metrics

{
  "hotpath_profiling_mode": "timing",
  "total_elapsed": 45861628,
  "description": "Execution duration of functions.",
  "caller_name": "andromeda::main",
  "output": {
    "recommended::recommended_extensions": {
      "calls": 1,
      "avg": 52207,
      "p50": 52223,
      "p95": 52223,
      "p99": 52223,
      "total": 52207,
      "percent_total": 11
    },
    "andromeda::run_main": {
      "calls": 1,
      "avg": 45172965,
      "p50": 45187071,
      "p95": 45187071,
      "p99": 45187071,
      "total": 45172965,
      "percent_total": 9995
    },
    "run::run": {
      "calls": 1,
      "avg": 44589997,
      "p50": 44597247,
      "p95": 44597247,
      "p99": 44597247,
      "total": 44589997,
      "percent_total": 9866
    },
    "run::create_runtime_files": {
      "calls": 1,
      "avg": 44585418,
      "p50": 44597247,
      "p95": 44597247,
      "p99": 44597247,
      "total": 44585418,
      "percent_total": 9865
    },
    "extension::load": {
      "calls": 22,
      "avg": 851660,
      "p50": 302335,
      "p95": 3110911,
      "p99": 5672959,
      "total": 18736535,
      "percent_total": 4145
    },
    "runtime::new": {
      "calls": 1,
      "avg": 19432753,
      "p50": 19447807,
      "p95": 19447807,
      "p99": 19447807,
      "total": 19432753,
      "percent_total": 4299
    },
    "console::internal_print": {
      "calls": 180,
      "avg": 3675,
      "p50": 3597,
      "p95": 4823,
      "p99": 15263,
      "total": 661587,
      "percent_total": 146
    },
    "console::get_group_indent": {
      "calls": 180,
      "avg": 107,
      "p50": 100,
      "p95": 240,
      "p99": 481,
      "total": 19314,
      "percent_total": 4
    },
    "andromeda::main": {
      "calls": 1,
      "avg": 45194344,
      "p50": 45219839,
      "p95": 45219839,
      "p99": 45219839,
      "total": 45194344,
      "percent_total": 10000
    },
    "runtime::run": {
      "calls": 1,
      "avg": 23641749,
      "p50": 23642111,
      "p95": 23642111,
      "p99": 23642111,
      "total": 23641749,
      "percent_total": 5231
    }
  }
}

[load1n9]

LGTM!