__step=000026
__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 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000026.txt
__path_data=data/data000026.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219762.912937245
binary=./brown_sigouin_int_avl_tm_auto.debra.tl2
parsed custom binding: 0-63,128-191,64-127,192-255
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=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.023s
prefilling_size=4376114 prefilling_elapsed_millis=1s
prefilling_size=4690808 prefilling_elapsed_millis=1.101s
prefilling_size=4989972 prefilling_elapsed_millis=1.201s
prefilling_size=5267433 prefilling_elapsed_millis=1.301s
prefilling_size=5533388 prefilling_elapsed_millis=1.401s
prefilling_size=5783194 prefilling_elapsed_millis=1.501s
prefilling_size=6017087 prefilling_elapsed_millis=1.601s
prefilling_size=6238651 prefilling_elapsed_millis=1.701s
prefilling_size=6447782 prefilling_elapsed_millis=1.801s
prefilling_size=6644468 prefilling_elapsed_millis=1.901s
prefilling_size=6828633 prefilling_elapsed_millis=2.002s
prefilling_size=7003899 prefilling_elapsed_millis=2.102s
prefilling_size=7169270 prefilling_elapsed_millis=2.202s
prefilling_size=7326503 prefilling_elapsed_millis=2.302s
prefilling_size=7474400 prefilling_elapsed_millis=2.402s
prefilling_size=7614683 prefilling_elapsed_millis=2.502s
prefilling_size=7747345 prefilling_elapsed_millis=2.602s
prefilling_size=7871874 prefilling_elapsed_millis=2.702s
prefilling_size=7991799 prefilling_elapsed_millis=2.803s
prefilling_size=8104491 prefilling_elapsed_millis=2.903s
prefilling_size=8210352 prefilling_elapsed_millis=3.003s
prefilling_size=8312051 prefilling_elapsed_millis=3.103s
prefilling_size=8407440 prefilling_elapsed_millis=3.203s
prefilling_size=8496838 prefilling_elapsed_millis=3.304s
prefilling_size=8580004 prefilling_elapsed_millis=3.404s
prefilling_size=8659932 prefilling_elapsed_millis=3.504s
prefilling_size=8732671 prefilling_elapsed_millis=3.604s
prefilling_size=8801482 prefilling_elapsed_millis=3.704s
prefilling_size=8866724 prefilling_elapsed_millis=3.804s
prefilling_size=8930832 prefilling_elapsed_millis=3.904s
prefilling_size=8990792 prefilling_elapsed_millis=4.004s
prefilling_size=9047844 prefilling_elapsed_millis=4.105s
prefilling_size=9102565 prefilling_elapsed_millis=4.205s
prefilling_size=9152432 prefilling_elapsed_millis=4.305s
prefilling_size=9199164 prefilling_elapsed_millis=4.405s
prefilling_size=9243104 prefilling_elapsed_millis=4.505s
prefilling_size=9284888 prefilling_elapsed_millis=4.605s
prefilling_size=9325351 prefilling_elapsed_millis=4.705s
prefilling_size=9363033 prefilling_elapsed_millis=4.805s
prefilling_size=9398975 prefilling_elapsed_millis=4.905s
prefilling_size=9433587 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 566390 keys with 64 threads...
tid=0 thread_prefill_amount=156250 percent_done_estimate=1765.6 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=159375 percent_done_estimate=1800.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=162500 percent_done_estimate=1836.2 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0995023s
prefilling_size=10000000 prefilling_elapsed_millis=0.104s
finished prefilling to size 10000000 for expected size 10000000 keysum=100003990925592, performing 58844427 updates; total_prefilling_elapsed_ms=104 ms)
pref_size=10000000
pref_millis=104
prefill_elapsed_ms=104
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=219768.182882103
REALTIME_END_PERF_FORMAT=219778.182953570

###############################################################################
################################## 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 (64 threads)...
partitioned into 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.028s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262101 520607 974466 1466506 1405066 693813 146493 10620 146 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 39 3291 57920 397989 1189585 1551388 858035 185780 13281 177
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262140 523898 1032386 1864495 2594651 2245201 1004528 196400 13427 177
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262140 523898 1032386 1864495 2594651 2245201 1004528 196400 13427 177
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99869 1.97687 1.86211 1.60507 1.32379 1.13839 1.04968 1.01429 1.00231 1

tree_stats_height=28
tree_stats_numInternals=5741960
tree_stats_numLeaves=4257486
tree_stats_numNodes=9999446
tree_stats_numKeys=9999446

tree_stats_avgDegreeInternal=1.74147
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42577
tree_stats_avgKeyDepth=21.6776


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=394380
first_thread_announced_epoch_by_thread=15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378 15378
sum_num_inserts_by_thread=189617 189794 188858 189332 189584 188835 188109 188568 189404 188648 187731 188605 189163 188936 188624 188390 189165 190160 188575 188060 188569 188979 188736 188087 187519 188292 188419 188839 189237 189106 188188 188434 190704 189878 189926 189117 189557 189874 189696 190189 190206 189546 190428 190115 190263 191068 189147 189256 188372 188955 187264 188522 187962 188462 188704 189395 188587 188460 188542 189083 189090 189279 189034 189136
sum_num_inserts_total=12098380
sum_num_deletes_by_thread=189438 189913 189475 188958 189895 189700 189083 188605 188626 189058 188242 189223 189777 188899 188192 189082 189605 189145 188498 187771 188831 190073 188605 187968 188131 188409 189009 188190 189227 189338 188245 187692 190033 189865 189316 189575 189735 190667 189647 188698 190694 189865 189287 189591 190188 189992 189527 189230 187922 188082 188273 188506 188477 188157 189116 189064 189757 189224 188515 188761 190071 189327 188648 189102
sum_num_deletes_total=12101815
sum_num_searches_by_thread=3416786 3416879 3409416 3400320 3406194 3409739 3400436 3402994 3397445 3393008 3392055 3398916 3409215 3405671 3398147 3398436 3415411 3408230 3391064 3392365 3399229 3399412 3390392 3389538 3388702 3402825 3392520 3390438 3403900 3400249 3394499 3395883 3421370 3420353 3411759 3412680 3419098 3416800 3410600 3412691 3421953 3426613 3417409 3414795 3423407 3423457 3412660 3402419 3388936 3393400 3379811 3382341 3391972 3389180 3398673 3398950 3401851 3404575 3394527 3396825 3408449 3406982 3405983 3409608
sum_num_searches_total=217830441
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3795841 3796586 3787749 3778610 3785673 3788274 3777628 3780167 3775475 3770714 3768028 3776744 3788155 3783506 3774963 3775908 3794181 3787535 3768137 3768196 3776629 3778464 3767733 3765593 3764352 3779526 3769948 3767467 3782364 3778693 3770932 3772009 3802107 3800096 3791001 3791372 3798390 3797341 3789943 3791578 3802853 3806024 3797124 3794501 3803858 3804517 3791334 3780905 3765230 3770437 3755348 3759369 3768411 3765799 3776493 3777409 3780195 3782259 3771584 3774669 3787610 3785588 3783665 3787846
average_num_operations_total=3781728
stdev_num_operations_total=1541
sum_num_operations_total=242030636
min_num_operations_total=3755348
max_num_operations_total=3806024
first_time_thread_terminate_by_thread=10000115 10000071 10000071 10000071 10000073 10000072 10000072 10000072 10000098 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000071 10000071 10000071 10000071 10000074 10000074 10000074 10000074 10000073 10000073 10000073 10000073 10000075 10000076 10000075 10000075 10000073 10000073 10000073 10000073
min_time_thread_terminate_total=10000071
max_time_thread_terminate_total=10000115
first_time_thread_start_by_thread=13 4 4 3 4 4 4 4 6 6 6 6 5 5 5 5 6 6 6 6 5 5 5 5 6 6 6 6 5 5 5 5 5 6 5 5 5 5 5 5 7 7 7 7 7 7 7 7 8 8 8 8 7 7 7 7 7 7 7 7 8 8 8 8
min_time_thread_start_total=3
max_time_thread_start_total=13
sum_duration_all_ops_total=

threads_final_keysum=100018830656344
threads_final_size=9999446
final_keysum=100018830656344
final_size=9999446
validate_result=success
Validation OK.
Structural validation OK.

total_find=217830441
total_rq=0
total_inserts=12098380
total_deletes=12101815
total_updates=24200195
total_queries=217830441
total_ops=242030636
find_throughput=21783044
rq_throughput=0
update_throughput=2420019
query_throughput=21783044
total_throughput=24203063


total find                    : 217830441
total rq                      : 0
total inserts                 : 12098380
total deletes                 : 12101815
total updates                 : 24200195
total queries                 : 217830441
total ops                     : 242030636
find throughput               : 21783044
rq throughput                 : 0
update throughput             : 2420019
query throughput              : 21783044
total throughput              : 24203063

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=373976468341069
total_execution_walltime=15.436s
REALTIME_MAIN_END_PERF_FORMAT=219778.349650246
TL2 system shutdown:
  GCLOCK=0x27812A4 Starts=307191221 Aborts=7400827
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.63, faults_major=0, faults_minor=702101, mem_maxresident_kb=1559412, user_cputime=936.38, sys_cputime=44.11, percent_cpu=6273%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.tl2
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2