__step=000754
__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 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000754.txt
__path_data=data/data000754.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6988034.760632507
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=50
DEL_FRAC=50
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=50.0 50.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=5101007 prefilling_elapsed_millis=1.001s
prefilling_size=5386454 prefilling_elapsed_millis=1.101s
prefilling_size=5651851 prefilling_elapsed_millis=1.201s
prefilling_size=5899110 prefilling_elapsed_millis=1.301s
prefilling_size=6128704 prefilling_elapsed_millis=1.402s
prefilling_size=6340782 prefilling_elapsed_millis=1.502s
prefilling_size=6542681 prefilling_elapsed_millis=1.602s
prefilling_size=6732094 prefilling_elapsed_millis=1.702s
prefilling_size=6908595 prefilling_elapsed_millis=1.802s
prefilling_size=7075587 prefilling_elapsed_millis=1.903s
prefilling_size=7233988 prefilling_elapsed_millis=2.003s
prefilling_size=7380751 prefilling_elapsed_millis=2.103s
prefilling_size=7519288 prefilling_elapsed_millis=2.203s
prefilling_size=7650176 prefilling_elapsed_millis=2.303s
prefilling_size=7774516 prefilling_elapsed_millis=2.404s
prefilling_size=7889602 prefilling_elapsed_millis=2.504s
prefilling_size=8000336 prefilling_elapsed_millis=2.604s
prefilling_size=8104754 prefilling_elapsed_millis=2.704s
prefilling_size=8202768 prefilling_elapsed_millis=2.804s
prefilling_size=8294786 prefilling_elapsed_millis=2.905s
prefilling_size=8383096 prefilling_elapsed_millis=3.005s
prefilling_size=8464578 prefilling_elapsed_millis=3.105s
prefilling_size=8542421 prefilling_elapsed_millis=3.205s
prefilling_size=8616927 prefilling_elapsed_millis=3.305s
prefilling_size=8686530 prefilling_elapsed_millis=3.406s
prefilling_size=8752517 prefilling_elapsed_millis=3.506s
prefilling_size=8815534 prefilling_elapsed_millis=3.606s
prefilling_size=8875301 prefilling_elapsed_millis=3.706s
prefilling_size=8931113 prefilling_elapsed_millis=3.806s
prefilling_size=8984502 prefilling_elapsed_millis=3.907s
prefilling_size=9034821 prefilling_elapsed_millis=4.007s
prefilling_size=9083485 prefilling_elapsed_millis=4.107s
prefilling_size=9128909 prefilling_elapsed_millis=4.207s
prefilling_size=9172134 prefilling_elapsed_millis=4.307s
prefilling_size=9212785 prefilling_elapsed_millis=4.408s
prefilling_size=9252152 prefilling_elapsed_millis=4.508s
prefilling_size=9289238 prefilling_elapsed_millis=4.608s
prefilling_size=9324616 prefilling_elapsed_millis=4.708s
prefilling_size=9357677 prefilling_elapsed_millis=4.808s
prefilling_size=9388611 prefilling_elapsed_millis=4.909s
prefilling_size=9418408 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 581595 keys with 48 threads...
tid=0 thread_prefill_amount=179138 percent_done_estimate=1478.5 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=183304 percent_done_estimate=1512.8 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=187470 percent_done_estimate=1547.2 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.100891s
prefilling_size=10000000 prefilling_elapsed_millis=0.101s
finished prefilling to size 10000000 for expected size 10000000 keysum=100005658691658, performing 59190838 updates; total_prefilling_elapsed_ms=101 ms)
pref_size=10000000
pref_millis=101
prefill_elapsed_ms=101
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=27958
4NodeIxPvE_limbo_details=11006 7646 9306 

global_epoch_counter=2990

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=12824
9SCXRecordIxPvE_limbo_details=5051 3535 4238 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6988040.162007271
REALTIME_END_PERF_FORMAT=6988050.162086892

###############################################################################
################################## 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.085s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65523 130750 257990 489939 856194 1310991 1692791 1792728 1523857 1018327 524287 203128 57843 11610 1603 136 7 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 13 296 3510 26041 123684 401397 929191 1592854 2061599 2029387 1512367 845446 348413 104076 21617 3070 265 14
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131046 261500 515980 979878 1712388 2621982 3385582 3585456 3047714 2036654 1048574 406256 115686 23220 3206 272 14
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 13 296 3510 26041 123684 401397 929191 1592854 2061599 2029387 1512367 845446 348413 104076 21617 3070 265 14
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.9998 1.99774 1.98658 1.94953 1.87378 1.76559 1.64562 1.52952 1.42501 1.33413 1.25743 1.19372 1.14238 1.10036 1.06904 1.04242 1.02574 1

tree_stats_height=34
tree_stats_numInternals=10003239
tree_stats_numLeaves=10003240
tree_stats_numNodes=20006479
tree_stats_numKeys=10003240

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=24.4353

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7338576 14644000 28894880 54873168 95893728 146830992 189592592 200785536 170671984 114052624 58720144 22750336 6478416 1300320 179536 15232 784
tree_stats_sizeInBytes=1120362824

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=8198

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:9 2:7 4:7 8:12 16:21 32:89 64:588 128:236770 256:240857 512:1629
    [2^00, 2^01]: 9
    (2^01, 2^02]: 7
    (2^02, 2^03]: 7
    (2^03, 2^04]: 12
    (2^04, 2^05]: 21
    (2^05, 2^06]: 89
    (2^06, 2^07]: 588
    (2^07, 2^08]: 236770
    (2^08, 2^09]: 240857
    (2^09, 2^10]: 1629
sum_limbo_reclamation_event_size_total=153053501
count_limbo_reclamation_event_size_by_thread=10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 9998 10000 10000 10000 10000 10000 10000 10000 10000 10000 9998 9999 10000 10000 10000 10000 9999 10000 10000 10000 10000 10000 10000 10000 9998 10000 10000 10000 10000 10000 9998 9999 10000 10000 10000 10000 10000
count_limbo_reclamation_event_size_total=479989
sum_limbo_reclamation_event_count_total=500060
first_thread_announced_epoch_by_thread=16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396 16396
sum_num_inserts_by_thread=1093149 1098189 1107635 1106284 1103433 1092985 1097419 1100097 1108094 1111753 1099127 1094367 1100392 1101858 1106618 1107357 1104279 1076857 1094468 1094280 1101501 1105029 1092335 1087259 1096421 1100039 1108118 1105470 1103881 1093924 1096396 1102615 1111974 1109886 1100871 1093109 1096681 1104958 1108368 1107457 1103150 1093589 1087537 1095578 1103445 1106053 1095521 1088048
sum_num_inserts_total=52797854
sum_num_deletes_by_thread=1091708 1097561 1107516 1106805 1104021 1094316 1096554 1100104 1109346 1109550 1100165 1091667 1099810 1102741 1107816 1108185 1104846 1076617 1092555 1095219 1101589 1106281 1093005 1088052 1097194 1098133 1106304 1104753 1104368 1094232 1096422 1099659 1108911 1109900 1100261 1092764 1097887 1103642 1106947 1107644 1103245 1094709 1092349 1098434 1104191 1106211 1094277 1088236
sum_num_deletes_total=52796702
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2184857 2195750 2215151 2213089 2207454 2187301 2193973 2200201 2217440 2221303 2199292 2186034 2200202 2204599 2214434 2215542 2209125 2153474 2187023 2189499 2203090 2211310 2185340 2175311 2193615 2198172 2214422 2210223 2208249 2188156 2192818 2202274 2220885 2219786 2201132 2185873 2194568 2208600 2215315 2215101 2206395 2188298 2179886 2194012 2207636 2212264 2189798 2176284
average_num_operations_total=2199886
stdev_num_operations_total=2076
sum_num_operations_total=105594556
min_num_operations_total=2153474
max_num_operations_total=2221303
first_time_thread_terminate_by_thread=10000130 10000079 10000082 10000079 10000079 10000080 10000081 10000081 10000081 10000079 10000079 10000079 10000089 10000079 10000080 10000080 10000079 10004401 10000079 10000088 10000081 10000079 10000079 10000080 10000080 10000081 10000092 10000079 10000080 10000079 10000081 10000079 10000079 10000079 10000079 10000079 10000086 10000079 10000080 10000080 10000081 10000080 10000090 10000080 10000082 10000081 10000082 10000081
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10004401
first_time_thread_start_by_thread=12 6 6 6 5 6 6 5 5 6 6 6 5 6 5 5 5 5 5 5 6 5 6 6 4 6 6 6 5 6 6 5 5 6 6 6 5 6 5 5 5 5 5 5 6 5 6 6
min_time_thread_start_total=4
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=100043138168528
threads_final_size=10003240
final_keysum=100043138168528
final_size=10003240
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=52797854
total_deletes=52796702
total_updates=105594556
total_queries=0
total_ops=105594556
find_throughput=0
rq_throughput=0
update_throughput=10559455
query_throughput=0
total_throughput=10559455


total find                    : 0
total rq                      : 0
total inserts                 : 52797854
total deletes                 : 52796702
total updates                 : 105594556
total queries                 : 0
total ops                     : 105594556
find throughput               : 0
rq throughput                 : 0
update throughput             : 10559455
query throughput              : 0
total throughput              : 10559455

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=70.0894
PAPI_L3_TCM=24.33
PAPI_TOT_CYC=12546
PAPI_TOT_INS=2149.61
garbage=0
total_execution_walltime=15.654s
REALTIME_MAIN_END_PERF_FORMAT=6988050.416329966
[time_cmd_output] time_elapsed_sec=16.05, faults_major=0, faults_minor=1062466, mem_maxresident_kb=4129956, user_cputime=737.08, sys_cputime=7.67, percent_cpu=4639%
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=2