__step=000041
__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=data000041.txt
__path_data=data/data000041.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207303.445795670
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.021s
prefilling_size=7297750 prefilling_elapsed_millis=1s
prefilling_size=7570910 prefilling_elapsed_millis=1.101s
prefilling_size=7808576 prefilling_elapsed_millis=1.201s
prefilling_size=8021522 prefilling_elapsed_millis=1.301s
prefilling_size=8212338 prefilling_elapsed_millis=1.401s
prefilling_size=8383537 prefilling_elapsed_millis=1.501s
prefilling_size=8537170 prefilling_elapsed_millis=1.601s
prefilling_size=8672102 prefilling_elapsed_millis=1.701s
prefilling_size=8793525 prefilling_elapsed_millis=1.801s
prefilling_size=8904475 prefilling_elapsed_millis=1.901s
prefilling_size=9003371 prefilling_elapsed_millis=2.002s
prefilling_size=9093910 prefilling_elapsed_millis=2.102s
prefilling_size=9177484 prefilling_elapsed_millis=2.202s
prefilling_size=9253552 prefilling_elapsed_millis=2.302s
prefilling_size=9320002 prefilling_elapsed_millis=2.402s
prefilling_size=9381327 prefilling_elapsed_millis=2.502s
prefilling_size=9437362 prefilling_elapsed_millis=2.602s
prefilling_size=9486765 prefilling_elapsed_millis=2.702s
prefilling_size=9532805 prefilling_elapsed_millis=2.802s
prefilling_size=9573099 prefilling_elapsed_millis=2.903s
prefilling_size=9610095 prefilling_elapsed_millis=3.003s
prefilling_size=9644669 prefilling_elapsed_millis=3.103s
prefilling_size=9674826 prefilling_elapsed_millis=3.203s
prefilling_size=9703977 prefilling_elapsed_millis=3.304s
prefilling_size=9727657 prefilling_elapsed_millis=3.404s
prefilling_size=9751343 prefilling_elapsed_millis=3.504s
prefilling_size=9772199 prefilling_elapsed_millis=3.604s
prefilling_size=9789271 prefilling_elapsed_millis=3.704s
prefilling_size=9806408 prefilling_elapsed_millis=3.804s
finished prefilling to size 9806441 for expected size 10000000 keysum=98039907793090, performing 82893537 updates; total_prefilling_elapsed_ms=3905 ms)
pref_size=9806441
pref_millis=3905
prefill_elapsed_ms=3922
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=2135
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=207307.406541978
REALTIME_END_PERF_FORMAT=207317.406585954
###############################################################################
################################## 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 2006 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.187s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 510 1003 1972 3794 7129 13055 23052 39079 63528 98812 146959 209153 283896 370685 464444 557683 643334 711499 757171 772496 756754 713852 649514 569530 482959 396147 313838 239474 176527 125200 86497 57463 36637 22797 13743 7937 4362 2324 1130 511 227 72 19 10 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 17 34 150 459 1203 3058 7025 14630 28244 50665 84765 134410 197107 276926 371205 472032 575169 665827 741846 788238 799656 778190 729498 656101 569771 478456 388202 302421 227854 163903 115531 78289 50477 31851 19549 11512 6400 3518 1749 795 382 125 28 18 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1020 2006 3944 7588 14258 26110 46104 78158 127056 197624 293918 418306 567792 741370 928888 1115366 1286668 1422998 1514342 1544992 1513508 1427704 1299028 1139060 965918 792294 627676 478948 353054 250400 172994 114926 73274 45594 27486 15874 8724 4648 2260 1022 454 144 38 20 4
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 2 17 34 150 459 1203 3057 7025 14630 28244 50665 84765 134410 197107 276926 371205 472032 575169 665827 741846 788238 799656 778190 729498 656101 569771 478456 388202 302421 227854 163903 115531 78289 50477 31851 19549 11512 6400 3518 1749 795 382 125 28 18 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.99609 1.98333 1.98305 1.96197 1.93951 1.91563 1.88284 1.84763 1.81282 1.7777 1.74363 1.7116 1.67868 1.65285 1.62647 1.60038 1.57679 1.55298 1.5321 1.51012 1.48981 1.47165 1.45494 1.43843 1.424 1.41012 1.39611 1.38152 1.36857 1.35462 1.34544 1.33217 1.31879 1.31112 1.30142 1.28877 1.27479 1.26639 1.24312 1.22611 1.22211 1.15859 1.13194 1.26316 1.1 1
tree_stats_height=55
tree_stats_numInternals=9827291
tree_stats_numLeaves=9827292
tree_stats_numNodes=19654583
tree_stats_numKeys=9827291
tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=30.2373
tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10240 20480 40800 80240 157760 303520 570320 1044400 1844160 3126320 5082240 7904960 11756720 16732240 22711680 29654800 37155520 44614640 51466720 56919920 60573680 61799680 60540320 57108160 51961120 45562400 38636720 31691760 25107040 19157920 14122160 10016000 6919760 4597040 2930960 1823760 1099440 634960 348960 185920 90400 40880 18160 5760 1520 800 160
tree_stats_sizeInBytes=786183320
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=7879
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:122776 4:139233 8:24471 16:138
(2^01, 2^02]: 122776
(2^02, 2^03]: 139233
(2^03, 2^04]: 24471
(2^04, 2^05]: 138
sum_limbo_reclamation_event_size_total=1111104
count_limbo_reclamation_event_size_by_thread=4461 4441 4500 4466 4476 4535 4471 4494 4485 4525 4499 4489 4481 4413 4411 4491 4492 4472 4433 4436 4529 4478 4487 4503 4504 4497 4437 4441 4481 4406 4479 4442 4532 4542 4425 4475 4482 4520 4513 4488 4505 4495 4527 4433 4436 4447 4460 4531 4507 4523 4475 4456 4465 4478 4463 4553 4474 4475 4411 4394 4472 4557 4512 4437
count_limbo_reclamation_event_size_total=286618
sum_limbo_reclamation_event_count_total=367616
first_thread_announced_epoch_by_thread=15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758 15758
sum_num_inserts_by_thread=17672 18018 17807 17803 17853 17532 17853 17740 17942 17571 17530 17829 17689 17693 17523 17552 17916 17647 17974 17759 17593 17909 17545 17717 17649 17991 17513 17664 17658 17619 17554 17485 18016 17921 17676 17965 17742 18021 17612 17755 17753 17825 17567 17518 17632 17894 17684 17759 17681 17993 17767 17622 17958 17679 17476 17479 17543 17563 17530 17414 17534 17891 17790 17898
sum_num_inserts_total=1133958
sum_num_deletes_by_thread=17891 17740 17800 17626 17941 18086 17881 17747 17951 17716 17729 17699 17716 17430 17385 17669 17507 17492 17626 17708 17657 17893 17758 18063 17704 17813 17449 17788 17429 17545 17396 17799 17909 17791 17672 17659 17635 17586 17773 17518 17667 17578 17676 17757 17459 17644 17642 17980 17824 17632 17518 17474 17697 17670 17482 17735 17929 17563 17218 17647 17685 17763 17763 17933
sum_num_deletes_total=1132113
sum_num_searches_by_thread=3541198 3542054 3515918 3497478 3540534 3533032 3528740 3521132 3525709 3518147 3503809 3497783 3497396 3494640 3482872 3490701 3502991 3510313 3534708 3532178 3527740 3538224 3517655 3525626 3514436 3512415 3487901 3488043 3490134 3495982 3469635 3482812 3546950 3545802 3538473 3533582 3538881 3540897 3528890 3518685 3506259 3501247 3491456 3494649 3504037 3505019 3530454 3533502 3511723 3516380 3503153 3509347 3515878 3502253 3474183 3475679 3489516 3481351 3478523 3477004 3487954 3530021 3518339 3525547
sum_num_searches_total=224717570
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3576761 3577812 3551525 3532907 3576328 3568650 3564474 3556619 3561602 3553434 3539068 3533311 3532801 3529763 3517780 3525922 3538414 3545452 3570308 3567645 3562990 3574026 3552958 3561406 3549789 3548219 3522863 3523495 3525221 3531146 3504585 3518096 3582875 3581514 3573821 3569206 3574258 3576504 3564275 3553958 3541679 3536650 3526699 3529924 3539128 3540557 3565780 3569241 3547228 3552005 3538438 3544443 3551533 3537602 3509141 3510893 3524988 3516477 3513271 3512065 3523173 3565675 3553892 3561378
average_num_operations_total=3546619
stdev_num_operations_total=2665
sum_num_operations_total=226983641
min_num_operations_total=3504585
max_num_operations_total=3582875
first_time_thread_terminate_by_thread=10000046 10000046 10000046 10000046 10000046 10000046 10000046 10000046 10000050 10000050 10000050 10000050 10000052 10000052 10000052 10000052 10000045 10000045 10000045 10000045 10000052 10000052 10000052 10000052 10000048 10000048 10000048 10000048 10000050 10000050 10000050 10000050 10000049 10000049 10000049 10000049 10000047 10000047 10000047 10000047 10000048 10000048 10000048 10000048 10000050 10000050 10000050 10000050 10000047 10000047 10000047 10000047 10000051 10000051 10000051 10000051 10000048 10000048 10000048 10000048 10000049 10000049 10000049 10000049
min_time_thread_terminate_total=10000045
max_time_thread_terminate_total=10000052
first_time_thread_start_by_thread=11 11 11 11 10 10 10 10 11 11 11 11 10 10 10 10 10 10 10 10 12 12 12 12 12 12 12 12 11 11 11 11 12 12 12 12 9 9 9 9 14 14 14 14 13 13 13 13 10 10 10 10 13 13 13 13 11 11 11 11 9 9 9 9
min_time_thread_start_total=9
max_time_thread_start_total=14
sum_duration_all_ops_total=
threads_final_keysum=98254661061675
threads_final_size=9827291
final_keysum=98254661061675
final_size=9827291
validate_result=success
Validation OK.
Structural validation OK.
total_find=224717570
total_rq=0
total_inserts=1133958
total_deletes=1132113
total_updates=2266071
total_queries=224717570
total_ops=226983641
find_throughput=22471757
rq_throughput=0
update_throughput=226607
query_throughput=22471757
total_throughput=22698364
total find : 224717570
total rq : 0
total inserts : 1133958
total deletes : 1132113
total updates : 2266071
total queries : 224717570
total ops : 226983641
find throughput : 22471757
rq throughput : 0
update throughput : 226607
query throughput : 22471757
total throughput : 22698364
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=310481421204113
total_execution_walltime=14.201s
REALTIME_MAIN_END_PERF_FORMAT=207317.647378780
[time_cmd_output] time_elapsed_sec=14.37, faults_major=1, faults_minor=308102, mem_maxresident_kb=1233476, user_cputime=923.67, sys_cputime=5.29, percent_cpu=6463%
timeout=false
algorithm=guerraoui_ext_bst_ticket.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1