__step=000444 __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_avl_kcas_validate.debra -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000444.txt __path_data=data/data000444.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x5598b8f49400 addrof(kcasDescriptors[1])=0x5598b8f49d00 addrof(rdcssDescriptors[0])=0x5598b9069400 addrof(rdcssDescriptors[1])=0x5598b90694c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6984262.510457858 binary=./sigouin_int_avl_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_avl_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=5 DEL_FRAC=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=5.0 5.0 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.009s prefilling_size=8333943 prefilling_elapsed_millis=1.001s prefilling_size=8584935 prefilling_elapsed_millis=1.101s prefilling_size=8797351 prefilling_elapsed_millis=1.201s prefilling_size=8977218 prefilling_elapsed_millis=1.301s prefilling_size=9128759 prefilling_elapsed_millis=1.402s prefilling_size=9257768 prefilling_elapsed_millis=1.502s prefilling_size=9366742 prefilling_elapsed_millis=1.602s prefilling_size=9460354 prefilling_elapsed_millis=1.702s prefilling_size=9540511 prefilling_elapsed_millis=1.802s prefilling_size=9606273 prefilling_elapsed_millis=1.903s prefilling_size=9665089 prefilling_elapsed_millis=2.003s prefilling_size=9714122 prefilling_elapsed_millis=2.103s prefilling_size=9756174 prefilling_elapsed_millis=2.203s prefilling_size=9792222 prefilling_elapsed_millis=2.304s prefilling_size=9822653 prefilling_elapsed_millis=2.404s finished prefilling to size 9822664 for expected size 10000000 keysum=98245837455160, performing 80596982 updates; total_prefilling_elapsed_ms=2505 ms) pref_size=9822664 pref_millis=2505 prefill_elapsed_ms=2528 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6984265.237166007 REALTIME_END_PERF_FORMAT=6984275.237310386 ############################################################################### ################################## 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 1024 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.044s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262117 521604 983362 1483790 1408237 675469 130384 6665 25 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 27 2425 52397 398565 1212344 1567840 844436 166417 8309 28 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524029 1035759 1882355 2620581 2243309 974820 173082 8334 28 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524029 1035759 1882355 2620581 2243309 974820 173082 8334 28 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99911 1.98116 1.86796 1.60392 1.31866 1.13344 1.0438 1.00964 1.00036 1 tree_stats_height=28 tree_stats_numInternals=5733796 tree_stats_numLeaves=4252788 tree_stats_numNodes=9986584 tree_stats_numKeys=9986584 tree_stats_avgDegreeInternal=1.74171 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42585 tree_stats_avgKeyDepth=21.6594 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:1 2:1 4:32 8:27324 16:528170 32:21240 [2^00, 2^01]: 1 (2^01, 2^02]: 1 (2^02, 2^03]: 32 (2^03, 2^04]: 27324 (2^04, 2^05]: 528170 (2^05, 2^06]: 21240 sum_limbo_reclamation_event_size_total=13236023 count_limbo_reclamation_event_size_by_thread=6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 count_limbo_reclamation_event_size_total=576768 sum_limbo_reclamation_event_count_total=576960 first_thread_announced_epoch_by_thread=13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 sum_num_inserts_by_thread=275911 278078 279933 279108 277824 275085 276063 278204 281067 279846 276743 275748 276867 279814 280333 280358 278985 277319 275005 276483 277887 278772 277533 272683 275730 276782 278046 276870 277529 274077 277051 275828 278805 279568 276813 276215 276379 278542 279807 279793 277805 275059 275101 275646 278070 278671 276693 273508 275808 277225 278971 280459 275630 275205 275891 277605 280691 280849 278366 275556 277162 278306 280207 280309 276887 274318 274477 275428 278283 279315 276726 274332 276688 277962 280217 278818 277726 276573 276923 278844 281158 281441 278606 276933 276606 277680 279559 279429 277585 274600 274666 275763 279488 279501 276333 275107 sum_num_inserts_total=26640249 sum_num_deletes_by_thread=275958 277268 280293 278161 278417 274560 277116 276502 281303 280373 276208 276202 277890 279319 280300 280186 279151 275902 274012 276892 277851 279749 275763 272608 275689 276738 279751 277423 276180 274691 276670 276477 279923 280084 276840 275564 277413 277590 279441 278711 277849 275547 274856 276446 278091 279401 276565 274747 276440 277287 280313 278825 277431 276073 276803 277286 281202 279755 277444 275169 276888 279313 278722 279722 277205 275148 274313 275750 279309 278763 277136 274074 276368 277840 280225 278563 277148 276650 277274 279161 281055 281545 278820 276937 277503 277895 279994 279869 277481 275805 274656 276248 279416 279542 277272 275798 sum_num_deletes_total=26648107 sum_num_searches_by_thread=4971132 4997903 5028648 5018696 4997213 4945680 4979150 5000637 5050822 5051987 4981491 4965292 4990812 5021724 5043329 5040585 5018029 4983719 4960910 4970749 5013030 5031117 4968952 4912541 4957391 4986930 5022512 5005464 4997051 4938302 4973805 4994114 5043063 5039599 4985636 4956795 4976739 5003956 5028550 5033328 4999073 4962879 4949055 4970557 5009460 5025875 4975617 4938321 4969545 4999845 5044760 5037113 4982494 4967580 4975969 4993485 5047894 5048238 5007416 4967554 4984537 5013838 5033725 5036402 4996284 4947668 4930520 4960410 5022790 5012695 4987404 4948798 4969780 4994261 5038218 5025255 4984348 4967932 4983569 5007045 5058809 5057375 5013339 4983575 4982674 5011095 5035727 5034631 4993155 4945499 4941171 4965461 5033929 5036344 4998587 4961487 sum_num_searches_total=479658450 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5523001 5553249 5588874 5575965 5553454 5495325 5532329 5555343 5613192 5612206 5534442 5517242 5545569 5580857 5603962 5601129 5576165 5536940 5509927 5524124 5568768 5589638 5522248 5457832 5508810 5540450 5580309 5559757 5550760 5487070 5527526 5546419 5601791 5599251 5539289 5508574 5530531 5560088 5587798 5591832 5554727 5513485 5499012 5522649 5565621 5583947 5528875 5486576 5521793 5554357 5604044 5596397 5535555 5518858 5528663 5548376 5609787 5608842 5563226 5518279 5538587 5571457 5592654 5596433 5550376 5497134 5479310 5511588 5580382 5570773 5541266 5497204 5522836 5550063 5598660 5582636 5539222 5521155 5537766 5565050 5621022 5620361 5570765 5537445 5536783 5566670 5595280 5593929 5548221 5495904 5490493 5517472 5592833 5595387 5552192 5512392 average_num_operations_total=5551529 stdev_num_operations_total=3819 sum_num_operations_total=532946806 min_num_operations_total=5457832 max_num_operations_total=5621022 first_time_thread_terminate_by_thread=10000145 10000144 10000144 10000144 10000144 10000146 10000144 10000145 10000145 10000145 10000145 10000146 10000148 10000146 10000146 10000144 10000145 10000146 10000146 10000146 10000144 10000145 10000159 10000146 10000145 10000144 10000144 10000144 10000144 10000146 10000144 10000145 10000145 10000145 10000146 10000146 10000147 10000146 10000144 10000144 10000145 10000145 10000146 10000144 10000144 10000147 10000146 10000146 10000145 10000145 10000146 10000146 10000147 10000144 10000146 10000147 10000147 10000147 10000146 10000145 10000145 10000149 10000146 10000145 10000146 10000146 10000145 10000144 10000147 10000147 10000145 10000146 10000145 10000145 10000145 10000146 10000147 10000144 10000146 10000145 10000144 10000144 10000146 10000145 10000145 10000145 10000146 10000144 10000144 10000145 10000145 10000144 10000147 10000151 10000145 10000144 min_time_thread_terminate_total=10000144 max_time_thread_terminate_total=10000159 first_time_thread_start_by_thread=3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 4 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=99868603307492 threads_final_size=9986584 final_keysum=99868603307492 final_size=9986584 validate_result=success Validation OK. Structural validation OK. total_find=479658450 total_rq=0 total_inserts=26640249 total_deletes=26648107 total_updates=53288356 total_queries=479658450 total_ops=532946806 find_throughput=47965845 rq_throughput=0 update_throughput=5328835 query_throughput=47965845 total_throughput=53294680 total find : 479658450 total rq : 0 total inserts : 26640249 total deletes : 26648107 total updates : 53288356 total queries : 479658450 total ops : 532946806 find throughput : 47965845 rq throughput : 0 update throughput : 5328835 query throughput : 47965845 total throughput : 53294680 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=27.7884 PAPI_L3_TCM=10.1724 PAPI_TOT_CYC=5016.39 PAPI_TOT_INS=1379.65 garbage=2383606402686553 total_execution_walltime=12.868s REALTIME_MAIN_END_PERF_FORMAT=6984275.379498894 [time_cmd_output] time_elapsed_sec=13.04, faults_major=0, faults_minor=238120, mem_maxresident_kb=950084, user_cputime=1226.10, sys_cputime=4.43, percent_cpu=9434% timeout=false algorithm=sigouin_int_avl_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