__step=000554 __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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000554.txt __path_data=data/data000554.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55b2badc1400 addrof(kcasDescriptors[1])=0x55b2badc1d00 addrof(rdcssDescriptors[0])=0x55b2baee1400 addrof(rdcssDescriptors[1])=0x55b2baee14c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6985556.35927683 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.016s prefilling_size=1000451 prefilling_elapsed_millis=1.001s finished prefilling to size 1000427 for expected size 1000000 keysum=1000835913283, performing 28547900 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1000427 pref_millis=1102 prefill_elapsed_ms=1124 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6985557.330427877 REALTIME_END_PERF_FORMAT=6985567.330558788 ############################################################################### ################################## 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 8192 16384 32768 65262 121328 165966 119969 33967 2348 4 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 260 8125 63139 161691 146404 43533 2973 5 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65522 129453 229105 281660 180371 45881 2977 5 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65522 129453 229105 281660 180371 45881 2977 5 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99957 1.97969 1.83256 1.50498 1.21445 1.06605 1.01371 1.00034 1 tree_stats_height=24 tree_stats_numInternals=574379 tree_stats_numLeaves=426130 tree_stats_numNodes=1000509 tree_stats_numKeys=1000509 tree_stats_avgDegreeInternal=1.7419 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42591 tree_stats_avgKeyDepth=18.2692 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:34 2:1494 4:55136 8:376908 16:46331 [2^00, 2^01]: 34 (2^01, 2^02]: 1494 (2^02, 2^03]: 55136 (2^03, 2^04]: 376908 (2^04, 2^05]: 46331 sum_limbo_reclamation_event_size_total=5395965 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 count_limbo_reclamation_event_size_total=479903 sum_limbo_reclamation_event_count_total=1062144 first_thread_announced_epoch_by_thread=46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 46962 sum_num_inserts_by_thread=492378 495322 502751 502878 496411 494741 493919 495353 501999 505787 497459 498246 499089 499132 504293 499925 497604 499790 491894 496170 501114 499955 495718 496217 496814 495972 498780 504635 500640 494511 498873 500044 506501 501748 501297 494012 493812 500292 500604 500032 498154 494215 495381 493515 497260 500292 494783 492492 sum_num_inserts_total=23912804 sum_num_deletes_by_thread=491967 494554 503453 503159 494597 495053 494201 495667 502586 506356 497184 497551 497680 500529 503570 500624 497546 499555 493248 495820 501236 500100 494174 495766 496415 496553 498912 504121 499629 494651 498743 501066 506791 504160 499608 494457 495111 500475 499375 500618 498102 493756 494853 493422 497348 500536 495669 491648 sum_num_deletes_total=23912195 sum_num_searches_by_thread=8868629 8925463 9061792 9068542 8936177 8893087 8892916 8921516 9044990 9111928 8953539 8970555 8968005 8996429 9063516 9015431 8953319 8986888 8865154 8923569 9020799 9003949 8900475 8911063 8939645 8914564 8977503 9074784 9016029 8905898 8969496 9013080 9119774 9039424 9018433 8894173 8901194 9000478 8995539 9009730 8955124 8889707 8917160 8883972 8965973 9004794 8919600 8854946 sum_num_searches_total=430438751 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9852974 9915339 10067996 10074579 9927185 9882881 9881036 9912536 10049575 10124071 9948182 9966352 9964774 9996090 10071379 10015980 9948469 9986233 9850296 9915559 10023149 10004004 9890367 9903046 9932874 9907089 9975195 10083540 10016298 9895060 9967112 10014190 10133066 10045332 10019338 9882642 9890117 10001245 9995518 10010380 9951380 9877678 9907394 9870909 9960581 10005622 9910052 9839086 average_num_operations_total=9963828 stdev_num_operations_total=10789 sum_num_operations_total=478263750 min_num_operations_total=9839086 max_num_operations_total=10133066 first_time_thread_terminate_by_thread=10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000130 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000130 10000131 min_time_thread_terminate_total=10000130 max_time_thread_terminate_total=10000131 first_time_thread_start_by_thread=3 4 4 4 4 4 4 4 4 4 4 4 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 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=1001170045783 threads_final_size=1000509 final_keysum=1001170045783 final_size=1000509 validate_result=success Validation OK. Structural validation OK. total_find=430438751 total_rq=0 total_inserts=23912804 total_deletes=23912195 total_updates=47824999 total_queries=430438751 total_ops=478263750 find_throughput=43043875 rq_throughput=0 update_throughput=4782499 query_throughput=43043875 total_throughput=47826375 total find : 430438751 total rq : 0 total inserts : 23912804 total deletes : 23912195 total updates : 47824999 total queries : 430438751 total ops : 478263750 find throughput : 43043875 rq throughput : 0 update throughput : 4782499 query throughput : 43043875 total throughput : 47826375 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=15.1809 PAPI_L3_TCM=2.11096 PAPI_TOT_CYC=2792.77 PAPI_TOT_INS=1235.77 garbage=215281570401297 total_execution_walltime=11.434s REALTIME_MAIN_END_PERF_FORMAT=6985567.470759035 [time_cmd_output] time_elapsed_sec=11.57, faults_major=0, faults_minor=91313, mem_maxresident_kb=367260, user_cputime=563.06, sys_cputime=3.26, percent_cpu=4893% 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