__step=000042
__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" ./guerraoui_ext_bst_ticket.debra -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000042.txt
__path_data=data/data000042.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207317.877581788
binary=./guerraoui_ext_bst_ticket.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=guerraoui_ext_bst_ticket
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=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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=40
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.02s
prefilling_size=7356711 prefilling_elapsed_millis=1s
prefilling_size=7629828 prefilling_elapsed_millis=1.1s
prefilling_size=7869361 prefilling_elapsed_millis=1.2s
prefilling_size=8082145 prefilling_elapsed_millis=1.3s
prefilling_size=8269799 prefilling_elapsed_millis=1.4s
prefilling_size=8438563 prefilling_elapsed_millis=1.501s
prefilling_size=8589612 prefilling_elapsed_millis=1.601s
prefilling_size=8722099 prefilling_elapsed_millis=1.701s
prefilling_size=8844024 prefilling_elapsed_millis=1.801s
prefilling_size=8952954 prefilling_elapsed_millis=1.901s
prefilling_size=9051574 prefilling_elapsed_millis=2.001s
prefilling_size=9141035 prefilling_elapsed_millis=2.101s
prefilling_size=9221472 prefilling_elapsed_millis=2.201s
prefilling_size=9293151 prefilling_elapsed_millis=2.301s
prefilling_size=9358134 prefilling_elapsed_millis=2.402s
prefilling_size=9416839 prefilling_elapsed_millis=2.502s
prefilling_size=9469472 prefilling_elapsed_millis=2.602s
prefilling_size=9517620 prefilling_elapsed_millis=2.702s
prefilling_size=9561017 prefilling_elapsed_millis=2.802s
prefilling_size=9600616 prefilling_elapsed_millis=2.902s
prefilling_size=9635967 prefilling_elapsed_millis=3.002s
prefilling_size=9669764 prefilling_elapsed_millis=3.103s
prefilling_size=9698359 prefilling_elapsed_millis=3.203s
prefilling_size=9726815 prefilling_elapsed_millis=3.303s
prefilling_size=9750502 prefilling_elapsed_millis=3.403s
prefilling_size=9771482 prefilling_elapsed_millis=3.503s
prefilling_size=9791006 prefilling_elapsed_millis=3.603s
prefilling_size=9809628 prefilling_elapsed_millis=3.704s
finished prefilling to size 9809620 for expected size 10000000 keysum=98105785201073, performing 80869126 updates; total_prefilling_elapsed_ms=3804 ms)
pref_size=9809620
pref_millis=3804
prefill_elapsed_ms=3818
6node_tIxPvE_object_size=40
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=2102
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=207321.740134136
REALTIME_END_PERF_FORMAT=207331.740201847
###############################################################################
################################## 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 1862 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.397s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 246 480 931 1754 3245 5845 10258 17423 28708 45754 70456 104582 149007 204328 270119 343811 423175 501559 573249 631519 674451 697086 697007 674155 633570 576648 510255 437934 365490 296130 233788 180108 135306 100292 72843 51935 36120 24445 16407 10733 6865 4315 2771 1753 1032 620 342 191 90 26 9 4 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 8 12 29 108 263 645 1432 3093 6138 11662 21052 36330 60157 93686 138537 196427 264447 344791 429869 514979 588587 651816 697165 719859 714740 690492 643041 582576 510378 434850 358472 287468 224910 170320 127741 93751 67750 47795 32483 22081 14601 9415 5859 3789 2474 1444 898 493 292 154 43 14 5 6
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 492 960 1862 3508 6490 11690 20516 34846 57416 91508 140912 209164 298014 408656 540238 687622 846350 1003118 1146498 1263038 1348902 1394172 1394014 1348310 1267140 1153296 1020510 875868 730980 592260 467576 360216 270612 200584 145686 103870 72240 48890 32814 21466 13730 8630 5542 3506 2064 1240 684 382 180 52 18 8 6
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1 8 12 29 108 262 645 1432 3093 6138 11662 21052 36330 60157 93686 138537 196427 264447 344791 429869 514979 588587 651816 697165 719859 714740 690492 643041 582576 510378 434850 358472 287468 224910 170320 127741 93751 67750 47795 32483 22081 14601 9415 5859 3789 2474 1444 898 493 292 154 43 14 5 6
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.9685 1.97561 1.96979 1.942 1.92474 1.90062 1.8775 1.84924 1.82385 1.79689 1.76994 1.74218 1.71239 1.68563 1.66099 1.63641 1.61542 1.59261 1.57147 1.55082 1.53399 1.51678 1.49994 1.48361 1.4699 1.45508 1.44243 1.42913 1.41729 1.40511 1.39474 1.3852 1.37562 1.37061 1.36315 1.35649 1.34774 1.33839 1.33559 1.32709 1.31981 1.31428 1.32109 1.31631 1.29435 1.30039 1.27581 1.27924 1.2356 1.14444 1.17308 1.22222 1.375 1
tree_stats_height=62
tree_stats_numInternals=9829427
tree_stats_numLeaves=9829428
tree_stats_numNodes=19658855
tree_stats_numKeys=9829427
tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=31.8481
tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10160 19680 38400 74480 140320 259600 467600 820640 1393840 2296640 3660320 5636480 8366560 11920560 16346240 21609520 27504880 33854000 40124720 45859920 50521520 53956080 55766880 55760560 53932400 50685600 46131840 40820400 35034720 29239200 23690400 18703040 14408640 10824480 8023360 5827440 4154800 2889600 1955600 1312560 858640 549200 345200 221680 140240 82560 49600 27360 15280 7200 2080 720 320 240
tree_stats_sizeInBytes=786354200
6node_tIxPvE_object_size=40
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=7942
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:124425 4:142148 8:25006 16:184 32:13
(2^01, 2^02]: 124425
(2^02, 2^03]: 142148
(2^03, 2^04]: 25006
(2^04, 2^05]: 184
(2^05, 2^06]: 13
sum_limbo_reclamation_event_size_total=1135526
count_limbo_reclamation_event_size_by_thread=4601 4570 4567 4549 4558 4562 4558 4586 4578 4647 4617 4538 4566 4515 4568 4566 4534 4551 4507 4476 4515 4480 4629 4551 4567 4538 4546 4567 4573 4577 4584 4500 4620 4528 4571 4551 4551 4637 4524 4533 4614 4557 4536 4573 4543 4611 4555 4585 4531 4551 4498 4518 4636 4554 4587 4591 4561 4505 4534 4510 4606 4569 4509 4586
count_limbo_reclamation_event_size_total=291776
sum_limbo_reclamation_event_count_total=373760
first_thread_announced_epoch_by_thread=15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884 15884
sum_num_inserts_by_thread=18314 18116 18338 17911 18204 18401 18108 18134 18163 18331 18141 18246 18136 18278 18058 18163 18031 18229 18145 17820 18410 17914 18232 17940 18081 17974 17672 17830 17869 18271 18163 17708 18154 17921 18012 17831 18038 18095 17932 18252 18121 17871 18203 18018 17932 17999 17808 17844 17981 17873 17652 18131 18158 17928 17939 18160 18211 17892 18050 17824 17851 18064 17701 17954
sum_num_inserts_total=1154731
sum_num_deletes_by_thread=18336 18121 17896 18289 18129 18105 18126 18163 18186 18158 18128 17961 18119 18077 18129 18017 17840 18062 17919 17805 17868 17874 18151 17972 18206 18050 18163 18240 17847 18140 17901 17796 18062 17939 18218 18054 18376 18236 18129 18145 18101 18315 18079 18107 17953 18230 17921 17828 17938 17926 17841 18023 18154 17817 18119 17985 18171 17990 17826 17843 18126 18010 17800 18077
sum_num_deletes_total=1155113
sum_num_searches_by_thread=3609603 3602522 3582373 3570459 3587260 3586283 3579368 3573350 3601409 3592451 3578877 3584250 3586283 3593945 3581750 3568942 3572810 3563442 3554487 3548875 3559358 3558414 3582115 3578369 3576442 3578721 3566168 3569151 3571675 3563355 3542631 3540418 3564635 3567487 3554851 3554688 3607703 3597999 3588739 3589631 3584550 3592045 3574879 3575064 3573241 3569894 3547067 3547472 3535800 3541753 3524014 3575268 3574700 3573466 3566740 3560533 3573798 3574646 3546193 3542222 3544897 3550240 3546658 3546543
sum_num_searches_total=228472972
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3646253 3638759 3618607 3606659 3623593 3622789 3615602 3609647 3637758 3628940 3615146 3620457 3622538 3630300 3617937 3605122 3608681 3599733 3590551 3584500 3595636 3594202 3618498 3614281 3612729 3614745 3602003 3605221 3607391 3599766 3578695 3575922 3600851 3603347 3591081 3590573 3644117 3634330 3624800 3626028 3620772 3628231 3611161 3611189 3609126 3606123 3582796 3583144 3571719 3577552 3559507 3611422 3611012 3609211 3602798 3596678 3610180 3610528 3582069 3577889 3580874 3586314 3582159 3582574
average_num_operations_total=3605981
stdev_num_operations_total=2387
sum_num_operations_total=230782816
min_num_operations_total=3559507
max_num_operations_total=3646253
first_time_thread_terminate_by_thread=10000071 10000071 10000071 10000071 10000071 10000070 10000070 10000070 10000070 10000070 10000070 10000070 10000069 10000070 10000070 10000069 10000071 10000071 10000071 10000071 10000069 10000070 10000069 10000069 10000070 10000070 10000070 10000071 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000071 10000071 10000071 10000069 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000070 10000069 10000069 10000070 10000070 10000071 10000070 10000070
min_time_thread_terminate_total=10000069
max_time_thread_terminate_total=10000072
first_time_thread_start_by_thread=9 9 9 9 10 10 10 10 11 11 11 11 11 11 11 11 10 10 10 10 10 10 10 10 14 14 14 14 14 14 14 14 12 13 12 13 12 12 12 12 9 9 9 9 10 10 10 10 13 13 13 13 14 14 14 14 13 13 13 13 12 12 12 12
min_time_thread_start_total=9
max_time_thread_start_total=14
sum_duration_all_ops_total=
threads_final_keysum=98297434235244
threads_final_size=9829427
final_keysum=98297434235244
final_size=9829427
validate_result=success
Validation OK.
Structural validation OK.
total_find=228472972
total_rq=0
total_inserts=1154731
total_deletes=1155113
total_updates=2309844
total_queries=228472972
total_ops=230782816
find_throughput=22847297
rq_throughput=0
update_throughput=230984
query_throughput=22847297
total_throughput=23078281
total find : 228472972
total rq : 0
total inserts : 1154731
total deletes : 1155113
total updates : 2309844
total queries : 228472972
total ops : 230782816
find throughput : 22847297
rq throughput : 0
update throughput : 230984
query throughput : 22847297
total throughput : 23078281
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=227556767837182
total_execution_walltime=14.312s
REALTIME_MAIN_END_PERF_FORMAT=207332.190904426
[time_cmd_output] time_elapsed_sec=14.47, faults_major=0, faults_minor=308235, mem_maxresident_kb=1233964, user_cputime=912.54, sys_cputime=5.08, percent_cpu=6339%
timeout=false
algorithm=guerraoui_ext_bst_ticket.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2