__step=000203 __cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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=data000203.txt __path_data=data/data000203.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55c636c61400 addrof(kcasDescriptors[1])=0x55c636c61d00 addrof(rdcssDescriptors[0])=0x55c636d81400 addrof(rdcssDescriptors[1])=0x55c636d814c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6996875.912800898 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.018s prefilling_size=8280586 prefilling_elapsed_millis=1.001s prefilling_size=8537898 prefilling_elapsed_millis=1.101s prefilling_size=8752213 prefilling_elapsed_millis=1.201s prefilling_size=8936028 prefilling_elapsed_millis=1.301s prefilling_size=9088648 prefilling_elapsed_millis=1.402s prefilling_size=9219252 prefilling_elapsed_millis=1.502s prefilling_size=9334054 prefilling_elapsed_millis=1.602s prefilling_size=9422872 prefilling_elapsed_millis=1.702s prefilling_size=9507632 prefilling_elapsed_millis=1.802s prefilling_size=9574698 prefilling_elapsed_millis=1.903s prefilling_size=9636962 prefilling_elapsed_millis=2.003s prefilling_size=9688486 prefilling_elapsed_millis=2.103s prefilling_size=9732128 prefilling_elapsed_millis=2.203s prefilling_size=9771247 prefilling_elapsed_millis=2.303s prefilling_size=9803086 prefilling_elapsed_millis=2.404s finished prefilling to size 9803091 for expected size 10000000 keysum=98027321756248, performing 80140780 updates; total_prefilling_elapsed_ms=2505 ms) pref_size=9803091 pref_millis=2505 prefill_elapsed_ms=2528 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6996878.644745981 REALTIME_END_PERF_FORMAT=6996888.644879552 ############################################################################### ################################## 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.046s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262076 521314 985593 1498056 1415080 657679 122727 7103 39 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 60 2591 49851 391964 1230553 1589076 822411 156522 8873 45 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262136 523905 1035444 1890020 2645633 2246755 945138 163625 8912 45 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262136 523905 1035444 1890020 2645633 2246755 945138 163625 8912 45 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99995 1.99883 1.98134 1.87347 1.60718 1.31436 1.12794 1.04327 1.01106 1.00067 1 tree_stats_height=28 tree_stats_numInternals=5731809 tree_stats_numLeaves=4251947 tree_stats_numNodes=9983756 tree_stats_numKeys=9983756 tree_stats_avgDegreeInternal=1.74182 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42589 tree_stats_avgKeyDepth=21.6504 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:40 8:27634 16:527615 32:20903 (2^02, 2^03]: 40 (2^03, 2^04]: 27634 (2^04, 2^05]: 527615 (2^05, 2^06]: 20903 sum_limbo_reclamation_event_size_total=13208377 count_limbo_reclamation_event_size_by_thread=6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 6002 count_limbo_reclamation_event_size_total=576192 sum_limbo_reclamation_event_count_total=576384 first_thread_announced_epoch_by_thread=13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 13886 sum_num_inserts_by_thread=275790 278328 276458 278088 277511 273817 274598 276367 278704 281907 276388 275094 276625 277822 280324 281766 278704 275097 274262 275666 277554 279753 274864 272500 275730 277826 277172 277697 277109 274142 274989 276222 280515 280633 276169 274428 277395 276753 280821 280775 278315 276583 275155 277508 278548 277953 275871 272940 278040 276947 279606 278445 277360 276250 277048 277244 279109 281232 279949 274197 277632 276736 278815 278913 278847 274423 273191 274796 277032 279166 277917 274380 274677 277280 281362 279208 276597 276250 275706 277449 280573 280637 277124 276663 275735 278981 278457 278502 276932 274066 274863 275860 279043 279709 277368 276505 sum_num_inserts_total=26616058 sum_num_deletes_by_thread=274372 279091 277397 278216 277867 273067 274714 275122 278687 281226 277179 275148 277879 277631 278970 280595 277770 274974 274601 276672 278181 279514 275058 273017 275888 277380 276954 278772 277199 274018 275275 276292 279658 280914 276000 274823 276934 277016 280799 280672 278887 276145 275859 277378 279305 278907 275216 272539 277189 277435 278366 278709 277351 276000 276763 277040 279640 281268 280398 274860 277030 277088 278574 278240 278818 274446 273772 274868 278368 278358 277696 274121 275113 276061 280150 278836 276670 277788 275631 278140 278650 279728 277685 275734 275908 278767 278451 278322 276895 274496 274433 275726 278135 279687 278592 277256 sum_num_deletes_total=26613030 sum_num_searches_by_thread=4946497 5000121 4992741 5007178 4995854 4921325 4946308 4973788 5026398 5057696 4973650 4948815 4988345 4992775 5039607 5047029 5008741 4956171 4931849 4956036 5016141 5032514 4948044 4909164 4965680 4989747 4989791 4997733 4970800 4925150 4948738 4972069 5034517 5047081 4967955 4937101 4980897 4978785 5047263 5052501 5015811 4972667 4960486 4984452 5009905 5012622 4949066 4906015 4989314 4987862 5025058 5013102 4991984 4968652 4973859 4998704 5028606 5057718 5016952 4948801 4993410 4989596 5013238 5016842 5012902 4942310 4936887 4946162 5009250 5032691 4994843 4942224 4953315 4984967 5048611 5015616 4981913 4978465 4950594 5002451 5021675 5058398 4990052 4982284 4972844 5006966 5013113 5010195 4978888 4932324 4950950 4969285 5010986 5039781 5007563 4979033 sum_num_searches_total=478904855 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5496659 5557540 5546596 5563482 5551232 5468209 5495620 5525277 5583789 5620829 5527217 5499057 5542849 5548228 5598901 5609390 5565215 5506242 5480712 5508374 5571876 5591781 5497966 5454681 5517298 5544953 5543917 5554202 5525108 5473310 5499002 5524583 5594690 5608628 5520124 5486352 5535226 5532554 5608883 5613948 5573013 5525395 5511500 5539338 5567758 5569482 5500153 5451494 5544543 5542244 5583030 5570256 5546695 5520902 5527670 5552988 5587355 5620218 5577299 5497858 5548072 5543420 5570627 5573995 5570567 5491179 5483850 5495826 5564650 5590215 5550456 5490725 5503105 5538308 5610123 5573660 5535180 5532503 5501931 5558040 5580898 5618763 5544861 5534681 5524487 5564714 5570021 5567019 5532715 5480886 5500246 5520871 5568164 5599177 5563523 5532794 average_num_operations_total=5543061 stdev_num_operations_total=4083 sum_num_operations_total=532133943 min_num_operations_total=5451494 max_num_operations_total=5620829 first_time_thread_terminate_by_thread=10000135 10000134 10000136 10000134 10000134 10000134 10000134 10000136 10000134 10000135 10000134 10000134 10000134 10000134 10000135 10000134 10000135 10000135 10000135 10000134 10000134 10000135 10000136 10000138 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000136 10000134 10000134 10000134 10000134 10000134 10000134 10000135 10000134 10000134 10000138 10000135 10000134 10000134 10000134 10000136 10000134 10000134 10000136 10000134 10000134 10000134 10000136 10000134 10000136 10000134 10000135 10000136 10000134 10000135 10000135 10000137 10000134 10000136 10000134 10000138 10000134 10000134 10000134 10000136 10000136 10000134 10000136 10000134 10000134 10000134 10000136 10000134 10000136 10000134 10000135 10000134 10000134 10000135 10000136 10000136 10000134 10000136 10000135 10000135 10000134 10000134 10000134 10000136 10000134 min_time_thread_terminate_total=10000134 max_time_thread_terminate_total=10000138 first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=99835760218424 threads_final_size=9983756 final_keysum=99835760218424 final_size=9983756 validate_result=success Validation OK. Structural validation OK. total_find=478904855 total_rq=0 total_inserts=26616058 total_deletes=26613030 total_updates=53229088 total_queries=478904855 total_ops=532133943 find_throughput=47890485 rq_throughput=0 update_throughput=5322908 query_throughput=47890485 total_throughput=53213394 total find : 478904855 total rq : 0 total inserts : 26616058 total deletes : 26613030 total updates : 53229088 total queries : 478904855 total ops : 532133943 find throughput : 47890485 rq throughput : 0 update throughput : 5322908 query throughput : 47890485 total throughput : 53213394 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=27.8356 PAPI_L3_TCM=10.166 PAPI_TOT_CYC=5024.7 PAPI_TOT_INS=1379.34 garbage=2378255653936100 total_execution_walltime=12.866s REALTIME_MAIN_END_PERF_FORMAT=6996888.788869845 [time_cmd_output] time_elapsed_sec=13.05, faults_major=0, faults_minor=238357, mem_maxresident_kb=950928, user_cputime=1227.77, sys_cputime=3.67, percent_cpu=9429% 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=1