__step=000753
__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=data000753.txt
__path_data=data/data000753.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6988018.667288553
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.004s
prefilling_size=5133566 prefilling_elapsed_millis=1.001s
prefilling_size=5420630 prefilling_elapsed_millis=1.101s
prefilling_size=5686208 prefilling_elapsed_millis=1.201s
prefilling_size=5935642 prefilling_elapsed_millis=1.301s
prefilling_size=6166354 prefilling_elapsed_millis=1.402s
prefilling_size=6381521 prefilling_elapsed_millis=1.502s
prefilling_size=6584297 prefilling_elapsed_millis=1.602s
prefilling_size=6774429 prefilling_elapsed_millis=1.702s
prefilling_size=6952170 prefilling_elapsed_millis=1.802s
prefilling_size=7119237 prefilling_elapsed_millis=1.903s
prefilling_size=7277152 prefilling_elapsed_millis=2.003s
prefilling_size=7425773 prefilling_elapsed_millis=2.103s
prefilling_size=7565159 prefilling_elapsed_millis=2.203s
prefilling_size=7696002 prefilling_elapsed_millis=2.303s
prefilling_size=7820250 prefilling_elapsed_millis=2.404s
prefilling_size=7936852 prefilling_elapsed_millis=2.504s
prefilling_size=8044645 prefilling_elapsed_millis=2.604s
prefilling_size=8148390 prefilling_elapsed_millis=2.704s
prefilling_size=8247984 prefilling_elapsed_millis=2.804s
prefilling_size=8339839 prefilling_elapsed_millis=2.905s
prefilling_size=8427210 prefilling_elapsed_millis=3.005s
prefilling_size=8511527 prefilling_elapsed_millis=3.105s
prefilling_size=8588922 prefilling_elapsed_millis=3.205s
prefilling_size=8661499 prefilling_elapsed_millis=3.305s
prefilling_size=8730766 prefilling_elapsed_millis=3.406s
prefilling_size=8796131 prefilling_elapsed_millis=3.506s
prefilling_size=8857565 prefilling_elapsed_millis=3.606s
prefilling_size=8915942 prefilling_elapsed_millis=3.706s
prefilling_size=8972328 prefilling_elapsed_millis=3.806s
prefilling_size=9024985 prefilling_elapsed_millis=3.907s
prefilling_size=9075475 prefilling_elapsed_millis=4.007s
prefilling_size=9121932 prefilling_elapsed_millis=4.107s
prefilling_size=9166039 prefilling_elapsed_millis=4.207s
prefilling_size=9208599 prefilling_elapsed_millis=4.307s
prefilling_size=9248546 prefilling_elapsed_millis=4.408s
prefilling_size=9287094 prefilling_elapsed_millis=4.508s
prefilling_size=9322956 prefilling_elapsed_millis=4.608s
prefilling_size=9356927 prefilling_elapsed_millis=4.708s
prefilling_size=9389340 prefilling_elapsed_millis=4.808s
prefilling_size=9420886 prefilling_elapsed_millis=4.909s
prefilling_size=9450551 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 549405 keys with 48 threads...
tid=0 thread_prefill_amount=204134 percent_done_estimate=1783.5 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=208300 percent_done_estimate=1819.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=212466 percent_done_estimate=1856.3 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0949024s
prefilling_size=10000000 prefilling_elapsed_millis=0.095s
finished prefilling to size 10000000 for expected size 10000000 keysum=99995742547484, performing 59279973 updates; total_prefilling_elapsed_ms=95 ms)
pref_size=10000000
pref_millis=95
prefill_elapsed_ms=95
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=28138
4NodeIxPvE_limbo_details=10933 7564 9641 

global_epoch_counter=2995

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=13163
9SCXRecordIxPvE_limbo_details=5128 3502 4533 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6988024.46623517
REALTIME_END_PERF_FORMAT=6988034.46699278

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

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65516 130677 257550 488435 852761 1304795 1681772 1781051 1518730 1023615 536021 213461 63342 13837 2088 209 5 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 16 355 3804 26665 124109 400727 927818 1582493 2043372 2013845 1511209 858581 363580 112847 25586 3967 413 10
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65532 131032 261354 515100 976870 1705522 2609590 3363544 3562102 3037460 2047230 1072042 426922 126684 27674 4176 418 10
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 16 355 3804 26665 124109 400727 927818 1582493 2043372 2013845 1511209 858581 363580 112847 25586 3967 413 10
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.99976 1.99729 1.98545 1.94823 1.87295 1.76504 1.64446 1.52952 1.42636 1.337 1.26183 1.19912 1.14837 1.10922 1.07545 1.05005 1.01196 1

tree_stats_height=34
tree_stats_numInternals=9999398
tree_stats_numLeaves=9999399
tree_stats_numNodes=19998797
tree_stats_numKeys=9999399

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

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3669792 7337792 14635824 28845600 54704720 95509232 146137040 188358464 199477712 170097760 114644880 60034352 23907632 7094304 1549744 233856 23408 560
tree_stats_sizeInBytes=1119932632

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

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:29 2:25 4:4 8:10 16:21 32:80 64:439 128:236786 256:240922 512:1645
    [2^00, 2^01]: 29
    (2^01, 2^02]: 25
    (2^02, 2^03]: 4
    (2^03, 2^04]: 10
    (2^04, 2^05]: 21
    (2^05, 2^06]: 80
    (2^06, 2^07]: 439
    (2^07, 2^08]: 236786
    (2^08, 2^09]: 240922
    (2^09, 2^10]: 1645
sum_limbo_reclamation_event_size_total=153248245
count_limbo_reclamation_event_size_by_thread=10000 9998 10000 10000 10000 9998 10000 9998 9999 9996 10000 10000 10000 10000 9999 9997 9998 10000 10000 10000 10000 10000 10000 10000 9997 10000 9999 10000 10000 10000 9999 10000 9998 9996 10000 10000 10000 10000 10000 9998 9998 9998 10000 9996 10000 10000 10000 9999
count_limbo_reclamation_event_size_total=479961
sum_limbo_reclamation_event_count_total=499078
first_thread_announced_epoch_by_thread=16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386 16386
sum_num_inserts_by_thread=1092417 1098056 1106645 1105987 1100903 1088238 1099106 1100363 1109558 1107573 1099322 1094634 1099815 1103005 1108684 1108023 1101342 1095447 1089397 1096376 1102346 1105027 1093104 1086859 1072711 1098679 1105875 1104519 1102674 1091724 1094208 1100838 1108741 1107096 1098024 1094842 1098901 1101449 1107726 1107185 1100257 1092574 1091425 1095643 1103244 1105004 1094950 1083943
sum_num_inserts_total=52754459
sum_num_deletes_by_thread=1095494 1099736 1106569 1104834 1100021 1090709 1094962 1100689 1108983 1108782 1098466 1092862 1100197 1104625 1107892 1109346 1101729 1096049 1089898 1097413 1104495 1105033 1096700 1087899 1072612 1099884 1106121 1105827 1101554 1090242 1095209 1101789 1108668 1110066 1097160 1092516 1098356 1102275 1107912 1107662 1101188 1097174 1091373 1094365 1102527 1105299 1094873 1086420
sum_num_deletes_total=52774455
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2187911 2197792 2213214 2210821 2200924 2178947 2194068 2201052 2218541 2216355 2197788 2187496 2200012 2207630 2216576 2217369 2203071 2191496 2179295 2193789 2206841 2210060 2189804 2174758 2145323 2198563 2211996 2210346 2204228 2181966 2189417 2202627 2217409 2217162 2195184 2187358 2197257 2203724 2215638 2214847 2201445 2189748 2182798 2190008 2205771 2210303 2189823 2170363
average_num_operations_total=2198519
stdev_num_operations_total=2155
sum_num_operations_total=105528914
min_num_operations_total=2145323
max_num_operations_total=2218541
first_time_thread_terminate_by_thread=10000125 10000075 10000075 10000075 10000077 10000075 10000075 10000075 10000077 10000090 10000077 10000076 10000075 10000077 10000077 10000076 10000075 10000075 10000076 10000075 10000076 10000075 10000075 10000076 10000077 10000075 10000075 10000077 10000075 10000075 10000075 10000075 10000075 10000076 10000076 10000075 10000076 10000075 10000077 10000087 10000075 10000090 10000075 10000075 10000082 10000075 10000076 10000075
min_time_thread_terminate_total=10000075
max_time_thread_terminate_total=10000125
first_time_thread_start_by_thread=11 6 5 6 6 6 6 5 5 5 5 6 5 5 6 5 5 6 6 6 6 6 5 6 4 6 5 6 6 6 6 5 5 5 5 6 5 5 6 5 5 6 6 6 6 6 5 6
min_time_thread_start_total=4
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=99991388422412
threads_final_size=9999399
final_keysum=99991388422412
final_size=9999399
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=52754459
total_deletes=52774455
total_updates=105528914
total_queries=0
total_ops=105528914
find_throughput=0
rq_throughput=0
update_throughput=10552891
query_throughput=0
total_throughput=10552891


total find                    : 0
total rq                      : 0
total inserts                 : 52754459
total deletes                 : 52774455
total updates                 : 105528914
total queries                 : 0
total ops                     : 105528914
find throughput               : 0
rq throughput                 : 0
update throughput             : 10552891
query throughput              : 0
total throughput              : 10552891

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=70.069
PAPI_L3_TCM=24.3111
PAPI_TOT_CYC=12559.6
PAPI_TOT_INS=2149.86
garbage=0
total_execution_walltime=15.637s
REALTIME_MAIN_END_PERF_FORMAT=6988034.305583434
[time_cmd_output] time_elapsed_sec=16.02, faults_major=0, faults_minor=1061616, mem_maxresident_kb=4136424, user_cputime=734.98, sys_cputime=8.31, 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=1