__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 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000715.txt __path_data=data/data000715.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6987518.760686683 binary=./guerraoui_ext_bst_ticket.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 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=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 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,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.039s prefilling_size=8580066 prefilling_elapsed_millis=1s prefilling_size=8786211 prefilling_elapsed_millis=1.1s prefilling_size=8961014 prefilling_elapsed_millis=1.201s prefilling_size=9109043 prefilling_elapsed_millis=1.301s prefilling_size=9233706 prefilling_elapsed_millis=1.401s prefilling_size=9340729 prefilling_elapsed_millis=1.501s prefilling_size=9432178 prefilling_elapsed_millis=1.601s prefilling_size=9510690 prefilling_elapsed_millis=1.701s prefilling_size=9580451 prefilling_elapsed_millis=1.802s prefilling_size=9639746 prefilling_elapsed_millis=1.902s prefilling_size=9687867 prefilling_elapsed_millis=2.002s prefilling_size=9731342 prefilling_elapsed_millis=2.102s prefilling_size=9767526 prefilling_elapsed_millis=2.202s prefilling_size=9795994 prefilling_elapsed_millis=2.302s prefilling_size=9823808 prefilling_elapsed_millis=2.403s finished prefilling to size 9823888 for expected size 10000000 keysum=98235068135138, performing 80862752 updates; total_prefilling_elapsed_ms=2504 ms) pref_size=9823888 pref_millis=2504 prefill_elapsed_ms=2540 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=979 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6987521.492980960 REALTIME_END_PERF_FORMAT=6987531.493127745 ############################################################################### ################################## 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 (192 threads)... partitioned into 1016 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.298s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 508 1000 1953 3760 7008 12589 21640 35805 57021 87351 128471 181534 246674 322427 405311 490738 571279 641489 692487 722709 729782 711272 669729 611843 541302 464547 388136 313525 247279 189560 142615 104472 75629 54593 38963 27476 19512 13498 8924 5482 3333 1902 1032 540 245 90 29 9 4 1 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 4 16 47 146 512 1427 3538 7475 14589 26691 46231 75408 116394 170921 239543 319884 410197 501069 590491 662265 715636 748292 752815 727615 682384 618057 540958 462747 379771 304998 236505 180758 133315 96665 70223 50450 35440 25526 18072 12366 7631 4764 2772 1524 835 400 151 49 14 7 1 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1016 2000 3906 7520 14016 25178 43280 71610 114042 174702 256942 363068 493348 644854 810622 981476 1142558 1282978 1384974 1445418 1459564 1422544 1339458 1223686 1082604 929094 776272 627050 494558 379120 285230 208944 151258 109186 77926 54952 39024 26996 17848 10964 6666 3804 2064 1080 490 180 58 18 8 2 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 4 16 47 146 512 1427 3538 7474 14589 26691 46231 75408 116394 170921 239543 319884 410197 501069 590491 662265 715636 748292 752815 727615 682384 618057 540958 462747 379771 304998 236505 180758 133315 96665 70223 50450 35440 25526 18072 12366 7631 4764 2772 1524 835 400 151 49 14 7 1 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.99219 1.98425 1.9765 1.96262 1.93191 1.89819 1.85948 1.82726 1.79627 1.76595 1.73537 1.70652 1.67942 1.65355 1.62853 1.60538 1.58206 1.56145 1.53975 1.52182 1.50489 1.48732 1.4708 1.45678 1.44235 1.4291 1.41776 1.40389 1.39435 1.38329 1.37617 1.36627 1.36196 1.36093 1.35685 1.35259 1.35507 1.34589 1.33057 1.30715 1.30399 1.28533 1.27129 1.26163 1.22685 1.18367 1.16111 1.15517 1.22222 1.125 1.5 1 tree_stats_height=61 tree_stats_numInternals=9997590 tree_stats_numLeaves=9997591 tree_stats_numNodes=19995181 tree_stats_numKeys=9997590 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=31.1484 tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10240 20480 40640 80000 156240 300800 560640 1007120 1731200 2864400 4561680 6988080 10277680 14522720 19733920 25794160 32424880 39259040 45702320 51319120 55398960 57816720 58382560 56901760 53578320 48947440 43304160 37163760 31050880 25082000 19782320 15164800 11409200 8357760 6050320 4367440 3117040 2198080 1560960 1079840 713920 438560 266640 152160 82560 43200 19600 7200 2320 720 320 80 80 tree_stats_sizeInBytes=799807240 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=4635 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:0 32:0 64:1 128:1263 256:347276 512:2244 (2^06, 2^07]: 1 (2^07, 2^08]: 1263 (2^08, 2^09]: 347276 (2^09, 2^10]: 2244 sum_limbo_reclamation_event_size_total=143129224 count_limbo_reclamation_event_size_by_thread=3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 3654 count_limbo_reclamation_event_size_total=350784 sum_limbo_reclamation_event_count_total=350976 first_thread_announced_epoch_by_thread=9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 9270 sum_num_inserts_by_thread=1483892 1494226 1497932 1500687 1495179 1483475 1489164 1495232 1506435 1510207 1483619 1483115 1492887 1489696 1501941 1502835 1491486 1482308 1477802 1487419 1491973 1497972 1487107 1474123 1487800 1487745 1502136 1502546 1496724 1482828 1491709 1495065 1509422 1505351 1492744 1482052 1493489 1500370 1501151 1502657 1497913 1482605 1479085 1487176 1496530 1495915 1488473 1475161 1486435 1491375 1500044 1500505 1490846 1483936 1487219 1491898 1503689 1505694 1497345 1489472 1492510 1495667 1505977 1504660 1496184 1487612 1483497 1489506 1498574 1502285 1488882 1483927 1487389 1497597 1497809 1505016 1487947 1485255 1487327 1493226 1506648 1506822 1499376 1488749 1492326 1500076 1505540 1508542 1496504 1487416 1483334 1486274 1502877 1503664 1493516 1487035 sum_num_inserts_total=143385363 sum_num_deletes_by_thread=1482257 1493169 1499153 1502270 1491862 1485203 1488970 1495883 1504819 1509226 1482117 1481624 1493248 1490417 1505740 1503001 1489637 1482997 1480541 1485767 1489921 1495689 1486221 1477218 1488760 1486772 1499669 1503176 1493876 1479645 1487620 1496399 1507649 1507743 1491943 1485387 1495070 1498648 1501957 1506143 1496617 1484654 1478458 1484237 1492911 1495283 1486725 1476224 1487289 1492670 1496788 1503386 1492002 1483980 1485657 1493293 1502340 1508075 1493211 1488768 1491602 1497458 1503300 1502931 1493939 1487517 1482141 1488503 1501707 1501730 1491234 1482659 1488883 1495602 1500327 1503343 1491790 1486648 1484538 1493230 1508031 1507962 1498632 1490326 1491158 1499729 1508731 1507948 1497685 1486502 1485781 1488366 1503874 1503146 1493165 1487038 sum_num_deletes_total=143377131 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=2966149 2987395 2997085 3002957 2987041 2968678 2978134 2991115 3011254 3019433 2965736 2964739 2986135 2980113 3007681 3005836 2981123 2965305 2958343 2973186 2981894 2993661 2973328 2951341 2976560 2974517 3001805 3005722 2990600 2962473 2979329 2991464 3017071 3013094 2984687 2967439 2988559 2999018 3003108 3008800 2994530 2967259 2957543 2971413 2989441 2991198 2975198 2951385 2973724 2984045 2996832 3003891 2982848 2967916 2972876 2985191 3006029 3013769 2990556 2978240 2984112 2993125 3009277 3007591 2990123 2975129 2965638 2978009 3000281 3004015 2980116 2966586 2976272 2993199 2998136 3008359 2979737 2971903 2971865 2986456 3014679 3014784 2998008 2979075 2983484 2999805 3014271 3016490 2994189 2973918 2969115 2974640 3006751 3006810 2986681 2974073 average_num_operations_total=2987109 stdev_num_operations_total=1709 sum_num_operations_total=286762494 min_num_operations_total=2951341 max_num_operations_total=3019433 first_time_thread_terminate_by_thread=10000148 10000146 10000147 10000148 10000146 10000146 10000146 10000145 10000146 10000148 10000146 10000145 10000148 10000147 10000146 10000146 10000145 10000147 10000147 10000147 10000147 10000148 10000147 10000146 10000148 10000147 10000147 10000149 10000146 10000146 10000145 10000145 10000146 10000147 10000146 10000145 10000148 10000147 10000146 10000146 10000145 10000147 10000147 10000145 10000146 10000147 10000147 10000146 10000147 10000148 10000145 10000147 10000149 10000145 10000148 10000148 10000149 10000148 10000147 10000148 10000145 10000149 10000149 10000145 10000148 10000145 10000149 10000147 10000147 10000148 10000148 10000149 10000145 10000148 10000146 10000145 10000149 10000148 10000148 10000145 10000145 10000149 10000147 10000145 10000147 10000145 10000149 10000145 10000148 10000146 10000145 10000145 10000147 10000148 10000146 10000149 min_time_thread_terminate_total=10000145 max_time_thread_terminate_total=10000149 first_time_thread_start_by_thread=6 6 6 6 6 6 6 6 6 6 6 6 7 6 6 6 6 6 6 6 7 6 6 7 6 6 6 6 6 6 6 6 6 6 6 6 7 6 6 6 6 6 6 6 7 6 6 7 6 6 6 5 6 6 6 6 6 6 6 7 6 6 5 6 5 5 6 6 6 5 6 6 6 6 6 5 6 6 6 6 6 6 6 7 6 6 5 6 5 5 6 6 6 5 6 6 min_time_thread_start_total=5 max_time_thread_start_total=7 sum_duration_all_ops_total= threads_final_keysum=99974111712955 threads_final_size=9997590 final_keysum=99974111712955 final_size=9997590 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=143385363 total_deletes=143377131 total_updates=286762494 total_queries=0 total_ops=286762494 find_throughput=0 rq_throughput=0 update_throughput=28676249 query_throughput=0 total_throughput=28676249 total find : 0 total rq : 0 total inserts : 143385363 total deletes : 143377131 total updates : 286762494 total queries : 0 total ops : 286762494 find throughput : 0 rq throughput : 0 update throughput : 28676249 query throughput : 0 total throughput : 28676249 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=52.2824 PAPI_L3_TCM=21.0381 PAPI_TOT_CYC=9318.46 PAPI_TOT_INS=1027.61 garbage=0 total_execution_walltime=13.118s REALTIME_MAIN_END_PERF_FORMAT=6987531.880030788 [time_cmd_output] time_elapsed_sec=13.31, faults_major=0, faults_minor=317142, mem_maxresident_kb=1266516, user_cputime=1227.50, sys_cputime=4.40, percent_cpu=9254% timeout=false algorithm=guerraoui_ext_bst_ticket.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1