__step=000618
__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 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000618.txt
__path_data=data/data000618.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=214259.467069996
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-63,128-191,64-127,192-255
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=200000
PREFILL_THREADS=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.022s
prefilling_size=99949 prefilling_elapsed_millis=1s
finished prefilling to size 99972 for expected size 100000 keysum=10002174872, performing 37860692 updates; total_prefilling_elapsed_ms=1101 ms)
pref_size=99972
pref_millis=1101
prefill_elapsed_ms=1113
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=1017
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=214260.626141756
REALTIME_END_PERF_FORMAT=214270.626239006
###############################################################################
################################## 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 (256 threads)...
partitioned into 1235 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.11s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 123 237 420 678 1068 1572 2141 2761 3318 3780 4112 4218 4278 4316 4450 4520 4465 4370 4008 3320 2629 1846 1229 788 445 227 99 27 4 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 1 1 6 32 95 167 301 549 820 1138 1517 1848 2168 2219 2229 2209 2309 2394 2397 2499 2464 2102 1784 1286 859 579 339 181 90 27 2 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 124 243 452 773 1235 1873 2690 3581 4456 5297 5960 6386 6497 6545 6659 6829 6859 6767 6507 5784 4731 3630 2515 1647 1024 566 280 117 31 4 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 124 243 452 773 1235 1873 2690 3581 4456 5297 5960 6386 6497 6545 6659 6829 6859 6767 6507 5784 4731 3630 2515 1647 1024 566 280 117 31 4 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.95312 1.96774 1.88477 1.78097 1.72057 1.65182 1.5969 1.53532 1.47333 1.44412 1.41155 1.38154 1.35687 1.34893 1.35798 1.35726 1.34251 1.33562 1.3158 1.27294 1.24395 1.21158 1.1843 1.1662 1.14329 1.11816 1.09364 1.06429 1.03419 1 1 1
tree_stats_height=38
tree_stats_numInternals=65577
tree_stats_numLeaves=34614
tree_stats_numNodes=100191
tree_stats_numKeys=100191
tree_stats_avgDegreeInternal=1.52784
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34548
tree_stats_avgKeyDepth=20.9402
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 23808 46656 86784 148416 237120 359616 516480 687552 855552 1017024 1144320 1226112 1247424 1256640 1278528 1311168 1316928 1299264 1249344 1110528 908352 696960 482880 316224 196608 108672 53760 22464 5952 768 384
tree_stats_sizeInBytes=19236672
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=24443
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:21 2:201 4:16061 8:389752 16:233587 32:21 64:62 128:125 256:1
[2^00, 2^01]: 21
(2^01, 2^02]: 201
(2^02, 2^03]: 16061
(2^03, 2^04]: 389752
(2^04, 2^05]: 233587
(2^05, 2^06]: 21
(2^06, 2^07]: 62
(2^07, 2^08]: 125
(2^08, 2^09]: 1
sum_limbo_reclamation_event_size_total=9206933
count_limbo_reclamation_event_size_by_thread=9997 9996 9998 9997 9997 9997 9997 9998 9998 9997 9998 9997 9998 9997 9997 9998 9998 9998 9998 9997 9998 9998 9997 9998 9997 9998 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997 9998 9998 9997 9998 9997 9998 9998 9997 9997 9997 9997 9997 9998 9998 9998 9997 9997 9998 9997 9997 9998 9997 9997 9997 9997 9998 9997 9998
count_limbo_reclamation_event_size_total=639831
sum_limbo_reclamation_event_count_total=1499298
first_thread_announced_epoch_by_thread=48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886 48886
sum_num_inserts_by_thread=713773 714093 713179 708731 723298 718441 716721 712641 703029 694760 642946 639796 681131 647163 645078 642074 700447 697581 691165 636925 690386 690571 648176 640288 668169 663023 661327 658753 666958 661572 689916 688673 694398 690105 691151 691733 664272 656872 646758 643974 629937 661757 628087 626317 678829 673021 669969 668929 685834 685322 682997 630039 650378 643182 642486 643145 652394 655160 655926 685828 721119 722157 724589 725839
sum_num_inserts_total=43193288
sum_num_deletes_by_thread=714798 715170 713771 707899 723673 717954 720306 710746 702624 694009 642744 640243 682238 646286 643688 641592 700552 697906 691647 638212 692295 688977 647828 641466 666947 662521 659834 658609 668705 660850 690621 690125 695908 691322 691379 689424 666253 658155 647008 644614 631651 660494 627188 627390 677171 671549 668853 670040 685904 686582 684988 631387 650895 642773 640549 642063 650692 653898 655825 684079 721492 723014 723104 725036
sum_num_deletes_total=43195516
sum_num_searches_by_thread=12844405 12864171 12844420 12763739 12991108 12942481 12933700 12827056 12645796 12488371 11550332 11511873 12292126 11642005 11599716 11549952 12611008 12566659 12443120 11484925 12447539 12433098 11648630 11526850 12016712 11921605 11913405 11869160 12008207 11891270 12433947 12421935 12508317 12434595 12436372 12428072 11961720 11831939 11658803 11592175 11357445 11878261 11316489 11274233 12196022 12081445 12046846 12043471 12348507 12340756 12306463 11352349 11709610 11589124 11535080 11574041 11718625 11777508 11801461 12331759 12986365 13013786 13023105 13058969
sum_num_searches_total=777443034
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=14272976 14293434 14271370 14180369 14438079 14378876 14370727 14250443 14051449 13877140 12836022 12791912 13655495 12935454 12888482 12833618 14012007 13962146 13825932 12760062 13830220 13812646 12944634 12808604 13351828 13247149 13234566 13186522 13343870 13213692 13814484 13800733 13898623 13816022 13818902 13809229 13292245 13146966 12952569 12880763 12619033 13200512 12571764 12527940 13552022 13426015 13385668 13382440 13720245 13712660 13674448 12613775 13010883 12875079 12818115 12859249 13021711 13086566 13113212 13701666 14428976 14458957 14470798 14509844
average_num_operations_total=13497372
stdev_num_operations_total=73162
sum_num_operations_total=863831838
min_num_operations_total=12527940
max_num_operations_total=14509844
first_time_thread_terminate_by_thread=10000098 10000098 10000098 10000098 10000095 10000095 10000095 10000095 10000098 10000098 10000097 10000097 10000097 10000097 10000097 10000097 10000098 10000098 10000098 10000098 10000098 10000098 10000098 10000098 10000097 10000097 10000097 10000097 10000096 10000096 10000096 10000096 10000096 10000096 10000096 10000096 10000096 10000096 10000096 10000096 10000095 10000096 10000096 10000095 10000098 10000098 10000098 10000098 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000097 10000097 10000097 10000097 10000097 10000097 10000097 10000097
min_time_thread_terminate_total=10000095
max_time_thread_terminate_total=10000098
first_time_thread_start_by_thread=8 8 8 8 8 8 8 8 10 10 10 10 9 9 9 9 11 11 11 11 10 10 10 10 8 8 8 8 8 7 8 7 9 9 9 9 9 10 9 9 10 10 10 10 10 10 10 10 8 8 8 8 9 9 9 9 9 9 9 9 7 7 7 7
min_time_thread_start_total=7
max_time_thread_start_total=11
sum_duration_all_ops_total=
threads_final_keysum=10037168166
threads_final_size=100191
final_keysum=10037168166
final_size=100191
validate_result=success
Validation OK.
Structural validation OK.
total_find=777443034
total_rq=0
total_inserts=43193288
total_deletes=43195516
total_updates=86388804
total_queries=777443034
total_ops=863831838
find_throughput=77744303
rq_throughput=0
update_throughput=8638880
query_throughput=77744303
total_throughput=86383183
total find : 777443034
total rq : 0
total inserts : 43193288
total deletes : 43195516
total updates : 86388804
total queries : 777443034
total ops : 863831838
find throughput : 77744303
rq throughput : 0
update throughput : 8638880
query throughput : 77744303
total throughput : 86383183
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=10314474765391
total_execution_walltime=11.363s
REALTIME_MAIN_END_PERF_FORMAT=214270.831260045
[time_cmd_output] time_elapsed_sec=11.46, faults_major=0, faults_minor=82536, mem_maxresident_kb=331056, user_cputime=736.61, sys_cputime=4.85, percent_cpu=6466%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2