__step=000690 __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 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000690.txt __path_data=data/data000690.txt __hostname=jax sizeof(kcasdesc_t)=1920 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x562a78649480 addrof(kcasDescriptors[1])=0x562a78649c00 addrof(rdcssDescriptors[0])=0x562a78739480 addrof(rdcssDescriptors[1])=0x562a78739540 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6987160.587548643 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=50 DEL_FRAC=50 RQ=0 RQSIZE=0 MAXKEY=20000000 PREFILL_THREADS=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=50.0 50.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.005s prefilling_size=8795013 prefilling_elapsed_millis=1.001s prefilling_size=9006698 prefilling_elapsed_millis=1.101s prefilling_size=9171695 prefilling_elapsed_millis=1.201s prefilling_size=9311832 prefilling_elapsed_millis=1.301s prefilling_size=9425638 prefilling_elapsed_millis=1.402s prefilling_size=9523423 prefilling_elapsed_millis=1.502s prefilling_size=9600378 prefilling_elapsed_millis=1.602s prefilling_size=9667106 prefilling_elapsed_millis=1.702s prefilling_size=9721556 prefilling_elapsed_millis=1.802s prefilling_size=9766781 prefilling_elapsed_millis=1.903s prefilling_size=9804861 prefilling_elapsed_millis=2.003s finished prefilling to size 9804877 for expected size 10000000 keysum=98077605194552, performing 86065127 updates; total_prefilling_elapsed_ms=2104 ms) pref_size=9804877 pref_millis=2104 prefill_elapsed_ms=2124 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6987162.849784722 REALTIME_END_PERF_FORMAT=6987172.849921989 ############################################################################### ################################## 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 4518 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.073s tree_stats_numInternalsAtDepth=1 1 11 80 592 4518 33573 262821 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1962270 tree_stats_numNodesAtDepth=1 1 11 80 592 4518 33573 262821 1962270 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10001443 tree_stats_avgDegreeAtDepth=1 11 7.27273 7.4 7.63176 7.43094 7.82834 7.46618 5.09687 tree_stats_height=9 tree_stats_numInternals=301597 tree_stats_numLeaves=1962270 tree_stats_numNodes=2263867 tree_stats_numKeys=10001443 tree_stats_avgDegreeInternal=7.50626 tree_stats_avgDegreeLeaves=5.09687 tree_stats_avgDegree=5.41786 tree_stats_avgKeyDepth=8 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:41719 2:138648 4:207869 8:61612 16:778 32:2 64:89 128:3 [2^00, 2^01]: 41719 (2^01, 2^02]: 138648 (2^02, 2^03]: 207869 (2^03, 2^04]: 61612 (2^04, 2^05]: 778 (2^05, 2^06]: 2 (2^06, 2^07]: 89 (2^07, 2^08]: 3 sum_limbo_reclamation_event_size_total=2066524 count_limbo_reclamation_event_size_by_thread=9496 9344 9316 9352 9514 9358 9292 9468 9348 9216 9332 9298 9290 9514 9372 9364 9316 9360 9504 9346 9344 9490 9280 9494 9472 9470 9504 9494 9338 9310 9494 9324 9528 9298 9472 9326 9286 9302 9326 9456 9474 9508 9318 9518 9496 9324 9330 9344 count_limbo_reclamation_event_size_total=450720 sum_limbo_reclamation_event_count_total=1757568 first_thread_announced_epoch_by_thread=44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 44566 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=2361 sum_abtree_erase_repeat_total=379 sum_abtree_search_repeat_total=63 sum_abtree_search_complete_total=406450043 sum_abtree_fix_weight_viol_attempt_total=674235 sum_abtree_fix_degree_viol_attempt_total=2306635 sum_abtree_rebalancing_kcas_attempt_total=2980705 sum_abtree_update_kcas_attempt_total=201727842 sum_abtree_internal_complex_search_while_iters_total=406450379 sum_abtree_internal_basic_search_while_iters_total= sum_kcas_desc_new_total= sum_num_inserts_by_thread=4169450 4206557 4228284 4225028 4194860 4179758 4201159 4200044 4243381 4251378 4206191 4184319 4206567 4205316 4234054 4236057 4216148 4190400 4158056 4195861 4223306 4218059 4195025 4143838 4162137 4185327 4211707 4211154 4214346 4184262 4186890 4209541 4229082 4250153 4186921 4168958 4204863 4225258 4241101 4233043 4205972 4182197 4164990 4175440 4203704 4233465 4195243 4161263 sum_num_inserts_total=201740113 sum_num_deletes_by_thread=4165550 4205503 4228417 4223331 4195869 4179147 4200791 4199298 4245810 4249183 4206288 4184642 4202108 4204168 4238648 4234209 4217946 4193375 4154971 4193740 4222720 4216114 4194597 4142965 4164196 4183525 4212581 4212369 4215483 4182214 4183686 4208667 4234356 4255608 4185262 4171334 4199716 4219416 4237471 4230338 4204720 4183810 4174798 4176118 4206066 4231132 4196565 4155847 sum_num_deletes_total=201724668 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=8335000 8412060 8456701 8448359 8390729 8358905 8401950 8399342 8489191 8500561 8412479 8368961 8408675 8409484 8472702 8470266 8434094 8383775 8313027 8389601 8446026 8434173 8389622 8286803 8326333 8368852 8424288 8423523 8429829 8366476 8370576 8418208 8463438 8505761 8372183 8340292 8404579 8444674 8478572 8463381 8410692 8366007 8339788 8351558 8409770 8464597 8391808 8317110 average_num_operations_total=8405516 stdev_num_operations_total=7554 sum_num_operations_total=403464781 min_num_operations_total=8286803 max_num_operations_total=8505761 first_time_thread_terminate_by_thread=10000137 10000137 10000138 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000138 10000137 10000137 10000138 10000137 10000137 10000137 10000137 10000137 10000137 10000138 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000138 10000137 10000137 10000138 10000137 10000137 10000137 10000137 min_time_thread_terminate_total=10000137 max_time_thread_terminate_total=10000138 first_time_thread_start_by_thread=5 6 5 6 5 5 5 6 5 5 5 6 5 5 5 6 5 5 5 5 5 5 5 6 5 6 5 6 5 5 5 6 5 5 5 6 5 5 5 6 5 5 5 5 5 5 5 6 min_time_thread_start_total=5 max_time_thread_start_total=6 sum_duration_all_ops_total= threads_final_keysum=100007684557845 threads_final_size=10001443 final_keysum=100007684557845 final_size=10001443 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=201740113 total_deletes=201724668 total_updates=403464781 total_queries=0 total_ops=403464781 find_throughput=0 rq_throughput=0 update_throughput=40346478 query_throughput=0 total_throughput=40346478 total find : 0 total rq : 0 total inserts : 201740113 total deletes : 201724668 total updates : 403464781 total queries : 0 total ops : 403464781 find throughput : 0 rq throughput : 0 update throughput : 40346478 query throughput : 0 total throughput : 40346478 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=19.4223 PAPI_L3_TCM=6.85107 PAPI_TOT_CYC=3297.87 PAPI_TOT_INS=1415.14 garbage=0 total_execution_walltime=12.431s REALTIME_MAIN_END_PERF_FORMAT=6987173.19478967 [time_cmd_output] time_elapsed_sec=12.58, faults_major=0, faults_minor=199755, mem_maxresident_kb=799444, user_cputime=612.74, sys_cputime=2.61, percent_cpu=4891% 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=2