__step=000394
__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 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=data000394.txt
__path_data=data/data000394.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983589.543719997
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=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=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.006s
prefilling_size=6790862 prefilling_elapsed_millis=1.001s
prefilling_size=7083865 prefilling_elapsed_millis=1.101s
prefilling_size=7347493 prefilling_elapsed_millis=1.201s
prefilling_size=7585191 prefilling_elapsed_millis=1.301s
prefilling_size=7799593 prefilling_elapsed_millis=1.402s
prefilling_size=7994172 prefilling_elapsed_millis=1.502s
prefilling_size=8173160 prefilling_elapsed_millis=1.602s
prefilling_size=8332998 prefilling_elapsed_millis=1.702s
prefilling_size=8478908 prefilling_elapsed_millis=1.802s
prefilling_size=8611672 prefilling_elapsed_millis=1.903s
prefilling_size=8731423 prefilling_elapsed_millis=2.003s
prefilling_size=8841875 prefilling_elapsed_millis=2.103s
prefilling_size=8942852 prefilling_elapsed_millis=2.203s
prefilling_size=9033657 prefilling_elapsed_millis=2.303s
prefilling_size=9116458 prefilling_elapsed_millis=2.404s
prefilling_size=9191417 prefilling_elapsed_millis=2.504s
prefilling_size=9260189 prefilling_elapsed_millis=2.604s
prefilling_size=9324137 prefilling_elapsed_millis=2.704s
prefilling_size=9382527 prefilling_elapsed_millis=2.804s
prefilling_size=9434252 prefilling_elapsed_millis=2.905s
prefilling_size=9480786 prefilling_elapsed_millis=3.005s
prefilling_size=9525646 prefilling_elapsed_millis=3.105s
prefilling_size=9565926 prefilling_elapsed_millis=3.205s
prefilling_size=9602030 prefilling_elapsed_millis=3.305s
prefilling_size=9633691 prefilling_elapsed_millis=3.406s
prefilling_size=9663466 prefilling_elapsed_millis=3.506s
prefilling_size=9691428 prefilling_elapsed_millis=3.606s
prefilling_size=9716426 prefilling_elapsed_millis=3.706s
prefilling_size=9739444 prefilling_elapsed_millis=3.806s
prefilling_size=9760557 prefilling_elapsed_millis=3.907s
prefilling_size=9781344 prefilling_elapsed_millis=4.007s
prefilling_size=9799992 prefilling_elapsed_millis=4.107s
prefilling_size=9817011 prefilling_elapsed_millis=4.207s
finished prefilling to size 9817035 for expected size 10000000 keysum=98155405367127, performing 82527883 updates; total_prefilling_elapsed_ms=4308 ms)
pref_size=9817035
pref_millis=4308
prefill_elapsed_ms=4335
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=3758
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6983594.41996790
REALTIME_END_PERF_FORMAT=6983604.42134870
###############################################################################
################################## 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 1005 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.106s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 506 986 1865 3403 5937 9979 16186 25323 38340 56386 80444 111640 151135 198178 252872 311705 369972 424400 467338 493944 501794 492038 463391 420652 366633 308248 250182 196345 149560 110388 78895 54237 36118 23371 14524 8800 5028 2740 1423 777 434 220 104 43 22 4 4 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 1 19 60 183 472 1040 2059 3803 6563 10998 17407 26462 38854 55754 76784 103856 135033 167921 201700 233721 256649 269647 274017 265935 248214 222041 191357 158206 126196 97993 73665 53814 37591 25182 16626 10366 6407 3762 2089 1063 559 342 168 87 26 21 1 5
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 507 1005 1925 3586 6409 11019 18245 29126 44903 67384 97851 138102 189989 253932 329656 415561 505005 592321 669038 727665 758443 761685 737408 686587 614847 530289 441539 354551 275756 208381 152560 108051 73709 48553 31150 19166 11435 6502 3512 1840 993 562 272 130 48 25 5 5
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 507 1005 1925 3586 6409 11019 18245 29126 44903 67384 97851 138102 189989 253932 329656 415561 505005 592321 669038 727665 758443 761685 737408 686587 614847 530289 441539 354551 275756 208381 152560 108051 73709 48553 31150 19166 11435 6502 3512 1840 993 562 272 130 48 25 5 5
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.99216 1.98422 1.93433 1.89403 1.83826 1.79295 1.75016 1.70924 1.67225 1.64682 1.61535 1.58924 1.56733 1.54107 1.51777 1.49351 1.46515 1.44029 1.41302 1.38911 1.36349 1.34266 1.32214 1.30268 1.28284 1.26617 1.25136 1.23638 1.22398 1.21331 1.20238 1.19111 1.18021 1.1687 1.16022 1.14902 1.13748 1.1289 1.11873 1.11874 1.11739 1.1289 1.09253 1.09559 1.03846 1.0625 1.04 1.2 1
tree_stats_height=57
tree_stats_numInternals=6507023
tree_stats_numLeaves=3424720
tree_stats_numNodes=9931743
tree_stats_numKeys=9931743
tree_stats_avgDegreeInternal=1.52631
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34483
tree_stats_avgKeyDepth=29.6295
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 48960 97344 192960 369600 688512 1230528 2115648 3503040 5592192 8621376 12937728 18787392 26515584 36477888 48754944 63293952 79787712 96960960 113725632 128455296 139711680 145621056 146243520 141582336 131824704 118050624 101815488 84775488 68073792 52945152 40009152 29291520 20745792 14152128 9322176 5980800 3679872 2195520 1248384 674304 353280 190656 107904 52224 24960 9216 4800 960 960
tree_stats_sizeInBytes=1906894656
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=13032
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:54 2:1530 4:52225 8:343322 16:47922
[2^00, 2^01]: 54
(2^01, 2^02]: 1530
(2^02, 2^03]: 52225
(2^03, 2^04]: 343322
(2^04, 2^05]: 47922
sum_limbo_reclamation_event_size_total=5037993
count_limbo_reclamation_event_size_by_thread=9272 9272 9272 9272 9272 9272 9272 9272 9272 9270 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9272 9271
count_limbo_reclamation_event_size_total=445053
sum_limbo_reclamation_event_count_total=445152
first_thread_announced_epoch_by_thread=26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064 26064
sum_num_inserts_by_thread=210724 211632 213968 214981 213426 209338 212149 213063 215911 216317 211548 210457 210768 211236 214423 214358 212608 211277 208809 212385 212321 213314 210854 208517 209135 212249 212248 214609 212872 211378 211437 211988 214960 216372 212061 210860 210680 214104 213478 214263 213974 211178 210317 211172 214454 215326 212347 211079
sum_num_inserts_total=10196925
sum_num_deletes_by_thread=209562 211004 214958 214776 214143 208951 211756 213124 216363 215662 211263 209847 210739 211612 213226 214872 212810 211056 209499 212841 212636 214388 211249 208994 209816 211895 212814 214439 213956 210669 211953 211059 214414 215963 211666 210157 211296 213943 214023 213183 214658 210902 210577 210616 213574 214757 212753 209666
sum_num_deletes_total=10194080
sum_num_searches_by_thread=3770439 3795523 3859633 3860905 3841954 3766192 3814260 3835728 3889322 3889894 3810769 3787842 3795846 3818347 3852017 3851437 3827394 3794341 3766075 3816533 3831799 3843702 3796527 3759937 3773274 3818930 3837128 3862256 3843937 3792672 3814291 3811282 3866511 3891704 3812547 3786404 3798422 3843033 3854821 3855750 3851424 3795798 3789204 3791226 3852243 3871565 3820227 3785177
sum_num_searches_total=183496242
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4190725 4218159 4288559 4290662 4269523 4184481 4238165 4261915 4321596 4321873 4233580 4208146 4217353 4241195 4279666 4280667 4252812 4216674 4184383 4241759 4256756 4271404 4218630 4177448 4192225 4243074 4262190 4291304 4270765 4214719 4237681 4234329 4295885 4324039 4236274 4207421 4220398 4271080 4282322 4283196 4280056 4217878 4210098 4213014 4280271 4301648 4245327 4205922
average_num_operations_total=4247650
stdev_num_operations_total=5657
sum_num_operations_total=203887247
min_num_operations_total=4177448
max_num_operations_total=4324039
first_time_thread_terminate_by_thread=10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000139 10000138 10000139 10000138 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138
min_time_thread_terminate_total=10000138
max_time_thread_terminate_total=10000139
first_time_thread_start_by_thread=3 2 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 3 2 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=
threads_final_keysum=99309005510652
threads_final_size=9931743
final_keysum=99309005510652
final_size=9931743
validate_result=success
Validation OK.
Structural validation OK.
total_find=183496242
total_rq=0
total_inserts=10196925
total_deletes=10194080
total_updates=20391005
total_queries=183496242
total_ops=203887247
find_throughput=18349624
rq_throughput=0
update_throughput=2039100
query_throughput=18349624
total_throughput=20388724
total find : 183496242
total rq : 0
total inserts : 10196925
total deletes : 10194080
total updates : 20391005
total queries : 183496242
total ops : 203887247
find throughput : 18349624
rq throughput : 0
update throughput : 2039100
query throughput : 18349624
total throughput : 20388724
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=32.6287
PAPI_L3_TCM=8.34063
PAPI_TOT_CYC=6564.32
PAPI_TOT_INS=418.117
garbage=907311159342240
total_execution_walltime=14.715s
REALTIME_MAIN_END_PERF_FORMAT=6983604.260073181
[time_cmd_output] time_elapsed_sec=14.97, faults_major=0, faults_minor=545821, mem_maxresident_kb=2185072, user_cputime=717.57, sys_cputime=4.75, percent_cpu=4822%
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=2