__step=000617
__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=data000617.txt
__path_data=data/data000617.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=214247.941863226
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.015s
prefilling_size=99862 prefilling_elapsed_millis=1s
finished prefilling to size 99875 for expected size 100000 keysum=9995971507, performing 37627934 updates; total_prefilling_elapsed_ms=1101 ms)
pref_size=99875
pref_millis=1101
prefill_elapsed_ms=1121
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=1014
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=214249.97956777
REALTIME_END_PERF_FORMAT=214259.98029235
###############################################################################
################################## 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 1441 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.105s
tree_stats_numInternalsAtDepth=1 2 4 8 16 31 61 118 225 418 768 1339 2195 3348 4591 5692 6602 6912 6742 6130 5269 4360 3377 2471 1737 1179 800 488 300 167 81 33 11 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 1 0 2 5 19 34 102 257 572 1158 1927 2567 3386 3761 3891 3708 3230 2784 2224 1663 1173 806 569 334 227 130 68 33 10 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 61 120 230 437 802 1441 2452 3920 5749 7619 9169 10298 10503 10021 8977 7590 6161 4695 3400 2352 1606 1057 634 394 211 101 44 13 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 61 120 230 437 802 1441 2452 3920 5749 7619 9169 10298 10503 10021 8977 7590 6161 4695 3400 2352 1606 1057 634 394 211 101 44 13 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 1.9375 1.96721 1.93333 1.92174 1.87872 1.83915 1.77238 1.70351 1.6125 1.5267 1.45636 1.4031 1.34871 1.3122 1.2841 1.25855 1.23729 1.21393 1.19787 1.18088 1.18155 1.16002 1.13813 1.14826 1.11168 1.09479 1.10891 1.04545 1 1
tree_stats_height=35
tree_stats_numInternals=65479
tree_stats_numLeaves=34644
tree_stats_numNodes=100123
tree_stats_numKeys=100123
tree_stats_avgDegreeInternal=1.52909
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34601
tree_stats_avgKeyDepth=18.3895
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 11712 23040 44160 83904 153984 276672 470784 752640 1103808 1462848 1760448 1977216 2016576 1924032 1723584 1457280 1182912 901440 652800 451584 308352 202944 121728 75648 40512 19392 8448 2496 576
tree_stats_sizeInBytes=19223616
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=23657
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:24 2:313 4:19531 8:407862 16:211871 32:14 64:68 128:184
[2^00, 2^01]: 24
(2^01, 2^02]: 313
(2^02, 2^03]: 19531
(2^03, 2^04]: 407862
(2^04, 2^05]: 211871
(2^05, 2^06]: 14
(2^06, 2^07]: 68
(2^07, 2^08]: 184
sum_limbo_reclamation_event_size_total=9000335
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=639867
sum_limbo_reclamation_event_count_total=1449149
first_thread_announced_epoch_by_thread=47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314 47314
sum_num_inserts_by_thread=699025 716532 707259 689381 655417 671942 666435 656652 663460 661336 607521 650249 650929 642794 630333 637232 632133 625788 628362 602898 652674 649508 640329 639593 644973 639712 637163 633432 628626 618059 616029 620876 618461 617226 646938 640504 644813 613096 621751 615526 634548 620198 626381 606831 613375 615097 610391 608891 645509 647559 637272 643546 644738 643969 635719 643745 617181 623629 617678 622418 639617 641539 650455 650258
sum_num_inserts_total=40875511
sum_num_deletes_by_thread=697474 716311 708428 689064 657520 670709 668728 655838 664081 661570 606203 650328 650935 643356 630654 637779 631547 624969 626873 602653 654919 650343 639221 639025 643888 638693 636846 635587 626660 617245 615525 618971 619584 618773 645329 641546 644206 612533 620762 615742 636365 619548 625253 607046 612555 614501 611110 607822 646048 647688 636536 644048 645846 644160 636438 643076 617350 622503 618949 622690 640793 642415 649489 649574
sum_num_deletes_total=40872221
sum_num_searches_by_thread=12555852 12878213 12740522 12416989 11805325 12066538 12019341 11814213 11943953 11918801 10918622 11700296 11726298 11566981 11342619 11473088 11373603 11250045 11290745 10861089 11760670 11709758 11535452 11502751 11619058 11499257 11461967 11404658 11311847 11130581 11081768 11157466 11151610 11114827 11625670 11542525 11598118 11028450 11187052 11096885 11452813 11135245 11266125 10943866 11054046 11081924 10987521 10947495 11625211 11652030 11459735 11607009 11607368 11589313 11445696 11573269 11109234 11196542 11156905 11215288 11522811 11559656 11716409 11708379
sum_num_searches_total=735797393
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=13952351 14311056 14156209 13795434 13118262 13409189 13354504 13126703 13271494 13241707 12132346 13000873 13028162 12853131 12603606 12748099 12637283 12500802 12545980 12066640 13068263 13009609 12815002 12781369 12907919 12777662 12735976 12673677 12567133 12365885 12313322 12397313 12389655 12350826 12917937 12824575 12887137 12254079 12429565 12328153 12723726 12374991 12517759 12157743 12279976 12311522 12209022 12164208 12916768 12947277 12733543 12894603 12897952 12877442 12717853 12860090 12343765 12442674 12393532 12460396 12803221 12843610 13016353 13008211
average_num_operations_total=12774142
stdev_num_operations_total=58467
sum_num_operations_total=817545125
min_num_operations_total=12066640
max_num_operations_total=14311056
first_time_thread_terminate_by_thread=10000071 10000071 10000073 10000073 10000072 10000070 10000070 10000072 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000071 10000071 10000071 10000071 10000072 10000070 10000070 10000071 10000072 10000072 10000072 10000072 10000073 10000073 10000073 10000073 10000072 10000072 10000072 10000072 10000075 10000075 10000075 10000075 10000074 10000074 10000074 10000074 10000071 10000071 10000071 10000071 10000073 10000073 10000073 10000073 10000073 10000072 10000073 10000073 10000073 10000073 10000073 10000073 10000075 10000075 10000075 10000075
min_time_thread_terminate_total=10000070
max_time_thread_terminate_total=10000075
first_time_thread_start_by_thread=9 9 9 9 9 10 9 10 10 10 10 10 11 11 11 11 9 9 9 9 14 14 14 14 10 10 10 10 10 10 10 10 14 14 14 14 15 15 15 15 14 14 14 14 11 11 11 11 11 11 11 11 12 12 12 12 12 11 11 11 12 12 12 12
min_time_thread_start_total=9
max_time_thread_start_total=15
sum_duration_all_ops_total=
threads_final_keysum=9974378959
threads_final_size=100123
final_keysum=9974378959
final_size=100123
validate_result=success
Validation OK.
Structural validation OK.
total_find=735797393
total_rq=0
total_inserts=40875511
total_deletes=40872221
total_updates=81747732
total_queries=735797393
total_ops=817545125
find_throughput=73579739
rq_throughput=0
update_throughput=8174773
query_throughput=73579739
total_throughput=81754512
total find : 735797393
total rq : 0
total inserts : 40875511
total deletes : 40872221
total updates : 81747732
total queries : 735797393
total ops : 817545125
find throughput : 73579739
rq throughput : 0
update throughput : 8174773
query throughput : 73579739
total throughput : 81754512
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=7638387358147
total_execution_walltime=11.355s
REALTIME_MAIN_END_PERF_FORMAT=214259.297518479
[time_cmd_output] time_elapsed_sec=11.45, faults_major=0, faults_minor=81670, mem_maxresident_kb=327220, user_cputime=734.73, sys_cputime=5.45, percent_cpu=6460%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1