__step=000715
__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 128 -nprefill 128 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000715.txt
__path_data=data/data000715.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215451.209022969
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=50
DEL_FRAC=50
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=128
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=128
WORK_THREADS=128
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=50.0 50.0
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,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167,168,169,170,171,172,173,174,175,176,177,178,179,180,181,182,183,184,185,186,187,188,189,190,191


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.023s
prefilling_size=8667492 prefilling_elapsed_millis=1s
prefilling_size=8870338 prefilling_elapsed_millis=1.1s
prefilling_size=9040012 prefilling_elapsed_millis=1.201s
prefilling_size=9184718 prefilling_elapsed_millis=1.301s
prefilling_size=9304998 prefilling_elapsed_millis=1.401s
prefilling_size=9401377 prefilling_elapsed_millis=1.501s
prefilling_size=9490875 prefilling_elapsed_millis=1.601s
prefilling_size=9564986 prefilling_elapsed_millis=1.701s
prefilling_size=9627587 prefilling_elapsed_millis=1.801s
prefilling_size=9680718 prefilling_elapsed_millis=1.901s
prefilling_size=9725895 prefilling_elapsed_millis=2.001s
prefilling_size=9765533 prefilling_elapsed_millis=2.101s
prefilling_size=9799488 prefilling_elapsed_millis=2.201s
prefilling_size=9830186 prefilling_elapsed_millis=2.302s
finished prefilling to size 9830204 for expected size 10000000 keysum=98291651261586, performing 82222650 updates; total_prefilling_elapsed_ms=2403 ms)
pref_size=9830204
pref_millis=2403
prefill_elapsed_ms=2415
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=554

main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=215453.671596441
REALTIME_END_PERF_FORMAT=215463.671666725

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (256 threads)...
partitioned into 1910 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.364s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 494 955 1832 3437 6278 11117 18837 30573 47562 70975 102049 140976 188340 243585 304930 369156 433034 495118 552229 599677 635055 654921 658834 646960 617828 573702 516157 451836 383050 315005 251830 196035 147695 107847 76706 53303 35579 23246 14576 8877 5213 2943 1567 781 331 113 44 17 5 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 2 14 33 78 227 596 1439 3397 7101 13584 24149 39901 63122 93612 133095 182240 240704 305278 370950 438007 504781 564299 615189 651008 670708 676092 661954 631247 580478 520622 451095 378180 307625 244375 187543 138988 100109 71027 47912 31916 20275 12541 7483 4319 2353 1231 549 182 71 29 9 1 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 508 988 1910 3664 6874 12556 22234 37674 61146 95124 141950 204098 281952 376680 487170 609860 738312 866068 990236 1104458 1199354 1270110 1309842 1317668 1293920 1235656 1147404 1032314 903672 766100 630010 503660 392070 295390 215694 153412 106606 71158 46492 29152 17754 10426 5886 3134 1562 662 226 88 34 10 2 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 2 14 33 78 227 596 1439 3397 7101 13584 24148 39901 63122 93612 133095 182240 240704 305278 370950 438007 504781 564299 615189 651008 670708 676092 661954 631247 580478 520622 451095 378180 307625 244375 187543 138988 100109 71027 47912 31916 20275 12541 7483 4319 2353 1231 549 182 71 29 9 1 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.97244 1.9666 1.95916 1.93805 1.9133 1.88539 1.84722 1.81151 1.77784 1.74612 1.71891 1.69073 1.66799 1.64666 1.62592 1.60531 1.58652 1.57168 1.55767 1.54296 1.5295 1.51564 1.50299 1.49099 1.47749 1.46429 1.44985 1.43769 1.42388 1.41118 1.39972 1.38922 1.37671 1.3651 1.35562 1.34745 1.33374 1.32668 1.31352 1.30451 1.29362 1.28228 1.26622 1.2492 1.21191 1.17069 1.19469 1.19318 1.14706 1.1 1.5 1

tree_stats_height=61
tree_stats_numInternals=10001721
tree_stats_numLeaves=10001722
tree_stats_numNodes=20003443
tree_stats_numKeys=10001721

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=32.625

tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10240 20320 39520 76400 146560 274960 502240 889360 1506960 2445840 3804960 5678000 8163920 11278080 15067200 19486800 24394400 29532480 34642720 39609440 44178320 47974160 50804400 52393680 52706720 51756800 49426240 45896160 41292560 36146880 30644000 25200400 20146400 15682800 11815600 8627760 6136480 4264240 2846320 1859680 1166080 710160 417040 235440 125360 62480 26480 9040 3520 1360 400 80 80
tree_stats_sizeInBytes=800137720

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=2759


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:4 4:1 8:1 16:3 32:15 64:24 128:74 256:51576 512:230018 1024:254
    (2^01, 2^02]: 4
    (2^02, 2^03]: 1
    (2^03, 2^04]: 1
    (2^04, 2^05]: 3
    (2^05, 2^06]: 15
    (2^06, 2^07]: 24
    (2^07, 2^08]: 74
    (2^08, 2^09]: 51576
    (2^09, 2^10]: 230018
    (2^10, 2^11]: 254
sum_limbo_reclamation_event_size_total=157077008
count_limbo_reclamation_event_size_by_thread=2203 2203 2203 2202 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2202 2202 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2202 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2202 2203 2203 2202 2203 2203 2203 2203 2203 2203 2203 2202 2203 2203 2203 2202 2203 2203 2203 2203 2203 2203 2203 2203 2202 2203 2203 2203 2203 2203 2203 2202 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2202 2203 2203 2203 2203 2203 2203 2203 2203 2203 2203 2201 2203 2203 2203 2203 2202 2203 2203 2203 2203 2203
count_limbo_reclamation_event_size_total=281970
sum_limbo_reclamation_event_count_total=282240
first_thread_announced_epoch_by_thread=5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518 5518
sum_num_inserts_by_thread=1238751 1236606 1226723 1223938 1235408 1230674 1221405 1222654 1230259 1227430 1226122 1224281 1233337 1232922 1231428 1230510 1236262 1237624 1228521 1229199 1226801 1222990 1220527 1225367 1221364 1228133 1222006 1227468 1229734 1231947 1230327 1228982 1237289 1233281 1226664 1226861 1226720 1232343 1225595 1232228 1227988 1234500 1231014 1232955 1234888 1228989 1228730 1229371 1223051 1225594 1222498 1220951 1224326 1224948 1219507 1230006 1232755 1231864 1229847 1228165 1229321 1229009 1221799 1221945 1222211 1227546 1223076 1222221 1226035 1234388 1233274 1227619 1231313 1234545 1228663 1232710 1225768 1224298 1220739 1220974 1227102 1223559 1222438 1223084 1234769 1229996 1231724 1234601 1232082 1231031 1226735 1219860 1224543 1226909 1221705 1220898 1232227 1226571 1235719 1237632 1241671 1233824 1234339 1236755 1235193 1231173 1229772 1231299 1236408 1232007 1231755 1228434 1235102 1234781 1231158 1230878 1238959 1235701 1234199 1232693 1237083 1240216 1234591 1235657 1240564 1243816 1235992 1235275
sum_num_inserts_total=157402562
sum_num_deletes_by_thread=1236451 1237118 1229064 1223025 1238322 1231681 1222840 1222875 1228759 1229475 1227242 1223700 1234556 1233604 1233076 1232278 1233175 1234117 1231029 1230621 1228720 1223743 1222023 1227741 1220665 1227442 1221327 1226005 1231804 1232820 1228755 1229695 1236214 1234588 1227103 1226609 1228791 1233671 1228180 1228938 1229974 1234181 1229328 1233345 1234047 1231061 1228902 1229667 1221052 1226099 1220890 1220723 1224723 1226894 1219732 1230101 1234853 1233812 1227714 1226432 1230918 1226997 1223349 1224983 1220612 1226590 1222244 1222000 1225766 1232091 1229333 1228493 1233806 1234782 1230890 1231012 1226461 1226747 1220256 1218654 1225501 1224454 1219573 1222296 1233404 1232386 1231273 1226506 1232808 1232554 1226949 1218375 1224434 1226348 1220699 1220372 1230991 1224231 1233911 1234190 1239200 1235153 1232648 1236083 1236645 1233604 1231450 1229695 1234946 1232511 1232474 1228222 1235210 1235489 1232981 1229998 1236787 1237885 1233375 1234011 1235307 1241615 1238362 1235054 1241217 1243366 1238330 1233187
sum_num_deletes_total=157403416
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2475202 2473724 2455787 2446963 2473730 2462355 2444245 2445529 2459018 2456905 2453364 2447981 2467893 2466526 2464504 2462788 2469437 2471741 2459550 2459820 2455521 2446733 2442550 2453108 2442029 2455575 2443333 2453473 2461538 2464767 2459082 2458677 2473503 2467869 2453767 2453470 2455511 2466014 2453775 2461166 2457962 2468681 2460342 2466300 2468935 2460050 2457632 2459038 2444103 2451693 2443388 2441674 2449049 2451842 2439239 2460107 2467608 2465676 2457561 2454597 2460239 2456006 2445148 2446928 2442823 2454136 2445320 2444221 2451801 2466479 2462607 2456112 2465119 2469327 2459553 2463722 2452229 2451045 2440995 2439628 2452603 2448013 2442011 2445380 2468173 2462382 2462997 2461107 2464890 2463585 2453684 2438235 2448977 2453257 2442404 2441270 2463218 2450802 2469630 2471822 2480871 2468977 2466987 2472838 2471838 2464777 2461222 2460994 2471354 2464518 2464229 2456656 2470312 2470270 2464139 2460876 2475746 2473586 2467574 2466704 2472390 2481831 2472953 2470711 2481781 2487182 2474322 2468462
average_num_operations_total=2459421
stdev_num_operations_total=946
sum_num_operations_total=314805978
min_num_operations_total=2438235
max_num_operations_total=2487182
first_time_thread_terminate_by_thread=10000522 10000947 10000982 10001139 10000672 10000493 10000869 10000947 10000837 10001014 10000490 10000929 10001018 10000831 10001080 10000490 10000723 10000527 10000782 10000830 10001012 10000552 10001106 10000600 10001003 10001043 10000560 10000436 10000976 10000897 10001088 10001000 10001129 10000904 10001150 10001010 10000952 10000796 10000470 10000597 10001079 10001069 10000561 10000647 10001133 10001215 10001033 10000892 10000855 10000780 10000703 10000868 10001052 10001204 10000889 10000772 10000483 10000904 10001037 10001038 10001099 10001235 10000994 10000866 10001174 10001221 10001093 10001046 10000473 10000738 10000567 10000838 10000829 10000615 10001070 10000475 10000544 10001120 10000449 10001022 10000633 10000758 10000630 10000880 10000626 10001024 10001171 10000499 10000828 10001092 10000588 10001076 10001013 10000911 10000530 10000535 10000932 10000470 10000981 10001006 10000931 10000912 10000847 10000532 10000520 10000892 10000548 10000569 10001019 10000980 10001168 10001144 10000511 10000851 10000815 10000819 10000807 10001174 10000964 10001094 10001054 10001077 10001104 10000564 10001093 10001302 10000954 10001021
min_time_thread_terminate_total=10000436
max_time_thread_terminate_total=10001302
first_time_thread_start_by_thread=4 4 4 4 6 6 6 6 5 5 5 5 6 6 6 6 7 7 7 7 7 7 7 7 8 8 8 8 6 6 6 6 6 6 6 6 7 7 7 7 7 7 7 7 5 5 5 5 8 8 8 8 5 5 5 5 5 5 5 5 8 8 8 8 4 4 4 4 6 6 6 6 5 5 5 5 6 6 6 6 7 7 7 7 7 7 7 7 8 8 8 8 6 6 6 6 6 6 6 6 7 7 7 7 7 7 7 7 5 5 5 5 8 8 8 8 5 5 5 5 5 5 5 5 8 8 8 8
min_time_thread_start_total=4
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=100018549339555
threads_final_size=10001721
final_keysum=100018549339555
final_size=10001721
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=157402562
total_deletes=157403416
total_updates=314805978
total_queries=0
total_ops=314805978
find_throughput=0
rq_throughput=0
update_throughput=31480597
query_throughput=0
total_throughput=31480597


total find                    : 0
total rq                      : 0
total inserts                 : 157402562
total deletes                 : 157403416
total updates                 : 314805978
total queries                 : 0
total ops                     : 314805978
find throughput               : 0
rq throughput                 : 0
update throughput             : 31480597
query throughput              : 0
total throughput              : 31480597

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=0
total_execution_walltime=12.958s
REALTIME_MAIN_END_PERF_FORMAT=215464.168145465
[time_cmd_output] time_elapsed_sec=13.13, faults_major=0, faults_minor=322088, mem_maxresident_kb=1286252, user_cputime=1563.41, sys_cputime=37.93, percent_cpu=12190%
timeout=false
algorithm=guerraoui_ext_bst_ticket.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1