Profiling results ----------------- Total cpu time observed: 107574ms (out of 108088ms) Number of samples taken: 342 (once every 315ms) (Hiding functions with self<1.0% and local<2.0%: 3 of 111 hidden) =================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =================================================================== ??? [5] 100.0% [1] 107574(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% ------------------------------------------------------------------- ??? [5] 100.0% [2] 2162(2.0%) 0(0.0%) errors ...bie/optional-posits/src/points.rkt:223:0 for-loop [4] 100.0% ------------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 107574(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [5] 100.0% ------------------------------------------------------------------- errors [2] 100.0% [4] 2162(2.0%) 0(0.0%) for-loop ...e/optional-posits/src/points.rkt:225:2 ??? [5] 100.0% ------------------------------------------------------------------- for-loop [4] 0.7% run [3] 49.7% [5] 107574(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.7% run-improve43 [6] 44.6% prepare-points [7] 2.6% eval-errors [24] 0.8% oracle-error [8] 0.7% errors [2] 0.7% ordinary-value? [64] 0.2% ??? [33] 0.2% baseline-error [9] 0.1% errors-score [10] 0.1% ??? [68] 0.1% ulp-difference [78] 0.1% ??? [46] 0.1% ------------------------------------------------------------------- ??? [5] 100.0% [6] 95912(89.2%) 0(0.0%) run-improve43 ...nal-posits/src/mainloop.rkt:340:0 simplify! [11] 99.7% for-loop [14] 0.3% ------------------------------------------------------------------- ??? [5] 100.0% [7] 5524(5.1%) 0(0.0%) prepare-points ...onal-posits/src/points.rkt:166:0 loop [12] 100.0% ------------------------------------------------------------------- ??? [5] 100.0% [8] 1568(1.5%) 0(0.0%) oracle-error ...tional-posits/src/points.rkt:207:0 for-loop [13] 100.0% ------------------------------------------------------------------- ??? [5] 100.0% [9] 302(0.3%) 0(0.0%) baseline-error ...onal-posits/src/points.rkt:211:0 argmin [15] 100.0% ------------------------------------------------------------------- ??? [5] 100.0% [10] 292(0.3%) 0(0.0%) errors-score ...tional-posits/src/points.rkt:215:0 loop [16] 100.0% ------------------------------------------------------------------- run-improve43 [6] 100.0% [11] 95580(88.9%) 0(0.0%) simplify! ...ptional-posits/src/mainloop.rkt:217:0 ??? [17] 100.0% ------------------------------------------------------------------- prepare-points [7] 100.0% [12] 5524(5.1%) 0(0.0%) loop ...erbie/optional-posits/src/points.rkt:143:4 loop [18] 70.3% ??? [20] 16.7% ordinary-value? [64] 13.0% ------------------------------------------------------------------- oracle-error [8] 100.0% [13] 1568(1.5%) 300(0.3%) for-loop ...e/optional-posits/src/points.rkt:208:2 map [48] 40.4% loop [19] 40.4% ------------------------------------------------------------------- run-improve43 [6] 100.0% [14] 332(0.3%) 0(0.0%) for-loop ...optional-posits/src/mainloop.rkt:355:4 run-iter! [21] 100.0% ------------------------------------------------------------------- baseline-error [9] 100.0% [15] 302(0.3%) 0(0.0%) argmin ...acket-7.0/collects/racket/list.rkt:765:0 ??? [22] 100.0% ------------------------------------------------------------------- errors-score [10] 100.0% [16] 292(0.3%) 292(0.3%) loop .../racket-7.0/collects/racket/list.rkt:559:2 ------------------------------------------------------------------- simplify! [11] 100.0% [17] 95580(88.9%) 0(0.0%) ??? .../optional-posits/src/core/simplify.rkt:26:0 for-loop [23] 99.3% loop [26] 0.3% one-iter [27] 0.3% ------------------------------------------------------------------- loop [12] 100.0% [18] 3886(3.6%) 1758(1.6%) loop ...erbie/optional-posits/src/points.rkt:102:2 ??? [33] 54.8% ------------------------------------------------------------------- loop! [76] 0.7% for-loop [13] 28.5% loop [19] 70.9% [19] 2226(2.1%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [19] 70.9% ordinary-value? [64] 15.0% ??? [33] 13.5% loop! [76] 0.7% ------------------------------------------------------------------- loop [12] 100.0% [20] 922(0.9%) 0(0.0%) ??? ...herbie/optional-posits/src/points.rkt:127:2 ??? [46] 65.7% curried [81] 34.3% ------------------------------------------------------------------- for-loop [14] 100.0% [21] 332(0.3%) 0(0.0%) run-iter! ...ptional-posits/src/mainloop.rkt:321:0 localize! [25] 100.0% ------------------------------------------------------------------- argmin [15] 100.0% [22] 302(0.3%) 0(0.0%) ??? ...erbie/optional-posits/src/points.rkt:212:27 eval-errors [24] 100.0% ------------------------------------------------------------------- ??? [17] 100.0% [23] 94950(88.3%) 0(0.0%) for-loop ...onal-posits/src/core/simplify.rkt:33:2 one-iter [27] 100.0% ------------------------------------------------------------------- ??? [22] 14.3% ??? [5] 85.7% [24] 2116(2.0%) 0(0.0%) eval-errors ...ptional-posits/src/points.rkt:198:0 for-loop [28] 100.0% ------------------------------------------------------------------- run-iter! [21] 100.0% [25] 332(0.3%) 0(0.0%) localize! ...ptional-posits/src/mainloop.rkt:120:0 localize-error [29] 100.0% ------------------------------------------------------------------- ??? [17] 100.0% [26] 326(0.3%) 0(0.0%) loop ...ptional-posits/src/core/simplify.rkt:142:2 for-loop [30] 100.0% ------------------------------------------------------------------- ??? [17] 0.3% for-loop [23] 99.7% [27] 95254(88.5%) 300(0.3%) one-iter ...onal-posits/src/core/simplify.rkt:85:0 for-loop [31] 53.1% find-matches [32] 46.3% for-loop [37] 0.3% ------------------------------------------------------------------- eval-errors [24] 100.0% [28] 2116(2.0%) 608(0.6%) for-loop ...e/optional-posits/src/points.rkt:200:2 ??? [33] 42.7% ulp-difference [78] 14.3% ??? [36] 14.3% ------------------------------------------------------------------- localize! [25] 100.0% [29] 332(0.3%) 0(0.0%) localize-error ...osits/src/core/localize.rkt:57:0 hash-ref! [34] 100.0% ------------------------------------------------------------------- loop [26] 100.0% [30] 326(0.3%) 0(0.0%) for-loop ...nal-posits/src/core/simplify.rkt:148:4 for-loop [35] 100.0% ------------------------------------------------------------------- one-iter [27] 100.0% [31] 50554(47.0%) 0(0.0%) for-loop ...onal-posits/src/core/simplify.rkt:94:2 loop [39] 67.6% update-leader! [72] 31.1% reduce-to-single! [41] 1.3% ------------------------------------------------------------------- one-iter [27] 100.0% [32] 44100(41.0%) 0(0.0%) find-matches ...-posits/src/core/simplify.rkt:55:0 for-loop [38] 100.0% ------------------------------------------------------------------- loop [19] 7.6% ??? [5] 15.5% for-loop [28] 22.9% loop [18] 54.0% [33] 3944(3.7%) 600(0.6%) ??? ...erbie/optional-posits/src/programs.rkt:99:2 ??? [40] 54.0% ??? [46] 23.2% ??? [68] 7.7% ------------------------------------------------------------------- localize-error [29] 100.0% [34] 332(0.3%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop30 [42] 100.0% ------------------------------------------------------------------- for-loop [30] 100.0% [35] 326(0.3%) 0(0.0%) for-loop ...optional-posits/src/programs.rkt:158:2 table-ref [43] 100.0% ------------------------------------------------------------------- for-loop [28] 100.0% [36] 302(0.3%) 302(0.3%) ??? ...7.0/collects/racket/private/for.rkt:1208:14 ------------------------------------------------------------------- one-iter [27] 100.0% [37] 300(0.3%) 0(0.0%) for-loop ...nal-posits/src/core/simplify.rkt:110:2 set-precompute! [44] 100.0% ------------------------------------------------------------------- for-loop [38] 50.0% find-matches [32] 50.0% [38] 44100(41.0%) 2442(2.3%) for-loop ...onal-posits/src/core/simplify.rkt:57:8 for-loop [38] 50.0% match-e [52] 47.2% ------------------------------------------------------------------- for-loop [31] 100.0% [39] 34180(31.8%) 0(0.0%) loop ...optional-posits/src/core/simplify.rkt:99:4 apply-match [45] 100.0% ------------------------------------------------------------------- ??? [33] 100.0% [40] 2128(2.0%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [47] 54.4% ??? [46] 45.6% ------------------------------------------------------------------- for-loop [31] 100.0% [41] 638(0.6%) 0(0.0%) reduce-to-single! ...its/src/core/egraph.rkt:318:0 for-loop [49] 100.0% ------------------------------------------------------------------- hash-ref! [34] 100.0% [42] 332(0.3%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 map [48] 100.0% ------------------------------------------------------------------- for-loop [35] 100.0% [43] 326(0.3%) 0(0.0%) table-ref ...e/optional-posits/src/common.rkt:41:0 dict? [50] 100.0% ------------------------------------------------------------------- for-loop [37] 100.0% [44] 300(0.3%) 0(0.0%) set-precompute! ...its/src/core/simplify.rkt:116:0 for-loop [51] 100.0% ------------------------------------------------------------------- loop [39] 100.0% [45] 34180(31.8%) 0(0.0%) apply-match ...l-posits/src/core/simplify.rkt:64:0 for-loop [53] 73.1% match-e [52] 26.9% ------------------------------------------------------------------- ??? [5] 10.4% ??? [20] 21.8% ??? [33] 32.9% ??? [40] 34.9% [46] 2780(2.6%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [54] 43.3% ival-mult [56] 34.9% sample-multi-bounded [58] 21.8% ------------------------------------------------------------------- ??? [40] 100.0% [47] 1158(1.1%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-add [55] 100.0% ------------------------------------------------------------------- parse-loop30 [42] 34.4% for-loop [13] 65.6% [48] 966(0.9%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [57] 65.6% loop [59] 34.4% ------------------------------------------------------------------- reduce-to-single! [41] 100.0% [49] 638(0.6%) 0(0.0%) for-loop ...ional-posits/src/core/egraph.rkt:319:8 constant? [102] 100.0% ------------------------------------------------------------------- table-ref [43] 100.0% [50] 326(0.3%) 326(0.3%) dict? ...ollects/racket/private/generic.rkt:146:11 ------------------------------------------------------------------- set-precompute! [44] 100.0% [51] 300(0.3%) 0(0.0%) for-loop ...nal-posits/src/core/simplify.rkt:118:2 setfindf [60] 100.0% ------------------------------------------------------------------- apply-match [45] 8.1% for-loop [73] 28.2% for-loop [38] 63.7% [52] 50848(47.3%) 2444(2.3%) match-e ...ptional-posits/src/core/ematch.rkt:39:0 for-loop [61] 48.1% constant? [102] 37.9% variable? [74] 7.8% ??? [86] 1.1% ------------------------------------------------------------------- apply-match [45] 100.0% [53] 24990(23.2%) 0(0.0%) for-loop ...onal-posits/src/core/simplify.rkt:77:2 merge-egraph-nodes! [62] 78.2% dedup-children! [63] 14.2% substitute-e [65] 4.9% mk-enode! [88] 2.7% ------------------------------------------------------------------- ??? [46] 100.0% [54] 1204(1.1%) 0(0.0%) ->flonum ...ie/optional-posits/src/float.rkt:149:0 ??? [66] 100.0% ------------------------------------------------------------------- ??? [47] 100.0% [55] 1158(1.1%) 1158(1.1%) ival-add ...tional-posits/src/biginterval.rkt:94:0 ------------------------------------------------------------------- ??? [46] 100.0% [56] 970(0.9%) 0(0.0%) ival-mult ...onal-posits/src/biginterval.rkt:112:0 bfmul [67] 100.0% ------------------------------------------------------------------- map [48] 100.0% [57] 634(0.6%) 0(0.0%) ??? ...erbie/optional-posits/src/points.rkt:209:26 ??? [68] 51.1% ordinary-value? [64] 48.9% ------------------------------------------------------------------- ??? [46] 100.0% [58] 606(0.6%) 300(0.3%) sample-multi-bounded ...posits/src/points.rkt:15:0 for-loop [70] 50.5% ------------------------------------------------------------------- map [48] 6.7% loop [59] 93.3% [59] 332(0.3%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:54:19 loop [59] 93.3% ??? [69] 6.7% ------------------------------------------------------------------- for-loop [51] 100.0% [60] 300(0.3%) 0(0.0%) setfindf ...e/optional-posits/src/common.rkt:138:0 for-loop [71] 100.0% ------------------------------------------------------------------- match-e [52] 100.0% [61] 33950(31.6%) 6064(5.6%) for-loop ...ional-posits/src/core/ematch.rkt:52:11 for-loop [73] 81.2% foldl [83] 1.0% curried [81] 0.4% ------------------------------------------------------------------- for-loop [53] 100.0% [62] 19544(18.2%) 0(0.0%) merge-egraph-nodes! ...s/src/core/egraph.rkt:143:0 update-leader! [72] 90.3% loop! [76] 9.7% ------------------------------------------------------------------- for-loop [53] 100.0% [63] 3550(3.3%) 0(0.0%) dedup-children! ...posits/src/core/enode.rkt:198:0 loop [75] 100.0% ------------------------------------------------------------------- ??? [57] 15.3% loop [19] 16.5% ??? [5] 32.7% loop [12] 35.4% [64] 2020(1.9%) 326(0.3%) ordinary-value? ...ional-posits/src/float.rkt:80:0 special-value? [77] 83.9% ------------------------------------------------------------------- for-loop [80] 16.3% for-loop [53] 83.7% [65] 1230(1.1%) 0(0.0%) substitute-e ...al-posits/src/core/ematch.rkt:64:0 for-loop [80] 42.9% constant? [102] 24.6% ??? [86] 24.4% variable? [74] 8.1% ------------------------------------------------------------------- ->flonum [54] 100.0% [66] 1204(1.1%) 290(0.3%) ??? ...s/herbie/optional-posits/src/float.rkt:23:2 for-loop [79] 75.9% ------------------------------------------------------------------- ival-mult [56] 100.0% [67] 970(0.9%) 332(0.3%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [82] 36.1% ??? [84] 29.7% ------------------------------------------------------------------- ??? [5] 32.4% ??? [33] 32.6% ??? [57] 35.0% [68] 926(0.9%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:746:8 value? [105] 65.0% for-loop [104] 35.0% ------------------------------------------------------------------- loop [59] 100.0% [69] 332(0.3%) 0(0.0%) ??? ...optional-posits/src/core/localize.rkt:50:36 ulp-difference [78] 100.0% ------------------------------------------------------------------- sample-multi-bounded [58]100.0% [70] 306(0.3%) 306(0.3%) for-loop ...ie/optional-posits/src/points.rkt:23:4 ------------------------------------------------------------------- setfindf [60] 100.0% [71] 300(0.3%) 0(0.0%) for-loop ...e/optional-posits/src/common.rkt:139:2 constant? [102] 100.0% ------------------------------------------------------------------- for-loop [31] 47.1% merge-egraph-nodes! [62] 52.9% [72] 33388(31.0%) 0(0.0%) update-leader! ...posits/src/core/egraph.rkt:211:0 for-loop [85] 100.0% ------------------------------------------------------------------- for-loop [61] 100.0% [73] 28758(26.7%) 362(0.3%) for-loop ...ional-posits/src/core/ematch.rkt:58:37 match-e [52] 87.1% ??? [87] 11.6% ------------------------------------------------------------------- substitute-e [65] 3.1% match-e [52] 96.9% [74] 9720(9.0%) 1502(1.4%) variable? ...al-posits/src/syntax/syntax.rkt:724:0 value? [105] 47.0% ??? [86] 19.0% for-loop [104] 18.5% ------------------------------------------------------------------- dedup-children! [63] 5.8% loop [75] 94.2% [75] 3550(3.3%) 3550(3.3%) loop ...racket-7.0/collects/racket/list.rkt:434:17 loop [75] 94.2% ------------------------------------------------------------------- loop [19] 42.1% merge-egraph-nodes! [62] 57.9% [76] 1892(1.8%) 646(0.6%) loop! .../optional-posits/src/core/enode.rkt:190:2 loop [19] 42.1% custom-set-union [90] 24.0% set [97] 8.9% update-en-expr [107] 7.9% ------------------------------------------------------------------- ordinary-value? [64] 100.0% [77] 1694(1.6%) 0(0.0%) special-value? ...tional-posits/src/float.rkt:76:0 for-loop [94] 100.0% ------------------------------------------------------------------- ??? [5] 32.1% for-loop [28] 32.3% ??? [69] 35.5% [78] 934(0.9%) 0(0.0%) ulp-difference ...tional-posits/src/float.rkt:46:0 infer-representation [89]100.0% ------------------------------------------------------------------- ??? [66] 100.0% [79] 914(0.8%) 612(0.6%) for-loop ...bie/optional-posits/src/float.rkt:24:5 ??? [106] 33.0% ------------------------------------------------------------------- substitute-e [65] 100.0% [80] 628(0.6%) 0(0.0%) for-loop ...ional-posits/src/core/ematch.rkt:73:24 mk-enode! [88] 52.2% substitute-e [65] 47.8% ------------------------------------------------------------------- for-loop [61] 48.9% ??? [20] 51.1% [81] 618(0.6%) 618(0.6%) curried ...-7.0/collects/racket/function.rkt:71:18 ------------------------------------------------------------------- bfmul [67] 100.0% [82] 350(0.3%) 350(0.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------- for-loop [61] 100.0% [83] 334(0.3%) 0(0.0%) foldl ....0/collects/racket/private/list.rkt:229:4 merge2 [91] 100.0% ------------------------------------------------------------------- bfmul [67] 100.0% [84] 288(0.3%) 288(0.3%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------- update-leader! [72] 100.0% [85] 33388(31.0%) 0(0.0%) for-loop ...ional-posits/src/core/egraph.rkt:216:6 hash-update! [96] 50.4% for-loop [92] 49.6% ------------------------------------------------------------------- substitute-e [65] 8.1% match-e [52] 41.7% variable? [74] 50.2% [86] 3684(3.4%) 3684(3.4%) ??? ...contract/private/arrow-val-first.rkt:388:18 ------------------------------------------------------------------- for-loop [73] 100.0% [87] 3350(3.1%) 0(0.0%) ??? ...ie/optional-posits/src/core/ematch.rkt:43:5 for-loop [93] 100.0% ------------------------------------------------------------------- for-loop [80] 33.0% for-loop [53] 67.0% [88] 994(0.9%) 328(0.3%) mk-enode! ...ional-posits/src/core/egraph.rkt:97:0 new-enode [95] 67.0% ------------------------------------------------------------------- ulp-difference [78] 100.0% [89] 934(0.9%) 302(0.3%) infer-representation ...-posits/src/float.rkt:15:0 for-loop [94] 67.7% ------------------------------------------------------------------- loop! [76] 100.0% [90] 610(0.6%) 610(0.6%) custom-set-union ...et/private/set-types.rkt:168:0 ------------------------------------------------------------------- foldl [83] 100.0% [91] 334(0.3%) 334(0.3%) merge2 ...optional-posits/src/core/ematch.rkt:24:0 ------------------------------------------------------------------- for-loop [85] 100.0% [92] 16560(15.4%) 302(0.3%) for-loop ...ional-posits/src/core/egraph.rkt:217:8 hash-update! [96] 98.2% ------------------------------------------------------------------- ??? [87] 100.0% [93] 3350(3.1%) 0(0.0%) for-loop ...tional-posits/src/core/ematch.rkt:44:7 constant? [102] 100.0% ------------------------------------------------------------------- infer-representation [89] 27.2% special-value? [77] 72.8% [94] 2326(2.2%) 1308(1.2%) for-loop ...bie/optional-posits/src/float.rkt:17:3 ??? [98] 27.2% return/no-unsupplied [99] 16.6% ------------------------------------------------------------------- mk-enode! [88] 100.0% [95] 666(0.6%) 0(0.0%) new-enode ...tional-posits/src/core/enode.rkt:95:0 type-of-enode-expr [100] 50.2% set [97] 49.8% ------------------------------------------------------------------- for-loop [92] 49.1% for-loop [85] 50.9% [96] 33086(30.8%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [101] 100.0% ------------------------------------------------------------------- new-enode [95] 49.7% loop! [76] 50.3% [97] 668(0.6%) 668(0.6%) set ...collects/racket/private/set-types.rkt:981:0 ------------------------------------------------------------------- for-loop [94] 100.0% [98] 632(0.6%) 632(0.6%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ------------------------------------------------------------------- for-loop [94] 100.0% [99] 386(0.4%) 386(0.4%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ------------------------------------------------------------------- new-enode [95] 100.0% [100] 334(0.3%) 0(0.0%) type-of-enode-expr ...sits/src/core/enode.rkt:67:0 constant? [102] 100.0% ------------------------------------------------------------------- hash-update! [96] 100.0% [101] 33086(30.8%) 0(0.0%) ??? .../optional-posits/src/core/egraph.rkt:219:24 for-loop [103] 100.0% ------------------------------------------------------------------- for-loop [71] 1.0% substitute-e [65] 1.0% type-of-enode-expr [100] 1.1% for-loop [49] 2.1% for-loop [93] 10.9% match-e [52] 84.0% [102] 30758(28.6%) 1522(1.4%) constant? ...al-posits/src/syntax/syntax.rkt:721:0 for-loop [104] 62.5% value? [105] 32.5% ------------------------------------------------------------------- ??? [101] 100.0% [103] 33086(30.8%) 30340(28.2%) for-loop ...onal-posits/src/core/egraph.rkt:220:26 update-en-expr [107] 8.3% ------------------------------------------------------------------- ??? [68] 1.5% variable? [74] 8.4% constant? [102] 90.0% [104] 21358(19.9%) 18608(17.3%) for-loop ...onal-posits/src/syntax/types.rkt:18:19 ??? [106] 10.1% keyword-apply [108] 2.8% ------------------------------------------------------------------- ??? [68] 4.0% variable? [74] 30.1% constant? [102] 65.9% [105] 15174(14.1%) 13972(13.0%) value? ...ptional-posits/src/syntax/types.rkt:18:0 ??? [106] 7.9% ------------------------------------------------------------------- for-loop [79] 8.3% value? [105] 32.9% for-loop [104] 58.8% [106] 3654(3.4%) 3654(3.4%) ??? ...-7.0/collects/racket/lazy-require.rkt:106:6 ------------------------------------------------------------------- loop! [76] 9.8% for-loop [103] 90.2% [107] 3046(2.8%) 3046(2.8%) update-en-expr ...posits/src/core/egraph.rkt:205:0 ------------------------------------------------------------------- for-loop [104] 100.0% [108] 600(0.6%) 600(0.6%) keyword-apply ...lects/racket/private/kw.rkt:421:2 -------------------------------------------------------------------