__step=000065
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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_sigouin_int_avl_tm_auto.debra.tl2 -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000065.txt
__path_data=data/data000065.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993710.789223311
binary=./brown_sigouin_int_avl_tm_auto.debra.tl2
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_sigouin_int_avl_tm_auto
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=20000000
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=48
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


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.005s
prefilling_size=4304252 prefilling_elapsed_millis=1.001s
prefilling_size=4594098 prefilling_elapsed_millis=1.101s
prefilling_size=4866755 prefilling_elapsed_millis=1.201s
prefilling_size=5122938 prefilling_elapsed_millis=1.301s
prefilling_size=5366645 prefilling_elapsed_millis=1.402s
prefilling_size=5597408 prefilling_elapsed_millis=1.502s
prefilling_size=5816355 prefilling_elapsed_millis=1.602s
prefilling_size=6018540 prefilling_elapsed_millis=1.702s
prefilling_size=6207266 prefilling_elapsed_millis=1.803s
prefilling_size=6387389 prefilling_elapsed_millis=1.903s
prefilling_size=6558649 prefilling_elapsed_millis=2.003s
prefilling_size=6722402 prefilling_elapsed_millis=2.103s
prefilling_size=6877743 prefilling_elapsed_millis=2.203s
prefilling_size=7025706 prefilling_elapsed_millis=2.304s
prefilling_size=7165925 prefilling_elapsed_millis=2.404s
prefilling_size=7298866 prefilling_elapsed_millis=2.504s
prefilling_size=7427071 prefilling_elapsed_millis=2.604s
prefilling_size=7547066 prefilling_elapsed_millis=2.704s
prefilling_size=7661038 prefilling_elapsed_millis=2.805s
prefilling_size=7769999 prefilling_elapsed_millis=2.905s
prefilling_size=7874303 prefilling_elapsed_millis=3.005s
prefilling_size=7974547 prefilling_elapsed_millis=3.105s
prefilling_size=8068820 prefilling_elapsed_millis=3.205s
prefilling_size=8159209 prefilling_elapsed_millis=3.306s
prefilling_size=8245091 prefilling_elapsed_millis=3.406s
prefilling_size=8326077 prefilling_elapsed_millis=3.506s
prefilling_size=8404424 prefilling_elapsed_millis=3.606s
prefilling_size=8477652 prefilling_elapsed_millis=3.706s
prefilling_size=8549074 prefilling_elapsed_millis=3.807s
prefilling_size=8615614 prefilling_elapsed_millis=3.907s
prefilling_size=8679601 prefilling_elapsed_millis=4.007s
prefilling_size=8741105 prefilling_elapsed_millis=4.107s
prefilling_size=8798607 prefilling_elapsed_millis=4.207s
prefilling_size=8855886 prefilling_elapsed_millis=4.308s
prefilling_size=8908492 prefilling_elapsed_millis=4.408s
prefilling_size=8958172 prefilling_elapsed_millis=4.508s
prefilling_size=9005911 prefilling_elapsed_millis=4.608s
prefilling_size=9051365 prefilling_elapsed_millis=4.708s
prefilling_size=9095756 prefilling_elapsed_millis=4.809s
prefilling_size=9136611 prefilling_elapsed_millis=4.909s
prefilling_size=9175094 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 824869 keys with 48 threads...
tid=0 thread_prefill_amount=170806 percent_done_estimate=993.9 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=174972 percent_done_estimate=1018.2 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=179138 percent_done_estimate=1042.4 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=183304 percent_done_estimate=1066.7 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.181293s
prefilling_size=10000000 prefilling_elapsed_millis=0.181s
finished prefilling to size 10000000 for expected size 10000000 keysum=99987664561218, performing 53430931 updates; total_prefilling_elapsed_ms=181 ms)
pref_size=10000000
pref_millis=181
prefill_elapsed_ms=181
main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=6993716.254952789
REALTIME_END_PERF_FORMAT=6993726.255032041

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (48 threads)...
partitioned into 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.049s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262026 520022 976975 1482272 1419438 674982 128085 7350 68 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 107 3601 54672 389965 1205768 1585522 841515 162334 9129 84
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523623 1031647 1872237 2625206 2260504 969600 169684 9197 84
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523623 1031647 1872237 2625206 2260504 969600 169684 9197 84
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99796 1.97709 1.8678 1.61046 1.32038 1.13033 1.0429 1.01088 1.00174 1

tree_stats_height=28
tree_stats_numInternals=5733360
tree_stats_numLeaves=4252698
tree_stats_numNodes=9986058
tree_stats_numKeys=9986058

tree_stats_avgDegreeInternal=1.74175
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42586
tree_stats_avgKeyDepth=21.6613


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0
    [2^00, 2^01]: 0
sum_limbo_reclamation_event_size_total=
count_limbo_reclamation_event_size_by_thread=
count_limbo_reclamation_event_size_total=
sum_limbo_reclamation_event_count_total=340751
first_thread_announced_epoch_by_thread=19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156 19156
sum_num_inserts_by_thread=160466 161884 163383 163073 163462 161244 162268 163225 163081 164613 162729 162467 161537 163194 163016 164286 163316 161347 160914 162758 163762 163369 163026 162050 161096 161908 163009 163659 163544 160723 161897 162685 164576 164799 163189 162818 161604 162339 163703 163602 162991 162487 161559 162075 163350 164109 161997 162086
sum_num_inserts_total=7810275
sum_num_deletes_by_thread=160919 162558 162836 163574 163138 160731 162185 162425 164247 163819 162917 161967 162256 162733 163330 163513 162923 162706 161973 162217 162983 163651 162222 161140 161429 161965 163348 163654 163205 160500 161929 162381 163857 164103 162904 162413 162110 162902 162915 163476 163789 162107 161267 162307 163857 164040 163571 161702
sum_num_deletes_total=7808694
sum_num_searches_by_thread=2893808 2919261 2936186 2938783 2935176 2899826 2913256 2925240 2956512 2960752 2932464 2912892 2919139 2926216 2942326 2946356 2936230 2915542 2904135 2918446 2941466 2951947 2925328 2904945 2904203 2914009 2939767 2942626 2936606 2903642 2914972 2925901 2952651 2960501 2939392 2923249 2911556 2934943 2944151 2950602 2942384 2918926 2907675 2916935 2941841 2955635 2926784 2906802
sum_num_searches_total=140571985
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3215193 3243703 3262405 3265430 3261776 3221801 3237709 3250890 3283840 3289184 3258110 3237326 3242932 3252143 3268672 3274155 3262469 3239595 3227022 3243421 3268211 3278967 3250576 3228135 3226728 3237882 3266124 3269939 3263355 3224865 3238798 3250967 3281084 3289403 3265485 3248480 3235270 3260184 3270769 3277680 3269164 3243520 3230501 3241317 3269048 3283784 3252352 3230590
average_num_operations_total=3253978
stdev_num_operations_total=2827
sum_num_operations_total=156190954
min_num_operations_total=3215193
max_num_operations_total=3289403
first_time_thread_terminate_by_thread=10000128 10000079 10000079 10000080 10000080 10000081 10000079 10000079 10000078 10000078 10000078 10000080 10000079 10000080 10000079 10000078 10000080 10000080 10000078 10000078 10000079 10000079 10000079 10000079 10000078 10000080 10000079 10000079 10000083 10000079 10000079 10000079 10000078 10000078 10000080 10000083 10000079 10000079 10000079 10000079 10000080 10000080 10000078 10000078 10000080 10000079 10000079 10000079
min_time_thread_terminate_total=10000078
max_time_thread_terminate_total=10000128
first_time_thread_start_by_thread=12 5 5 5 5 5 5 5 5 6 5 6 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 6 5 6 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=99841303815648
threads_final_size=9986058
final_keysum=99841303815648
final_size=9986058
validate_result=success
Validation OK.
Structural validation OK.

total_find=140571985
total_rq=0
total_inserts=7810275
total_deletes=7808694
total_updates=15618969
total_queries=140571985
total_ops=156190954
find_throughput=14057198
rq_throughput=0
update_throughput=1561896
query_throughput=14057198
total_throughput=15619095


total find                    : 140571985
total rq                      : 0
total inserts                 : 7810275
total deletes                 : 7808694
total updates                 : 15618969
total queries                 : 140571985
total ops                     : 156190954
find throughput               : 14057198
rq throughput                 : 0
update throughput             : 1561896
query throughput              : 14057198
total throughput              : 15619095

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=45.4536
PAPI_L3_TCM=7.6571
PAPI_TOT_CYC=7013.29
PAPI_TOT_INS=2406.75
garbage=704012380797473
total_execution_walltime=15.657s
REALTIME_MAIN_END_PERF_FORMAT=6993726.447520326
TL2 system shutdown:
  GCLOCK=0x3418D12 Starts=209203864 Aborts=1134541
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.86, faults_major=0, faults_minor=610382, mem_maxresident_kb=1378412, user_cputime=696.95, sys_cputime=48.34, percent_cpu=4698%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.tl2
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1