Profiling results ----------------- Total cpu time observed: 25420ms (out of 26336ms) Number of samples taken: 69 (once every 368ms) ===================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ===================================================================== run [10] 50.0% [1] 25124(98.8%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [5] 50.0% prepare-points [6] 24.7% loop [11] 22.0% run-improve50 [7] 3.3% --------------------------------------------------------------------- ??? [48] 100.0% [2] 1116(4.4%) 0(0.0%) iterate-egraph!13 ...y/src/core/simplify.rkt:105:0 one-iter [8] 100.0% --------------------------------------------------------------------- hash-ref! [36] 100.0% [3] 568(2.2%) 0(0.0%) ??? ...move-backup-simplify/src/programs.rkt:194:5 loop [31] 100.0% --------------------------------------------------------------------- ??? [48] 100.0% [4] 296(1.2%) 0(0.0%) loop ...ackup-simplify/src/core/simplify.rkt:237:2 pass [9] 100.0% --------------------------------------------------------------------- ??? [1] 100.0% [5] 25124(98.8%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [10] 100.0% --------------------------------------------------------------------- ??? [1] 100.0% [6] 12392(48.7%) 0(0.0%) prepare-points ...up-simplify/src/points.rkt:214:0 make-exacts* [24] 43.8% loop [17] 29.3% loop [25] 26.9% --------------------------------------------------------------------- ??? [1] 100.0% [7] 1678(6.6%) 0(0.0%) run-improve50 ...p-simplify/src/mainloop.rkt:234:0 setup-alt-simplified [12] 66.5% for-loop [14] 17.8% loop [11] 15.7% --------------------------------------------------------------------- iterate-egraph!13 [2] 100.0% [8] 1116(4.4%) 0(0.0%) one-iter ...p-simplify/src/core/simplify.rkt:116:0 for-loop [13] 75.4% for-loop [16] 24.6% --------------------------------------------------------------------- loop [4] 100.0% [9] 296(1.2%) 0(0.0%) pass ...ackup-simplify/src/core/simplify.rkt:227:2 for-loop [15] 100.0% --------------------------------------------------------------------- profile-thunk16 [5] 100.0% [10] 25124(98.8%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% --------------------------------------------------------------------- run-improve50 [7] 2.3% ??? [1] 97.7% [11] 11318(44.5%) 0(0.0%) loop ...emove-backup-simplify/src/points.rkt:238:2 make-exacts* [24] 37.8% loop [17] 35.9% loop [25] 23.6% for-loop [20] 2.7% --------------------------------------------------------------------- run-improve50 [7] 100.0% [12] 1116(4.4%) 0(0.0%) setup-alt-simplified ...simplify/src/glue.rkt:49:0 simplify-alt [18] 100.0% --------------------------------------------------------------------- one-iter [8] 100.0% [13] 842(3.3%) 0(0.0%) for-loop ...p-simplify/src/core/simplify.rkt:166:2 for-loop [19] 100.0% --------------------------------------------------------------------- run-improve50 [7] 100.0% [14] 298(1.2%) 0(0.0%) for-loop ...ackup-simplify/src/mainloop.rkt:249:10 run-iter! [21] 100.0% --------------------------------------------------------------------- pass [9] 100.0% [15] 296(1.2%) 0(0.0%) for-loop ...-simplify/src/core/simplify.rkt:230:29 argmin [22] 100.0% --------------------------------------------------------------------- for-loop [16] 50.0% one-iter [8] 50.0% [16] 274(1.1%) 0(0.0%) for-loop ...-simplify/src/core/simplify.rkt:126:12 for-loop [16] 50.0% match-e [23] 50.0% --------------------------------------------------------------------- prepare-points [6] 47.2% loop [11] 52.8% [17] 7688(30.2%) 0(0.0%) loop ...emove-backup-simplify/src/points.rkt:158:2 make-exacts* [24] 52.3% loop [25] 43.2% loop [26] 4.5% --------------------------------------------------------------------- setup-alt-simplified [12] 100.0% [18] 1116(4.4%) 0(0.0%) simplify-alt ...-backup-simplify/src/glue.rkt:76:0 ??? [48] 100.0% --------------------------------------------------------------------- for-loop [13] 100.0% [19] 842(3.3%) 294(1.2%) for-loop ...-simplify/src/core/simplify.rkt:148:10 loop! [38] 33.0% mk-enode! [30] 32.1% --------------------------------------------------------------------- loop [11] 100.0% [20] 306(1.2%) 0(0.0%) for-loop ...-backup-simplify/src/points.rkt:255:26 ??? [27] 100.0% --------------------------------------------------------------------- for-loop [14] 100.0% [21] 298(1.2%) 0(0.0%) run-iter! ...ackup-simplify/src/mainloop.rkt:215:0 localize-error [28] 100.0% --------------------------------------------------------------------- for-loop [15] 100.0% [22] 296(1.2%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [29] 100.0% --------------------------------------------------------------------- for-loop [16] 100.0% [23] 274(1.1%) 274(1.1%) match-e ...ackup-simplify/src/core/ematch.rkt:46:0 --------------------------------------------------------------------- loop [17] 29.3% loop [11] 31.1% prepare-points [6] 39.6% [24] 13734(54.0%) 0(0.0%) make-exacts* ...ckup-simplify/src/points.rkt:138:0 loop [31] 90.5% eval-prog [32] 9.5% --------------------------------------------------------------------- loop [11] 28.7% loop [17] 35.6% prepare-points [6] 35.7% [25] 9326(36.7%) 0(0.0%) loop ...emove-backup-simplify/src/points.rkt:141:4 loop [31] 100.0% --------------------------------------------------------------------- loop [17] 0.1% loop [26] 99.9% [26] 344(1.4%) 344(1.4%) loop ...emove-backup-simplify/src/points.rkt:130:2 loop [26] 99.9% --------------------------------------------------------------------- for-loop [20] 100.0% [27] 306(1.2%) 0(0.0%) ??? ...remove-backup-simplify/src/points.rkt:230:7 for-loop [33] 100.0% --------------------------------------------------------------------- run-iter! [21] 100.0% [28] 298(1.2%) 0(0.0%) localize-error ...plify/src/core/localize.rkt:47:0 hash-ref! [36] 100.0% --------------------------------------------------------------------- argmin [22] 100.0% [29] 296(1.2%) 0(0.0%) for-loop ...backup-simplify/src/programs.rkt:210:2 ??? [48] 100.0% --------------------------------------------------------------------- for-loop [19] 100.0% [30] 270(1.1%) 0(0.0%) mk-enode! ...up-simplify/src/core/egraph.rkt:102:0 new-enode [34] 100.0% --------------------------------------------------------------------- parse-loop12 [44] 0.0% make-exacts* [24] 0.3% loop! [38] 0.6% loop [25] 1.2% ??? [3] 2.5% loop [31] 95.3% [31] 22894(90.1%) 1632(6.4%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [31] 95.3% hash-ref! [36] 2.5% ??? [35] 1.5% loop! [38] 0.6% ??? [41] 0.0% ??? [42] 0.0% --------------------------------------------------------------------- make-exacts* [24] 100.0% [32] 1310(5.2%) 0(0.0%) eval-prog ...ackup-simplify/src/programs.rkt:150:0 hash-ref! [36] 43.4% ??? [41] 29.0% f137 [37] 27.6% --------------------------------------------------------------------- ??? [27] 100.0% [33] 306(1.2%) 0(0.0%) for-loop ...e-backup-simplify/src/points.rkt:231:9 ??? [41] 100.0% --------------------------------------------------------------------- mk-enode! [30] 100.0% [34] 270(1.1%) 0(0.0%) new-enode ...ckup-simplify/src/core/enode.rkt:97:0 type-of-enode-expr [39] 100.0% --------------------------------------------------------------------- loop [31] 100.0% [35] 19340(76.1%) 1392(5.5%) ??? ...move-backup-simplify/src/programs.rkt:156:4 ??? [40] 59.6% ??? [47] 30.9% ??? [42] 2.3% --------------------------------------------------------------------- eval-prog [32] 27.7% localize-error [28] 34.4% loop [31] 37.9% [36] 866(3.4%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 ??? [3] 37.9% parse-loop12 [44] 34.4% bigfloat-hash [43] 27.7% --------------------------------------------------------------------- f137 [37] 50.0% eval-prog [32] 50.0% [37] 362(1.4%) 0(0.0%) f137 .../collects/racket/match/compiler.rkt:507:40 f137 [37] 50.0% ??? [41] 50.0% --------------------------------------------------------------------- loop [31] 50.0% for-loop [19] 50.0% [38] 278(1.1%) 0(0.0%) loop! ...-backup-simplify/src/core/enode.rkt:192:2 loop [31] 50.0% update-en-expr [45] 50.0% --------------------------------------------------------------------- new-enode [34] 100.0% [39] 270(1.1%) 0(0.0%) type-of-enode-expr ...lify/src/core/enode.rkt:68:0 get-sigs [46] 100.0% --------------------------------------------------------------------- ??? [35] 100.0% [40] 11536(45.4%) 3570(14.0%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [54] 53.1% bf* [49] 10.2% ...higher-order.rkt:346:33 [50] 5.7% --------------------------------------------------------------------- for-loop [33] 17.3% f137 [37] 20.4% eval-prog [32] 21.4% loop [31] 40.9% [41] 1772(7.0%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [48] 58.1% ??? [60] 21.4% ??? [47] 20.4% --------------------------------------------------------------------- loop [31] 44.3% ??? [35] 55.7% [42] 794(3.1%) 442(1.7%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ??? [52] 44.3% --------------------------------------------------------------------- hash-ref! [36] 100.0% [43] 568(2.2%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 sig+exp->bigfloat [51] 100.0% --------------------------------------------------------------------- hash-ref! [36] 100.0% [44] 298(1.2%) 0(0.0%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [31] 100.0% --------------------------------------------------------------------- loop! [38] 100.0% [45] 278(1.1%) 278(1.1%) update-en-expr ...mplify/src/core/egraph.rkt:212:0 --------------------------------------------------------------------- type-of-enode-expr [39] 100.0% [46] 270(1.1%) 0(0.0%) get-sigs ...backup-simplify/src/type-check.rkt:5:0 ??? [53] 100.0% --------------------------------------------------------------------- ??? [41] 5.7% ??? [35] 94.3% [47] 6332(24.9%) 300(1.2%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [55] 95.3% --------------------------------------------------------------------- for-loop [29] 4.0% simplify-alt [18] 22.9% ??? [48] 26.9% ??? [41] 42.2% [48] 2442(9.6%) 1030(4.1%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [48] 26.9% iterate-egraph!13 [2] 22.9% loop [4] 4.0% ??? [65] 4.0% --------------------------------------------------------------------- ??? [40] 100.0% [49] 1180(4.6%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [54] 100.0% --------------------------------------------------------------------- ??? [40] 100.0% [50] 656(2.6%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [56] 100.0% --------------------------------------------------------------------- bigfloat-hash [43] 100.0% [51] 568(2.2%) 568(2.2%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:385:0 --------------------------------------------------------------------- ??? [42] 100.0% [52] 352(1.4%) 352(1.4%) ??? ...ects/racket/contract/private/prop.rkt:493:4 --------------------------------------------------------------------- get-sigs [46] 100.0% [53] 270(1.1%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 ??? [57] 100.0% --------------------------------------------------------------------- bf* [49] 16.1% ??? [40] 83.9% [54] 7310(28.8%) 1622(6.4%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfexp [58] 57.6% bfmul [59] 20.2% --------------------------------------------------------------------- ??? [47] 100.0% [55] 6032(23.7%) 3818(15.0%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [62] 36.7% --------------------------------------------------------------------- ...higher-order.rkt:346:33 [50]100.0% [56] 656(2.6%) 302(1.2%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [60] 54.0% --------------------------------------------------------------------- ??? [53] 100.0% [57] 270(1.1%) 0(0.0%) ??? ...ects/racket/contract/private/hash.rkt:217:4 ??? [61] 100.0% --------------------------------------------------------------------- ??? [54] 100.0% [58] 4210(16.6%) 2104(8.3%) bfexp ...-lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [62] 50.0% --------------------------------------------------------------------- ??? [54] 100.0% [59] 1478(5.8%) 1478(5.8%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 --------------------------------------------------------------------- ??? [56] 48.2% ??? [41] 51.8% [60] 734(2.9%) 354(1.4%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ??? [63] 51.8% --------------------------------------------------------------------- ??? [57] 100.0% [61] 270(1.1%) 270(1.1%) ??? ...ects/racket/contract/private/list.rkt:169:2 --------------------------------------------------------------------- bfexp [58] 48.8% real->bigfloat [55] 51.2% [62] 4320(17.0%) 4320(17.0%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 --------------------------------------------------------------------- ??? [60] 100.0% [63] 380(1.5%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [64] 100.0% --------------------------------------------------------------------- ??? [63] 100.0% [64] 380(1.5%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [65] 100.0% --------------------------------------------------------------------- ??? [48] 43.8% hash-has-key? [64] 56.2% [65] 676(2.7%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 do-chaperone-procedure [66] 56.2% for-loop [67] 43.8% --------------------------------------------------------------------- ??? [65] 100.0% [66] 380(1.5%) 380(1.5%) do-chaperone-procedure ...et/private/kw.rkt:1616:2 --------------------------------------------------------------------- ??? [65] 100.0% [67] 296(1.2%) 296(1.2%) for-loop ...acket/contract/private/list.rkt:743:12 ---------------------------------------------------------------------