__step=000610 __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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000610.txt __path_data=data/data000610.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55f5c4311400 addrof(kcasDescriptors[1])=0x55f5c4311d00 addrof(rdcssDescriptors[0])=0x55f5c4431400 addrof(rdcssDescriptors[1])=0x55f5c44314c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=7002150.84134078 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=200000 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.022s prefilling_size=99862 prefilling_elapsed_millis=1.001s finished prefilling to size 99788 for expected size 100000 keysum=9994980398, performing 62428131 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99788 pref_millis=1102 prefill_elapsed_ms=1140 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7002151.454116319 REALTIME_END_PERF_FORMAT=7002161.454258900 ############################################################################### ################################## 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 846 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 126 247 448 771 1287 1972 2909 3933 5004 5851 6316 6507 6324 5809 4992 3977 2996 2198 1490 949 579 327 192 137 90 55 21 5 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 3 23 75 138 346 549 1014 1526 2218 2905 3254 3563 3612 3475 3149 2596 1970 1509 1047 693 438 239 121 103 63 50 23 5 1 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 250 471 846 1425 2318 3458 4947 6530 8069 9221 9761 9887 9421 8467 7126 5592 4168 2999 1996 1272 765 431 258 193 118 71 28 6 1 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 250 471 846 1425 2318 3458 4947 6530 8069 9221 9761 9887 9421 8467 7126 5592 4168 2999 1996 1272 765 431 258 193 118 71 28 6 1 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.97638 1.896 1.84501 1.77305 1.72351 1.64107 1.58936 1.52496 1.46937 1.41765 1.3736 1.34628 1.31324 1.28214 1.25204 1.22663 1.20959 1.19218 1.16872 1.16182 1.14623 1.13595 1.15313 1.21705 1.14508 1.13559 1.09859 1.03571 1 1 tree_stats_height=37 tree_stats_numInternals=65640 tree_stats_numLeaves=34709 tree_stats_numNodes=100349 tree_stats_numKeys=100349 tree_stats_avgDegreeInternal=1.52878 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34588 tree_stats_avgKeyDepth=18.8255 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:76 2:1500 4:51148 8:371456 16:55717 [2^00, 2^01]: 76 (2^01, 2^02]: 1500 (2^02, 2^03]: 51148 (2^03, 2^04]: 371456 (2^04, 2^05]: 55717 sum_limbo_reclamation_event_size_total=5509687 count_limbo_reclamation_event_size_by_thread=9998 9998 9997 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 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9996 9998 9998 9997 count_limbo_reclamation_event_size_total=479897 sum_limbo_reclamation_event_count_total=1767024 first_thread_announced_epoch_by_thread=79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 79454 sum_num_inserts_by_thread=835898 840318 843897 845828 843598 840417 833077 838273 857497 860955 845416 852404 849816 845047 849515 847847 858851 856429 838682 844683 853710 853083 840650 837544 846180 853180 846621 851913 846668 837063 846230 854982 855803 855116 856364 854253 853721 846134 851485 856947 848313 853118 834749 837249 848693 857307 847119 836831 sum_num_inserts_total=40689474 sum_num_deletes_by_thread=837096 840171 843037 847721 842231 840290 831872 840199 859524 861042 842930 853275 849385 844296 849559 848815 859657 854940 839089 845116 852290 852495 840153 840123 845888 851579 849519 848408 846871 836027 846009 854730 855197 856708 855811 853524 852431 845146 852754 857526 849315 853353 835873 837342 850376 858383 847020 838520 sum_num_deletes_total=40693616 sum_num_searches_by_thread=15055028 15109885 15185194 15227828 15175145 15133864 14991855 15106390 15467525 15492850 15207965 15340729 15302672 15194082 15298343 15254330 15464697 15406551 15089064 15178893 15344686 15352611 15137158 15101941 15205724 15344501 15252817 15311334 15254212 15035177 15214720 15388825 15396755 15421304 15429859 15372264 15366200 15207515 15342470 15423904 15263689 15356472 15033912 15084366 15302839 15412782 15256187 15055943 sum_num_searches_total=732353057 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=16728022 16790374 16872128 16921377 16860974 16814571 16656804 16784862 17184546 17214847 16896311 17046408 17001873 16883425 16997417 16950992 17183205 17117920 16766835 16868692 17050686 17058189 16817961 16779608 16897792 17049260 16948957 17011655 16947751 16708267 16906959 17098537 17107755 17133128 17142034 17080041 17072352 16898795 17046709 17138377 16961317 17062943 16704534 16758957 17001908 17128472 16950326 16731294 average_num_operations_total=16952836 stdev_num_operations_total=21529 sum_num_operations_total=813736147 min_num_operations_total=16656804 max_num_operations_total=17214847 first_time_thread_terminate_by_thread=10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000141 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000139 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000141 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000139 10000140 10000140 10000140 10000140 min_time_thread_terminate_total=10000139 max_time_thread_terminate_total=10000141 first_time_thread_start_by_thread=4 3 4 3 3 4 3 3 4 4 4 4 3 4 4 4 3 4 3 4 4 4 4 3 4 3 4 3 3 4 3 3 4 4 4 4 3 4 4 4 3 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=10008031029 threads_final_size=100349 final_keysum=10008031029 final_size=100349 validate_result=success Validation OK. Structural validation OK. total_find=732353057 total_rq=0 total_inserts=40689474 total_deletes=40693616 total_updates=81383090 total_queries=732353057 total_ops=813736147 find_throughput=73235305 rq_throughput=0 update_throughput=8138309 query_throughput=73235305 total_throughput=81373614 total find : 732353057 total rq : 0 total inserts : 40689474 total deletes : 40693616 total updates : 81383090 total queries : 732353057 total ops : 813736147 find throughput : 73235305 rq throughput : 0 update throughput : 8138309 query throughput : 73235305 total throughput : 81373614 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=6.4447 PAPI_L3_TCM=0.000165525 PAPI_TOT_CYC=1603.58 PAPI_TOT_INS=1096.04 garbage=36618121865039 total_execution_walltime=11.512s REALTIME_MAIN_END_PERF_FORMAT=7002161.608779881 [time_cmd_output] time_elapsed_sec=11.66, faults_major=0, faults_minor=77106, mem_maxresident_kb=310216, user_cputime=563.18, sys_cputime=1.94, percent_cpu=4845% 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=2