__step=000577 __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" ./brown_sigouin_abtree_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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000577.txt __path_data=data/data000577.txt __hostname=jax sizeof(kcasdesc_t)=1920 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x5651687b4480 addrof(kcasDescriptors[1])=0x5651687b4c00 addrof(rdcssDescriptors[0])=0x5651688a4480 addrof(rdcssDescriptors[1])=0x5651688a4540 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6985826.70049082 binary=./brown_sigouin_abtree_kcas_validate.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_sigouin_abtree_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=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=40 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=100231 prefilling_elapsed_millis=1.001s finished prefilling to size 100178 for expected size 100000 keysum=10009624008, performing 76361425 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100178 pref_millis=1102 prefill_elapsed_ms=1124 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6985827.362514868 REALTIME_END_PERF_FORMAT=6985837.362656656 ############################################################################### ################################## 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 3150 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.058s tree_stats_numInternalsAtDepth=1 1 8 50 410 3150 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 19479 tree_stats_numNodesAtDepth=1 1 8 50 410 3150 19479 tree_stats_numKeysAtDepth=0 0 0 0 0 0 99879 tree_stats_avgDegreeAtDepth=1 8 6.25 8.2 7.68293 6.18381 5.12752 tree_stats_height=7 tree_stats_numInternals=3620 tree_stats_numLeaves=19479 tree_stats_numNodes=23099 tree_stats_numKeys=99879 tree_stats_avgDegreeInternal=6.38094 tree_stats_avgDegreeLeaves=5.12752 tree_stats_avgDegree=5.32395 tree_stats_avgKeyDepth=6 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:61944 2:76406 4:11034 8:90 [2^00, 2^01]: 61944 (2^01, 2^02]: 76406 (2^02, 2^03]: 11034 (2^03, 2^04]: 90 sum_limbo_reclamation_event_size_total=267454 count_limbo_reclamation_event_size_by_thread=3064 3008 3138 3318 3078 3124 3074 3090 3146 3054 3118 3116 3148 3102 3038 3132 3090 2970 3058 3090 3068 3172 3040 3104 3074 2996 3102 3086 3170 3034 3230 3208 3118 3240 3176 3166 3196 3142 3058 3114 3182 3070 3152 3104 3188 3100 3098 3130 count_limbo_reclamation_event_size_total=149474 sum_limbo_reclamation_event_count_total=4922684 first_thread_announced_epoch_by_thread=109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 109612 sum_commit_tx_reader_total= sum_commit_tx_reader_by_thread= sum_commit_tx_writer_total= sum_commit_tx_writer_by_thread= sum_abort_tx_total= sum_abort_tx_by_thread= max_ht_capacity_by_thread= linear_histogram_of_none_tx_rdset_size_full_data= linear_histogram_of_none_tx_wrset_size_full_data= sum_fasthtm_commit_total= sum_fasthtm_abort_total= sum_htmpostfix_commit_total= sum_htmpostfix_abort_total= sum_htmprefix_commit_total= sum_htmprefix_abort_total= sum_htmprefix_abort_explicit_total= sum_htmprefix_abort_conflict_total= sum_htmprefix_abort_capacity_total= sum_htmprefix_abort_nesting_total= sum_htmprefix_abort_illegal_total= sum_htmprefix_abort_zero_total= sum_stmprefix_abort_total= sum_stmprefix_commit_total= sum_slow_commit_total= sum_abtree_insert_repeat_total=6326 sum_abtree_erase_repeat_total=1001 sum_abtree_search_repeat_total=364 sum_abtree_search_complete_total=113566970 sum_abtree_fix_weight_viol_attempt_total=105654 sum_abtree_fix_degree_viol_attempt_total=816148 sum_abtree_rebalancing_kcas_attempt_total=920608 sum_abtree_update_kcas_attempt_total=56313851 sum_abtree_internal_complex_search_while_iters_total=113567881 sum_abtree_internal_basic_search_while_iters_total=1013849242 sum_kcas_desc_new_total= sum_num_inserts_by_thread=1165387 1163972 1181041 1180268 1168777 1171681 1164981 1179409 1184202 1187378 1171863 1170835 1171147 1179403 1187291 1188490 1170233 1166205 1157966 1165082 1166002 1183464 1164249 1158760 1144504 1161436 1177975 1185286 1176189 1170299 1171453 1177744 1180027 1179817 1173281 1170392 1170676 1178748 1185763 1186534 1174595 1169439 1156358 1165446 1174328 1186918 1174462 1175549 sum_num_inserts_total=56315305 sum_num_deletes_by_thread=1164791 1165640 1181884 1178779 1169386 1172388 1167347 1178680 1184109 1186309 1173971 1171219 1172515 1177361 1188591 1187924 1168237 1168388 1158260 1166364 1166000 1183064 1163705 1162442 1145340 1162149 1177767 1183410 1173309 1171038 1169913 1176601 1179212 1180828 1173617 1171893 1170285 1180283 1183940 1186680 1173574 1167766 1154958 1167509 1175113 1186416 1175763 1175472 sum_num_deletes_total=56320190 sum_num_searches_by_thread=20993421 21002724 21276074 21247514 21035997 21106715 20992551 21232612 21310916 21332671 21095323 21086727 21081407 21206553 21380645 21374680 21048335 21014471 20861944 20986086 20994277 21311602 20935865 20881904 20621963 20919324 21200920 21306094 21136975 21094267 21097151 21212511 21224479 21226343 21121038 21058932 21074591 21232195 21337882 21355173 21132255 21039043 20792854 20996996 21152083 21367376 21154039 21164654 sum_num_searches_total=1013810152 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=23323599 23332336 23638999 23606561 23374160 23450784 23324879 23590701 23679227 23706358 23441157 23428781 23425069 23563317 23756527 23751094 23386805 23349064 23178170 23317532 23326279 23678130 23263819 23203106 22911807 23242909 23556662 23674790 23486473 23435604 23438517 23566856 23583718 23586988 23467936 23401217 23415552 23591226 23707585 23728387 23480424 23376248 23104170 23329951 23501524 23740710 23504264 23515675 average_num_operations_total=23467617 stdev_num_operations_total=26495 sum_num_operations_total=1126445647 min_num_operations_total=22911807 max_num_operations_total=23756527 first_time_thread_terminate_by_thread=10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000140 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000140 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 min_time_thread_terminate_total=10000140 max_time_thread_terminate_total=10000141 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=9982262768 threads_final_size=99879 final_keysum=9982262768 final_size=99879 validate_result=success Validation OK. Structural validation OK. total_find=1013810152 total_rq=0 total_inserts=56315305 total_deletes=56320190 total_updates=112635495 total_queries=1013810152 total_ops=1126445647 find_throughput=101381015 rq_throughput=0 update_throughput=11263549 query_throughput=101381015 total_throughput=112644564 total find : 1013810152 total rq : 0 total inserts : 56315305 total deletes : 56320190 total updates : 112635495 total queries : 1013810152 total ops : 1126445647 find throughput : 101381015 rq throughput : 0 update throughput : 11263549 query throughput : 101381015 total throughput : 112644564 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=5.89208 PAPI_L3_TCM=8.83434e-05 PAPI_TOT_CYC=1133.94 PAPI_TOT_INS=819.274 garbage=50689526450205 total_execution_walltime=11.442s REALTIME_MAIN_END_PERF_FORMAT=6985837.513135821 [time_cmd_output] time_elapsed_sec=11.57, faults_major=0, faults_minor=76185, mem_maxresident_kb=305992, user_cputime=565.93, sys_cputime=2.43, percent_cpu=4912% timeout=false algorithm=brown_sigouin_abtree_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