__step=000257 __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" ./ellen_ext_bst_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000257.txt __path_data=data/data000257.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6981884.701353662 binary=./ellen_ext_bst_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=ellen_ext_bst_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=200000 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 sizes: node=40 descriptor=64 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=100212 prefilling_elapsed_millis=1.001s finished prefilling to size 100198 for expected size 100000 keysum=10048025470, performing 49577621 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100198 pref_millis=1102 prefill_elapsed_ms=1129 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=2677 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6981885.977987598 REALTIME_END_PERF_FORMAT=6981895.978145714 ############################################################################### ################################## 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 810 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.036s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 61 118 224 405 686 1119 1707 2553 3627 4767 6132 7456 8397 8866 8789 8247 7395 6461 5525 4537 3618 2801 2147 1545 1063 691 417 240 118 55 36 13 5 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 3 4 12 43 124 253 531 861 1479 2487 3402 4808 6515 7928 8943 9331 9099 8329 7397 6513 5456 4435 3455 2749 2027 1435 965 594 362 181 74 59 21 10 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 122 236 448 810 1372 2238 3414 5106 7254 9534 12264 14912 16794 17732 17578 16494 14790 12922 11050 9074 7236 5602 4294 3090 2126 1382 834 480 236 110 72 26 10 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 3 4 12 43 124 253 531 861 1479 2487 3402 4808 6515 7928 8943 9331 9099 8329 7397 6513 5456 4435 3455 2749 2027 1435 965 594 362 181 74 59 21 10 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 1.95312 1.96721 1.94915 1.90402 1.84691 1.8156 1.76273 1.7478 1.71034 1.65715 1.64317 1.60796 1.5631 1.52793 1.49566 1.46917 1.44834 1.43685 1.42757 1.41059 1.39872 1.38709 1.38326 1.3598 1.34401 1.32502 1.30174 1.28777 1.24583 1.23305 1.32727 1.18056 1.19231 1 tree_stats_height=42 tree_stats_numInternals=99886 tree_stats_numLeaves=99887 tree_stats_numNodes=199773 tree_stats_numKeys=99885 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=0.99998 tree_stats_avgDegree=1.49999 tree_stats_avgKeyDepth=24.1217 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 4880 9440 17920 32400 54880 89520 136560 204240 290160 381360 490560 596480 671760 709280 703120 659760 591600 516880 442000 362960 289440 224080 171760 123600 85040 55280 33360 19200 9440 4400 2880 1040 400 tree_stats_sizeInBytes=7990920 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=45426 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:82457 2:194666 4:140739 8:15349 16:31 [2^00, 2^01]: 82457 (2^01, 2^02]: 194666 (2^02, 2^03]: 140739 (2^03, 2^04]: 15349 (2^04, 2^05]: 31 sum_limbo_reclamation_event_size_total=1386753 count_limbo_reclamation_event_size_by_thread=9008 9030 9074 9058 8990 9100 8944 8972 9076 9008 9012 8972 9066 9034 9042 9042 9034 9062 9046 8988 9026 8972 8994 9052 8988 9002 9126 9004 9010 9084 8956 9080 8994 9070 8958 9032 9066 9056 9058 9028 8996 9028 8998 9088 9020 9020 8978 9000 count_limbo_reclamation_event_size_total=433242 sum_limbo_reclamation_event_count_total=4103902 first_thread_announced_epoch_by_thread=90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 90852 sum_num_inserts_by_thread=97409 98216 99027 98559 98412 97606 98223 98747 99604 100130 98232 98406 98318 99185 100212 99622 100097 98797 97320 97920 99891 99009 98232 97761 96527 97898 99220 99109 99027 97582 97920 98431 99752 100207 98198 98672 98220 99207 100141 99252 98477 98508 97462 98700 99176 99939 98919 97207 sum_num_inserts_total=4736686 sum_num_deletes_by_thread=97362 97895 99058 98863 98875 97329 97601 99086 99603 100818 98831 98846 98975 99479 99236 99912 100080 98674 97753 97897 99042 99376 97852 98270 96452 98064 98948 99357 99099 97624 98427 99168 100274 100170 99289 98228 98022 99175 99793 99179 99011 98795 97304 97932 99919 99196 98170 96828 sum_num_deletes_total=4739137 sum_num_searches_by_thread=19303638 19456173 19625072 19617310 19513133 19301800 19420271 19578367 19765882 19918465 19507407 19472169 19496654 19593727 19749949 19739134 19742900 19559988 19296923 19448811 19695515 19687502 19366615 19315288 19116742 19407304 19601187 19697016 19651043 19349829 19446584 19594003 19793222 19897833 19595424 19527442 19520703 19620419 19792130 19688511 19516763 19488471 19329887 19485879 19764708 19749012 19517456 19248916 sum_num_searches_total=938573177 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=19498409 19652284 19823157 19814732 19710420 19496735 19616095 19776200 19965089 20119413 19704470 19669421 19693947 19792391 19949397 19938668 19943077 19757459 19491996 19644628 19894448 19885887 19562699 19511319 19309721 19603266 19799355 19895482 19849169 19545035 19642931 19791602 19993248 20098210 19792911 19724342 19716945 19818801 19992064 19886942 19714251 19685774 19524653 19682511 19963803 19948147 19714545 19442951 average_num_operations_total=19751020 stdev_num_operations_total=26023 sum_num_operations_total=948049000 min_num_operations_total=19309721 max_num_operations_total=20119413 first_time_thread_terminate_by_thread=10000156 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000156 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000156 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000156 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 min_time_thread_terminate_total=10000156 max_time_thread_terminate_total=10000157 first_time_thread_start_by_thread=2 3 2 2 2 2 3 2 2 3 3 2 2 2 2 2 2 2 3 3 2 3 3 3 2 3 2 2 2 2 3 2 2 3 3 2 2 2 2 2 2 2 3 3 2 3 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=10003419083 threads_final_size=99885 final_keysum=10003419083 final_size=99885 validate_result=success Validation OK. Structural validation OK. total_find=938573177 total_rq=0 total_inserts=4736686 total_deletes=4739137 total_updates=9475823 total_queries=938573177 total_ops=948049000 find_throughput=93857317 rq_throughput=0 update_throughput=947582 query_throughput=93857317 total_throughput=94804900 total find : 938573177 total rq : 0 total inserts : 4736686 total deletes : 4739137 total updates : 9475823 total queries : 938573177 total ops : 948049000 find throughput : 93857317 rq throughput : 0 update throughput : 947582 query throughput : 93857317 total throughput : 94804900 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=9.57327 PAPI_L3_TCM=0.000321061 PAPI_TOT_CYC=1412 PAPI_TOT_INS=319.763 garbage=46951275148404 total_execution_walltime=11.41s REALTIME_MAIN_END_PERF_FORMAT=6981896.112485206 [time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=78032, mem_maxresident_kb=313116, user_cputime=559.51, sys_cputime=2.45, percent_cpu=4871% timeout=false algorithm=ellen_ext_bst_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