__step=000418 __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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000418.txt __path_data=data/data000418.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55fe1ee47400 addrof(kcasDescriptors[1])=0x55fe1ee47d00 addrof(rdcssDescriptors[0])=0x55fe1ef67400 addrof(rdcssDescriptors[1])=0x55fe1ef674c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6999838.73501893 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=2000000 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=999909 prefilling_elapsed_millis=1.001s finished prefilling to size 999895 for expected size 1000000 keysum=1000098469018, performing 28733570 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999895 pref_millis=1102 prefill_elapsed_ms=1129 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6999839.358001250 REALTIME_END_PERF_FORMAT=6999849.358149699 ############################################################################### ################################## 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.035s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32764 65203 121760 167535 119191 32554 2328 13 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 4 307 7662 62648 164676 145744 41639 2889 15 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65510 129422 230183 283867 178298 43967 2902 15 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65510 129422 230183 283867 178298 43967 2902 15 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99933 1.98029 1.83775 1.50539 1.20822 1.06401 1.01306 1.00069 1 tree_stats_height=24 tree_stats_numInternals=574115 tree_stats_numLeaves=425584 tree_stats_numNodes=999699 tree_stats_numKeys=999699 tree_stats_avgDegreeInternal=1.74129 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42571 tree_stats_avgKeyDepth=18.2616 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:47 2:1413 4:54821 8:376993 16:46624 32:1 [2^00, 2^01]: 47 (2^01, 2^02]: 1413 (2^02, 2^03]: 54821 (2^03, 2^04]: 376993 (2^04, 2^05]: 46624 (2^05, 2^06]: 1 sum_limbo_reclamation_event_size_total=5398787 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9997 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 count_limbo_reclamation_event_size_total=479899 sum_limbo_reclamation_event_count_total=1068624 first_thread_announced_epoch_by_thread=47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 47256 sum_num_inserts_by_thread=496755 500122 503422 503246 500354 495473 498360 499402 503447 505493 503473 498899 498819 503074 503641 504804 503182 499659 498017 501499 504392 505222 500407 493611 496742 499771 503582 503973 500406 495653 496194 499400 508457 508222 502538 499650 498720 501219 505421 506763 504741 500603 497005 501134 503524 504333 501642 497588 sum_num_inserts_total=24062054 sum_num_deletes_by_thread=494936 499423 503255 503241 500970 497285 499531 496677 505582 506250 502757 498467 500111 502811 502856 505936 502712 499120 497778 500546 503052 504892 501448 495283 497849 498225 502836 503612 501030 494750 498023 499696 507302 507585 503601 499019 499260 500756 504722 506556 503541 499693 496871 499421 503867 504178 501124 495939 sum_num_deletes_total=24054375 sum_num_searches_by_thread=8913868 8989019 9050406 9058291 9016083 8922659 8966322 8958373 9071343 9099639 9037834 8988631 9002062 9043359 9073984 9106828 9057949 8987187 8967296 9021290 9082268 9088841 8988909 8896138 8947276 8985276 9058706 9059847 9017318 8910156 8960082 9000981 9132601 9130180 9053622 8986840 8980269 9023670 9088163 9112755 9066786 8989277 8943370 8992048 9058614 9074157 9022388 8940902 sum_num_searches_total=432923863 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9905559 9988564 10057083 10064778 10017407 9915417 9964213 9954452 10080372 10111382 10044064 9985997 10000992 10049244 10080481 10117568 10063843 9985966 9963091 10023335 10089712 10098955 9990764 9885032 9941867 9983272 10065124 10067432 10018754 9900559 9954299 10000077 10148360 10145987 10059761 9985509 9978249 10025645 10098306 10126074 10075068 9989573 9937246 9992603 10066005 10082668 10025154 9934429 average_num_operations_total=10021672 stdev_num_operations_total=9756 sum_num_operations_total=481040292 min_num_operations_total=9885032 max_num_operations_total=10148360 first_time_thread_terminate_by_thread=10000147 10000147 10000147 10000147 10000148 10000147 10000147 10000147 10000148 10000147 10000148 10000147 10000147 10000147 10000147 10000148 10000147 10000147 10000147 10000147 10000147 10000147 10000148 10000147 10000147 10000147 10000147 10000147 10000148 10000147 10000147 10000147 10000148 10000147 10000148 10000147 10000147 10000147 10000147 10000148 10000147 10000147 10000147 10000147 10000147 10000147 10000148 10000147 min_time_thread_terminate_total=10000147 max_time_thread_terminate_total=10000148 first_time_thread_start_by_thread=7 7 6 7 7 7 7 7 7 7 6 7 6 7 7 6 7 6 7 7 6 7 7 7 7 7 6 7 7 7 7 7 7 7 6 7 6 7 7 6 7 6 7 7 6 7 7 7 min_time_thread_start_total=6 max_time_thread_start_total=7 sum_duration_all_ops_total= threads_final_keysum=999900508261 threads_final_size=999699 final_keysum=999900508261 final_size=999699 validate_result=success Validation OK. Structural validation OK. total_find=432923863 total_rq=0 total_inserts=24062054 total_deletes=24054375 total_updates=48116429 total_queries=432923863 total_ops=481040292 find_throughput=43292386 rq_throughput=0 update_throughput=4811642 query_throughput=43292386 total_throughput=48104029 total find : 432923863 total rq : 0 total inserts : 24062054 total deletes : 24054375 total updates : 48116429 total queries : 432923863 total ops : 481040292 find throughput : 43292386 rq throughput : 0 update throughput : 4811642 query throughput : 43292386 total throughput : 48104029 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=15.068 PAPI_L3_TCM=2.10037 PAPI_TOT_CYC=2775.53 PAPI_TOT_INS=1235.54 garbage=211957725384399 total_execution_walltime=11.415s REALTIME_MAIN_END_PERF_FORMAT=6999849.490274703 [time_cmd_output] time_elapsed_sec=11.56, faults_major=0, faults_minor=91178, mem_maxresident_kb=366656, user_cputime=563.77, sys_cputime=1.96, percent_cpu=4890% 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