Profiling results ----------------- Total cpu time observed: 21906ms (out of 22772ms) Number of samples taken: 56 (once every 391ms) =================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =================================================================== ??? [19] 100.0% [1] 21248(97.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% ------------------------------------------------------------------- ??? [19] 100.0% [2] 7578(34.6%) 0(0.0%) run-improve50 ...egimes-bug/src/mainloop.rkt:234:0 get-final-combination [6] 77.3% for-loop [7] 18.0% ??? [16] 4.8% ------------------------------------------------------------------- copying-mergesort [55] 100.0% [3] 1056(4.8%) 0(0.0%) jloop ...t/collects/racket/private/sort.rkt:121:23 ??? [57] 100.0% ------------------------------------------------------------------- ??? [25] 100.0% [4] 334(1.5%) 0(0.0%) loop ...ix-regimes-bug/src/core/simplify.rkt:210:2 pass [8] 100.0% ------------------------------------------------------------------- profile-thunk16 [1] 98.5% [5] 21572(98.5%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [19] 100.0% ------------------------------------------------------------------- run-improve50 [2] 100.0% [6] 5856(26.7%) 0(0.0%) get-final-combination ...ug/src/mainloop.rkt:264:0 split-table [9] 100.0% ------------------------------------------------------------------- run-improve50 [2] 100.0% [7] 1362(6.2%) 0(0.0%) for-loop ...ix-regimes-bug/src/mainloop.rkt:249:10 run-iter! [10] 100.0% ------------------------------------------------------------------- loop [4] 100.0% [8] 334(1.5%) 0(0.0%) pass ...ix-regimes-bug/src/core/simplify.rkt:200:2 for-loop [11] 100.0% ------------------------------------------------------------------- get-final-combination [6] 100.0% [9] 5856(26.7%) 0(0.0%) split-table .../fix-regimes-bug/src/glue.rkt:159:0 f12 [12] 100.0% ------------------------------------------------------------------- for-loop [7] 100.0% [10] 1362(6.2%) 0(0.0%) run-iter! ...ix-regimes-bug/src/mainloop.rkt:215:0 finalize-iter! [13] 75.3% simplify! [14] 24.7% ------------------------------------------------------------------- pass [8] 100.0% [11] 334(1.5%) 0(0.0%) for-loop ...gimes-bug/src/core/simplify.rkt:203:29 argmin [15] 100.0% ------------------------------------------------------------------- f12 [12] 3.9% eval-prog [53] 3.9% split-table [9] 92.2% [12] 5856(26.7%) 0(0.0%) f12 ...t/collects/racket/match/compiler.rkt:507:40 loop [43] 60.2% option-on-expr [46] 31.9% f12 [12] 3.9% ??? [41] 3.9% ------------------------------------------------------------------- run-iter! [10] 100.0% [13] 1026(4.7%) 0(0.0%) finalize-iter! ...gimes-bug/src/mainloop.rkt:169:0 ??? [16] 100.0% ------------------------------------------------------------------- run-iter! [10] 100.0% [14] 336(1.5%) 0(0.0%) simplify! ...ix-regimes-bug/src/mainloop.rkt:155:0 for-loop [17] 100.0% ------------------------------------------------------------------- for-loop [11] 100.0% [15] 334(1.5%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [18] 100.0% ------------------------------------------------------------------- run-improve50 [2] 17.4% ??? [44] 33.1% finalize-iter! [13] 49.5% [16] 2072(9.5%) 362(1.7%) ??? ...contract/private/arrow-val-first.rkt:357:18 atab-add-altns [20] 33.5% for-loop [21] 33.4% hash-has-key? [22] 15.6% ------------------------------------------------------------------- simplify! [14] 100.0% [17] 336(1.5%) 0(0.0%) for-loop ...fix-regimes-bug/src/mainloop.rkt:159:6 ??? [19] 100.0% ------------------------------------------------------------------- argmin [15] 100.0% [18] 334(1.5%) 0(0.0%) for-loop ...fix-regimes-bug/src/programs.rkt:200:2 ??? [25] 100.0% ------------------------------------------------------------------- for-loop [17] 0.5% run [5] 49.7% [19] 21906(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 48.2% loop [23] 32.7% run-improve50 [2] 17.0% ??? [25] 2.0% ------------------------------------------------------------------- ??? [16] 100.0% [20] 694(3.2%) 0(0.0%) atab-add-altns ...-bug/src/core/alt-table.rkt:50:0 atab-add-altn [24] 100.0% ------------------------------------------------------------------- ??? [16] 100.0% [21] 692(3.2%) 0(0.0%) for-loop ...egimes-bug/src/core/alt-table.rkt:51:2 atab-add-altn [24] 100.0% ------------------------------------------------------------------- ??? [16] 100.0% [22] 324(1.5%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [30] 100.0% ------------------------------------------------------------------- ??? [19] 100.0% [23] 13994(63.9%) 0(0.0%) loop ...erbie/fix-regimes-bug/src/points.rkt:193:2 make-exacts* [33] 56.1% loop [26] 37.3% for-loop [28] 6.6% ------------------------------------------------------------------- for-loop [21] 49.9% atab-add-altns [20] 50.1% [24] 1386(6.3%) 0(0.0%) atab-add-altn ...-bug/src/core/alt-table.rkt:208:0 best-and-tied-at-points [27] 74.0% override-at-pnts [31] 26.0% ------------------------------------------------------------------- for-loop [18] 16.6% for-loop [29] 41.7% ??? [19] 41.7% [25] 670(3.1%) 0(0.0%) ??? ...llects/racket/private/norm-define.rkt:53:83 for-loop [29] 41.7% iterate-egraph!13 [32] 25.1% loop [4] 16.6% ??? [30] 16.6% ------------------------------------------------------------------- loop [23] 100.0% [26] 5222(23.8%) 0(0.0%) loop ...erbie/fix-regimes-bug/src/points.rkt:151:2 loop [34] 59.9% make-exacts* [33] 33.4% eval-prog [53] 6.6% ------------------------------------------------------------------- atab-add-altn [24] 100.0% [27] 1026(4.7%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [35] 65.1% for-loop [37] 34.9% ------------------------------------------------------------------- loop [23] 100.0% [28] 924(4.2%) 0(0.0%) for-loop .../fix-regimes-bug/src/points.rkt:200:24 for-loop [36] 100.0% ------------------------------------------------------------------- ??? [25] 100.0% [29] 670(3.1%) 0(0.0%) for-loop ...egimes-bug/src/core/simplify.rkt:63:12 ??? [25] 100.0% ------------------------------------------------------------------- hash-has-key? [22] 49.2% ??? [25] 50.8% [30] 658(3.0%) 334(1.5%) ??? ...ects/racket/contract/private/list.rkt:737:4 ??? [44] 49.2% ------------------------------------------------------------------- atab-add-altn [24] 100.0% [31] 360(1.6%) 0(0.0%) override-at-pnts ...g/src/core/alt-table.rkt:145:0 errors [35] 100.0% ------------------------------------------------------------------- ??? [25] 100.0% [32] 336(1.5%) 0(0.0%) iterate-egraph!13 ...g/src/core/simplify.rkt:102:0 one-iter [38] 100.0% ------------------------------------------------------------------- loop [26] 18.2% loop [23] 81.8% [33] 9594(43.8%) 0(0.0%) make-exacts* ...x-regimes-bug/src/points.rkt:138:0 map [39] 63.0% loop [43] 37.0% ------------------------------------------------------------------- loop [26] 100.0% [34] 3130(14.3%) 0(0.0%) loop ...erbie/fix-regimes-bug/src/points.rkt:141:4 map [39] 100.0% ------------------------------------------------------------------- override-at-pnts [31] 25.9% option-on-expr [46] 25.9% best-and-tied-at-points [27] 48.1% [35] 1388(6.3%) 0(0.0%) errors ...bie/fix-regimes-bug/src/points.rkt:218:0 for-loop [40] 100.0% ------------------------------------------------------------------- for-loop [28] 100.0% [36] 924(4.2%) 324(1.5%) for-loop .../fix-regimes-bug/src/points.rkt:201:26 ??? [41] 64.9% ------------------------------------------------------------------- best-and-tied-at-points [27]100.0% [37] 358(1.6%) 358(1.6%) for-loop ...imes-bug/src/core/alt-table.rkt:124:16 ------------------------------------------------------------------- iterate-egraph!13 [32] 100.0% [38] 336(1.5%) 0(0.0%) one-iter ...egimes-bug/src/core/simplify.rkt:113:0 for-loop [42] 100.0% ------------------------------------------------------------------- loop [34] 34.1% make-exacts* [33] 65.9% [39] 9174(41.9%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [43] 100.0% ------------------------------------------------------------------- errors [35] 100.0% [40] 1388(6.3%) 0(0.0%) for-loop ...e/fix-regimes-bug/src/points.rkt:221:4 ??? [57] 100.0% ------------------------------------------------------------------- for-loop [36] 46.7% f12 [12] 53.3% [41] 1286(5.9%) 600(2.7%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [44] 53.3% ------------------------------------------------------------------- for-loop [42] 50.0% one-iter [38] 50.0% [42] 336(1.5%) 0(0.0%) for-loop ...gimes-bug/src/core/simplify.rkt:123:12 for-loop [42] 50.0% match-e [45] 50.0% ------------------------------------------------------------------- make-exacts* [33] 0.1% ??? [57] 0.1% map [39] 1.8% f12 [12] 22.7% loop [43] 75.3% [43] 16468(75.2%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [43] 75.3% option-on-expr [46] 22.7% ??? [57] 1.7% ->flonum [59] 0.1% ??? [58] 0.1% ------------------------------------------------------------------- ??? [30] 23.6% ??? [41] 76.4% [44] 686(3.1%) 324(1.5%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ??? [16] 76.4% ------------------------------------------------------------------- for-loop [42] 100.0% [45] 336(1.5%) 336(1.5%) match-e ...ix-regimes-bug/src/core/ematch.rkt:46:0 ------------------------------------------------------------------- f12 [12] 36.1% loop [43] 63.9% [46] 5856(26.7%) 0(0.0%) option-on-expr ...es-bug/src/core/regimes.rkt:92:0 pick-errors [47] 52.3% sort-context-on-expr [48] 35.9% errors [35] 6.1% sindices->spoints [49] 5.7% ------------------------------------------------------------------- option-on-expr [46] 100.0% [47] 3060(14.0%) 0(0.0%) pick-errors ...imes-bug/src/core/regimes.rkt:160:0 for-loop [50] 100.0% ------------------------------------------------------------------- option-on-expr [46] 100.0% [48] 2102(9.6%) 0(0.0%) sort-context-on-expr ...s-bug/src/points.rkt:115:0 ??? [51] 100.0% ------------------------------------------------------------------- option-on-expr [46] 100.0% [49] 334(1.5%) 0(0.0%) sindices->spoints ...ug/src/core/regimes.rkt:105:0 for-loop [52] 100.0% ------------------------------------------------------------------- pick-errors [47] 100.0% [50] 3060(14.0%) 0(0.0%) for-loop ...regimes-bug/src/core/regimes.rkt:163:6 eval-prog [53] 100.0% ------------------------------------------------------------------- sort-context-on-expr [48] 100.0% [51] 2102(9.6%) 0(0.0%) ??? ...cket/collects/racket/private/sort.rkt:403:3 generic-sort/key [54] 100.0% ------------------------------------------------------------------- sindices->spoints [49] 100.0% [52] 334(1.5%) 334(1.5%) for-loop ...regimes-bug/src/core/regimes.rkt:106:2 ------------------------------------------------------------------- loop [26] 10.2% for-loop [50] 89.8% [53] 3406(15.5%) 2720(12.4%) eval-prog ...ix-regimes-bug/src/programs.rkt:149:0 f12 [12] 20.1% ------------------------------------------------------------------- ??? [51] 100.0% [54] 2102(9.6%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:185:2 copying-mergesort [55] 100.0% ------------------------------------------------------------------- copying-mergesort [55] 48.3% generic-sort/key [54] 51.7% [55] 2102(9.6%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:130:8 copying-mergesort [55] 48.3% loop [56] 39.1% jloop [3] 12.6% ------------------------------------------------------------------- copying-mergesort [55] 100.0% [56] 1046(4.8%) 0(0.0%) loop ...ket/collects/racket/private/sort.rkt:97:12 ??? [57] 100.0% ------------------------------------------------------------------- loop [56] 6.9% jloop [3] 7.0% for-loop [40] 9.2% loop [43] 77.0% [57] 15142(69.1%) 994(4.5%) ??? ...rbie/fix-regimes-bug/src/programs.rkt:155:4 ??? [58] 50.3% ??? [60] 19.8% ->flonum [59] 16.2% loop [43] 7.2% ------------------------------------------------------------------- loop [43] 12.5% ??? [57] 87.5% [58] 8698(39.7%) 1074(4.9%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [61] 87.7% ------------------------------------------------------------------- loop [43] 30.4% ??? [57] 69.6% [59] 3526(16.1%) 1762(8.0%) ->flonum .../fix-regimes-bug/src/programs.rkt:37:0 ??? [62] 39.8% return/no-unsupplied [64] 10.2% ------------------------------------------------------------------- ??? [57] 100.0% [60] 2996(13.7%) 2118(9.7%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [63] 29.3% ------------------------------------------------------------------- ??? [58] 100.0% [61] 7624(34.8%) 4686(21.4%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [68] 38.5% ------------------------------------------------------------------- ->flonum [59] 100.0% [62] 1404(6.4%) 720(3.3%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [66] 48.7% ------------------------------------------------------------------- ??? [60] 100.0% [63] 878(4.0%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [65] 100.0% ------------------------------------------------------------------- ->flonum [59] 100.0% [64] 360(1.6%) 360(1.6%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 ------------------------------------------------------------------- bf* [63] 100.0% [65] 878(4.0%) 374(1.7%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [67] 57.4% ------------------------------------------------------------------- ??? [62] 100.0% [66] 684(3.1%) 684(3.1%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 ------------------------------------------------------------------- ??? [65] 100.0% [67] 504(2.3%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [68] 100.0% ------------------------------------------------------------------- bfmul [67] 14.6% real->bigfloat [61] 85.4% [68] 3442(15.7%) 3442(15.7%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 -------------------------------------------------------------------