__step=000075 __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 96 -nprefill 96 -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=data000075.txt __path_data=data/data000075.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x5578d67e2400 addrof(kcasDescriptors[1])=0x5578d67e2d00 addrof(rdcssDescriptors[0])=0x5578d6902400 addrof(rdcssDescriptors[1])=0x5578d69024c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6979688.736029628 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=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 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,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.038s prefilling_size=8854841 prefilling_elapsed_millis=1s prefilling_size=9049510 prefilling_elapsed_millis=1.1s prefilling_size=9213062 prefilling_elapsed_millis=1.201s prefilling_size=9344954 prefilling_elapsed_millis=1.301s prefilling_size=9453762 prefilling_elapsed_millis=1.401s prefilling_size=9546682 prefilling_elapsed_millis=1.501s prefilling_size=9621754 prefilling_elapsed_millis=1.601s prefilling_size=9685940 prefilling_elapsed_millis=1.701s prefilling_size=9738482 prefilling_elapsed_millis=1.802s prefilling_size=9781915 prefilling_elapsed_millis=1.902s prefilling_size=9817530 prefilling_elapsed_millis=2.002s finished prefilling to size 9817534 for expected size 10000000 keysum=98163294162634, performing 80424162 updates; total_prefilling_elapsed_ms=2103 ms) pref_size=9817534 pref_millis=2103 prefill_elapsed_ms=2129 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6979691.89778891 REALTIME_END_PERF_FORMAT=6979701.89913173 ############################################################################### ################################## 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 1014 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.231s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 508 1003 1927 3608 6507 11259 18617 29362 44870 65565 92424 125212 163880 207991 255300 304380 351081 390944 420777 439020 442524 434209 414708 385730 348920 307580 264144 222016 181502 144298 111929 84289 61726 43795 30001 19824 12617 7895 4841 2871 1679 958 505 237 111 41 14 7 7 3 3 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 11 37 129 386 974 2102 4253 7345 12977 20711 31948 46188 64223 85454 109590 136296 164924 190278 212006 229153 236719 238381 232127 221339 204000 183198 159126 136798 113896 91954 72333 55410 41335 29722 20750 13924 8948 5651 3452 2096 1179 708 381 192 95 34 10 3 6 1 3 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1014 1964 3737 6893 12233 20719 33615 52215 78542 113135 157160 210068 272214 340754 413970 487377 555868 611055 651026 671677 670928 653089 617857 570259 511580 447342 381142 318300 258194 203883 156622 117136 85130 59723 40574 26541 16843 10492 6323 3775 2137 1213 618 303 136 48 17 10 9 4 3 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 510 1014 1964 3737 6893 12233 20719 33615 52215 78542 113135 157160 210068 272214 340754 413970 487377 555868 611055 651026 671677 670928 653089 617857 570259 511580 447342 381142 318300 258194 203883 156622 117136 85130 59723 40574 26541 16843 10492 6323 3775 2137 1213 618 303 136 48 17 10 9 4 3 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.99216 1.94773 1.92159 1.87905 1.8307 1.77332 1.72388 1.67985 1.64487 1.60566 1.5722 1.53993 1.51571 1.48772 1.46564 1.44205 1.42018 1.39598 1.37681 1.35737 1.34005 1.32623 1.31106 1.29866 1.28524 1.2732 1.26154 1.25262 1.24094 1.23078 1.21921 1.20972 1.1998 1.1871 1.17703 1.16555 1.15923 1.15419 1.14125 1.14297 1.12132 1.11933 1.09316 1.1068 1.08251 1.05147 1.0625 1.17647 1.2 1.11111 1 1 tree_stats_height=61 tree_stats_numInternals=6463730 tree_stats_numLeaves=3392758 tree_stats_numNodes=9856488 tree_stats_numKeys=9856488 tree_stats_avgDegreeInternal=1.52489 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34422 tree_stats_avgKeyDepth=29.9555 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:129678 2:261717 4:109144 8:1353 [2^00, 2^01]: 129678 (2^01, 2^02]: 261717 (2^02, 2^03]: 109144 (2^03, 2^04]: 1353 sum_limbo_reclamation_event_size_total=1276578 count_limbo_reclamation_event_size_by_thread=5204 5238 5247 5260 5226 5184 5190 5236 5233 5265 5203 5192 5227 5238 5242 5238 5225 5222 5213 5255 5219 5223 5262 5215 5222 5265 5234 5198 5254 5220 5195 5222 5250 5188 5234 5219 5216 5230 5235 5276 5258 5224 5228 5246 5245 5223 5237 5187 5283 5261 5200 5251 5220 5238 5174 5274 5209 5233 5266 5219 5206 5256 5290 5255 5231 5217 5191 5219 5277 5245 5203 5222 5169 5238 5204 5216 5228 5240 5188 5256 5233 5244 5243 5198 5194 5197 5206 5224 5206 5238 5223 5212 5259 5238 5206 5229 count_limbo_reclamation_event_size_total=501892 sum_limbo_reclamation_event_count_total=558432 first_thread_announced_epoch_by_thread=13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 13506 sum_num_inserts_by_thread=26664 27198 27008 27014 27194 26725 26861 26881 27232 27188 27101 26663 27064 27329 27373 27137 27120 27021 26692 26961 27471 27113 26837 26563 26537 27100 27343 27073 27008 26694 26605 27094 27046 27064 26873 26938 26815 27337 27125 27072 27139 26746 27066 26933 26757 27189 27160 26634 26870 26867 27205 27161 26975 26791 26885 27116 27295 27249 26611 26975 26834 27120 27391 27120 27134 26596 26320 27017 27187 27112 26802 26789 26831 26914 27008 27116 27111 26510 26932 27178 27629 26973 27071 26844 26774 27257 27364 27161 27007 26627 26353 26816 27148 27380 26833 26769 sum_num_inserts_total=2590786 sum_num_deletes_by_thread=26918 26977 27194 27048 27121 26671 26897 27207 27180 27298 27037 26856 27214 27091 27121 27133 27024 26990 27003 26913 27278 27332 26822 27019 26621 26892 27649 27033 27137 26934 26714 27066 27613 27217 27033 26681 26828 27203 26972 27457 27133 26779 26820 26863 27025 26927 26923 26101 27123 27167 27205 27080 26853 26823 26570 27043 27321 27316 27132 26948 27119 27080 27526 27477 26890 26776 26845 26784 27287 26730 26685 27016 26777 26954 27073 27280 27028 26787 26944 26964 27307 27708 27154 27167 26986 26960 27346 26963 26890 26655 26596 26799 27366 27043 26918 26846 sum_num_deletes_total=2594272 sum_num_searches_by_thread=5300100 5340760 5380815 5354390 5351473 5286824 5317782 5337093 5406050 5410071 5352846 5310014 5327952 5372035 5404510 5392070 5365026 5327059 5299828 5319854 5368125 5390777 5313430 5265798 5301147 5337230 5380139 5376068 5352470 5298415 5309636 5334683 5392856 5403845 5329774 5300069 5336133 5361600 5395073 5402119 5369291 5322701 5290954 5319263 5352468 5379602 5305731 5261727 5327123 5346904 5404013 5388088 5338897 5294076 5327721 5342822 5415491 5420697 5339212 5320182 5345080 5373500 5405791 5398061 5359811 5298085 5292250 5315380 5384264 5373279 5348091 5293637 5329263 5337483 5398672 5387537 5357443 5315524 5339526 5350669 5422792 5402355 5360028 5329653 5347560 5373604 5392301 5393515 5362321 5295646 5279170 5305684 5387512 5386446 5347063 5310733 sum_num_searches_total=513472631 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5353682 5394935 5435017 5408452 5405788 5340220 5371540 5391181 5460462 5464557 5406984 5363533 5382230 5426455 5459004 5446340 5419170 5381070 5353523 5373728 5422874 5445222 5367089 5319380 5354305 5391222 5435131 5430174 5406615 5352043 5362955 5388843 5447515 5458126 5383680 5353688 5389776 5416140 5449170 5456648 5423563 5376226 5344840 5373059 5406250 5433718 5359814 5314462 5381116 5400938 5458423 5442329 5392725 5347690 5381176 5396981 5470107 5475262 5392955 5374105 5399033 5427700 5460708 5452658 5413835 5351457 5345415 5369181 5438738 5427121 5401578 5347442 5382871 5391351 5452753 5441933 5411582 5368821 5393402 5404811 5477728 5457036 5414253 5383664 5401320 5427821 5447011 5447639 5416218 5348928 5332119 5359299 5442026 5440869 5400814 5364348 average_num_operations_total=5402684 stdev_num_operations_total=4045 sum_num_operations_total=518657689 min_num_operations_total=5314462 max_num_operations_total=5477728 first_time_thread_terminate_by_thread=10000136 10000137 10000137 10000137 10000136 10000136 10000136 10000137 10000136 10000136 10000137 10000137 10000136 10000137 10000136 10000136 10000137 10000136 10000137 10000136 10000136 10000136 10000136 10000136 10000136 10000137 10000137 10000137 10000136 10000136 10000136 10000137 10000136 10000136 10000137 10000137 10000136 10000137 10000136 10000136 10000136 10000136 10000137 10000136 10000136 10000136 10000136 10000136 10000136 10000137 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000136 10000135 10000137 10000135 10000135 10000136 10000135 10000135 10000135 10000135 10000136 10000136 10000136 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000135 10000136 min_time_thread_terminate_total=10000135 max_time_thread_terminate_total=10000137 first_time_thread_start_by_thread=6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 7 7 6 7 6 6 6 6 6 6 7 6 7 7 6 6 7 7 7 7 6 6 6 6 7 7 6 7 6 6 6 6 6 6 7 6 7 7 6 6 7 6 7 7 6 min_time_thread_start_total=6 max_time_thread_start_total=7 sum_duration_all_ops_total= threads_final_keysum=98559621558083 threads_final_size=9856488 final_keysum=98559621558083 final_size=9856488 validate_result=success Validation OK. Structural validation OK. total_find=513472631 total_rq=0 total_inserts=2590786 total_deletes=2594272 total_updates=5185058 total_queries=513472631 total_ops=518657689 find_throughput=51347263 rq_throughput=0 update_throughput=518505 query_throughput=51347263 total_throughput=51865768 total find : 513472631 total rq : 0 total inserts : 2590786 total deletes : 2594272 total updates : 5185058 total queries : 513472631 total ops : 518657689 find throughput : 51347263 rq throughput : 0 update throughput : 518505 query throughput : 51347263 total throughput : 51865768 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=31.4398 PAPI_L3_TCM=11.18 PAPI_TOT_CYC=5151.84 PAPI_TOT_INS=1533.54 garbage=2525835055730069 total_execution_walltime=12.664s REALTIME_MAIN_END_PERF_FORMAT=6979701.417834229 [time_cmd_output] time_elapsed_sec=12.87, faults_major=0, faults_minor=236653, mem_maxresident_kb=944356, user_cputime=1192.43, sys_cputime=3.53, percent_cpu=9289% 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=1