__step=000418
__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 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=data000418.txt
__path_data=data/data000418.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983904.494950414
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=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=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.007s
prefilling_size=5090700 prefilling_elapsed_millis=1.001s
prefilling_size=5376618 prefilling_elapsed_millis=1.101s
prefilling_size=5644122 prefilling_elapsed_millis=1.201s
prefilling_size=5892722 prefilling_elapsed_millis=1.301s
prefilling_size=6125226 prefilling_elapsed_millis=1.402s
prefilling_size=6343347 prefilling_elapsed_millis=1.502s
prefilling_size=6547746 prefilling_elapsed_millis=1.602s
prefilling_size=6739327 prefilling_elapsed_millis=1.702s
prefilling_size=6917836 prefilling_elapsed_millis=1.802s
prefilling_size=7087410 prefilling_elapsed_millis=1.903s
prefilling_size=7243343 prefilling_elapsed_millis=2.003s
prefilling_size=7392614 prefilling_elapsed_millis=2.103s
prefilling_size=7530394 prefilling_elapsed_millis=2.203s
prefilling_size=7662787 prefilling_elapsed_millis=2.303s
prefilling_size=7786874 prefilling_elapsed_millis=2.404s
prefilling_size=7904929 prefilling_elapsed_millis=2.504s
prefilling_size=8015438 prefilling_elapsed_millis=2.604s
prefilling_size=8119385 prefilling_elapsed_millis=2.704s
prefilling_size=8218261 prefilling_elapsed_millis=2.804s
prefilling_size=8311516 prefilling_elapsed_millis=2.905s
prefilling_size=8399394 prefilling_elapsed_millis=3.005s
prefilling_size=8482190 prefilling_elapsed_millis=3.105s
prefilling_size=8559940 prefilling_elapsed_millis=3.205s
prefilling_size=8634286 prefilling_elapsed_millis=3.305s
prefilling_size=8704238 prefilling_elapsed_millis=3.406s
prefilling_size=8770449 prefilling_elapsed_millis=3.506s
prefilling_size=8833034 prefilling_elapsed_millis=3.606s
prefilling_size=8892487 prefilling_elapsed_millis=3.706s
prefilling_size=8949723 prefilling_elapsed_millis=3.807s
prefilling_size=9003754 prefilling_elapsed_millis=3.907s
prefilling_size=9055674 prefilling_elapsed_millis=4.007s
prefilling_size=9103723 prefilling_elapsed_millis=4.107s
prefilling_size=9148308 prefilling_elapsed_millis=4.207s
prefilling_size=9191402 prefilling_elapsed_millis=4.308s
prefilling_size=9232212 prefilling_elapsed_millis=4.408s
prefilling_size=9269833 prefilling_elapsed_millis=4.508s
prefilling_size=9306490 prefilling_elapsed_millis=4.608s
prefilling_size=9341300 prefilling_elapsed_millis=4.708s
prefilling_size=9374331 prefilling_elapsed_millis=4.809s
prefilling_size=9406169 prefilling_elapsed_millis=4.909s
prefilling_size=9437359 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 562628 keys with 48 threads...
tid=0 thread_prefill_amount=191636 percent_done_estimate=1634.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=195802 percent_done_estimate=1670.5 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0994743s
prefilling_size=10000000 prefilling_elapsed_millis=0.099s
finished prefilling to size 10000000 for expected size 10000000 keysum=100008081707059, performing 58865538 updates; total_prefilling_elapsed_ms=99 ms)
pref_size=10000000
pref_millis=99
prefill_elapsed_ms=100
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=27264
4NodeIxPvE_limbo_details=8462 10652 8150 

global_epoch_counter=2975

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=12702
9SCXRecordIxPvE_limbo_details=3959 4836 3907 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983909.896158876
REALTIME_END_PERF_FORMAT=6983919.896237922

###############################################################################
################################## 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 65532 130765 257609 484991 832415 1247939 1589647 1695538 1498905 1085064 630003 284943 97676 24017 4097 410 19 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 4 299 3921 30227 137567 416891 906231 1483756 1892171 1912746 1540125 975063 472210 171335 43937 7784 801 38
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131064 261530 515218 969982 1664830 2495878 3179294 3391076 2997810 2170128 1260006 569886 195352 48034 8194 820 38
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 4 299 3921 30227 137567 416891 906231 1483756 1892171 1912746 1540125 975063 472210 171335 43937 7784 801 38
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.99772 1.98501 1.94133 1.85818 1.74959 1.63691 1.53331 1.44201 1.36195 1.29031 1.22614 1.1714 1.12294 1.08529 1.05004 1.02317 1

tree_stats_height=34
tree_stats_numInternals=9995105
tree_stats_numLeaves=9995106
tree_stats_numNodes=19990211
tree_stats_numKeys=9995106

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

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7339584 14645680 28852208 54318992 93230480 139769168 178040464 189900256 167877360 121527168 70560336 31913616 10939712 2689904 458864 45920 2128
tree_stats_sizeInBytes=1119451816

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

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:4 2:21 4:295 8:24195 16:210545 32:228751 64:16069 128:113 256:3
    [2^00, 2^01]: 4
    (2^01, 2^02]: 21
    (2^02, 2^03]: 295
    (2^03, 2^04]: 24195
    (2^04, 2^05]: 210545
    (2^05, 2^06]: 228751
    (2^06, 2^07]: 16069
    (2^07, 2^08]: 113
    (2^08, 2^09]: 3
sum_limbo_reclamation_event_size_total=16805824
count_limbo_reclamation_event_size_by_thread=10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 9998 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 9999 10000 9999 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000
count_limbo_reclamation_event_size_total=479996
sum_limbo_reclamation_event_count_total=808572
first_thread_announced_epoch_by_thread=22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794 22794
sum_num_inserts_by_thread=191892 192869 194308 194858 194306 192413 192942 194324 196335 196070 194187 192265 193667 194063 195698 195639 193748 192625 191607 192899 194792 195161 193399 191272 190943 193215 194205 194422 193202 190824 192903 194319 196961 195993 194025 192237 193193 193693 196058 196060 194844 193096 191755 192235 194595 195375 193068 191153
sum_num_inserts_total=9299713
sum_num_deletes_by_thread=192232 193236 195298 195419 193307 191263 192219 192508 195939 196214 194364 192462 193328 194417 196023 195884 194224 193866 191483 192750 193615 195015 192613 191856 192797 193917 194795 195509 194136 192309 192898 193469 196113 195023 193734 192540 193747 195171 194938 195993 194427 193166 190673 193608 193845 196018 192944 191564
sum_num_deletes_total=9302869
sum_num_searches_by_thread=3459156 3483283 3508739 3507416 3489390 3453289 3468247 3485543 3524429 3527197 3484905 3463971 3481753 3499191 3519610 3522350 3501115 3471653 3451319 3476028 3499156 3511776 3470763 3448027 3456822 3477869 3509518 3504021 3494515 3450741 3469018 3484485 3527158 3528346 3486965 3463251 3477134 3497066 3521203 3522119 3497121 3475775 3453575 3473892 3494673 3512456 3474175 3443835
sum_num_searches_total=167404039
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3843280 3869388 3898345 3897693 3877003 3836965 3853408 3872375 3916703 3919481 3873456 3848698 3868748 3887671 3911331 3913873 3889087 3858144 3834409 3861677 3887563 3901952 3856775 3831155 3840562 3865001 3898518 3893952 3881853 3833874 3854819 3872273 3920232 3919362 3874724 3848028 3864074 3885930 3912199 3914172 3886392 3862037 3836003 3859735 3883113 3903849 3860187 3826552
average_num_operations_total=3875137
stdev_num_operations_total=3961
sum_num_operations_total=186006621
min_num_operations_total=3826552
max_num_operations_total=3920232
first_time_thread_terminate_by_thread=10000136 10000079 10000079 10000079 10000080 10000079 10000080 10000079 10000079 10000080 10000079 10000078 10000079 10000079 10000079 10000080 10000079 10000078 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000078 10000079 10000079 10000078 10000079 10000080 10000078 10000079 10000079 10000079 10000079 10000079 10000079
min_time_thread_terminate_total=10000078
max_time_thread_terminate_total=10000136
first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99962178172165
threads_final_size=9995106
final_keysum=99962178172165
final_size=9995106
validate_result=success
Validation OK.
Structural validation OK.

total_find=167404039
total_rq=0
total_inserts=9299713
total_deletes=9302869
total_updates=18602582
total_queries=167404039
total_ops=186006621
find_throughput=16740403
rq_throughput=0
update_throughput=1860258
query_throughput=16740403
total_throughput=18600662


total find                    : 167404039
total rq                      : 0
total inserts                 : 9299713
total deletes                 : 9302869
total updates                 : 18602582
total queries                 : 167404039
total ops                     : 186006621
find throughput               : 16740403
rq throughput                 : 0
update throughput             : 1860258
query throughput              : 16740403
total throughput              : 18600662

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=35.9709
PAPI_L3_TCM=12.5216
PAPI_TOT_CYC=7194.88
PAPI_TOT_INS=435.094
garbage=837360730319487
total_execution_walltime=15.642s
REALTIME_MAIN_END_PERF_FORMAT=6983920.150852779
[time_cmd_output] time_elapsed_sec=15.94, faults_major=0, faults_minor=607013, mem_maxresident_kb=2429168, user_cputime=740.96, sys_cputime=5.82, percent_cpu=4682%
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