__step=000521 __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_bst_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=data000521.txt __path_data=data/data000521.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55a6cad7c400 addrof(kcasDescriptors[1])=0x55a6cad7cd00 addrof(rdcssDescriptors[0])=0x55a6cae9c400 addrof(rdcssDescriptors[1])=0x55a6cae9c4c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6985169.136463542 binary=./sigouin_int_bst_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_bst_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.005s prefilling_size=999537 prefilling_elapsed_millis=1.001s finished prefilling to size 999582 for expected size 1000000 keysum=999468389798, performing 38390882 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999582 pref_millis=1102 prefill_elapsed_ms=1124 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6985170.410409010 REALTIME_END_PERF_FORMAT=6985180.410562086 ############################################################################### ################################## 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 951 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.04s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 126 250 484 929 1760 3203 5582 9280 14648 21619 30256 39755 49088 56742 61346 62723 60106 54569 46871 38251 29678 21867 15473 10708 7224 4700 2940 1683 950 531 267 136 64 27 14 7 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 1 9 22 59 168 478 1046 2159 4188 6977 11286 16352 22115 27904 31889 34544 34626 32731 29115 24405 19561 14707 10510 7335 5000 3367 2154 1244 704 428 216 104 53 19 10 9 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 251 493 951 1819 3371 6060 10326 16807 25807 37233 51041 65440 78857 89250 94612 94650 89195 79602 67366 54083 41428 30180 21218 14559 9700 6307 3837 2194 1235 695 352 168 80 33 17 9 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 251 493 951 1819 3371 6060 10326 16807 25807 37233 51041 65440 78857 89250 94612 94650 89195 79602 67366 54083 41428 30180 21218 14559 9700 6307 3837 2194 1235 695 352 168 80 33 17 9 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.98425 1.96813 1.94726 1.93586 1.88565 1.84752 1.78284 1.72894 1.66395 1.60503 1.55824 1.50322 1.45491 1.41224 1.37273 1.33745 1.30733 1.28065 1.25747 1.23501 1.21726 1.20066 1.19036 1.1815 1.17007 1.16567 1.14222 1.13318 1.1299 1.13279 1.1223 1.09091 1.09524 1.075 1.09091 1.11765 1 tree_stats_height=44 tree_stats_numInternals=653984 tree_stats_numLeaves=345496 tree_stats_numNodes=999480 tree_stats_numKeys=999480 tree_stats_avgDegreeInternal=1.52829 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34568 tree_stats_avgKeyDepth=22.7701 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:46 2:1410 4:52611 8:372326 16:53507 [2^00, 2^01]: 46 (2^01, 2^02]: 1410 (2^02, 2^03]: 52611 (2^03, 2^04]: 372326 (2^04, 2^05]: 53507 sum_limbo_reclamation_event_size_total=5480110 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 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9997 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 count_limbo_reclamation_event_size_total=479900 sum_limbo_reclamation_event_count_total=1014288 first_thread_announced_epoch_by_thread=45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 45824 sum_num_inserts_by_thread=475448 480738 485487 483129 482119 474394 478349 480361 487697 490033 481345 480471 479328 484677 487361 485921 483838 480758 479321 480324 486867 485870 481765 476544 476500 480767 485794 487059 486141 476972 479234 485261 488989 489921 483647 482977 479641 483005 488205 489196 484487 481126 477298 482089 485709 483980 482955 477222 sum_num_inserts_total=23170320 sum_num_deletes_by_thread=476226 481913 485465 482892 482211 474838 478942 480602 486996 490477 480350 481212 479056 483600 486317 486330 483684 480363 478956 480412 486466 485898 482206 477094 477831 481040 486741 486158 484896 475380 479062 482743 488996 488117 484690 481198 480324 482330 488268 487306 484126 480940 478107 481069 486171 484710 480615 478241 sum_num_deletes_total=23161565 sum_num_searches_by_thread=8555383 8656776 8736172 8718590 8666904 8556126 8624109 8654648 8762728 8809433 8662930 8648851 8620879 8712013 8755650 8744195 8722402 8664127 8630497 8642294 8757340 8751092 8667352 8585278 8592376 8658978 8763339 8773144 8720732 8574363 8625523 8696729 8787548 8794136 8709693 8662219 8647475 8683064 8794094 8798724 8728580 8661708 8580003 8668090 8721235 8718588 8671541 8598438 sum_num_searches_total=416936089 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9507057 9619427 9707124 9684611 9631234 9505358 9581400 9615611 9737421 9789943 9624625 9610534 9579263 9680290 9729328 9716446 9689924 9625248 9588774 9603030 9730673 9722860 9631323 9538916 9546707 9620785 9735874 9746361 9691769 9526715 9583819 9664733 9765533 9772174 9678030 9626394 9607440 9648399 9770567 9775226 9697193 9623774 9535408 9631248 9693115 9687278 9635111 9553901 average_num_operations_total=9651416 stdev_num_operations_total=11098 sum_num_operations_total=463267974 min_num_operations_total=9505358 max_num_operations_total=9789943 first_time_thread_terminate_by_thread=10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 min_time_thread_terminate_total=10000153 max_time_thread_terminate_total=10000153 first_time_thread_start_by_thread=4 4 4 3 4 4 4 3 3 4 4 3 4 4 3 4 3 4 4 4 4 4 3 3 4 4 4 3 4 4 4 3 3 4 4 3 4 4 3 4 3 4 4 4 4 4 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=999575558887 threads_final_size=999480 final_keysum=999575558887 final_size=999480 validate_result=success Validation OK. Structural validation OK. total_find=416936089 total_rq=0 total_inserts=23170320 total_deletes=23161565 total_updates=46331885 total_queries=416936089 total_ops=463267974 find_throughput=41693608 rq_throughput=0 update_throughput=4633188 query_throughput=41693608 total_throughput=46326797 total find : 416936089 total rq : 0 total inserts : 23170320 total deletes : 23161565 total updates : 46331885 total queries : 416936089 total ops : 463267974 find throughput : 41693608 rq throughput : 0 update throughput : 4633188 query throughput : 41693608 total throughput : 46326797 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=16.1306 PAPI_L3_TCM=2.17709 PAPI_TOT_CYC=2872.66 PAPI_TOT_INS=1291.05 garbage=208491061050356 total_execution_walltime=11.411s REALTIME_MAIN_END_PERF_FORMAT=6985180.548439616 [time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=91506, mem_maxresident_kb=367860, user_cputime=560.50, sys_cputime=1.29, percent_cpu=4866% timeout=false algorithm=sigouin_int_bst_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