__step=000393 __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_bst_kcas_validate_htm.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=data000393.txt __path_data=data/data000393.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x564438c2e400 addrof(kcasDescriptors[1])=0x564438c2ed00 addrof(rdcssDescriptors[0])=0x564438d4e400 addrof(rdcssDescriptors[1])=0x564438d4e4c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6999545.442719259 binary=./sigouin_int_bst_kcas_validate_htm.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_htm 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=999772 prefilling_elapsed_millis=1.001s finished prefilling to size 999745 for expected size 1000000 keysum=999700789984, performing 40019417 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999745 pref_millis=1102 prefill_elapsed_ms=1122 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6999546.729551877 REALTIME_END_PERF_FORMAT=6999556.729685269 ############################################################################### ################################## 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 977 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.033s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 498 945 1738 3086 5181 8333 12814 18840 26274 34503 42844 50785 56467 59264 58983 55592 50016 43132 35640 28035 20979 15058 10089 6396 3789 2092 1070 524 230 92 33 6 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 7 32 71 220 541 1166 2136 3674 6223 9752 13977 18735 24029 28643 31589 32971 32060 29874 26650 22626 18257 13893 10400 7140 4654 2838 1608 830 411 188 77 31 6 1 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 505 977 1809 3306 5722 9499 14950 22514 32497 44255 56821 69520 80496 87907 90572 88563 82076 73006 62290 50661 39236 28951 20489 13536 8443 4930 2678 1354 641 280 110 37 7 1 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 505 977 1809 3306 5722 9499 14950 22514 32497 44255 56821 69520 80496 87907 90572 88563 82076 73006 62290 50661 39236 28951 20489 13536 8443 4930 2678 1354 641 280 110 37 7 1 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.98039 1.94851 1.88434 1.86678 1.79734 1.75463 1.6966 1.64883 1.6066 1.55331 1.5043 1.46947 1.42737 1.39058 1.35615 1.32659 1.29904 1.28011 1.26242 1.24115 1.2211 1.20318 1.18759 1.16824 1.15123 1.13514 1.11886 1.10605 1.08641 1.078 1.06429 1.03636 1.02703 1 1 tree_stats_height=43 tree_stats_numInternals=653839 tree_stats_numLeaves=345310 tree_stats_numNodes=999149 tree_stats_numKeys=999149 tree_stats_avgDegreeInternal=1.52813 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.3456 tree_stats_avgKeyDepth=23.2955 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:53 2:1451 4:52860 8:371211 16:54320 32:3 [2^00, 2^01]: 53 (2^01, 2^02]: 1451 (2^02, 2^03]: 52860 (2^03, 2^04]: 371211 (2^04, 2^05]: 54320 (2^05, 2^06]: 3 sum_limbo_reclamation_event_size_total=5482618 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9996 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 9997 count_limbo_reclamation_event_size_total=479898 sum_limbo_reclamation_event_count_total=1024128 first_thread_announced_epoch_by_thread=46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 46396 sum_num_inserts_by_thread=480767 485805 490996 490159 487195 478653 482741 486601 490733 490608 486134 483086 485043 487939 493325 490201 490974 488293 481609 485065 491983 491397 488449 479784 484796 482728 493605 489425 487483 484380 487321 487418 490988 491094 487288 486510 483874 490439 489843 494972 491496 483736 484207 483840 489117 493944 485563 484253 sum_num_inserts_total=23395860 sum_num_deletes_by_thread=482145 487669 491479 491030 487482 480334 481649 488339 491045 491370 487452 483943 485311 486599 493087 490231 489349 489490 481588 485413 492791 491079 489322 479488 484615 483659 491288 488800 486419 484481 487612 488363 490604 491945 486805 485218 483345 490004 490288 493554 490593 484253 483990 482880 489706 494280 486650 483813 sum_num_deletes_total=23400850 sum_num_searches_by_thread=8668744 8769380 8852491 8831020 8753758 8627341 8672636 8768787 8844015 8847311 8749628 8694278 8727089 8757061 8892365 8822210 8830931 8785502 8659989 8728377 8851753 8831926 8793642 8657429 8729809 8702135 8864666 8795851 8765129 8720768 8770127 8804835 8837379 8858834 8760156 8746231 8708951 8814079 8827570 8889818 8835532 8720325 8706723 8708434 8791167 8898412 8741606 8729021 sum_num_searches_total=421145221 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9631656 9742854 9834966 9812209 9728435 9586328 9637026 9743727 9825793 9829289 9723214 9661307 9697443 9731599 9878777 9802642 9811254 9763285 9623186 9698855 9836527 9814402 9771413 9616701 9699220 9668522 9849559 9774076 9739031 9689629 9745060 9780616 9818971 9841873 9734249 9717959 9676170 9794522 9807701 9878344 9817621 9688314 9674920 9675154 9769990 9886636 9713819 9697087 average_num_operations_total=9748790 stdev_num_operations_total=11048 sum_num_operations_total=467941931 min_num_operations_total=9586328 max_num_operations_total=9886636 first_time_thread_terminate_by_thread=10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 min_time_thread_terminate_total=10000132 max_time_thread_terminate_total=10000132 first_time_thread_start_by_thread=4 4 4 4 4 4 3 3 4 4 4 4 3 3 4 4 4 4 3 4 4 4 4 3 4 4 4 4 4 4 3 3 4 4 4 4 3 3 4 4 4 4 3 4 4 4 4 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=999038961587 threads_final_size=999149 final_keysum=999038961587 final_size=999149 validate_result=success Validation OK. Structural validation OK. total_find=421145221 total_rq=0 total_inserts=23395860 total_deletes=23400850 total_updates=46796710 total_queries=421145221 total_ops=467941931 find_throughput=42114522 rq_throughput=0 update_throughput=4679671 query_throughput=42114522 total_throughput=46794193 total find : 421145221 total rq : 0 total inserts : 23395860 total deletes : 23400850 total updates : 46796710 total queries : 421145221 total ops : 467941931 find throughput : 42114522 rq throughput : 0 update throughput : 4679671 query throughput : 42114522 total throughput : 46794193 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=16.0967 PAPI_L3_TCM=2.16724 PAPI_TOT_CYC=2844.72 PAPI_TOT_INS=1281.52 garbage=210617802352977 total_execution_walltime=11.419s REALTIME_MAIN_END_PERF_FORMAT=6999556.863586916 [time_cmd_output] time_elapsed_sec=11.55, faults_major=0, faults_minor=91576, mem_maxresident_kb=368232, user_cputime=559.00, sys_cputime=2.81, percent_cpu=4860% timeout=false algorithm=sigouin_int_bst_kcas_validate_htm.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1