__step=000529 __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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000529.txt __path_data=data/data000529.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6985262.742266131 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=2000000 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.005s prefilling_size=998392 prefilling_elapsed_millis=1.001s finished prefilling to size 998409 for expected size 1000000 keysum=998307148767, performing 16191661 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=998409 pref_millis=1102 prefill_elapsed_ms=1125 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=922 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=6985264.58212766 REALTIME_END_PERF_FORMAT=6985274.58359696 ############################################################################### ################################## 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.05s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8191 16361 32373 61790 107644 159963 193196 181569 128990 67928 26046 7258 1434 223 23 4 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 1 21 349 2956 15936 55325 126730 204823 234148 190052 109810 44834 13082 2645 423 42 8 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16382 32722 64746 123580 215288 319926 386392 363138 257980 135856 52092 14516 2868 446 46 8 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 1 21 349 2956 15936 55325 126730 204823 234148 190052 109810 44834 13082 2645 423 42 8 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 1.99988 1.99872 1.98933 1.95434 1.87105 1.74302 1.60388 1.46991 1.35521 1.26331 1.19172 1.13933 1.09879 1.07775 1.05157 1.08696 1 tree_stats_height=30 tree_stats_numInternals=1001184 tree_stats_numLeaves=1001185 tree_stats_numNodes=2002369 tree_stats_numKeys=1001185 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=20.9077 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917392 1832432 3625776 6920480 12056128 17915856 21637952 20335728 14446880 7607936 2917152 812896 160608 24976 2576 448 tree_stats_sizeInBytes=112132664 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=17247 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:1 2:5 4:234 8:23721 16:216010 32:228122 64:11715 [2^00, 2^01]: 1 (2^01, 2^02]: 5 (2^02, 2^03]: 234 (2^03, 2^04]: 23721 (2^04, 2^05]: 216010 (2^05, 2^06]: 228122 (2^06, 2^07]: 11715 sum_limbo_reclamation_event_size_total=16463442 count_limbo_reclamation_event_size_by_thread=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 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 count_limbo_reclamation_event_size_total=479808 sum_limbo_reclamation_event_count_total=1567200 first_thread_announced_epoch_by_thread=34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 34494 sum_num_inserts_by_thread=371018 373195 374141 375433 372893 368842 371519 372586 378113 377714 375364 369771 372386 375042 377500 376025 374886 373897 369618 372091 374742 376416 371893 369385 370107 371168 375302 377087 374519 369939 373280 372056 377762 377429 374378 372494 372936 373656 376918 377474 374072 371133 369075 370334 375247 378102 372826 370367 sum_num_inserts_total=17932131 sum_num_deletes_by_thread=371552 371745 374925 375195 372618 369840 372408 372902 378045 377404 373693 371050 372120 374364 376865 376731 373905 373663 369830 371697 375667 374880 371620 369171 369947 372048 375520 375407 374900 370171 371639 372266 376480 377489 373895 370490 372606 374342 375184 376839 374134 371554 369012 372256 374834 376707 372077 369596 sum_num_deletes_total=17921283 sum_num_searches_by_thread=6674651 6695381 6759102 6762250 6721081 6664574 6688499 6714216 6805692 6802601 6736255 6683984 6694082 6742038 6791736 6783218 6743079 6709891 6650999 6696052 6760638 6759887 6698611 6659902 6660413 6687293 6761511 6763071 6741551 6654296 6701128 6710080 6783359 6795328 6727547 6692817 6712442 6747253 6784280 6780200 6740306 6683821 6643952 6692948 6756885 6785594 6702185 6659917 sum_num_searches_total=322766596 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=7417221 7440321 7508168 7512878 7466592 7403256 7432426 7459704 7561850 7557719 7485312 7424805 7438588 7491444 7546101 7535974 7491870 7457451 7390447 7439840 7511047 7511183 7442124 7398458 7400467 7430509 7512333 7515565 7490970 7394406 7446047 7454402 7537601 7550246 7475820 7435801 7457984 7495251 7536382 7534513 7488512 7426508 7382039 7435538 7506966 7540403 7447088 7399880 average_num_operations_total=7471250 stdev_num_operations_total=7423 sum_num_operations_total=358620010 min_num_operations_total=7382039 max_num_operations_total=7561850 first_time_thread_terminate_by_thread=10000146 10000146 10000145 10000146 10000146 10000145 10000146 10000145 10000145 10000145 10000145 10000145 10000145 10000146 10000145 10000145 10000145 10000146 10000145 10000145 10000145 10000146 10000145 10000146 10000146 10000146 10000145 10000146 10000146 10000145 10000146 10000145 10000145 10000145 10000145 10000145 10000145 10000146 10000145 10000145 10000145 10000146 10000145 10000145 10000145 10000145 10000145 10000146 min_time_thread_terminate_total=10000145 max_time_thread_terminate_total=10000146 first_time_thread_start_by_thread=4 4 3 3 4 4 4 4 4 4 4 4 4 4 3 3 4 4 4 4 4 4 4 4 4 4 3 3 4 4 4 4 4 4 4 4 4 4 3 3 4 4 4 4 4 4 4 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=1001071215444 threads_final_size=1001185 final_keysum=1001071215444 final_size=1001185 validate_result=success Validation OK. Structural validation OK. total_find=322766596 total_rq=0 total_inserts=17932131 total_deletes=17921283 total_updates=35853414 total_queries=322766596 total_ops=358620010 find_throughput=32276659 rq_throughput=0 update_throughput=3585341 query_throughput=32276659 total_throughput=35862001 total find : 322766596 total rq : 0 total inserts : 17932131 total deletes : 17921283 total updates : 35853414 total queries : 322766596 total ops : 358620010 find throughput : 32276659 rq throughput : 0 update throughput : 3585341 query throughput : 32276659 total throughput : 35862001 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=18.5205 PAPI_L3_TCM=4.79223 PAPI_TOT_CYC=3731.08 PAPI_TOT_INS=407.334 garbage=161432492362500 total_execution_walltime=11.458s REALTIME_MAIN_END_PERF_FORMAT=6985274.201111416 [time_cmd_output] time_elapsed_sec=11.60, faults_major=0, faults_minor=130120, mem_maxresident_kb=522460, user_cputime=557.49, sys_cputime=1.83, percent_cpu=4818% 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