__step=000057
__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" ./drachsler_pext_bst_lock.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=data000057.txt
__path_data=data/data000057.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979449.602352019
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=drachsler_pext_bst_lock
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=192
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.003s
prefilling_size=6824770 prefilling_elapsed_millis=1.001s
prefilling_size=7119451 prefilling_elapsed_millis=1.101s
prefilling_size=7383219 prefilling_elapsed_millis=1.201s
prefilling_size=7621786 prefilling_elapsed_millis=1.301s
prefilling_size=7838231 prefilling_elapsed_millis=1.402s
prefilling_size=8035402 prefilling_elapsed_millis=1.502s
prefilling_size=8212588 prefilling_elapsed_millis=1.602s
prefilling_size=8373819 prefilling_elapsed_millis=1.702s
prefilling_size=8519608 prefilling_elapsed_millis=1.802s
prefilling_size=8650611 prefilling_elapsed_millis=1.903s
prefilling_size=8770089 prefilling_elapsed_millis=2.003s
prefilling_size=8879330 prefilling_elapsed_millis=2.103s
prefilling_size=8978173 prefilling_elapsed_millis=2.203s
prefilling_size=9069606 prefilling_elapsed_millis=2.303s
prefilling_size=9152478 prefilling_elapsed_millis=2.404s
prefilling_size=9226710 prefilling_elapsed_millis=2.504s
prefilling_size=9294426 prefilling_elapsed_millis=2.604s
prefilling_size=9355858 prefilling_elapsed_millis=2.704s
prefilling_size=9411222 prefilling_elapsed_millis=2.804s
prefilling_size=9461901 prefilling_elapsed_millis=2.905s
prefilling_size=9509220 prefilling_elapsed_millis=3.005s
prefilling_size=9550179 prefilling_elapsed_millis=3.105s
prefilling_size=9589303 prefilling_elapsed_millis=3.205s
prefilling_size=9624410 prefilling_elapsed_millis=3.305s
prefilling_size=9656834 prefilling_elapsed_millis=3.406s
prefilling_size=9686758 prefilling_elapsed_millis=3.506s
prefilling_size=9713917 prefilling_elapsed_millis=3.606s
prefilling_size=9739724 prefilling_elapsed_millis=3.706s
prefilling_size=9762043 prefilling_elapsed_millis=3.807s
prefilling_size=9783477 prefilling_elapsed_millis=3.907s
prefilling_size=9802569 prefilling_elapsed_millis=4.007s
finished prefilling to size 9802581 for expected size 10000000 keysum=98019191627456, performing 79312978 updates; total_prefilling_elapsed_ms=4108 ms)
pref_size=9802581
pref_millis=4108
prefill_elapsed_ms=4125
6node_tIxPvE_object_size=192
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=3611

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979453.870256726
REALTIME_END_PERF_FORMAT=6979463.870393401

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (192 threads)...
partitioned into 967 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.103s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 248 488 958 1814 3375 6074 10478 17582 28337 44133 66195 95139 132194 176715 227573 282972 339657 392610 438279 471410 489146 488053 469206 434253 387212 333971 278154 224636 175122 131489 96072 67562 46601 31242 20243 13000 8069 4928 2831 1606 855 509 298 148 76 40 15 5 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 0 5 9 55 139 389 889 1827 3689 6757 11881 19937 31102 46828 67136 91671 120073 151923 182996 213849 238857 257792 266028 263829 251037 230238 203173 172334 142426 113490 86460 64466 45579 31890 21852 14148 9158 5748 3596 2097 1209 606 369 237 112 62 33 13 5 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 126 248 493 967 1869 3514 6463 11367 19409 32026 50890 78076 115076 163296 223543 294709 374643 459730 544533 621275 685259 728003 745845 735234 698082 638249 564209 481327 396970 317548 244979 182532 132028 92180 63132 42095 27148 17227 10676 6427 3703 2064 1115 667 385 188 102 48 18 6 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 126 248 493 967 1869 3514 6463 11367 19409 32026 50890 78076 115076 163296 223543 294709 374643 459730 544533 621275 685259 728003 745845 735234 698082 638249 564209 481327 396970 317548 244979 182532 132028 92180 63132 42095 27148 17227 10676 6427 3703 2064 1115 667 385 188 102 48 18 6 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.97619 1.9879 1.9716 1.94209 1.90958 1.87877 1.81897 1.7857 1.74419 1.70421 1.66699 1.62607 1.59228 1.55941 1.52784 1.49903 1.4718 1.44564 1.41993 1.39754 1.37445 1.35261 1.33141 1.3113 1.29222 1.27732 1.26117 1.24685 1.23405 1.21999 1.20836 1.19698 1.18646 1.17933 1.17191 1.16403 1.1557 1.15133 1.14041 1.13568 1.12368 1.12597 1.1417 1.13043 1.1039 1.1383 1.07843 1.0625 1.05556 1 1

tree_stats_height=58
tree_stats_numInternals=6441826
tree_stats_numLeaves=3378001
tree_stats_numNodes=9819827
tree_stats_numKeys=9819827

tree_stats_avgDegreeInternal=1.52439
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.344
tree_stats_avgKeyDepth=29.1946

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24192 47616 94656 185664 358848 674688 1240896 2182464 3726528 6148992 9770880 14990592 22094592 31352832 42920256 56584128 71931456 88268160 104550336 119284800 131569728 139776576 143202240 141164928 134031744 122543808 108328128 92414784 76218240 60969216 47035968 35046144 25349376 17698560 12121344 8082240 5212416 3307584 2049792 1233984 710976 396288 214080 128064 73920 36096 19584 9216 3456 1152 192
tree_stats_sizeInBytes=1885406784

6node_tIxPvE_object_size=192
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=13003


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:164428 2:127821 4:12582 8:41
    [2^00, 2^01]: 164428
    (2^01, 2^02]: 127821
    (2^02, 2^03]: 12582
    (2^03, 2^04]: 41
sum_limbo_reclamation_event_size_total=508447
count_limbo_reclamation_event_size_by_thread=6389 6387 6402 6336 6344 6330 6341 6349 6426 6537 6303 6254 6263 6376 6385 6346 6346 6384 6263 6372 6418 6357 6328 6324 6312 6333 6302 6403 6350 6393 6311 6390 6467 6380 6250 6382 6336 6285 6295 6375 6346 6370 6208 6341 6376 6425 6425 6257
count_limbo_reclamation_event_size_total=304872
sum_limbo_reclamation_event_count_total=450816
first_thread_announced_epoch_by_thread=26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006 26006
sum_num_inserts_by_thread=21344 21691 21754 21737 21591 21272 21312 21486 21779 21690 21568 21412 21427 21689 22050 21766 21582 21610 21265 21359 21543 21720 21603 21266 21141 21544 21633 21828 21566 21361 21509 21577 21833 21760 21620 21397 21291 21618 22042 21869 21665 21455 21397 21398 21286 21721 21551 21409
sum_num_inserts_total=1034987
sum_num_deletes_by_thread=21354 21248 22045 21372 21711 21339 21510 21621 21861 22289 21606 21299 21390 21684 21907 21818 21648 21480 21386 21711 21656 21705 21424 21378 21639 21462 21483 22046 21487 21419 21318 21396 22180 21691 21657 21386 21334 21610 21668 21830 21462 21478 21195 21465 21616 21796 21693 21303
sum_num_deletes_total=1036056
sum_num_searches_by_thread=4214519 4245565 4303179 4285050 4287851 4212566 4236926 4261358 4338014 4325974 4278755 4240054 4260935 4286984 4307789 4310780 4300587 4264097 4230054 4256430 4281268 4303789 4246901 4223877 4235959 4261001 4287083 4306898 4270107 4217788 4255780 4261290 4339480 4340939 4282567 4232550 4250166 4289563 4327596 4311301 4282328 4246950 4227923 4241379 4280365 4303638 4244048 4205914
sum_num_searches_total=205005915
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4257217 4288504 4346978 4328159 4331153 4255177 4279748 4304465 4381654 4369953 4321929 4282765 4303752 4330357 4351746 4354364 4343817 4307187 4272705 4299500 4324467 4347214 4289928 4266521 4278739 4304007 4330199 4350772 4313160 4260568 4298607 4304263 4383493 4384390 4325844 4275333 4292791 4332791 4371306 4355000 4325455 4289883 4270515 4284242 4323267 4347155 4287292 4248626
average_num_operations_total=4314103
stdev_num_operations_total=5308
sum_num_operations_total=207076958
min_num_operations_total=4248626
max_num_operations_total=4384390
first_time_thread_terminate_by_thread=10000136 10000137 10000137 10000137 10000137 10000136 10000137 10000136 10000137 10000136 10000136 10000137 10000136 10000137 10000137 10000136 10000137 10000137 10000136 10000136 10000136 10000137 10000136 10000136 10000136 10000137 10000137 10000137 10000137 10000136 10000136 10000136 10000137 10000136 10000136 10000137 10000136 10000137 10000137 10000136 10000137 10000137 10000137 10000136 10000136 10000137 10000136 10000136
min_time_thread_terminate_total=10000136
max_time_thread_terminate_total=10000137
first_time_thread_start_by_thread=4 3 3 3 3 3 3 4 3 3 3 3 4 4 3 3 3 3 3 3 4 3 4 4 4 3 3 3 3 3 3 4 3 3 3 3 4 4 3 3 3 3 3 3 4 3 4 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=98190317992680
threads_final_size=9819827
final_keysum=98190317992680
final_size=9819827
validate_result=success
Validation OK.
Structural validation OK.

total_find=205005915
total_rq=0
total_inserts=1034987
total_deletes=1036056
total_updates=2071043
total_queries=205005915
total_ops=207076958
find_throughput=20500591
rq_throughput=0
update_throughput=207104
query_throughput=20500591
total_throughput=20707695


total find                    : 205005915
total rq                      : 0
total inserts                 : 1034987
total deletes                 : 1036056
total updates                 : 2071043
total queries                 : 205005915
total ops                     : 207076958
find throughput               : 20500591
rq throughput                 : 0
update throughput             : 207104
query throughput              : 20500591
total throughput              : 20707695

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=31.956
PAPI_L3_TCM=8.02231
PAPI_TOT_CYC=6462.7
PAPI_TOT_INS=403.033
garbage=1006179097291533
total_execution_walltime=14.47s
REALTIME_MAIN_END_PERF_FORMAT=6979464.73858711
[time_cmd_output] time_elapsed_sec=14.76, faults_major=0, faults_minor=540558, mem_maxresident_kb=2164108, user_cputime=705.85, sys_cputime=4.71, percent_cpu=4812%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1