__step=000082
__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 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000082.txt
__path_data=data/data000082.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979779.199298584
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=0.5
DEL_FRAC=0.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=0.5 0.5
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=5114822 prefilling_elapsed_millis=1.001s
prefilling_size=5401579 prefilling_elapsed_millis=1.101s
prefilling_size=5667572 prefilling_elapsed_millis=1.201s
prefilling_size=5914136 prefilling_elapsed_millis=1.301s
prefilling_size=6144550 prefilling_elapsed_millis=1.402s
prefilling_size=6360761 prefilling_elapsed_millis=1.502s
prefilling_size=6564259 prefilling_elapsed_millis=1.602s
prefilling_size=6754139 prefilling_elapsed_millis=1.702s
prefilling_size=6931936 prefilling_elapsed_millis=1.802s
prefilling_size=7098614 prefilling_elapsed_millis=1.903s
prefilling_size=7254499 prefilling_elapsed_millis=2.003s
prefilling_size=7401974 prefilling_elapsed_millis=2.103s
prefilling_size=7541573 prefilling_elapsed_millis=2.203s
prefilling_size=7672508 prefilling_elapsed_millis=2.303s
prefilling_size=7796655 prefilling_elapsed_millis=2.404s
prefilling_size=7913172 prefilling_elapsed_millis=2.504s
prefilling_size=8023743 prefilling_elapsed_millis=2.604s
prefilling_size=8127014 prefilling_elapsed_millis=2.704s
prefilling_size=8227027 prefilling_elapsed_millis=2.804s
prefilling_size=8319773 prefilling_elapsed_millis=2.905s
prefilling_size=8408552 prefilling_elapsed_millis=3.005s
prefilling_size=8492567 prefilling_elapsed_millis=3.105s
prefilling_size=8570368 prefilling_elapsed_millis=3.205s
prefilling_size=8643817 prefilling_elapsed_millis=3.306s
prefilling_size=8713327 prefilling_elapsed_millis=3.406s
prefilling_size=8779230 prefilling_elapsed_millis=3.506s
prefilling_size=8842637 prefilling_elapsed_millis=3.606s
prefilling_size=8900893 prefilling_elapsed_millis=3.706s
prefilling_size=8958116 prefilling_elapsed_millis=3.807s
prefilling_size=9010042 prefilling_elapsed_millis=3.907s
prefilling_size=9060237 prefilling_elapsed_millis=4.007s
prefilling_size=9108567 prefilling_elapsed_millis=4.107s
prefilling_size=9153593 prefilling_elapsed_millis=4.207s
prefilling_size=9196374 prefilling_elapsed_millis=4.308s
prefilling_size=9236434 prefilling_elapsed_millis=4.408s
prefilling_size=9275293 prefilling_elapsed_millis=4.508s
prefilling_size=9311906 prefilling_elapsed_millis=4.608s
prefilling_size=9346767 prefilling_elapsed_millis=4.708s
prefilling_size=9379052 prefilling_elapsed_millis=4.809s
prefilling_size=9409828 prefilling_elapsed_millis=4.909s
prefilling_size=9439175 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 560810 keys with 48 threads...
tid=0 thread_prefill_amount=204134 percent_done_estimate=1747.2 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=208300 percent_done_estimate=1782.8 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=212466 percent_done_estimate=1818.5 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0965539s
prefilling_size=10000000 prefilling_elapsed_millis=0.096s
finished prefilling to size 10000000 for expected size 10000000 keysum=100007141235161, performing 58950714 updates; total_prefilling_elapsed_ms=96 ms)
pref_size=10000000
pref_millis=96
prefill_elapsed_ms=97
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=27567
4NodeIxPvE_limbo_details=10901 7862 8804 

global_epoch_counter=2980

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=12659
9SCXRecordIxPvE_limbo_details=5108 3562 3989 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979784.600153548
REALTIME_END_PERF_FORMAT=6979794.600227331

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

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32763 65473 130206 254347 472792 801243 1191460 1520847 1646542 1496584 1131734 700295 345460 131604 36796 7102 870 71 4 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 5 53 740 6065 35902 144341 411026 862073 1395152 1796500 1861434 1563173 1055130 559316 226412 66490 13334 1669 138 8
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65526 130946 260412 508694 945584 1602486 2382920 3041694 3293084 2993168 2263468 1400590 690920 263208 73592 14204 1740 142 8
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 5 53 740 6065 35902 144341 411026 862073 1395152 1796500 1861434 1563173 1055130 559316 226412 66490 13334 1669 138 8
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99985 1.99919 1.99435 1.97671 1.92942 1.84735 1.74351 1.63823 1.54132 1.45446 1.37811 1.30939 1.24665 1.19048 1.1398 1.09651 1.06125 1.0408 1.02817 1

tree_stats_height=35
tree_stats_numInternals=9998960
tree_stats_numLeaves=9998961
tree_stats_numNodes=19997921
tree_stats_numKeys=9998961

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

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3669456 7332976 14583072 28486864 52952704 89739216 133443520 170334864 184412704 167617408 126754208 78433040 38691520 14739648 4121152 795424 97440 7952 448
tree_stats_sizeInBytes=1119883576

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

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:82109 2:131792 4:146420 8:48533 16:1732 32:23 64:127 128:115 256:4
    [2^00, 2^01]: 82109
    (2^01, 2^02]: 131792
    (2^02, 2^03]: 146420
    (2^03, 2^04]: 48533
    (2^04, 2^05]: 1732
    (2^05, 2^06]: 23
    (2^06, 2^07]: 127
    (2^07, 2^08]: 115
    (2^08, 2^09]: 4
sum_limbo_reclamation_event_size_total=1720918
count_limbo_reclamation_event_size_by_thread=8547 8496 8590 8613 8514 8430 8523 8561 8611 8652 8498 8533 8594 8540 8637 8609 8524 8619 8600 8450 8649 8553 8651 8522 8579 8479 8663 8505 8573 8544 8554 8563 8638 8509 8552 8476 8537 8640 8623 8542 8493 8520 8565 8585 8611 8567 8527 8494
count_limbo_reclamation_event_size_total=410855
sum_limbo_reclamation_event_count_total=840262
first_thread_announced_epoch_by_thread=23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464 23464
sum_num_inserts_by_thread=19812 20243 20154 20348 19972 20076 19932 20294 20493 20646 20059 20109 20090 20240 20152 20296 20200 20026 20111 20097 20249 20363 20106 19982 20027 19810 20322 20299 20193 20177 19990 20244 20481 20522 20259 20032 20057 20497 20454 20305 20018 20099 19925 19901 20362 20313 20189 19763
sum_num_inserts_total=968289
sum_num_deletes_by_thread=19975 20214 20291 20506 20248 19920 20258 20215 20446 20451 20227 20039 20258 20499 20304 20403 20021 20053 20075 20081 20105 20346 20336 20093 20152 20059 20473 20574 20384 19967 20004 20106 20464 20428 20381 20130 20064 20211 20449 20157 20068 20238 19852 19946 20206 20061 20039 20123
sum_num_deletes_total=969900
sum_num_searches_by_thread=3961985 3988165 4021513 4017789 3998689 3952034 3977576 3991953 4037182 4043578 3991749 3969533 3983018 4005001 4033861 4032612 4009359 3979790 3952326 3979166 4010386 4024598 3975134 3942940 3966572 3986428 4020460 4013949 3999175 3953249 3973628 3993630 4038962 4044370 3988273 3967264 3984854 4004649 4033086 4030685 4007684 3978173 3950256 3975235 4004370 4025455 3975096 3944936
sum_num_searches_total=191740376
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4001772 4028622 4061958 4058643 4038909 3992030 4017766 4032462 4078121 4084675 4032035 4009681 4023366 4045740 4074317 4073311 4049580 4019869 3992512 4019344 4050740 4065307 4015576 3983015 4006751 4026297 4061255 4054822 4039752 3993393 4013622 4033980 4079907 4085320 4028913 4007426 4024975 4045357 4073989 4071147 4047770 4018510 3990033 4015082 4044938 4065829 4015324 3984822
average_num_operations_total=4034970
stdev_num_operations_total=4198
sum_num_operations_total=193678565
min_num_operations_total=3983015
max_num_operations_total=4085320
first_time_thread_terminate_by_thread=10000124 10000073 10000073 10000073 10000073 10000073 10000074 10000074 10000073 10000074 10000075 10000075 10000073 10000073 10000074 10000073 10000074 10000073 10000073 10000074 10000073 10000074 10000074 10000074 10000073 10000074 10000075 10000073 10000074 10000073 10000074 10000073 10000073 10000073 10000074 10000074 10000075 10000073 10000074 10000073 10000074 10000073 10000073 10000073 10000073 10000073 10000074 10000074
min_time_thread_terminate_total=10000073
max_time_thread_terminate_total=10000124
first_time_thread_start_by_thread=9 6 5 5 5 6 5 5 5 5 5 6 5 5 5 6 5 5 5 5 5 6 5 5 4 6 5 5 5 6 5 5 5 5 5 6 5 5 5 6 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=99992705791408
threads_final_size=9998961
final_keysum=99992705791408
final_size=9998961
validate_result=success
Validation OK.
Structural validation OK.

total_find=191740376
total_rq=0
total_inserts=968289
total_deletes=969900
total_updates=1938189
total_queries=191740376
total_ops=193678565
find_throughput=19174037
rq_throughput=0
update_throughput=193818
query_throughput=19174037
total_throughput=19367856


total find                    : 191740376
total rq                      : 0
total inserts                 : 968289
total deletes                 : 969900
total updates                 : 1938189
total queries                 : 191740376
total ops                     : 193678565
find throughput               : 19174037
rq throughput                 : 0
update throughput             : 193818
query throughput              : 19174037
total throughput              : 19367856

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=33.9613
PAPI_L3_TCM=11.5662
PAPI_TOT_CYC=6911.1
PAPI_TOT_INS=359.317
garbage=957906770357176
total_execution_walltime=15.659s
REALTIME_MAIN_END_PERF_FORMAT=6979794.859670367
[time_cmd_output] time_elapsed_sec=15.94, faults_major=0, faults_minor=589995, mem_maxresident_kb=2361056, user_cputime=739.92, sys_cputime=4.81, percent_cpu=4670%
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