__step=000778 __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 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000778.txt __path_data=data/data000778.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55d71eb53400 addrof(kcasDescriptors[1])=0x55d71eb53d00 addrof(rdcssDescriptors[0])=0x55d71ec73400 addrof(rdcssDescriptors[1])=0x55d71ec734c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6988363.212135369 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=50 DEL_FRAC=50 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=50.0 50.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.005s prefilling_size=6352674 prefilling_elapsed_millis=1.001s prefilling_size=6666259 prefilling_elapsed_millis=1.101s prefilling_size=6951616 prefilling_elapsed_millis=1.201s prefilling_size=7209025 prefilling_elapsed_millis=1.301s prefilling_size=7444875 prefilling_elapsed_millis=1.402s prefilling_size=7659654 prefilling_elapsed_millis=1.502s prefilling_size=7856079 prefilling_elapsed_millis=1.602s prefilling_size=8035397 prefilling_elapsed_millis=1.702s prefilling_size=8198433 prefilling_elapsed_millis=1.802s prefilling_size=8345656 prefilling_elapsed_millis=1.903s prefilling_size=8483669 prefilling_elapsed_millis=2.003s prefilling_size=8607833 prefilling_elapsed_millis=2.103s prefilling_size=8722073 prefilling_elapsed_millis=2.203s prefilling_size=8827600 prefilling_elapsed_millis=2.303s prefilling_size=8924609 prefilling_elapsed_millis=2.404s prefilling_size=9011791 prefilling_elapsed_millis=2.504s prefilling_size=9092814 prefilling_elapsed_millis=2.604s prefilling_size=9165851 prefilling_elapsed_millis=2.704s prefilling_size=9233505 prefilling_elapsed_millis=2.804s prefilling_size=9295184 prefilling_elapsed_millis=2.904s prefilling_size=9352696 prefilling_elapsed_millis=3.005s prefilling_size=9404923 prefilling_elapsed_millis=3.105s prefilling_size=9453229 prefilling_elapsed_millis=3.205s prefilling_size=9497397 prefilling_elapsed_millis=3.305s prefilling_size=9537915 prefilling_elapsed_millis=3.406s prefilling_size=9575572 prefilling_elapsed_millis=3.506s prefilling_size=9609906 prefilling_elapsed_millis=3.606s prefilling_size=9640960 prefilling_elapsed_millis=3.706s prefilling_size=9670616 prefilling_elapsed_millis=3.806s prefilling_size=9697767 prefilling_elapsed_millis=3.907s prefilling_size=9720607 prefilling_elapsed_millis=4.007s prefilling_size=9741372 prefilling_elapsed_millis=4.107s prefilling_size=9761396 prefilling_elapsed_millis=4.207s prefilling_size=9780538 prefilling_elapsed_millis=4.307s prefilling_size=9797297 prefilling_elapsed_millis=4.408s prefilling_size=9811595 prefilling_elapsed_millis=4.508s finished prefilling to size 9811637 for expected size 10000000 keysum=98118832993383, performing 82149244 updates; total_prefilling_elapsed_ms=4609 ms) pref_size=9811637 pref_millis=4609 prefill_elapsed_ms=4627 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6988367.972343305 REALTIME_END_PERF_FORMAT=6988377.972494796 ############################################################################### ################################## 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 131072 262102 521345 985327 1501392 1424302 662234 116830 5380 14 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 40 2600 50121 388473 1229687 1600188 831557 148460 6708 18 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 523945 1035448 1889865 2653989 2262422 948387 153840 6722 18 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 523945 1035448 1889865 2653989 2262422 948387 153840 6722 18 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99886 1.98122 1.87357 1.60988 1.3158 1.12648 1.03902 1.00872 1.0006 1 tree_stats_height=28 tree_stats_numInternals=5741069 tree_stats_numLeaves=4257852 tree_stats_numNodes=9998921 tree_stats_numKeys=9998921 tree_stats_avgDegreeInternal=1.74165 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42583 tree_stats_avgKeyDepth=21.6493 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:1 4:0 8:0 16:7 32:69 64:367450 128:16952 256:2 512:47 (2^01, 2^02]: 1 (2^02, 2^03]: 0 (2^03, 2^04]: 0 (2^04, 2^05]: 7 (2^05, 2^06]: 69 (2^06, 2^07]: 367450 (2^07, 2^08]: 16952 (2^08, 2^09]: 2 (2^09, 2^10]: 47 sum_limbo_reclamation_event_size_total=42998722 count_limbo_reclamation_event_size_by_thread=8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 8011 count_limbo_reclamation_event_size_total=384528 sum_limbo_reclamation_event_count_total=384624 first_thread_announced_epoch_by_thread=23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 23652 sum_num_inserts_by_thread=1779714 1790604 1802159 1803688 1798383 1786010 1789916 1791132 1811618 1799948 1792998 1785655 1791551 1800092 1807580 1807290 1796965 1794841 1788488 1795795 1804217 1807108 1791029 1781078 1791064 1795557 1805289 1802653 1792964 1782865 1788676 1792206 1812808 1812959 1795403 1786917 1793218 1800329 1806175 1813665 1802059 1793682 1778916 1796558 1803015 1804081 1786530 1782076 sum_num_inserts_total=86217524 sum_num_deletes_by_thread=1782670 1792621 1804601 1804108 1798846 1785503 1793317 1789103 1808497 1801927 1794467 1787538 1788959 1802805 1806203 1806449 1800015 1794667 1788743 1794618 1805114 1806359 1794459 1781736 1789959 1797251 1806039 1803971 1793528 1782481 1789702 1794073 1814486 1812498 1796731 1787390 1792708 1800460 1806428 1807093 1797334 1791524 1777707 1791176 1803305 1802509 1788261 1781379 sum_num_deletes_total=86221318 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3562384 3583225 3606760 3607796 3597229 3571513 3583233 3580235 3620115 3601875 3587465 3573193 3580510 3602897 3613783 3613739 3596980 3589508 3577231 3590413 3609331 3613467 3585488 3562814 3581023 3592808 3611328 3606624 3586492 3565346 3578378 3586279 3627294 3625457 3592134 3574307 3585926 3600789 3612603 3620758 3599393 3585206 3556623 3587734 3606320 3606590 3574791 3563455 average_num_operations_total=3592475 stdev_num_operations_total=2610 sum_num_operations_total=172438842 min_num_operations_total=3556623 max_num_operations_total=3627294 first_time_thread_terminate_by_thread=10000151 10000151 10000153 10000151 10000151 10000151 10000151 10000155 10000152 10000152 10000153 10000153 10000151 10000151 10000151 10000152 10000152 10000151 10000152 10000152 10000151 10000152 10000152 10000153 10000152 10000151 10000151 10000151 10000151 10000151 10000154 10000154 10000153 10000159 10000151 10000151 10000162 10000151 10000152 10000162 10000153 10000152 10000154 10000151 10000151 10000151 10000151 10000152 min_time_thread_terminate_total=10000151 max_time_thread_terminate_total=10000162 first_time_thread_start_by_thread=8 7 8 8 7 8 7 7 7 7 8 8 8 7 8 8 8 8 7 7 8 7 8 8 8 7 8 8 7 8 7 7 7 7 8 8 8 7 8 8 8 8 7 7 8 7 8 8 min_time_thread_start_total=7 max_time_thread_start_total=8 sum_duration_all_ops_total= threads_final_keysum=99971976347031 threads_final_size=9998921 final_keysum=99971976347031 final_size=9998921 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=86217524 total_deletes=86221318 total_updates=172438842 total_queries=0 total_ops=172438842 find_throughput=0 rq_throughput=0 update_throughput=17243884 query_throughput=0 total_throughput=17243884 total find : 0 total rq : 0 total inserts : 86217524 total deletes : 86221318 total updates : 172438842 total queries : 0 total ops : 172438842 find throughput : 0 rq throughput : 0 update throughput : 17243884 query throughput : 0 total throughput : 17243884 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=28.2916 PAPI_L3_TCM=10.6582 PAPI_TOT_CYC=7760.08 PAPI_TOT_INS=3156.76 garbage=0 total_execution_walltime=14.901s REALTIME_MAIN_END_PERF_FORMAT=6988378.114472194 [time_cmd_output] time_elapsed_sec=15.08, faults_major=0, faults_minor=238822, mem_maxresident_kb=957132, user_cputime=732.12, sys_cputime=3.84, percent_cpu=4880% 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