__step=000641 __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 48 -nprefill 48 -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=data000641.txt __path_data=data/data000641.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6986575.26828202 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=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.004s prefilling_size=100017 prefilling_elapsed_millis=1.001s finished prefilling to size 100012 for expected size 100000 keysum=9978614519, performing 28506236 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100012 pref_millis=1102 prefill_elapsed_ms=1123 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=2107 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=6986576.322291517 REALTIME_END_PERF_FORMAT=6986586.322439958 ############################################################################### ################################## 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.034s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4089 7999 14532 21474 22559 15529 6902 2032 435 72 13 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 7 179 1466 7590 20389 29589 24156 11772 3629 798 131 26 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8178 15998 29064 42948 45118 31058 13804 4064 870 144 26 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 7 179 1466 7590 20389 29589 24156 11772 3629 798 131 26 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 1.99829 1.97811 1.90836 1.73885 1.52526 1.34419 1.22223 1.1472 1.10704 1.08276 1.09028 1 tree_stats_height=24 tree_stats_numInternals=99731 tree_stats_numLeaves=99732 tree_stats_numNodes=199463 tree_stats_numKeys=99732 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=17.2193 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 457968 895888 1627584 2405088 2526608 1739248 773024 227584 48720 8064 1456 tree_stats_sizeInBytes=11169928 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=42082 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:3 2:17 4:370 8:23160 16:215795 32:229163 64:11295 [2^00, 2^01]: 3 (2^01, 2^02]: 17 (2^02, 2^03]: 370 (2^03, 2^04]: 23160 (2^04, 2^05]: 215795 (2^05, 2^06]: 229163 (2^06, 2^07]: 11295 sum_limbo_reclamation_event_size_total=16474397 count_limbo_reclamation_event_size_by_thread=9996 9996 9996 9996 9996 9996 9995 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9992 9996 9996 9996 count_limbo_reclamation_event_size_total=479803 sum_limbo_reclamation_event_count_total=3837578 first_thread_announced_epoch_by_thread=84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 84164 sum_num_inserts_by_thread=909962 914016 925348 918192 914156 902237 907094 915880 930273 932166 919638 910785 910322 914976 922215 922755 919103 910839 905518 915277 923421 918781 913515 904130 906847 911398 923593 922582 917126 904160 910261 916832 927577 929755 918855 912658 911677 917312 924872 921694 917729 903829 899094 910828 921877 925903 916546 907705 sum_num_inserts_total=43961309 sum_num_deletes_by_thread=908785 914153 924306 918635 915291 901691 908453 915386 928819 932701 918432 908627 908455 916922 923621 924754 919440 912142 905136 914503 922039 920919 913863 904532 907656 910220 923615 924502 916421 903169 910195 916896 927384 928014 918919 911142 910667 916580 925684 921869 916449 906665 902284 911499 921947 925720 918228 904224 sum_num_deletes_total=43961554 sum_num_searches_by_thread=16362858 16449962 16630089 16556365 16472789 16224416 16330870 16470831 16731608 16783524 16537446 16382128 16393220 16471888 16601373 16612149 16554533 16401843 16274449 16464039 16588405 16568465 16425820 16282786 16319997 16393494 16642960 16630149 16495682 16254158 16382220 16519137 16695407 16732532 16540629 16427981 16385780 16487477 16661606 16617895 16522205 16291227 16215843 16407765 16572411 16667914 16519300 16299713 sum_num_searches_total=791255338 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=18181605 18278131 18479743 18393192 18302236 18028344 18146417 18302097 18590700 18648391 18375516 18201540 18211997 18303786 18447209 18459658 18393076 18224824 18085103 18293819 18433865 18408165 18253198 18091448 18134500 18215112 18490168 18477233 18329229 18061487 18202676 18352865 18550368 18590301 18378403 18251781 18208124 18321369 18512162 18461458 18356383 18101721 18017221 18230092 18416235 18519537 18354074 18111642 average_num_operations_total=18316212 stdev_num_operations_total=23184 sum_num_operations_total=879178201 min_num_operations_total=18017221 max_num_operations_total=18648391 first_time_thread_terminate_by_thread=10000148 10000148 10000147 10000148 10000148 10000148 10000147 10000148 10000148 10000148 10000148 10000148 10000148 10000147 10000147 10000147 10000148 10000147 10000147 10000148 10000147 10000147 10000148 10000147 10000148 10000148 10000147 10000148 10000148 10000148 10000147 10000148 10000148 10000148 10000148 10000148 10000148 10000147 10000147 10000147 10000148 10000147 10000147 10000148 10000147 10000147 10000148 10000147 min_time_thread_terminate_total=10000147 max_time_thread_terminate_total=10000148 first_time_thread_start_by_thread=5 4 4 5 5 5 5 5 5 4 5 5 4 5 4 5 5 5 5 4 5 5 5 5 5 4 4 5 5 5 5 5 5 4 5 5 4 5 4 5 5 5 5 4 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=5 sum_duration_all_ops_total= threads_final_keysum=9998344976 threads_final_size=99732 final_keysum=9998344976 final_size=99732 validate_result=success Validation OK. Structural validation OK. total_find=791255338 total_rq=0 total_inserts=43961309 total_deletes=43961554 total_updates=87922863 total_queries=791255338 total_ops=879178201 find_throughput=79125533 rq_throughput=0 update_throughput=8792286 query_throughput=79125533 total_throughput=87917820 total find : 791255338 total rq : 0 total inserts : 43961309 total deletes : 43961554 total updates : 87922863 total queries : 791255338 total ops : 879178201 find throughput : 79125533 rq throughput : 0 update throughput : 8792286 query throughput : 79125533 total throughput : 87917820 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=8.46683 PAPI_L3_TCM=0.0196202 PAPI_TOT_CYC=1515.87 PAPI_TOT_INS=377.904 garbage=39563183002034 total_execution_walltime=11.421s REALTIME_MAIN_END_PERF_FORMAT=6986586.449152189 [time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=90613, mem_maxresident_kb=331320, user_cputime=558.39, sys_cputime=2.43, percent_cpu=4860% 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