Profiling results ----------------- Total cpu time observed: 14038ms (out of 14612ms) Number of samples taken: 34 (once every 413ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== run [12] 48.8% [1] 14038(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [7] 48.8% loop [13] 40.3% run-improve50 [9] 10.9% ---------------------------------------------------------------------- loop [54] 100.0% [2] 9172(65.3%) 0(0.0%) ??? ...bie/sampling-logging/src/programs.rkt:154:4 ??? [8] 100.0% ---------------------------------------------------------------------- map [32] 16.8% f137 [40] 17.8% loop [54] 65.4% [3] 2136(15.2%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [28] 82.2% ??? [11] 17.8% ---------------------------------------------------------------------- hash-ref! [56] 100.0% [4] 1044(7.4%) 0(0.0%) ??? ...bie/sampling-logging/src/programs.rkt:183:5 loop [54] 67.8% hash-ref! [56] 32.2% ---------------------------------------------------------------------- match-e [46] 100.0% [5] 682(4.9%) 326(2.3%) for-loop ...ling-logging/src/core/ematch.rkt:59:11 for-loop [10] 76.1% ---------------------------------------------------------------------- loop [54] 100.0% [6] 358(2.6%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 map [32] 100.0% ---------------------------------------------------------------------- ??? [1] 100.0% [7] 13712(97.7%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [12] 100.0% ---------------------------------------------------------------------- ??? [2] 100.0% [8] 9172(65.3%) 1148(8.2%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [20] 63.1% bf* [15] 11.6% parse-loop12 [57] 4.6% ??? [16] 4.6% ...higher-order.rkt:346:33 [19] 3.6% ---------------------------------------------------------------------- ??? [1] 100.0% [9] 2732(19.5%) 0(0.0%) run-improve50 ...ng-logging/src/mainloop.rkt:234:0 setup-alt-simplified [14] 74.0% for-loop [18] 13.1% loop [13] 12.9% ---------------------------------------------------------------------- for-loop [5] 100.0% [10] 682(4.9%) 0(0.0%) for-loop ...ling-logging/src/core/ematch.rkt:65:37 match-e [46] 100.0% ---------------------------------------------------------------------- ??? [3] 100.0% [11] 380(2.7%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 integer->bigfloat [17] 100.0% ---------------------------------------------------------------------- profile-thunk16 [7] 100.0% [12] 13712(97.7%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ---------------------------------------------------------------------- run-improve50 [9] 3.0% ??? [1] 97.0% [13] 11658(83.0%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:192:2 make-exacts* [26] 60.6% loop [21] 39.4% ---------------------------------------------------------------------- run-improve50 [9] 100.0% [14] 2022(14.4%) 0(0.0%) setup-alt-simplified ...-logging/src/glue.rkt:49:0 simplify-alt [22] 100.0% ---------------------------------------------------------------------- ??? [8] 100.0% [15] 1062(7.6%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [20] 100.0% ---------------------------------------------------------------------- ??? [8] 100.0% [16] 422(3.0%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:493:4 ??? [23] 100.0% ---------------------------------------------------------------------- ??? [11] 100.0% [17] 380(2.7%) 380(2.7%) integer->bigfloat ...ivate/bigfloat/mpfr.rkt:399:0 ---------------------------------------------------------------------- run-improve50 [9] 100.0% [18] 358(2.6%) 0(0.0%) for-loop ...mpling-logging/src/mainloop.rkt:249:10 run-iter! [24] 100.0% ---------------------------------------------------------------------- ??? [8] 100.0% [19] 332(2.4%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [25] 100.0% ---------------------------------------------------------------------- bf* [15] 15.5% ??? [8] 84.5% [20] 6846(48.8%) 346(2.5%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [27] 94.9% ---------------------------------------------------------------------- loop [13] 100.0% [21] 4588(32.7%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:151:2 loop [29] 54.4% make-exacts* [26] 30.1% eval-prog [34] 15.4% ---------------------------------------------------------------------- setup-alt-simplified [14] 100.0% [22] 2022(14.4%) 0(0.0%) simplify-alt ...sampling-logging/src/glue.rkt:76:0 ??? [28] 100.0% ---------------------------------------------------------------------- ??? [16] 100.0% [23] 422(3.0%) 422(3.0%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ---------------------------------------------------------------------- for-loop [18] 100.0% [24] 358(2.6%) 0(0.0%) run-iter! ...mpling-logging/src/mainloop.rkt:215:0 localize-error [30] 100.0% ---------------------------------------------------------------------- ...higher-order.rkt:346:33 [19]100.0% [25] 332(2.4%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [31] 100.0% ---------------------------------------------------------------------- loop [21] 16.4% loop [13] 83.6% [26] 8452(60.2%) 0(0.0%) make-exacts* ...pling-logging/src/points.rkt:138:0 map [32] 60.2% loop [54] 35.3% eval-prog [34] 4.5% ---------------------------------------------------------------------- ??? [20] 100.0% [27] 6500(46.3%) 4572(32.6%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [38] 29.7% ---------------------------------------------------------------------- ??? [28] 26.8% simplify-alt [22] 26.8% ??? [3] 46.5% [28] 3778(26.9%) 1026(7.3%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [28] 26.8% iterate-egraph!13 [33] 22.3% ??? [35] 9.8% ??? [36] 9.5% loop [37] 4.4% ---------------------------------------------------------------------- loop [21] 100.0% [29] 2498(17.8%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:141:4 map [32] 100.0% ---------------------------------------------------------------------- run-iter! [24] 100.0% [30] 358(2.6%) 0(0.0%) localize-error ...gging/src/core/localize.rkt:47:0 hash-ref! [56] 100.0% ---------------------------------------------------------------------- ??? [25] 100.0% [31] 332(2.4%) 332(2.4%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ---------------------------------------------------------------------- ??? [6] 4.5% loop [29] 31.5% make-exacts* [26] 64.0% [32] 7942(56.6%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [54] 95.5% ??? [3] 4.5% ---------------------------------------------------------------------- ??? [28] 100.0% [33] 1686(12.0%) 0(0.0%) iterate-egraph!13 ...g/src/core/simplify.rkt:102:0 one-iter [39] 100.0% ---------------------------------------------------------------------- make-exacts* [26] 34.9% loop [21] 65.1% [34] 1088(7.8%) 0(0.0%) eval-prog ...mpling-logging/src/programs.rkt:148:0 hash-ref! [56] 65.1% f137 [40] 34.9% ---------------------------------------------------------------------- ??? [28] 100.0% [35] 372(2.6%) 0(0.0%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [41] 100.0% ---------------------------------------------------------------------- ??? [28] 100.0% [36] 358(2.6%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [38] 100.0% ---------------------------------------------------------------------- ??? [28] 100.0% [37] 336(2.4%) 0(0.0%) loop ...mpling-logging/src/core/simplify.rkt:215:2 pass [42] 100.0% ---------------------------------------------------------------------- ??? [36] 15.7% bfmul [27] 84.3% [38] 2286(16.3%) 2286(16.3%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ---------------------------------------------------------------------- iterate-egraph!13 [33] 100.0% [39] 1686(12.0%) 0(0.0%) one-iter ...ng-logging/src/core/simplify.rkt:113:0 for-loop [43] 61.7% loop [44] 38.3% ---------------------------------------------------------------------- eval-prog [34] 25.0% f137 [40] 75.0% [40] 380(2.7%) 0(0.0%) f137 .../collects/racket/match/compiler.rkt:507:40 f137 [40] 75.0% ??? [3] 25.0% ---------------------------------------------------------------------- ??? [35] 100.0% [41] 372(2.6%) 372(2.6%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 ---------------------------------------------------------------------- loop [37] 100.0% [42] 336(2.4%) 0(0.0%) pass ...mpling-logging/src/core/simplify.rkt:205:2 for-loop [45] 100.0% ---------------------------------------------------------------------- for-loop [43] 50.0% one-iter [39] 50.0% [43] 1040(7.4%) 358(2.6%) for-loop ...g-logging/src/core/simplify.rkt:123:12 for-loop [43] 50.0% match-e [46] 32.8% ---------------------------------------------------------------------- one-iter [39] 100.0% [44] 646(4.6%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:52:19 apply-match [47] 100.0% ---------------------------------------------------------------------- pass [42] 100.0% [45] 336(2.4%) 0(0.0%) for-loop ...g-logging/src/core/simplify.rkt:208:29 argmin [48] 100.0% ---------------------------------------------------------------------- for-loop [10] 50.0% for-loop [43] 50.0% [46] 682(4.9%) 0(0.0%) match-e ...mpling-logging/src/core/ematch.rkt:46:0 for-loop [5] 73.9% ??? [50] 26.1% ---------------------------------------------------------------------- loop [44] 100.0% [47] 646(4.6%) 0(0.0%) apply-match ...logging/src/core/simplify.rkt:130:2 for-loop [49] 100.0% ---------------------------------------------------------------------- for-loop [45] 100.0% [48] 336(2.4%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 expression-cost [51] 100.0% ---------------------------------------------------------------------- apply-match [47] 100.0% [49] 646(4.6%) 0(0.0%) for-loop ...g-logging/src/core/simplify.rkt:145:10 loop! [52] 100.0% ---------------------------------------------------------------------- match-e [46] 100.0% [50] 356(2.5%) 356(2.5%) ??? ...contract/private/arrow-val-first.rkt:357:18 ---------------------------------------------------------------------- argmin [48] 100.0% [51] 336(2.4%) 0(0.0%) expression-cost ...-logging/src/programs.rkt:198:0 compile [53] 100.0% ---------------------------------------------------------------------- loop [54] 24.8% for-loop [49] 75.2% [52] 646(4.6%) 0(0.0%) loop! ...sampling-logging/src/core/enode.rkt:192:2 loop [54] 75.2% set [55] 24.8% ---------------------------------------------------------------------- expression-cost [51] 100.0% [53] 336(2.4%) 0(0.0%) compile ...sampling-logging/src/programs.rkt:175:0 hash-ref! [56] 100.0% ---------------------------------------------------------------------- make-exacts* [26] 0.0% parse-loop12 [57] 0.1% map [32] 0.8% loop! [52] 2.2% ??? [4] 5.8% loop [54] 91.1% [54] 12282(87.5%) 326(2.3%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [54] 91.1% hash-ref! [56] 5.8% loop! [52] 0.9% ??? [2] 0.9% ??? [6] 0.1% ??? [3] 0.0% ---------------------------------------------------------------------- loop! [52] 100.0% [55] 320(2.3%) 320(2.3%) set ...collects/racket/private/set-types.rkt:981:0 ---------------------------------------------------------------------- ??? [4] 12.0% compile [53] 12.0% loop [54] 25.2% eval-prog [34] 25.2% localize-error [30] 25.5% [56] 1402(10.0%) 336(2.4%) hash-ref! ...racket/private/more-scheme.rkt:373:13 ??? [4] 37.2% parse-loop12 [57] 25.5% bigfloat-hash [58] 25.2% ---------------------------------------------------------------------- hash-ref! [56] 45.8% ??? [8] 54.2% [57] 782(5.6%) 424(3.0%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [54] 45.8% ---------------------------------------------------------------------- hash-ref! [56] 100.0% [58] 708(5.0%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 bfcanonicalize [59] 100.0% ---------------------------------------------------------------------- bigfloat-hash [58] 100.0% [59] 708(5.0%) 708(5.0%) bfcanonicalize .../private/bigfloat/mpfr.rkt:155:0 ----------------------------------------------------------------------