Profiling results ----------------- Total cpu time observed: 66976ms (out of 68820ms) Number of samples taken: 154 (once every 435ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [6] 100.0% [1] 66976(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ------------------------------------------------------------------------ hash-ref! [47] 100.0% [2] 374(0.6%) 0(0.0%) ??? ...erbie/no-inf-columns/src/programs.rkt:161:5 loop [25] 100.0% ------------------------------------------------------------------------ for-loop [75] 100.0% [3] 332(0.5%) 0(0.0%) loop (unknown source) expand-id-application-form17 [5]100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [4] 66976(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% ------------------------------------------------------------------------ loop [3] 100.0% [5] 332(0.5%) 0(0.0%) expand-id-application-form17 (unknown source) resolve+shift28 [7] 100.0% ------------------------------------------------------------------------ for-loop [41] 0.3% run [4] 49.8% [6] 66976(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.8% loop [11] 47.2% run-improve47 [8] 2.2% oracle-error [10] 0.2% errors [36] 0.2% ??? [35] 0.2% ??? [32] 0.2% ------------------------------------------------------------------------ expand-id-application-form17 [5]100.0% [7] 332(0.5%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [9] 100.0% ------------------------------------------------------------------------ ??? [6] 100.0% [8] 3100(4.6%) 0(0.0%) run-improve47 ...nf-columns/src/mainloop.rkt:345:0 loop [11] 59.9% for-loop [12] 20.1% ??? [26] 10.2% simplify! [13] 9.9% ------------------------------------------------------------------------ resolve+shift28 [7] 100.0% [9] 332(0.5%) 0(0.0%) fallback-loop (unknown source) for-loop [75] 100.0% ------------------------------------------------------------------------ ??? [6] 100.0% [10] 268(0.4%) 0(0.0%) oracle-error ...o-inf-columns/src/points.rkt:188:0 for-loop [14] 100.0% ------------------------------------------------------------------------ run-improve47 [8] 2.9% ??? [6] 97.1% [11] 65118(97.2%) 0(0.0%) loop ...herbie/no-inf-columns/src/points.rkt:277:2 loop [19] 34.0% loop [15] 31.4% make-exacts-walkup [20] 30.2% for-loop [16] 4.4% ------------------------------------------------------------------------ run-improve47 [8] 100.0% [12] 622(0.9%) 0(0.0%) for-loop .../no-inf-columns/src/mainloop.rkt:363:4 run-iter! [17] 100.0% ------------------------------------------------------------------------ run-improve47 [8] 100.0% [13] 306(0.5%) 0(0.0%) simplify! ...no-inf-columns/src/mainloop.rkt:223:0 ??? [18] 100.0% ------------------------------------------------------------------------ oracle-error [10] 100.0% [14] 268(0.4%) 268(0.4%) for-loop ...ie/no-inf-columns/src/points.rkt:189:2 ------------------------------------------------------------------------ loop [11] 100.0% [15] 20444(30.5%) 0(0.0%) loop ...herbie/no-inf-columns/src/points.rkt:240:2 loop [19] 60.3% make-exacts-walkup [20] 39.7% ------------------------------------------------------------------------ loop [11] 100.0% [16] 2884(4.3%) 312(0.5%) for-loop ...e/no-inf-columns/src/points.rkt:297:19 ??? [21] 89.2% ------------------------------------------------------------------------ for-loop [12] 100.0% [17] 622(0.9%) 0(0.0%) run-iter! ...no-inf-columns/src/mainloop.rkt:326:0 finalize-iter! [22] 53.4% localize! [24] 46.6% ------------------------------------------------------------------------ simplify! [13] 100.0% [18] 306(0.5%) 0(0.0%) ??? ...e/no-inf-columns/src/core/simplify.rkt:26:0 one-iter [23] 100.0% ------------------------------------------------------------------------ loop [15] 35.8% loop [11] 64.2% [19] 34476(51.5%) 442(0.7%) loop ...herbie/no-inf-columns/src/points.rkt:223:4 loop [25] 96.9% composed [29] 1.8% ------------------------------------------------------------------------ loop [15] 29.2% loop [11] 70.8% [20] 27758(41.4%) 0(0.0%) make-exacts-walkup ...columns/src/points.rkt:218:0 loop [25] 97.5% eval-prog [40] 2.5% ------------------------------------------------------------------------ for-loop [16] 100.0% [21] 2572(3.8%) 670(1.0%) ??? .../herbie/no-inf-columns/src/points.rkt:106:2 ??? [35] 74.0% ------------------------------------------------------------------------ run-iter! [17] 100.0% [22] 332(0.5%) 0(0.0%) finalize-iter! ...f-columns/src/mainloop.rkt:277:0 ??? [26] 100.0% ------------------------------------------------------------------------ ??? [18] 100.0% [23] 306(0.5%) 0(0.0%) one-iter ...inf-columns/src/core/simplify.rkt:72:0 find-matches [27] 100.0% ------------------------------------------------------------------------ run-iter! [17] 100.0% [24] 290(0.4%) 0(0.0%) localize! ...no-inf-columns/src/mainloop.rkt:126:0 localize-error [28] 100.0% ------------------------------------------------------------------------ map [62] 0.0% ??? [2] 0.6% make-exacts-walkup [20] 1.0% loop [19] 1.3% loop [25] 97.1% [25] 61132(91.3%) 336(0.5%) loop ...-7.2/collects/racket/private/map.rkt:40:19 loop [25] 97.1% composed [29] 1.5% ??? [32] 0.8% hash-ref! [47] 0.6% ??? [37] 0.0% ------------------------------------------------------------------------ run-improve47 [8] 48.8% finalize-iter! [22] 51.2% [26] 648(1.0%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:390:18 atab-add-altns [30] 51.2% make-alt-table [31] 48.8% ------------------------------------------------------------------------ one-iter [23] 100.0% [27] 306(0.5%) 306(0.5%) find-matches ...columns/src/core/simplify.rkt:62:0 ------------------------------------------------------------------------ localize! [24] 100.0% [28] 290(0.4%) 0(0.0%) localize-error ...lumns/src/core/localize.rkt:66:0 hash-ref! [47] 100.0% ------------------------------------------------------------------------ loop [19] 1.0% loop [25] 99.0% [29] 58870(87.9%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [32] 100.0% ------------------------------------------------------------------------ ??? [26] 100.0% [30] 332(0.5%) 0(0.0%) atab-add-altns ...umns/src/core/alt-table.rkt:49:0 atab-add-altn [33] 100.0% ------------------------------------------------------------------------ ??? [26] 100.0% [31] 316(0.5%) 0(0.0%) make-alt-table ...umns/src/core/alt-table.rkt:32:0 errors [36] 100.0% ------------------------------------------------------------------------ ??? [6] 0.5% loop [25] 3.1% composed [29] 96.4% [32] 61066(91.2%) 316(0.5%) ??? ...erbie/no-inf-columns/src/programs.rkt:115:2 ??? [34] 93.4% ??? [35] 6.1% ------------------------------------------------------------------------ atab-add-altns [30] 100.0% [33] 332(0.5%) 0(0.0%) atab-add-altn ...umns/src/core/alt-table.rkt:203:0 errors [36] 100.0% ------------------------------------------------------------------------ ??? [32] 100.0% [34] 57044(85.2%) 1858(2.8%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [37] 96.7% ------------------------------------------------------------------------ ??? [6] 5.8% ??? [21] 31.9% ??? [32] 62.2% [35] 5954(8.9%) 668(1.0%) ??? ...tract/private/arrow-higher-order.rkt:360:33 bf [38] 56.8% sample-multi-bounded [39] 31.9% ------------------------------------------------------------------------ make-alt-table [31] 31.8% atab-add-altn [33] 33.4% ??? [6] 34.8% [36] 994(1.5%) 0(0.0%) errors ...rbie/no-inf-columns/src/points.rkt:201:0 for-loop [41] 66.6% eval-prog [40] 33.4% ------------------------------------------------------------------------ loop [25] 0.5% ??? [34] 99.5% [37] 55476(82.8%) 352(0.5%) ??? ...tract/private/arrow-higher-order.rkt:364:33 bf-complex-exp [42] 44.6% bf-complex-div [43] 43.5% bf-complex-neg [44] 6.7% bf-complex-add [45] 4.6% ------------------------------------------------------------------------ ??? [35] 100.0% [38] 3384(5.1%) 342(0.5%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [68] 89.9% ------------------------------------------------------------------------ ??? [35] 100.0% [39] 1902(2.8%) 286(0.4%) sample-multi-bounded ...olumns/src/points.rkt:15:0 random-ranges [46] 85.0% ------------------------------------------------------------------------ errors [36] 32.0% make-exacts-walkup [20] 68.0% [40] 1038(1.5%) 0(0.0%) eval-prog .../no-inf-columns/src/programs.rkt:83:0 hash-ref! [47] 68.0% temp85_0 [48] 32.0% ------------------------------------------------------------------------ errors [36] 100.0% [41] 662(1.0%) 0(0.0%) for-loop ...ie/no-inf-columns/src/points.rkt:203:2 ??? [6] 100.0% ------------------------------------------------------------------------ ??? [37] 100.0% [42] 24728(36.9%) 1822(2.7%) bf-complex-exp ...-columns/src/bigcomplex.rkt:37:0 ??? [56] 79.9% bf* [49] 12.7% ------------------------------------------------------------------------ ??? [37] 100.0% [43] 24142(36.0%) 298(0.4%) bf-complex-div ...-columns/src/bigcomplex.rkt:54:0 ??? [56] 54.8% bf* [49] 32.6% ...higher-order.rkt:364:33 [50] 11.4% ------------------------------------------------------------------------ ??? [37] 100.0% [44] 3702(5.5%) 0(0.0%) bf-complex-neg ...-columns/src/bigcomplex.rkt:20:0 ??? [56] 90.9% ...higher-order.rkt:364:33 [50] 9.1% ------------------------------------------------------------------------ ??? [37] 100.0% [45] 2552(3.8%) 344(0.5%) bf-complex-add ...-columns/src/bigcomplex.rkt:23:0 ??? [56] 86.5% ------------------------------------------------------------------------ sample-multi-bounded [39] 100.0% [46] 1616(2.4%) 0(0.0%) random-ranges ...-inf-columns/src/common.rkt:171:0 cons/c [51] 80.3% build--> [54] 19.7% ------------------------------------------------------------------------ localize-error [28] 7.3% map [62] 21.8% loop [25] 25.0% eval-prog [40] 45.9% [47] 996(1.5%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 bigfloat-hash [52] 45.9% parse-loop30 [55] 29.1% ??? [2] 25.0% ------------------------------------------------------------------------ eval-prog [40] 100.0% [48] 332(0.5%) 0(0.0%) temp85_0 (unknown source) compile15 [53] 100.0% ------------------------------------------------------------------------ bf-complex-exp [42] 28.6% bf-complex-div [43] 71.4% [49] 11018(16.5%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [56] 100.0% ------------------------------------------------------------------------ bf-complex-neg [44] 11.0% bf-complex-div [43] 89.0% [50] 3084(4.6%) 1176(1.8%) ...higher-order.rkt:364:33 (unknown source) ??? [57] 61.9% ------------------------------------------------------------------------ random-ranges [46] 100.0% [51] 1298(1.9%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:504:15 coerce-contract [58] 100.0% ------------------------------------------------------------------------ hash-ref! [47] 100.0% [52] 706(1.1%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 sig+exp->bigfloat [59] 53.0% bfcanonicalize [60] 47.0% ------------------------------------------------------------------------ temp85_0 [48] 100.0% [53] 332(0.5%) 0(0.0%) compile15 (unknown source) temp91_0 [61] 100.0% ------------------------------------------------------------------------ random-ranges [46] 100.0% [54] 318(0.5%) 0(0.0%) build--> ...act/private/arrow-val-first.rkt:1088:0 coerce-contract [58] 100.0% ------------------------------------------------------------------------ hash-ref! [47] 100.0% [55] 290(0.4%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 map [62] 100.0% ------------------------------------------------------------------------ bf-complex-add [45] 4.5% bf-complex-neg [44] 6.8% bf* [49] 22.2% bf-complex-div [43] 26.7% bf-complex-exp [42] 39.8% [56] 49576(74.0%) 15482(23.1%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [63] 39.6% bfneg [64] 29.2% ------------------------------------------------------------------------ ...higher-order.rkt:364:33 [50] 100.0% [57] 1908(2.8%) 1908(2.8%) ??? ...cts/racket/contract/private/list.rkt:209:10 ------------------------------------------------------------------------ build--> [54] 19.7% cons/c [51] 80.3% [58] 1616(2.4%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:308:0 coerce-contract/f [65] 100.0% ------------------------------------------------------------------------ bigfloat-hash [52] 100.0% [59] 374(0.6%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [68] 100.0% ------------------------------------------------------------------------ bigfloat-hash [52] 100.0% [60] 332(0.5%) 0(0.0%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 bigfloat->sig+exp [66] 100.0% ------------------------------------------------------------------------ compile15 [53] 100.0% [61] 332(0.5%) 0(0.0%) temp91_0 (unknown source) temp118_0 [67] 100.0% ------------------------------------------------------------------------ parse-loop30 [55] 100.0% [62] 290(0.4%) 0(0.0%) map ...t-7.2/collects/racket/private/map.rkt:35:13 hash-ref! [47] 75.0% loop [25] 25.0% ------------------------------------------------------------------------ ??? [56] 100.0% [63] 19624(29.3%) 482(0.7%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [68] 75.1% ??? [69] 22.5% ------------------------------------------------------------------------ ??? [56] 100.0% [64] 14470(21.6%) 380(0.6%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [68] 76.9% ??? [69] 20.5% ------------------------------------------------------------------------ coerce-contract [58] 100.0% [65] 1616(2.4%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:373:0 coerce-simple-value [70] 100.0% ------------------------------------------------------------------------ bfcanonicalize [60] 100.0% [66] 332(0.5%) 0(0.0%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:372:0 new-mpz [71] 100.0% ------------------------------------------------------------------------ temp91_0 [61] 100.0% [67] 332(0.5%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [72] 100.0% ------------------------------------------------------------------------ sig+exp->bigfloat [59] 1.3% bf [38] 10.4% bfneg [64] 38.0% bfmul [63] 50.3% [68] 29274(43.7%) 29274(43.7%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ bfneg [64] 40.2% bfmul [63] 59.8% [69] 7374(11.0%) 7374(11.0%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 ------------------------------------------------------------------------ coerce-contract/f [65] 100.0% [70] 1616(2.4%) 1616(2.4%) coerce-simple-value ...ract/private/guts.rkt:386:0 ------------------------------------------------------------------------ bigfloat->sig+exp [66] 100.0% [71] 332(0.5%) 332(0.5%) new-mpz ...-lib/math/private/bigfloat/gmp.rkt:73:0 ------------------------------------------------------------------------ temp118_0 [67] 100.0% [72] 332(0.5%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [73] 100.0% ------------------------------------------------------------------------ expand-capturing-lifts [72] 100.0% [73] 332(0.5%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [74] 100.0% ------------------------------------------------------------------------ lambda-clause-expander [73] 9.1% for-loop [75] 90.9% [74] 332(0.5%) 0(0.0%) finish-bodys (unknown source) for-loop [75] 100.0% ------------------------------------------------------------------------ fallback-loop [9] 8.3% finish-bodys [74] 91.7% [75] 332(0.5%) 332(0.5%) for-loop (unknown source) finish-bodys [74] 83.3% loop [3] 8.3% ------------------------------------------------------------------------