__step=000001 __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_abtree_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000001.txt __path_data=data/data000001.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6978700.125681441 binary=./brown_ext_abtree_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_abtree_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=0.5 DEL_FRAC=0.5 RQ=0 RQSIZE=0 MAXKEY=20000000 PREFILL_THREADS=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=0.5 0.5 PREFILL_TYPE=PREFILL_HYBRID PREFILL_HYBRID_MIN_MS=1000 PREFILL_HYBRID_MAX_MS=5000 size_node=208 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=8602049 prefilling_elapsed_millis=1.001s prefilling_size=8831026 prefilling_elapsed_millis=1.101s prefilling_size=9021034 prefilling_elapsed_millis=1.201s prefilling_size=9178448 prefilling_elapsed_millis=1.301s prefilling_size=9310872 prefilling_elapsed_millis=1.402s prefilling_size=9417975 prefilling_elapsed_millis=1.502s prefilling_size=9507425 prefilling_elapsed_millis=1.602s prefilling_size=9586788 prefilling_elapsed_millis=1.702s prefilling_size=9651502 prefilling_elapsed_millis=1.802s prefilling_size=9705553 prefilling_elapsed_millis=1.903s prefilling_size=9752429 prefilling_elapsed_millis=2.003s prefilling_size=9788927 prefilling_elapsed_millis=2.103s prefilling_size=9819998 prefilling_elapsed_millis=2.203s finished prefilling to size 9820096 for expected size 10000000 keysum=98209648084282, performing 87696080 updates; total_prefilling_elapsed_ms=2304 ms) pref_size=9820096 pref_millis=2304 prefill_elapsed_ms=2325 N9abtree_ns4NodeILi11ExEE_object_size=208 N9abtree_ns4NodeILi11ExEE_allocated_count=0 N9abtree_ns4NodeILi11ExEE_allocated_size=0MB N9abtree_ns4NodeILi11ExEE_get_from_pool=0 N9abtree_ns4NodeILi11ExEE_deallocated=0 N9abtree_ns4NodeILi11ExEE_limbo_count=0 N9abtree_ns4NodeILi11ExEE_limbo_details=0 0 0 global_epoch_counter=4255 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6978702.686649232 REALTIME_END_PERF_FORMAT=6978712.686784920 ############################################################################### ################################## 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 3503 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.054s tree_stats_numInternalsAtDepth=1 1 8 66 473 3503 26438 206319 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1627603 tree_stats_numNodesAtDepth=1 1 8 66 473 3503 26438 206319 1627603 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9858140 tree_stats_avgDegreeAtDepth=1 8 8.25 7.16667 7.40592 7.54725 7.80388 7.88877 6.05685 tree_stats_height=9 tree_stats_numInternals=236809 tree_stats_numLeaves=1627603 tree_stats_numNodes=1864412 tree_stats_numKeys=9858140 tree_stats_avgDegreeInternal=7.87306 tree_stats_avgDegreeLeaves=6.05685 tree_stats_avgDegree=6.28753 tree_stats_avgKeyDepth=8 tree_stats_bytesAtDepth=208 208 1664 13728 98384 728624 5499104 42914352 338541424 tree_stats_sizeInBytes=387797696 N9abtree_ns4NodeILi11ExEE_object_size=208 N9abtree_ns4NodeILi11ExEE_allocated_count=0 N9abtree_ns4NodeILi11ExEE_allocated_size=0MB N9abtree_ns4NodeILi11ExEE_get_from_pool=0 N9abtree_ns4NodeILi11ExEE_deallocated=0 N9abtree_ns4NodeILi11ExEE_limbo_count=0 N9abtree_ns4NodeILi11ExEE_limbo_details=0 0 0 global_epoch_counter=30037 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:108680 2:220444 4:100327 8:3107 [2^00, 2^01]: 108680 (2^01, 2^02]: 220444 (2^02, 2^03]: 100327 (2^03, 2^04]: 3107 sum_limbo_reclamation_event_size_total=1139868 count_limbo_reclamation_event_size_by_thread=9047 9026 9058 8991 9006 9039 8960 9023 9104 9021 9023 8997 9008 9014 9013 8998 9088 8982 8981 8976 8948 9017 8978 8988 8943 9009 9048 9058 9074 9005 8996 8968 8970 9065 8997 9045 8940 8980 9018 9006 9023 8988 8978 9006 9080 9002 9052 9021 count_limbo_reclamation_event_size_total=432558 sum_limbo_reclamation_event_count_total=1237536 first_thread_announced_epoch_by_thread=60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 60074 sum_num_inserts_by_thread=58858 59592 59349 59911 59631 59052 59217 59371 60236 60038 59791 59316 58970 59302 59882 59608 59831 59147 58956 59109 59529 60072 59109 58835 59144 59475 59922 59985 59389 58958 59274 59549 60119 60246 59354 59017 59599 59485 59813 59708 59903 59273 58562 59115 59722 59988 59394 58987 sum_num_inserts_total=2854693 sum_num_deletes_by_thread=58912 59528 60129 59564 59989 58890 59288 59304 60059 60761 59523 59060 59651 59862 59544 59287 59952 58937 58870 58953 59201 59982 59204 58817 58904 59035 59969 59464 59171 59160 59082 59848 59904 59864 59482 59403 59207 59449 59721 59581 59675 59052 58636 59201 59288 59693 58891 58965 sum_num_deletes_total=2851912 sum_num_searches_by_thread=11668147 11777351 11835014 11837519 11813584 11675471 11719060 11788536 11908059 11916037 11774459 11685602 11720510 11792795 11857167 11839079 11823391 11688824 11665428 11732483 11824229 11873929 11751403 11643460 11680008 11728706 11849110 11836444 11801174 11692635 11716454 11798002 11890589 11922375 11776595 11732426 11733573 11793253 11845215 11845713 11820113 11728775 11649103 11724367 11754728 11852024 11717917 11692081 sum_num_searches_total=565192917 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=11785917 11896471 11954492 11956994 11933204 11793413 11837565 11907211 12028354 12036836 11893773 11803978 11839131 11911959 11976593 11957974 11943174 11806908 11783254 11850545 11942959 11993983 11869716 11761112 11798056 11847216 11969001 11955893 11919734 11810753 11834810 11917399 12010612 12042485 11895431 11850846 11852379 11912187 11964749 11965002 11939691 11847100 11766301 11842683 11873738 11971705 11836202 11810033 average_num_operations_total=11893740 stdev_num_operations_total=11098 sum_num_operations_total=570899522 min_num_operations_total=11761112 max_num_operations_total=12042485 first_time_thread_terminate_by_thread=10000136 10000136 10000136 10000136 10000135 10000135 10000136 10000136 10000136 10000135 10000136 10000136 10000136 10000136 10000135 10000136 10000135 10000135 10000136 10000136 10000135 10000136 10000135 10000135 10000136 10000135 10000136 10000136 10000135 10000135 10000136 10000136 10000136 10000135 10000136 10000136 10000136 10000136 10000135 10000136 10000135 10000135 10000136 10000136 10000135 10000136 10000135 10000135 min_time_thread_terminate_total=10000135 max_time_thread_terminate_total=10000136 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=98578560966204 threads_final_size=9858140 final_keysum=98578560966204 final_size=9858140 validate_result=success Validation OK. Structural validation OK. total_find=565192917 total_rq=0 total_inserts=2854693 total_deletes=2851912 total_updates=5706605 total_queries=565192917 total_ops=570899522 find_throughput=56519291 rq_throughput=0 update_throughput=570660 query_throughput=56519291 total_throughput=57089952 total find : 565192917 total rq : 0 total inserts : 2854693 total deletes : 2851912 total updates : 5706605 total queries : 565192917 total ops : 570899522 find throughput : 56519291 rq throughput : 0 update throughput : 570660 query throughput : 56519291 total throughput : 57089952 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=17.2218 PAPI_L3_TCM=5.27369 PAPI_TOT_CYC=2338.49 PAPI_TOT_INS=382.396 garbage=2787481632928623 total_execution_walltime=12.702s REALTIME_MAIN_END_PERF_FORMAT=6978712.828812529 [time_cmd_output] time_elapsed_sec=12.85, faults_major=0, faults_minor=177933, mem_maxresident_kb=713372, user_cputime=623.34, sys_cputime=2.44, percent_cpu=4866% timeout=false algorithm=brown_ext_abtree_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