Profiling results ----------------- Total cpu time observed: 15476ms (out of 16036ms) Number of samples taken: 36 (once every 430ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== ??? [3] 100.0% [1] 15118(97.7%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [2] 100.0% ---------------------------------------------------------------------- profile-thunk16 [1] 97.7% [2] 15476(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [3] 100.0% ---------------------------------------------------------------------- run [2] 51.2% [3] 15476(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 loop [4] 49.8% profile-thunk16 [1] 48.8% run-improve50 [5] 1.3% ---------------------------------------------------------------------- ??? [3] 100.0% [4] 15068(97.4%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:194:2 make-exacts* [9] 52.4% loop [6] 42.4% for-loop [7] 5.2% ---------------------------------------------------------------------- ??? [3] 100.0% [5] 408(2.6%) 0(0.0%) run-improve50 ...ng-logging/src/mainloop.rkt:234:0 setup-alt-simplified [8] 100.0% ---------------------------------------------------------------------- loop [4] 100.0% [6] 6390(41.3%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:153:2 make-exacts* [9] 100.0% ---------------------------------------------------------------------- loop [4] 100.0% [7] 780(5.0%) 0(0.0%) for-loop ...sampling-logging/src/points.rkt:208:24 for-loop [10] 100.0% ---------------------------------------------------------------------- run-improve50 [5] 100.0% [8] 408(2.6%) 0(0.0%) setup-alt-simplified ...-logging/src/glue.rkt:49:0 simplify-alt [11] 100.0% ---------------------------------------------------------------------- loop [6] 44.7% loop [4] 55.3% [9] 14288(92.3%) 0(0.0%) make-exacts* ...pling-logging/src/points.rkt:138:0 loop [14] 53.5% map [12] 44.0% eval-prog [13] 2.5% ---------------------------------------------------------------------- for-loop [7] 100.0% [10] 780(5.0%) 358(2.3%) for-loop ...sampling-logging/src/points.rkt:209:26 ??? [17] 54.1% ---------------------------------------------------------------------- setup-alt-simplified [8] 100.0% [11] 408(2.6%) 0(0.0%) simplify-alt ...sampling-logging/src/glue.rkt:76:0 ??? [21] 100.0% ---------------------------------------------------------------------- make-exacts* [9] 100.0% [12] 6292(40.7%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [14] 100.0% ---------------------------------------------------------------------- make-exacts* [9] 100.0% [13] 358(2.3%) 0(0.0%) eval-prog ...mpling-logging/src/programs.rkt:148:0 f162 [15] 100.0% ---------------------------------------------------------------------- map [12] 0.5% make-exacts* [9] 0.6% loop [14] 98.9% [14] 13930(90.0%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [14] 98.9% ??? [16] 1.1% ??? [17] 0.0% ---------------------------------------------------------------------- eval-prog [13] 100.0% [15] 358(2.3%) 0(0.0%) f162 .../collects/racket/match/compiler.rkt:507:40 ??? [18] 100.0% ---------------------------------------------------------------------- loop [14] 100.0% [16] 13062(84.4%) 1520(9.8%) ??? ...bie/sampling-logging/src/programs.rkt:154:4 ??? [19] 50.0% ??? [20] 36.0% ??? [23] 2.4% ---------------------------------------------------------------------- for-loop [10] 32.7% loop [14] 67.3% [17] 1290(8.3%) 422(2.7%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [21] 67.3% ---------------------------------------------------------------------- f162 [15] 100.0% [18] 358(2.3%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [22] 100.0% ---------------------------------------------------------------------- ??? [16] 100.0% [19] 6532(42.2%) 1758(11.4%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [25] 33.6% ??? [31] 21.2% ...higher-order.rkt:346:33 [26] 12.8% ??? [29] 5.5% ---------------------------------------------------------------------- ??? [16] 100.0% [20] 4696(30.3%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [24] 100.0% ---------------------------------------------------------------------- ??? [21] 16.0% simplify-alt [11] 16.0% ??? [17] 68.0% [21] 1276(8.2%) 410(2.6%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [27] 35.9% ??? [21] 16.0% iterate-egraph!13 [28] 16.0% ---------------------------------------------------------------------- ??? [18] 100.0% [22] 358(2.3%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [30] 100.0% ---------------------------------------------------------------------- ??? [16] 100.0% [23] 314(2.0%) 314(2.0%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ---------------------------------------------------------------------- ??? [20] 100.0% [24] 4696(30.3%) 3668(23.7%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [35] 21.9% ---------------------------------------------------------------------- ??? [19] 100.0% [25] 2192(14.2%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [31] 100.0% ---------------------------------------------------------------------- ??? [19] 100.0% [26] 836(5.4%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [44] 100.0% ---------------------------------------------------------------------- ??? [21] 100.0% [27] 458(3.0%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [35] 100.0% ---------------------------------------------------------------------- ??? [21] 100.0% [28] 408(2.6%) 0(0.0%) iterate-egraph!13 ...g/src/core/simplify.rkt:102:0 one-iter [32] 100.0% ---------------------------------------------------------------------- ??? [19] 100.0% [29] 360(2.3%) 360(2.3%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ---------------------------------------------------------------------- hash-has-key? [22] 100.0% [30] 358(2.3%) 358(2.3%) ??? ...ects/racket/contract/private/list.rkt:737:4 ---------------------------------------------------------------------- ??? [19] 38.7% bf* [25] 61.3% [31] 3578(23.1%) 310(2.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfsub [33] 91.3% ---------------------------------------------------------------------- iterate-egraph!13 [28] 100.0% [32] 408(2.6%) 0(0.0%) one-iter ...ng-logging/src/core/simplify.rkt:113:0 apply-match [34] 100.0% ---------------------------------------------------------------------- ??? [31] 100.0% [33] 3268(21.1%) 2582(16.7%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [35] 21.0% ---------------------------------------------------------------------- one-iter [32] 100.0% [34] 408(2.6%) 0(0.0%) apply-match ...logging/src/core/simplify.rkt:130:2 for-loop [36] 100.0% ---------------------------------------------------------------------- ??? [27] 21.1% bfsub [33] 31.6% real->bigfloat [24] 47.3% [35] 2172(14.0%) 2172(14.0%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ---------------------------------------------------------------------- apply-match [34] 100.0% [36] 408(2.6%) 0(0.0%) for-loop ...g-logging/src/core/simplify.rkt:145:10 substitute-e [37] 100.0% ---------------------------------------------------------------------- for-loop [36] 100.0% [37] 408(2.6%) 0(0.0%) substitute-e ...g-logging/src/core/ematch.rkt:71:0 mk-enode! [38] 100.0% ---------------------------------------------------------------------- substitute-e [37] 100.0% [38] 408(2.6%) 0(0.0%) mk-enode! ...ing-logging/src/core/egraph.rkt:101:0 new-enode [39] 100.0% ---------------------------------------------------------------------- mk-enode! [38] 100.0% [39] 408(2.6%) 0(0.0%) new-enode ...pling-logging/src/core/enode.rkt:97:0 type-of-enode-expr [40] 100.0% ---------------------------------------------------------------------- new-enode [39] 100.0% [40] 408(2.6%) 0(0.0%) type-of-enode-expr ...ging/src/core/enode.rkt:68:0 get-sigs [41] 100.0% ---------------------------------------------------------------------- type-of-enode-expr [40] 100.0% [41] 408(2.6%) 0(0.0%) get-sigs ...ampling-logging/src/type-check.rkt:5:0 ??? [42] 100.0% ---------------------------------------------------------------------- get-sigs [41] 100.0% [42] 408(2.6%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 for-loop [43] 100.0% ---------------------------------------------------------------------- ??? [42] 100.0% [43] 408(2.6%) 0(0.0%) for-loop ...racket/contract/private/hash.rkt:239:6 ??? [44] 100.0% ---------------------------------------------------------------------- for-loop [43] 32.8% ...higher-order.rkt:346:33 [26] 67.2% [44] 1244(8.0%) 836(5.4%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [45] 32.8% ---------------------------------------------------------------------- ??? [44] 100.0% [45] 408(2.6%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:686:7 ??? [46] 100.0% ---------------------------------------------------------------------- ??? [45] 100.0% [46] 408(2.6%) 408(2.6%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ----------------------------------------------------------------------