__step=000709 __cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 numactl -i all /usr/bin/time -f "[time_cmd_output] time_elapsed_sec=%e, faults_major=%F, faults_minor=%R, mem_maxresident_kb=%M, user_cputime=%U, sys_cputime=%S, percent_cpu=%P" ./ellen_ext_bst_lf.debra -nwork 144 -nprefill 144 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000709.txt __path_data=data/data000709.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6987433.177016696 binary=./ellen_ext_bst_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=ellen_ext_bst_lf FIND_FUNC=FIND_FUNC INSERT_FUNC=insertIfAbsent ERASE_FUNC=ERASE_FUNC RQ_FUNC=RQ_FUNC RECLAIM=reclaimer_debra ALLOC=allocator_new POOL=pool_none MAX_THREADS_POW2=512 CPU_FREQ_GHZ=2.1 MILLIS_TO_RUN=10000 INS_FRAC=50 DEL_FRAC=50 RQ=0 RQSIZE=0 MAXKEY=20000000 PREFILL_THREADS=144 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=144 WORK_THREADS=144 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=50.0 50.0 PREFILL_TYPE=PREFILL_HYBRID PREFILL_HYBRID_MIN_MS=1000 PREFILL_HYBRID_MAX_MS=5000 sizes: node=40 descriptor=64 ACTUAL_THREAD_BINDINGS=0,1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.015s prefilling_size=7672399 prefilling_elapsed_millis=1.001s prefilling_size=7981926 prefilling_elapsed_millis=1.101s prefilling_size=8249899 prefilling_elapsed_millis=1.201s prefilling_size=8480436 prefilling_elapsed_millis=1.301s prefilling_size=8673990 prefilling_elapsed_millis=1.402s prefilling_size=8845981 prefilling_elapsed_millis=1.502s prefilling_size=8992962 prefilling_elapsed_millis=1.602s prefilling_size=9125805 prefilling_elapsed_millis=1.702s prefilling_size=9238136 prefilling_elapsed_millis=1.802s prefilling_size=9338683 prefilling_elapsed_millis=1.903s prefilling_size=9420860 prefilling_elapsed_millis=2.003s prefilling_size=9493888 prefilling_elapsed_millis=2.103s prefilling_size=9560858 prefilling_elapsed_millis=2.203s prefilling_size=9616759 prefilling_elapsed_millis=2.303s prefilling_size=9661655 prefilling_elapsed_millis=2.404s prefilling_size=9703943 prefilling_elapsed_millis=2.504s prefilling_size=9739064 prefilling_elapsed_millis=2.604s prefilling_size=9773394 prefilling_elapsed_millis=2.704s prefilling_size=9801603 prefilling_elapsed_millis=2.804s finished prefilling to size 9801668 for expected size 10000000 keysum=98018540545626, performing 78735732 updates; total_prefilling_elapsed_ms=2907 ms) pref_size=9801668 pref_millis=2907 prefill_elapsed_ms=2929 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=624 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6987436.337721132 REALTIME_END_PERF_FORMAT=6987446.337860890 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10.1s PRODUCING OUTPUT computing tree_stats in PARALLEL... bounded depth BFS to partition into subtrees for parallel computation (192 threads)... partitioned into 1018 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.217s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 128 256 509 1011 1988 3845 7202 13011 22866 38604 62571 97078 144311 204192 275920 357039 440762 522780 593751 649437 687442 705243 702409 681374 642023 588010 522467 450742 378066 308158 244716 189704 142619 104122 73722 50803 34391 22648 14567 9135 5616 3246 1840 971 525 238 108 46 21 5 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 0 0 3 7 34 131 488 1393 3156 7128 14637 28064 49845 84430 132464 194801 273316 358744 451809 538065 611432 669641 708077 723444 720725 696036 653553 594192 523418 447974 371600 299728 236789 181116 134522 96641 67215 46134 30729 19999 12654 7986 4652 2709 1417 812 368 170 71 37 10 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 256 512 1018 2022 3976 7690 14404 26022 45732 77208 125142 194156 288622 408384 551840 714078 881524 1045560 1187502 1298874 1374884 1410486 1404818 1362748 1284046 1176020 1044934 901484 756132 616316 489432 379408 285238 208244 147444 101606 68782 45296 29134 18270 11232 6492 3680 1942 1050 476 216 92 42 10 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 3 7 34 131 488 1393 3156 7128 14637 28064 49845 84430 132464 194801 273316 358744 451809 538065 611432 669641 708077 723444 720725 696036 653553 594192 523418 447974 371600 299728 236789 181116 134522 96641 67215 46134 30729 19999 12654 7986 4652 2709 1417 812 368 170 71 37 10 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 2 2 1.99414 1.99312 1.98318 1.96705 1.93654 1.90329 1.87872 1.84414 1.81042 1.77574 1.74327 1.70747 1.67564 1.647 1.61725 1.59304 1.56788 1.54689 1.52926 1.51295 1.49799 1.48503 1.47112 1.45794 1.44427 1.43136 1.41938 1.40755 1.39706 1.3876 1.3759 1.36504 1.35402 1.34456 1.33847 1.32927 1.3216 1.31355 1.30739 1.289 1.28343 1.26386 1.27034 1.22667 1.22689 1.21296 1.22826 1.11905 1 tree_stats_height=60 tree_stats_numInternals=10002367 tree_stats_numLeaves=10002368 tree_stats_numNodes=20004735 tree_stats_numKeys=10002366 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=32.8989 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10240 20480 40720 80880 159040 307600 576160 1040880 1829280 3088320 5005680 7766240 11544880 16335360 22073600 28563120 35260960 41822400 47500080 51954960 54995360 56419440 56192720 54509920 51361840 47040800 41797360 36059360 30245280 24652640 19577280 15176320 11409520 8329760 5897760 4064240 2751280 1811840 1165360 730800 449280 259680 147200 77680 42000 19040 8640 3680 1680 400 tree_stats_sizeInBytes=800189400 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=2845 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:417 2:185 4:105 8:249 16:711 32:2266 64:11918 128:65610 256:237495 512:302813 1024:16777 [2^00, 2^01]: 417 (2^01, 2^02]: 185 (2^02, 2^03]: 105 (2^03, 2^04]: 249 (2^04, 2^05]: 711 (2^05, 2^06]: 2266 (2^06, 2^07]: 11918 (2^07, 2^08]: 65610 (2^08, 2^09]: 237495 (2^09, 2^10]: 302813 (2^10, 2^11]: 16777 sum_limbo_reclamation_event_size_total=338891509 count_limbo_reclamation_event_size_by_thread=4436 4434 4436 4436 4432 4434 4436 4434 4434 4436 4432 4436 4434 4436 4436 4432 4436 4438 4430 4438 4434 4434 4434 4434 4434 4434 4438 4438 4434 4434 4432 4432 4436 4436 4434 4438 4432 4434 4436 4428 4436 4436 4436 4434 4438 4436 4436 4434 4436 4438 4432 4432 4434 4434 4436 4436 4432 4432 4430 4428 4432 4434 4432 4436 4436 4438 4430 4428 4436 4428 4436 4434 4432 4432 4436 4438 4434 4434 4434 4428 4432 4432 4438 4434 4434 4436 4432 4434 4434 4438 4434 4434 4434 4434 4428 4436 4438 4432 4432 4434 4432 4436 4434 4434 4436 4438 4438 4438 4432 4430 4432 4430 4436 4438 4436 4434 4434 4436 4432 4436 4434 4438 4434 4430 4436 4430 4436 4436 4438 4434 4438 4430 4432 4438 4434 4438 4436 4436 4436 4438 4436 4434 4434 4432 count_limbo_reclamation_event_size_total=638546 sum_limbo_reclamation_event_count_total=639648 first_thread_announced_epoch_by_thread=5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 5690 sum_num_inserts_by_thread=936337 936016 943874 950400 940624 940773 941394 949763 949701 956910 943477 938114 941520 946876 953497 951688 946886 943359 940930 938761 944761 948317 943637 934112 941198 950213 952182 950279 939305 939087 939924 949312 954762 954015 949977 945340 945300 944822 954706 950283 945437 947156 943025 935652 946479 952514 945090 933102 939005 944083 940126 942828 942084 934830 933774 943127 944086 944535 937188 932070 935513 941320 941017 944378 937329 938888 930758 940915 943766 944354 938779 938417 933017 938391 948872 951277 944957 937028 937047 941443 948175 949632 942513 938831 941343 950026 943354 947744 940029 936729 935552 941986 948910 947868 940420 933965 934973 942689 942474 946489 941895 935140 937786 937804 942412 947861 943184 936460 937617 940239 951836 948617 935147 935995 932885 945222 949174 943958 944344 932177 940815 944671 943424 944725 943542 935452 940465 940603 944468 947623 940342 939736 940968 943869 953876 955289 944043 940675 935570 938062 942226 945694 940664 933859 sum_num_inserts_total=135750205 sum_num_deletes_by_thread=936941 937583 943122 951141 941258 942620 943523 948227 954187 957998 944511 937601 941606 948934 952036 950765 945333 942068 942484 939849 943912 949749 942606 935147 941323 949719 951268 947657 942598 939856 941195 949408 953787 953207 949816 946681 947244 944814 953408 949910 946369 946028 944488 936415 947695 953310 944841 932379 938360 941740 943004 941210 941143 936742 931911 943559 943936 944121 937997 932136 936051 940648 941363 942376 939477 936981 931644 939820 945845 946553 940376 937990 933232 938373 946783 950460 946920 937430 935791 944071 946250 950472 943311 939486 938294 950003 943235 946025 938123 936528 931235 944114 948196 947925 940839 935154 935200 943832 943576 944986 942240 936932 936271 940655 941694 949755 942282 937002 938770 939994 950520 949128 933773 937277 931935 945737 947473 945562 943283 933469 937934 944207 942175 943280 943882 933842 941031 941957 945175 949361 940179 938624 938728 942561 951673 955817 943301 939513 934872 936805 944565 944081 940404 933485 sum_num_deletes_total=135752648 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=1873278 1873599 1886996 1901541 1881882 1883393 1884917 1897990 1903888 1914908 1887988 1875715 1883126 1895810 1905533 1902453 1892219 1885427 1883414 1878610 1888673 1898066 1886243 1869259 1882521 1899932 1903450 1897936 1881903 1878943 1881119 1898720 1908549 1907222 1899793 1892021 1892544 1889636 1908114 1900193 1891806 1893184 1887513 1872067 1894174 1905824 1889931 1865481 1877365 1885823 1883130 1884038 1883227 1871572 1865685 1886686 1888022 1888656 1875185 1864206 1871564 1881968 1882380 1886754 1876806 1875869 1862402 1880735 1889611 1890907 1879155 1876407 1866249 1876764 1895655 1901737 1891877 1874458 1872838 1885514 1894425 1900104 1885824 1878317 1879637 1900029 1886589 1893769 1878152 1873257 1866787 1886100 1897106 1895793 1881259 1869119 1870173 1886521 1886050 1891475 1884135 1872072 1874057 1878459 1884106 1897616 1885466 1873462 1876387 1880233 1902356 1897745 1868920 1873272 1864820 1890959 1896647 1889520 1887627 1865646 1878749 1888878 1885599 1888005 1887424 1869294 1881496 1882560 1889643 1896984 1880521 1878360 1879696 1886430 1905549 1911106 1887344 1880188 1870442 1874867 1886791 1889775 1881068 1867344 average_num_operations_total=1885436 stdev_num_operations_total=940 sum_num_operations_total=271502853 min_num_operations_total=1862402 max_num_operations_total=1914908 first_time_thread_terminate_by_thread=10000142 10000141 10000141 10000145 10000145 10000138 10000141 10000141 10000139 10000145 10000142 10000141 10000141 10000139 10000142 10000143 10000149 10000138 10000147 10000138 10000143 10000143 10000142 10000142 10000142 10000140 10000139 10000145 10000138 10000144 10000141 10000142 10000139 10000138 10000142 10000145 10000141 10000141 10000142 10000145 10000139 10000138 10000141 10000138 10000143 10000143 10000178 10000142 10000140 10000144 10000144 10000144 10000140 10000138 10000140 10000143 10000142 10000141 10000140 10000141 10000140 10000142 10000139 10000138 10000140 10000140 10000139 10000143 10000142 10000138 10000165 10000140 10000140 10000144 10000145 10000142 10000142 10000140 10000139 10000144 10000139 10000139 10000138 10000139 10000140 10000142 10000138 10000175 10000140 10000141 10000203 10000141 10000142 10000142 10000139 10000140 10000144 10000139 10000138 10000143 10000141 10000141 10000142 10000143 10000164 10000138 10000144 10000138 10000140 10000143 10000140 10000143 10000139 10000139 10000140 10000144 10000144 10000164 10000144 10000139 10000143 10000139 10000143 10000143 10000139 10000141 10000139 10000143 10000144 10000138 10000144 10000138 10000140 10000143 10000140 10000143 10000140 10000139 10000140 10000140 10000144 10000140 10000144 10000144 min_time_thread_terminate_total=10000138 max_time_thread_terminate_total=10000203 first_time_thread_start_by_thread=4 5 4 4 4 4 4 4 4 4 5 5 4 4 4 5 4 5 4 4 4 4 5 5 4 5 4 4 4 4 4 4 4 4 5 5 4 4 4 5 4 5 4 4 4 4 5 5 5 4 4 4 5 4 5 4 4 4 4 4 5 4 5 4 4 5 4 4 5 4 4 4 5 4 4 4 5 4 5 4 4 4 4 4 5 4 5 4 4 5 4 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 min_time_thread_start_total=4 max_time_thread_start_total=5 sum_duration_all_ops_total= threads_final_keysum=100027106207768 threads_final_size=10002366 final_keysum=100027106207768 final_size=10002366 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=135750205 total_deletes=135752648 total_updates=271502853 total_queries=0 total_ops=271502853 find_throughput=0 rq_throughput=0 update_throughput=27150285 query_throughput=0 total_throughput=27150285 total find : 0 total rq : 0 total inserts : 135750205 total deletes : 135752648 total updates : 271502853 total queries : 0 total ops : 271502853 find throughput : 0 rq throughput : 0 update throughput : 27150285 query throughput : 0 total throughput : 27150285 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=61.7526 PAPI_L3_TCM=27.4301 PAPI_TOT_CYC=14171.3 PAPI_TOT_INS=1413.33 garbage=0 total_execution_walltime=13.573s REALTIME_MAIN_END_PERF_FORMAT=6987446.751478276 [time_cmd_output] time_elapsed_sec=13.77, faults_major=0, faults_minor=339502, mem_maxresident_kb=1328700, user_cputime=1801.79, sys_cputime=26.56, percent_cpu=13277% timeout=false algorithm=ellen_ext_bst_lf.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1