__step=000666 __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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000666.txt __path_data=data/data000666.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x562187ec5400 addrof(kcasDescriptors[1])=0x562187ec5d00 addrof(rdcssDescriptors[0])=0x562187fe5400 addrof(rdcssDescriptors[1])=0x562187fe54c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6986867.708134498 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=200000 PREFILL_THREADS=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.006s prefilling_size=100025 prefilling_elapsed_millis=1.001s finished prefilling to size 99960 for expected size 100000 keysum=10005511096, performing 34843553 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99960 pref_millis=1102 prefill_elapsed_ms=1132 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6986869.34566740 REALTIME_END_PERF_FORMAT=6986879.34730913 ############################################################################### ################################## 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.037s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8159 15022 17892 7518 583 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 32 1158 10049 20881 9639 718 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8191 16180 27941 28399 10222 718 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8191 16180 27941 28399 10222 718 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 1.99976 1.97925 1.79845 1.37604 1.09521 1.01321 1 tree_stats_height=19 tree_stats_numInternals=57365 tree_stats_numLeaves=42477 tree_stats_numNodes=99842 tree_stats_numKeys=99842 tree_stats_avgDegreeInternal=1.74047 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42544 tree_stats_avgKeyDepth=14.8566 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:68 2:1664 4:58651 8:380994 16:38492 32:23 [2^00, 2^01]: 68 (2^01, 2^02]: 1664 (2^02, 2^03]: 58651 (2^03, 2^04]: 380994 (2^04, 2^05]: 38492 (2^05, 2^06]: 23 sum_limbo_reclamation_event_size_total=5291754 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9997 9998 9998 9997 9998 9998 9998 9997 9997 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9997 9998 9998 9996 9998 9998 9998 9998 9997 9998 9998 9997 9997 9998 9998 9998 9997 9998 9998 9998 count_limbo_reclamation_event_size_total=479892 sum_limbo_reclamation_event_count_total=1726896 first_thread_announced_epoch_by_thread=75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 75332 sum_num_inserts_by_thread=784795 793233 799548 795789 793526 785754 789493 791728 804107 806589 792684 788846 788950 793372 799104 802391 797593 789186 782443 793184 798529 805043 789805 788251 782818 796143 800157 798024 797025 786732 788144 793399 803114 806461 795047 791580 788993 792449 802287 800224 792833 789985 784636 785640 794039 797067 797076 789519 sum_num_inserts_total=38107335 sum_num_deletes_by_thread=784423 794107 799821 798113 792546 787122 788049 792134 804787 805873 791703 787756 790089 792692 798952 800762 796845 791573 782961 795223 800932 803144 787842 788457 783750 794834 801587 798322 796419 787970 789409 794012 803823 803458 793798 791023 788388 792272 799799 799205 794055 792750 782627 787031 793365 798396 797910 789515 sum_num_deletes_total=38109624 sum_num_searches_by_thread=14121661 14278941 14384622 14352602 14263259 14167166 14184773 14267106 14462726 14494320 14257741 14197642 14202317 14274010 14366706 14431344 14337923 14218782 14110982 14297595 14387487 14458767 14209997 14186124 14106250 14317605 14410136 14367126 14319987 14164660 14213736 14316860 14469076 14485035 14309791 14218363 14194768 14275852 14416665 14400857 14277827 14248333 14070950 14150576 14279947 14365725 14338242 14209409 sum_num_searches_total=685842369 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=15690879 15866281 15983991 15946504 15849331 15740042 15762315 15850968 16071620 16106782 15842128 15774244 15781356 15860074 15964762 16034497 15932361 15799541 15676386 15886002 15986948 16066954 15787644 15762832 15672818 15908582 16011880 15963472 15913431 15739362 15791289 15904271 16076013 16094954 15898636 15800966 15772149 15860573 16018751 16000286 15864715 15831068 15638213 15723247 15867351 15961188 15933228 15788443 average_num_operations_total=15876236 stdev_num_operations_total=17610 sum_num_operations_total=762059328 min_num_operations_total=15638213 max_num_operations_total=16106782 first_time_thread_terminate_by_thread=10000161 10000162 10000162 10000161 10000161 10000162 10000162 10000161 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000161 10000161 10000162 10000162 10000161 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 min_time_thread_terminate_total=10000161 max_time_thread_terminate_total=10000162 first_time_thread_start_by_thread=3 3 3 2 3 2 3 3 3 2 3 3 3 3 3 3 2 2 2 2 3 2 2 3 3 3 3 2 3 2 3 3 3 2 3 3 3 3 3 3 2 2 2 2 3 2 2 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=9987384825 threads_final_size=99842 final_keysum=9987384825 final_size=99842 validate_result=success Validation OK. Structural validation OK. total_find=685842369 total_rq=0 total_inserts=38107335 total_deletes=38109624 total_updates=76216959 total_queries=685842369 total_ops=762059328 find_throughput=68584236 rq_throughput=0 update_throughput=7621695 query_throughput=68584236 total_throughput=76205932 total find : 685842369 total rq : 0 total inserts : 38107335 total deletes : 38109624 total updates : 76216959 total queries : 685842369 total ops : 762059328 find throughput : 68584236 rq throughput : 0 update throughput : 7621695 query throughput : 68584236 total throughput : 76205932 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=6.48205 PAPI_L3_TCM=0.000171558 PAPI_TOT_CYC=1719.07 PAPI_TOT_INS=1093.34 garbage=34294066209026 total_execution_walltime=11.462s REALTIME_MAIN_END_PERF_FORMAT=6986879.170738911 [time_cmd_output] time_elapsed_sec=11.59, faults_major=0, faults_minor=76856, mem_maxresident_kb=309084, user_cputime=560.37, sys_cputime=3.15, percent_cpu=4860% 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