__step=000703 __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" ./wang_openbwtree -nwork 190 -nprefill 190 -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=data000703.txt __path_data=data/data000703.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6987340.297180145 binary=./wang_openbwtree parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=wang_openbwtree 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=190 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=190 WORK_THREADS=190 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 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,72,73,74,75,76,77,78,79,80,81,82,83,84,85,86,87,88,89,90,91,92,93,94,95,168,169,170,171,172,173,174,175,176,177,178,179,180,181,182,183,184,185,186,187,188,189 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.029s prefilling_size=3567092 prefilling_elapsed_millis=1.004s prefilling_size=3846171 prefilling_elapsed_millis=1.105s prefilling_size=4115355 prefilling_elapsed_millis=1.206s prefilling_size=4369166 prefilling_elapsed_millis=1.307s prefilling_size=4613487 prefilling_elapsed_millis=1.407s prefilling_size=4848031 prefilling_elapsed_millis=1.508s prefilling_size=5069873 prefilling_elapsed_millis=1.609s prefilling_size=5283724 prefilling_elapsed_millis=1.709s prefilling_size=5488968 prefilling_elapsed_millis=1.81s prefilling_size=5683770 prefilling_elapsed_millis=1.911s prefilling_size=5872290 prefilling_elapsed_millis=2.011s prefilling_size=6051260 prefilling_elapsed_millis=2.112s prefilling_size=6220957 prefilling_elapsed_millis=2.213s prefilling_size=6385618 prefilling_elapsed_millis=2.314s prefilling_size=6539057 prefilling_elapsed_millis=2.414s prefilling_size=6688773 prefilling_elapsed_millis=2.515s prefilling_size=6832843 prefilling_elapsed_millis=2.616s prefilling_size=6968578 prefilling_elapsed_millis=2.716s prefilling_size=7098503 prefilling_elapsed_millis=2.817s prefilling_size=7222754 prefilling_elapsed_millis=2.917s prefilling_size=7342761 prefilling_elapsed_millis=3.018s prefilling_size=7455776 prefilling_elapsed_millis=3.119s prefilling_size=7565417 prefilling_elapsed_millis=3.219s prefilling_size=7670870 prefilling_elapsed_millis=3.32s prefilling_size=7770128 prefilling_elapsed_millis=3.421s prefilling_size=7866216 prefilling_elapsed_millis=3.521s prefilling_size=7958720 prefilling_elapsed_millis=3.622s prefilling_size=8045329 prefilling_elapsed_millis=3.723s prefilling_size=8128158 prefilling_elapsed_millis=3.823s prefilling_size=8206654 prefilling_elapsed_millis=3.924s prefilling_size=8284851 prefilling_elapsed_millis=4.025s prefilling_size=8358854 prefilling_elapsed_millis=4.126s prefilling_size=8429625 prefilling_elapsed_millis=4.226s prefilling_size=8498286 prefilling_elapsed_millis=4.327s prefilling_size=8563293 prefilling_elapsed_millis=4.428s prefilling_size=8621605 prefilling_elapsed_millis=4.528s prefilling_size=8680833 prefilling_elapsed_millis=4.63s prefilling_size=8736752 prefilling_elapsed_millis=4.731s prefilling_size=8789539 prefilling_elapsed_millis=4.831s prefilling_size=8840527 prefilling_elapsed_millis=4.932s prefilling_size=8890376 prefilling_elapsed_millis=5.033s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 1109376 keys with 190 threads... tid=0 thread_prefill_amount=49444 percent_done_estimate=846.8 elapsed_s=5 est_remaining_s=-10 / -5 tid=0 thread_prefill_amount=50496 percent_done_estimate=864.8 elapsed_s=5 est_remaining_s=-10 / -5 tid=0 thread_prefill_amount=51548 percent_done_estimate=882.8 elapsed_s=5 est_remaining_s=-10 / -5 tid=0 thread_prefill_amount=52600 percent_done_estimate=900.9 elapsed_s=5 est_remaining_s=-10 / -5 tid=0 thread_prefill_amount=53652 percent_done_estimate=918.9 elapsed_s=5 est_remaining_s=-11 / -5 tid=0 thread_prefill_amount=54704 percent_done_estimate=936.9 elapsed_s=5 est_remaining_s=-11 / -5 timing_elapsed 0.129727s prefilling_size=10000000 prefilling_elapsed_millis=0.13s finished prefilling to size 10000000 for expected size 10000000 keysum=100006402810769, performing 46512008 updates; total_prefilling_elapsed_ms=130 ms) pref_size=10000000 pref_millis=130 prefill_elapsed_ms=130 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6987345.800174931 REALTIME_END_PERF_FORMAT=6987355.800337651 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10.1s PRODUCING OUTPUT Open BwTree: createTreeStats iterating over all kv-pairs... Open BwTree: createTreeStats finished iterating. tree_stats_computeWalltime=0.202s tree_stats_numInternalsAtDepth=0 tree_stats_numLeavesAtDepth=1 tree_stats_numNodesAtDepth=1 tree_stats_numKeysAtDepth=9999055 tree_stats_avgDegreeAtDepth=9.99906e+06 tree_stats_height=1 tree_stats_numInternals=0 tree_stats_numLeaves=1 tree_stats_numNodes=1 tree_stats_numKeys=9999055 tree_stats_avgDegreeInternal=0 tree_stats_avgDegreeLeaves=9.99906e+06 tree_stats_avgDegree=9.99906e+06 tree_stats_avgKeyDepth=0 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 [2^00, 2^01]: 0 sum_limbo_reclamation_event_size_total= count_limbo_reclamation_event_size_by_thread= count_limbo_reclamation_event_size_total= sum_limbo_reclamation_event_count_total= first_thread_announced_epoch_by_thread= sum_num_inserts_by_thread=222469 224063 224131 223225 222236 220974 224189 223260 224636 223981 222950 224533 223167 224053 223553 223855 223573 222821 224459 224383 224567 224228 223394 222550 223736 223682 223602 223560 223020 221595 223555 224233 223513 223463 222843 224803 223648 223543 224677 223541 223057 223397 224000 223875 224167 223233 223401 223408 235452 235430 235532 235149 234397 230692 236306 236237 235703 234683 235844 235372 236622 236071 234700 234886 233528 235408 233714 234256 235226 234421 235511 235747 234530 235514 235559 234649 234102 230737 235603 235562 235966 234568 234103 235325 235839 236419 234072 234975 233699 234443 234661 234476 236311 234753 235688 234866 220617 221157 220174 220283 219968 216689 221546 221651 221135 221552 220318 221206 221818 221694 221273 220479 220326 219901 220907 221767 220956 220606 221660 220685 221716 221248 220166 221024 219935 216494 222255 220849 221974 221363 220716 221302 221575 220625 221282 220461 220168 220364 221054 221690 220532 220418 221141 220195 240255 241459 240515 241304 240692 238673 240158 241970 242161 242898 240562 242454 242040 242919 242536 241839 240766 240189 241641 241111 240948 240862 299276 295685 242267 242422 242352 241871 240103 238660 241439 241825 242596 241840 240737 242683 243544 242899 241862 241044 239770 241562 241690 241460 240529 241445 sum_num_inserts_total=43822537 sum_num_deletes_by_thread=223848 223520 224134 223644 223619 220370 223391 223702 222880 223355 223482 224959 223592 224612 224316 223945 222326 223933 224162 224413 223743 223734 223270 222385 224004 223579 223801 223009 223403 220937 223687 223643 223216 223601 223050 224412 224134 225253 223987 224122 223475 223406 223777 224180 224180 223537 223018 223235 235692 236197 235406 235096 234497 231380 235080 236493 236125 236065 234339 235899 236315 236241 235015 234726 234271 234850 235036 233580 235835 235353 236546 234972 235945 236474 235222 234558 234126 230085 235771 235903 235565 235066 234906 235810 235239 235193 233908 234686 234487 235310 234689 235034 234668 234668 235574 235479 221328 220719 220850 220209 220492 217153 221680 221213 221085 221346 220379 220922 221024 221067 221541 220496 220230 220723 222366 221084 221280 220271 221340 220476 221311 220688 220819 219919 220738 216784 221248 220929 221146 220862 220157 221460 221208 221209 220774 220623 219639 220575 221275 221357 221604 220560 221792 220345 241406 241808 242149 240947 241692 239428 241064 242339 241497 242448 241074 242496 242823 242335 242987 240226 239720 241087 241771 240763 241257 241064 300228 296812 240974 241898 241817 241773 240622 238598 240991 242213 240924 242016 240266 242674 242262 242567 242545 240573 239973 240901 241771 242107 241592 241681 sum_num_deletes_total=43831811 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=446317 447583 448265 446869 445855 441344 447580 446962 447516 447336 446432 449492 446759 448665 447869 447800 445899 446754 448621 448796 448310 447962 446664 444935 447740 447261 447403 446569 446423 442532 447242 447876 446729 447064 445893 449215 447782 448796 448664 447663 446532 446803 447777 448055 448347 446770 446419 446643 471144 471627 470938 470245 468894 462072 471386 472730 471828 470748 470183 471271 472937 472312 469715 469612 467799 470258 468750 467836 471061 469774 472057 470719 470475 471988 470781 469207 468228 460822 471374 471465 471531 469634 469009 471135 471078 471612 467980 469661 468186 469753 469350 469510 470979 469421 471262 470345 441945 441876 441024 440492 440460 433842 443226 442864 442220 442898 440697 442128 442842 442761 442814 440975 440556 440624 443273 442851 442236 440877 443000 441161 443027 441936 440985 440943 440673 433278 443503 441778 443120 442225 440873 442762 442783 441834 442056 441084 439807 440939 442329 443047 442136 440978 442933 440540 481661 483267 482664 482251 482384 478101 481222 484309 483658 485346 481636 484950 484863 485254 485523 482065 480486 481276 483412 481874 482205 481926 599504 592497 483241 484320 484169 483644 480725 477258 482430 484038 483520 483856 481003 485357 485806 485466 484407 481617 479743 482463 483461 483567 482121 483126 average_num_operations_total=461338 stdev_num_operations_total=1582 sum_num_operations_total=87654348 min_num_operations_total=433278 max_num_operations_total=599504 first_time_thread_terminate_by_thread=10000290 10000162 10000168 10000172 10000172 10000163 10000912 10000163 10000162 10000171 10000166 10000162 10000165 10000162 10000171 10000181 10000162 10000170 10000162 10000167 10000163 10000162 10000171 10000166 10000164 10000172 10000168 10000174 10000172 10000168 10000172 10000167 10000162 10000172 10000162 10000162 10000166 10000168 10000171 10000162 10000162 10000170 10000162 10000167 10000163 10000162 10000171 10000166 10000174 10000165 10000173 10000167 10000166 10000174 10000174 10000167 10000168 10000175 10000173 10000174 10000173 10000173 10000169 10000168 10001066 10000166 10000175 10000166 10000174 10000169 10000166 10000178 10000166 10000170 10000169 10000169 10000169 10000173 10000174 10000168 10000169 10000174 10000166 10000169 10000173 10000173 10000172 10000168 10000168 10000165 10000174 10000166 10000175 10000169 10000166 10000169 10000529 10000502 10000172 10000167 10000167 10000165 10000171 10000169 10000174 10000172 10000790 10000168 10000172 10000170 10000171 10000163 10000165 10000175 10000165 10000170 10000170 10000171 10000171 10000169 10000173 10000173 10000361 10000167 10000170 10000165 10001374 10000169 10000174 10000167 10000176 10000168 10000603 10000173 10000171 10000187 10000165 10000175 10000172 10000163 10000163 10000164 10000171 10000166 10000171 10000174 10000170 10000167 10000163 10000173 10000167 10000162 10000161 10000173 10000167 10000169 10000171 10000162 10000161 10000163 10000171 10000165 10000167 10000171 10000171 10000162 10000161 10000168 10000171 10000165 10000170 10000163 10000163 10000173 10000167 10000162 10000164 10000178 10000165 10000163 10000174 10000164 10000171 10000163 10000162 10000162 10000167 10000173 10000168 10000162 min_time_thread_terminate_total=10000161 max_time_thread_terminate_total=10001374 first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 6 5 6 6 6 5 5 5 6 5 6 5 6 6 5 5 5 5 6 5 5 5 5 5 6 5 6 6 6 5 5 5 6 5 6 5 6 6 5 5 5 5 5 6 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 6 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=99964873340705 threads_final_size=9999055 final_keysum=99964873340705 final_size=9999055 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=43822537 total_deletes=43831811 total_updates=87654348 total_queries=0 total_ops=87654348 find_throughput=0 rq_throughput=0 update_throughput=8765434 query_throughput=0 total_throughput=8765434 total find : 0 total rq : 0 total inserts : 43822537 total deletes : 43831811 total updates : 87654348 total queries : 0 total ops : 87654348 find throughput : 0 rq throughput : 0 update throughput : 8765434 query throughput : 0 total throughput : 8765434 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=57.2598 PAPI_L3_TCM=32.6169 PAPI_TOT_CYC=59947 PAPI_TOT_INS=1290.1 garbage=0 total_execution_walltime=15.828s REALTIME_MAIN_END_PERF_FORMAT=6987356.125986296 [time_cmd_output] time_elapsed_sec=16.04, faults_major=0, faults_minor=415929, mem_maxresident_kb=1606692, user_cputime=2889.03, sys_cputime=14.47, percent_cpu=18100% timeout=false algorithm=wang_openbwtree thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1