Profiling results ----------------- Total cpu time observed: 8962ms (out of 9300ms) Number of samples taken: 25 (once every 358ms) =========================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =========================================================== run [11] 50.0% [1] 8962(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [4] 50.0% loop [5] 48.1% run-improve50 [9] 1.9% ----------------------------------------------------------- loop [28] 100.0% [2] 6894(76.9%) 716(8.0%) ??? ...bie/sampling-logging/src/programs.rkt:154:4 ??? [6] 58.2% ??? [7] 26.4% ??? [10] 5.0% ----------------------------------------------------------- ??? [31] 100.0% [3] 348(3.9%) 0(0.0%) best-alt ...bie/sampling-logging/src/glue.rkt:71:0 argmins [8] 100.0% ----------------------------------------------------------- ??? [1] 100.0% [4] 8962(100.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [11] 100.0% ----------------------------------------------------------- ??? [1] 100.0% [5] 8614(96.1%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:192:2 make-exacts* [19] 41.5% loop [12] 41.4% loop [20] 17.1% ----------------------------------------------------------- ??? [2] 100.0% [6] 4010(44.7%) 738(8.2%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [13] 64.5% ??? [15] 9.3% parse-loop145 [18] 7.8% ----------------------------------------------------------- ??? [2] 100.0% [7] 1822(20.3%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [14] 100.0% ----------------------------------------------------------- best-alt [3] 100.0% [8] 348(3.9%) 0(0.0%) argmins ...e/sampling-logging/src/common.rkt:150:0 composed [16] 100.0% ----------------------------------------------------------- ??? [1] 100.0% [9] 348(3.9%) 0(0.0%) run-improve50 ...ng-logging/src/mainloop.rkt:234:0 for-loop [17] 100.0% ----------------------------------------------------------- ??? [2] 100.0% [10] 346(3.9%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ??? [33] 100.0% ----------------------------------------------------------- profile-thunk16 [4] 100.0% [11] 8962(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ----------------------------------------------------------- loop [5] 100.0% [12] 3568(39.8%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:151:2 loop [20] 58.3% make-exacts* [19] 41.7% ----------------------------------------------------------- ??? [6] 100.0% [13] 2586(28.9%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [21] 100.0% ----------------------------------------------------------- ??? [7] 100.0% [14] 1822(20.3%) 408(4.6%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [29] 77.6% ----------------------------------------------------------- ??? [6] 100.0% [15] 372(4.2%) 372(4.2%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ----------------------------------------------------------- argmins [8] 100.0% [16] 348(3.9%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 errors [22] 100.0% ----------------------------------------------------------- run-improve50 [9] 100.0% [17] 348(3.9%) 0(0.0%) for-loop ...mpling-logging/src/mainloop.rkt:249:10 run-iter! [23] 100.0% ----------------------------------------------------------- ??? [6] 100.0% [18] 314(3.5%) 314(3.5%) parse-loop145 .../racket/match/compiler.rkt:418:15 ----------------------------------------------------------- loop [12] 29.4% loop [5] 70.6% [19] 5060(56.5%) 0(0.0%) make-exacts* ...pling-logging/src/points.rkt:138:0 map [24] 56.0% loop [28] 44.0% ----------------------------------------------------------- loop [5] 41.5% loop [12] 58.5% [20] 3554(39.7%) 0(0.0%) loop ...rbie/sampling-logging/src/points.rkt:141:4 map [24] 100.0% ----------------------------------------------------------- bf* [13] 100.0% [21] 2586(28.9%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [25] 100.0% ----------------------------------------------------------- composed [16] 100.0% [22] 348(3.9%) 0(0.0%) errors ...ie/sampling-logging/src/points.rkt:229:0 eval-prog [26] 100.0% ----------------------------------------------------------- for-loop [17] 100.0% [23] 348(3.9%) 0(0.0%) run-iter! ...mpling-logging/src/mainloop.rkt:215:0 choose-best-alt! [27]100.0% ----------------------------------------------------------- make-exacts* [19] 44.4% loop [20] 55.6% [24] 6390(71.3%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [28] 100.0% ----------------------------------------------------------- ??? [21] 100.0% [25] 2586(28.9%) 1438(16.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [29] 44.4% ----------------------------------------------------------- errors [22] 100.0% [26] 348(3.9%) 0(0.0%) eval-prog ...mpling-logging/src/programs.rkt:148:0 f137 [30] 100.0% ----------------------------------------------------------- run-iter! [23] 100.0% [27] 348(3.9%) 0(0.0%) choose-best-alt! ...logging/src/mainloop.rkt:115:0 ??? [35] 100.0% ----------------------------------------------------------- make-exacts* [19] 0.1% map [24] 5.0% loop [28] 94.9% [28] 8614(96.1%) 316(3.5%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [28] 94.9% ??? [31] 4.2% ??? [2] 0.7% ----------------------------------------------------------- bfmul [25] 44.8% real->bigfloat [14] 55.2% [29] 2562(28.6%) 2562(28.6%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ----------------------------------------------------------- eval-prog [26] 100.0% [30] 348(3.9%) 0(0.0%) f137 .../collects/racket/match/compiler.rkt:507:40 ??? [31] 100.0% ----------------------------------------------------------- f137 [30] 9.9% ??? [39] 9.9% loop [28] 80.1% [31] 1752(19.5%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ->flonum [32] 80.1% best-alt [3] 9.9% ??? [33] 9.9% ----------------------------------------------------------- ??? [31] 100.0% [32] 1404(15.7%) 710(7.9%) ->flonum ...s/racket/private/norm-define.rkt:53:83 ??? [34] 24.9% ??? [36] 24.5% ----------------------------------------------------------- ??? [10] 49.9% ??? [31] 50.1% [33] 694(7.7%) 346(3.9%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ??? [35] 50.1% ----------------------------------------------------------- ->flonum [32] 100.0% [34] 350(3.9%) 0(0.0%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [37] 100.0% ----------------------------------------------------------- ??? [33] 50.0% choose-best-alt! [27] 50.0% [35] 348(3.9%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 ??? [39] 50.0% hash-has-key? [38] 50.0% ----------------------------------------------------------- ->flonum [32] 100.0% [36] 344(3.8%) 344(3.8%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ----------------------------------------------------------- ??? [34] 100.0% [37] 350(3.9%) 350(3.9%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 ----------------------------------------------------------- ??? [35] 100.0% [38] 348(3.9%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [40] 100.0% ----------------------------------------------------------- ??? [35] 100.0% [39] 348(3.9%) 0(0.0%) ??? ...ampling-logging/src/core/alt-table.rkt:54:0 ??? [31] 100.0% ----------------------------------------------------------- hash-has-key? [38] 100.0% [40] 348(3.9%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 ??? [41] 100.0% ----------------------------------------------------------- ??? [40] 100.0% [41] 348(3.9%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [42] 100.0% ----------------------------------------------------------- ??? [41] 100.0% [42] 348(3.9%) 348(3.9%) ??? ...ects/racket/contract/private/prop.rkt:493:4 -----------------------------------------------------------