__step=000643 __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" ./brown_ext_chromatic_lf.debra -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000643.txt __path_data=data/data000643.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6986598.232455106 binary=./brown_ext_chromatic_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_ext_chromatic_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=5 DEL_FRAC=5 RQ=0 RQSIZE=0 MAXKEY=200000 PREFILL_THREADS=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=5.0 5.0 PREFILL_TYPE=PREFILL_HYBRID PREFILL_HYBRID_MIN_MS=1000 PREFILL_HYBRID_MAX_MS=5000 sizes: node=56 descriptor=128 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.036s prefilling_size=100216 prefilling_elapsed_millis=1s finished prefilling to size 100215 for expected size 100000 keysum=10021762306, performing 26985729 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100215 pref_millis=1102 prefill_elapsed_ms=1120 4NodeIxPvE_object_size=56 4NodeIxPvE_allocated_count=0 4NodeIxPvE_allocated_size=0MB 4NodeIxPvE_get_from_pool=0 4NodeIxPvE_deallocated=0 4NodeIxPvE_limbo_count=0 4NodeIxPvE_limbo_details=0 0 0 global_epoch_counter=872 9SCXRecordIxPvE_object_size=128 9SCXRecordIxPvE_allocated_count=0 9SCXRecordIxPvE_allocated_size=0MB 9SCXRecordIxPvE_get_from_pool=0 9SCXRecordIxPvE_deallocated=0 9SCXRecordIxPvE_limbo_count=0 9SCXRecordIxPvE_limbo_details=0 0 0 global_epoch_counter=0 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6986599.544045651 REALTIME_END_PERF_FORMAT=6986609.544183278 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10s PRODUCING OUTPUT computing tree_stats in PARALLEL... bounded depth BFS to partition into subtrees for parallel computation (192 threads)... partitioned into 1024 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.037s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4085 8017 14379 21026 22198 16020 7531 2375 536 81 3 1 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 11 153 1655 7732 19854 28376 24509 12687 4214 991 159 5 1 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8170 16034 28758 42052 44396 32040 15062 4750 1072 162 6 2 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 11 153 1655 7732 19854 28376 24509 12687 4214 991 159 5 1 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 1.99731 1.98127 1.89678 1.73114 1.52787 1.36084 1.23505 1.15768 1.11284 1.07556 1.01852 1.16667 1.5 1 tree_stats_height=26 tree_stats_numInternals=100348 tree_stats_numLeaves=100349 tree_stats_numNodes=200697 tree_stats_numKeys=100349 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=17.263 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 457520 897904 1610448 2354912 2486176 1794240 843472 266000 60032 9072 336 112 112 tree_stats_sizeInBytes=11239032 4NodeIxPvE_object_size=56 4NodeIxPvE_allocated_count=0 4NodeIxPvE_allocated_size=0MB 4NodeIxPvE_get_from_pool=0 4NodeIxPvE_deallocated=0 4NodeIxPvE_limbo_count=0 4NodeIxPvE_limbo_details=0 0 0 global_epoch_counter=18619 9SCXRecordIxPvE_object_size=128 9SCXRecordIxPvE_allocated_count=0 9SCXRecordIxPvE_allocated_size=0MB 9SCXRecordIxPvE_get_from_pool=0 9SCXRecordIxPvE_deallocated=0 9SCXRecordIxPvE_limbo_count=0 9SCXRecordIxPvE_limbo_details=0 0 0 global_epoch_counter=0 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:7 2:14 4:22 8:51 16:21297 32:464639 64:470622 128:2721 256:54 [2^00, 2^01]: 7 (2^01, 2^02]: 14 (2^02, 2^03]: 22 (2^03, 2^04]: 51 (2^04, 2^05]: 21297 (2^05, 2^06]: 464639 (2^06, 2^07]: 470622 (2^07, 2^08]: 2721 (2^08, 2^09]: 54 sum_limbo_reclamation_event_size_total=64539063 count_limbo_reclamation_event_size_by_thread=9994 9994 9994 9996 9996 9994 9994 9994 9994 9994 9994 9994 9996 9994 9994 9994 9994 9994 9992 9992 9996 9992 9994 9994 9994 9992 9994 9992 9994 9992 9994 9994 9992 9994 9994 9996 9994 9994 9994 9994 9994 9996 9994 9994 9996 9995 9996 9994 9994 9992 9996 9996 9992 9994 9992 9994 9992 9994 9994 9994 9996 9994 9996 9994 9994 9994 9994 9992 9994 9996 9996 9994 9994 9994 9994 9996 9992 9994 9994 9992 9992 9994 9996 9994 9994 9994 9994 9994 9992 9994 9994 9994 9994 9996 9994 9994 count_limbo_reclamation_event_size_total=959427 sum_limbo_reclamation_event_count_total=3407534 first_thread_announced_epoch_by_thread=37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 37238 sum_num_inserts_by_thread=783719 788487 793880 793202 790751 781552 785429 791340 802341 800975 789915 792236 790051 795845 802223 802350 799799 792456 785747 789674 796642 795974 789685 784079 783356 788097 793727 796008 789686 781811 784618 787893 801285 798332 790484 785406 787774 789886 795382 797408 793233 790839 783111 791221 799083 802637 790924 782121 794176 795966 801463 805095 799026 792359 789977 796102 803869 802551 793209 790703 790627 797552 799340 802026 796019 787932 784266 789622 803068 804582 800318 793265 790824 792183 798677 803167 795489 796964 794753 798884 811628 807411 799784 795976 790907 798385 800302 804503 797466 792476 790295 799125 811096 810464 795905 794548 sum_num_inserts_total=76286999 sum_num_deletes_by_thread=782510 786550 793358 792666 789543 781077 785061 790551 799191 800993 790601 792878 789587 796731 802230 802932 799860 791842 786219 790496 796520 795226 789293 785980 783492 788109 793265 795849 790005 779859 784320 787744 798955 799547 790607 785510 788161 791286 795321 798634 793011 790064 782905 789841 798331 802836 791843 783396 792009 796579 802820 805411 798472 794522 790298 796813 803625 803322 793160 792445 790227 798498 800482 801299 797100 786872 786348 789595 804307 803747 798563 794233 789982 794392 799649 805082 795587 795492 796196 799627 812726 808765 799216 796295 791204 797922 800188 803979 798250 793381 789545 798584 811584 810806 798138 794714 sum_num_deletes_total=76296837 sum_num_searches_by_thread=14084419 14174968 14281205 14289180 14220577 14059123 14133563 14245597 14418313 14435524 14246440 14270676 14242982 14328645 14435186 14435468 14389001 14242104 14149410 14232309 14342885 14303239 14211604 14121420 14105131 14184541 14277654 14318520 14231154 14057554 14121445 14174707 14381834 14391700 14224342 14168872 14186160 14240570 14330831 14356551 14279027 14219134 14082052 14243904 14371779 14455491 14235752 14080542 14282246 14332830 14469047 14492496 14388649 14276559 14219000 14321818 14473510 14461911 14280729 14247988 14222271 14351691 14397022 14433933 14318545 14165447 14153153 14195170 14452148 14451444 14395437 14295162 14222611 14268272 14363835 14476833 14322970 14343774 14323541 14376671 14606503 14545000 14383184 14310079 14258744 14363567 14393063 14465476 14354803 14270430 14213680 14374731 14600521 14585687 14366169 14286415 sum_num_searches_total=1373167850 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=15650648 15750005 15868443 15875048 15800871 15621752 15704053 15827488 16019845 16037492 15826956 15855790 15822620 15921221 16039639 16040750 15988660 15826402 15721376 15812479 15936047 15894439 15790582 15691479 15671979 15760747 15864646 15910377 15810845 15619224 15690383 15750344 15982074 15989579 15805433 15739788 15762095 15821742 15921534 15952593 15865271 15800037 15648068 15824966 15969193 16060964 15818519 15646059 15868431 15925375 16073330 16103002 15986147 15863440 15799275 15914733 16081004 16067784 15867098 15831136 15803125 15947741 15996844 16037258 15911664 15740251 15723767 15774387 16059523 16059773 15994318 15882660 15803417 15854847 15962161 16085082 15914046 15936230 15914490 15975182 16230857 16161176 15982184 15902350 15840855 15959874 15993553 16073958 15950519 15856287 15793520 15972440 16223201 16206957 15960212 15875677 average_num_operations_total=15893246 stdev_num_operations_total=13873 sum_num_operations_total=1525751686 min_num_operations_total=15619224 max_num_operations_total=16230857 first_time_thread_terminate_by_thread=10000136 10000135 10000136 10000136 10000135 10000135 10000138 10000138 10000135 10000136 10000135 10000135 10000136 10000135 10000137 10000136 10000135 10000136 10000136 10000135 10000136 10000137 10000135 10000138 10000136 10000135 10000138 10000136 10000135 10000135 10000138 10000138 10000135 10000136 10000135 10000135 10000136 10000135 10000137 10000136 10000135 10000135 10000136 10000135 10000136 10000137 10000135 10000138 10000138 10000136 10000137 10000138 10000137 10000137 10000137 10000138 10000138 10000137 10000137 10000137 10000137 10000138 10000137 10000137 10000138 10000137 10000137 10000138 10000137 10000137 10000137 10000137 10000138 10000136 10000137 10000138 10000137 10000137 10000137 10000138 10000138 10000137 10000137 10000138 10000137 10000138 10000137 10000137 10000138 10000137 10000137 10000138 10000137 10000137 10000137 10000137 min_time_thread_terminate_total=10000135 max_time_thread_terminate_total=10000138 first_time_thread_start_by_thread=5 5 5 5 5 5 5 4 5 5 4 4 5 4 4 4 4 5 5 5 4 5 5 4 5 5 5 5 5 5 5 4 5 5 4 4 5 4 4 4 4 5 5 5 4 5 5 4 4 5 5 4 4 5 5 4 4 4 4 5 4 5 4 5 4 4 4 4 4 5 4 5 4 5 5 4 4 4 5 4 4 4 4 4 4 5 4 5 4 4 4 4 4 5 4 5 min_time_thread_start_total=4 max_time_thread_start_total=5 sum_duration_all_ops_total= threads_final_keysum=10025016484 threads_final_size=100349 final_keysum=10025016484 final_size=100349 validate_result=success Validation OK. Structural validation OK. total_find=1373167850 total_rq=0 total_inserts=76286999 total_deletes=76296837 total_updates=152583836 total_queries=1373167850 total_ops=1525751686 find_throughput=137316785 rq_throughput=0 update_throughput=15258383 query_throughput=137316785 total_throughput=152575168 total find : 1373167850 total rq : 0 total inserts : 76286999 total deletes : 76296837 total updates : 152583836 total queries : 1373167850 total ops : 1525751686 find throughput : 137316785 rq throughput : 0 update throughput : 15258383 query throughput : 137316785 total throughput : 152575168 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=9.22993 PAPI_L3_TCM=0.629523 PAPI_TOT_CYC=1751.49 PAPI_TOT_INS=388.862 garbage=68648155292901 total_execution_walltime=11.47s REALTIME_MAIN_END_PERF_FORMAT=6986609.703347894 [time_cmd_output] time_elapsed_sec=11.58, faults_major=0, faults_minor=91885, mem_maxresident_kb=344328, user_cputime=1084.73, sys_cputime=5.01, percent_cpu=9405% timeout=false algorithm=brown_ext_chromatic_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