__step=000074 __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" ./sigouin_int_bst_kcas_validate.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000074.txt __path_data=data/data000074.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x561b4d475400 addrof(kcasDescriptors[1])=0x561b4d475d00 addrof(rdcssDescriptors[0])=0x561b4d595400 addrof(rdcssDescriptors[1])=0x561b4d5954c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6979674.144133454 binary=./sigouin_int_bst_kcas_validate.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=sigouin_int_bst_kcas_validate 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=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 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=56 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.004s prefilling_size=6969268 prefilling_elapsed_millis=1.001s prefilling_size=7265781 prefilling_elapsed_millis=1.101s prefilling_size=7529427 prefilling_elapsed_millis=1.201s prefilling_size=7768331 prefilling_elapsed_millis=1.301s prefilling_size=7982920 prefilling_elapsed_millis=1.402s prefilling_size=8175947 prefilling_elapsed_millis=1.502s prefilling_size=8350375 prefilling_elapsed_millis=1.602s prefilling_size=8507024 prefilling_elapsed_millis=1.702s prefilling_size=8647243 prefilling_elapsed_millis=1.802s prefilling_size=8774703 prefilling_elapsed_millis=1.903s prefilling_size=8890143 prefilling_elapsed_millis=2.003s prefilling_size=8992874 prefilling_elapsed_millis=2.103s prefilling_size=9086684 prefilling_elapsed_millis=2.203s prefilling_size=9172963 prefilling_elapsed_millis=2.303s prefilling_size=9252332 prefilling_elapsed_millis=2.404s prefilling_size=9322656 prefilling_elapsed_millis=2.504s prefilling_size=9385440 prefilling_elapsed_millis=2.604s prefilling_size=9442900 prefilling_elapsed_millis=2.704s prefilling_size=9495822 prefilling_elapsed_millis=2.804s prefilling_size=9544661 prefilling_elapsed_millis=2.905s prefilling_size=9587515 prefilling_elapsed_millis=3.005s prefilling_size=9625218 prefilling_elapsed_millis=3.105s prefilling_size=9659278 prefilling_elapsed_millis=3.205s prefilling_size=9691544 prefilling_elapsed_millis=3.305s prefilling_size=9721292 prefilling_elapsed_millis=3.405s prefilling_size=9747426 prefilling_elapsed_millis=3.506s prefilling_size=9771123 prefilling_elapsed_millis=3.606s prefilling_size=9791696 prefilling_elapsed_millis=3.706s prefilling_size=9811990 prefilling_elapsed_millis=3.806s finished prefilling to size 9812020 for expected size 10000000 keysum=98117796595233, performing 79403147 updates; total_prefilling_elapsed_ms=3907 ms) pref_size=9812020 pref_millis=3907 prefill_elapsed_ms=3926 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6979678.250623173 REALTIME_END_PERF_FORMAT=6979688.250752579 ############################################################################### ################################## 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 1004 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.14s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 506 993 1894 3547 6339 11033 18434 29515 45866 68705 99089 137767 183884 236624 293644 350701 403196 446272 475999 489800 485331 464287 428480 381519 326756 270399 216057 167332 125513 91421 64671 44811 30032 19386 12399 7715 4703 2740 1529 913 481 256 115 50 17 5 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 1 11 47 132 419 896 1985 3982 7091 12356 20623 32133 48887 69971 95656 125405 157756 190698 219396 243009 259415 265617 261545 248992 227491 199653 168761 137456 108464 82731 61102 43644 30806 20979 13603 8816 5511 3427 2067 1100 689 367 201 89 41 15 4 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 507 1004 1941 3679 6758 11929 20419 33497 52957 81061 119712 169900 232771 306595 389300 476106 560952 636970 695395 732809 744746 729904 690025 630511 554247 470052 384818 304788 233977 174152 125773 88455 60838 40365 26002 16531 10214 6167 3596 2013 1170 623 316 139 58 20 6 2 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 507 1004 1941 3679 6758 11929 20419 33497 52957 81061 119712 169900 232771 306595 389300 476106 560952 636970 695395 732809 744746 729904 690025 630511 554247 470052 384818 304788 233977 174152 125773 88455 60838 40365 26002 16531 10214 6167 3596 2013 1170 623 316 139 58 20 6 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.98824 1.98225 1.94422 1.91963 1.87279 1.82717 1.78682 1.7377 1.69982 1.6646 1.62924 1.59151 1.55918 1.52717 1.49797 1.46869 1.44161 1.41675 1.39111 1.3693 1.3479 1.3284 1.30927 1.29279 1.27395 1.25854 1.24342 1.23058 1.21866 1.20788 1.19725 1.1891 1.18119 1.16984 1.1639 1.15891 1.15117 1.14333 1.1388 1.13459 1.12767 1.12137 1.09631 1.07595 1.05755 1.05172 1.05 1 1 tree_stats_height=57 tree_stats_numInternals=6451238 tree_stats_numLeaves=3383042 tree_stats_numNodes=9834280 tree_stats_numKeys=9834280 tree_stats_avgDegreeInternal=1.5244 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34401 tree_stats_avgKeyDepth=29.0575 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:175062 2:135836 4:13027 8:7 [2^00, 2^01]: 175062 (2^01, 2^02]: 135836 (2^02, 2^03]: 13027 (2^03, 2^04]: 7 sum_limbo_reclamation_event_size_total=539062 count_limbo_reclamation_event_size_by_thread=6684 6767 6816 6823 6777 6742 6731 6813 6827 6830 6756 6762 6746 6799 6722 6743 6729 6721 6791 6747 6830 6782 6630 6720 6766 6680 6707 6696 6767 6653 6743 6726 6730 6847 6756 6673 6662 6775 6812 6681 6796 6735 6723 6743 6765 6741 6765 6702 count_limbo_reclamation_event_size_total=323932 sum_limbo_reclamation_event_count_total=510384 first_thread_announced_epoch_by_thread=28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 28500 sum_num_inserts_by_thread=24159 24262 24564 24710 24645 23927 24220 24331 24535 24861 24379 24049 24602 24324 24627 24625 24532 24066 24105 24416 24605 24672 24100 24297 24146 24446 24472 24688 24472 23699 24124 24328 24637 24814 24556 23887 24469 24469 24690 24665 24587 24253 23887 24181 24313 24710 24304 24144 sum_num_inserts_total=1170554 sum_num_deletes_by_thread=24164 24205 24425 24730 24276 24609 24177 24457 24796 24807 24381 24162 24321 24555 24424 24691 24416 24335 24441 24286 24264 24547 23877 24158 24490 24104 24387 24347 24294 23847 24231 24402 24641 24649 24524 24175 23947 24484 24678 24343 24465 24184 24152 24396 24156 24508 24187 24023 sum_num_deletes_total=1169118 sum_num_searches_by_thread=4791007 4822608 4852571 4857613 4835802 4788595 4805922 4829705 4872522 4885070 4826340 4796299 4815164 4837344 4872228 4871222 4840413 4804970 4780444 4802052 4840058 4855974 4789575 4768567 4800075 4818191 4850483 4850654 4826722 4735450 4798264 4823592 4874069 4868330 4818445 4791017 4813589 4836774 4864933 4863085 4840156 4802413 4775426 4807478 4843002 4858971 4798922 4776057 sum_num_searches_total=231578163 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=4839330 4871075 4901560 4907053 4884723 4837131 4854319 4878493 4921853 4934738 4875100 4844510 4864087 4886223 4921279 4920538 4889361 4853371 4828990 4850754 4888927 4905193 4837552 4817022 4848711 4866741 4899342 4899689 4875488 4782996 4846619 4872322 4923347 4917793 4867525 4839079 4862005 4885727 4914301 4912093 4889208 4850850 4823465 4856055 4891471 4908189 4847413 4824224 average_num_operations_total=4873288 stdev_num_operations_total=4931 sum_num_operations_total=233917835 min_num_operations_total=4782996 max_num_operations_total=4934738 first_time_thread_terminate_by_thread=10000130 10000129 10000129 10000129 10000129 10000130 10000130 10000129 10000130 10000129 10000130 10000129 10000130 10000129 10000129 10000129 10000129 10000129 10000129 10000130 10000130 10000130 10000129 10000129 10000130 10000129 10000129 10000129 10000129 10000129 10000130 10000129 10000130 10000129 10000129 10000129 10000130 10000129 10000129 10000129 10000129 10000129 10000129 10000130 10000130 10000130 10000129 10000129 min_time_thread_terminate_total=10000129 max_time_thread_terminate_total=10000130 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 4 3 3 4 3 3 4 4 3 3 3 4 4 3 3 3 3 3 3 3 3 3 3 3 4 3 3 4 3 3 4 4 3 3 3 3 4 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=98346881800921 threads_final_size=9834280 final_keysum=98346881800921 final_size=9834280 validate_result=success Validation OK. Structural validation OK. total_find=231578163 total_rq=0 total_inserts=1170554 total_deletes=1169118 total_updates=2339672 total_queries=231578163 total_ops=233917835 find_throughput=23157816 rq_throughput=0 update_throughput=233967 query_throughput=23157816 total_throughput=23391783 total find : 231578163 total rq : 0 total inserts : 1170554 total deletes : 1169118 total updates : 2339672 total queries : 231578163 total ops : 233917835 find throughput : 23157816 rq throughput : 0 update throughput : 233967 query throughput : 23157816 total throughput : 23391783 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=31.2295 PAPI_L3_TCM=9.93154 PAPI_TOT_CYC=5721 PAPI_TOT_INS=1497.2 garbage=1137453109234325 total_execution_walltime=14.346s REALTIME_MAIN_END_PERF_FORMAT=6979688.491385370 [time_cmd_output] time_elapsed_sec=14.55, faults_major=0, faults_minor=233546, mem_maxresident_kb=936052, user_cputime=698.24, sys_cputime=4.95, percent_cpu=4832% timeout=false algorithm=sigouin_int_bst_kcas_validate.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2