__step=000545 __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" ./brown_abtree_tm_auto.debra.hytm1 -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=data000545.txt __path_data=data/data000545.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7001386.756144957 binary=./brown_abtree_tm_auto.debra.hytm1 parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_abtree_tm_auto 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=200 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 HyTM1 system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.008s prefilling_size=99884 prefilling_elapsed_millis=1.001s finished prefilling to size 99879 for expected size 100000 keysum=9983029470, performing 12083640 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99879 pref_millis=1102 prefill_elapsed_ms=1122 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7001388.45902434 REALTIME_END_PERF_FORMAT=7001398.46049406 ############################################################################### ################################## 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 3036 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.039s tree_stats_numInternalsAtDepth=1 1 7 52 402 3036 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 19458 tree_stats_numNodesAtDepth=1 1 7 52 402 3036 19458 tree_stats_numKeysAtDepth=0 0 0 0 0 0 100049 tree_stats_avgDegreeAtDepth=1 7 7.42857 7.73077 7.55224 6.40909 5.14179 tree_stats_height=7 tree_stats_numInternals=3499 tree_stats_numLeaves=19458 tree_stats_numNodes=22957 tree_stats_numKeys=100049 tree_stats_avgDegreeInternal=6.56102 tree_stats_avgDegreeLeaves=5.14179 tree_stats_avgDegree=5.3581 tree_stats_avgKeyDepth=6 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 [2^00, 2^01]: 0 sum_limbo_reclamation_event_size_total= count_limbo_reclamation_event_size_by_thread= count_limbo_reclamation_event_size_total= sum_limbo_reclamation_event_count_total=1167984 first_thread_announced_epoch_by_thread=49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 49976 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= sum_abtree_erase_repeat_total= sum_abtree_search_repeat_total= sum_abtree_search_complete_total= sum_abtree_fix_weight_viol_attempt_total= sum_abtree_fix_degree_viol_attempt_total= sum_abtree_rebalancing_kcas_attempt_total= sum_abtree_update_kcas_attempt_total= sum_abtree_internal_complex_search_while_iters_total= sum_abtree_internal_basic_search_while_iters_total= sum_kcas_desc_new_total= sum_num_inserts_by_thread=539640 541748 542970 545744 541023 538401 541438 542648 547606 548674 543536 541900 544121 546258 549355 546305 545380 546080 543022 546210 549244 547579 544657 532040 541151 546427 545141 548545 546796 540117 540030 544145 546473 549877 543234 543457 542920 545673 548021 548744 545351 540795 541545 543332 546578 548969 547094 547701 sum_num_inserts_total=26137695 sum_num_deletes_by_thread=538593 542201 543356 544964 541419 537846 540483 543206 546700 550151 542408 541978 543000 544346 548210 548447 545194 545734 543372 547541 549832 549125 545500 531637 542118 544528 546155 548385 546738 539802 540833 544308 546655 549014 542722 542422 543850 545338 546932 551076 545642 541913 541542 543243 546969 548750 546029 547523 sum_num_deletes_total=26137730 sum_num_searches_by_thread=9694288 9749659 9776336 9803097 9727088 9667507 9758634 9774964 9844348 9910291 9760890 9744374 9792012 9821437 9871900 9871394 9828460 9826490 9789225 9834490 9898617 9869685 9805784 9574703 9756468 9822373 9824988 9882900 9853107 9710985 9738666 9801245 9824886 9899516 9762789 9768669 9787770 9807629 9836823 9892928 9821499 9749587 9744840 9789018 9849417 9881131 9818204 9850142 sum_num_searches_total=470471253 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=10772521 10833608 10862662 10893805 10809530 10743754 10840555 10860818 10938654 11009116 10846834 10828252 10879133 10912041 10969465 10966146 10919034 10918304 10875619 10928241 10997693 10966389 10895941 10638380 10839737 10913328 10916284 10979830 10946641 10790904 10819529 10889698 10918014 10998407 10848745 10854548 10874540 10898640 10931776 10992748 10912492 10832295 10827927 10875593 10942964 10978850 10911327 10945366 average_num_operations_total=10890555 stdev_num_operations_total=10595 sum_num_operations_total=522746678 min_num_operations_total=10638380 max_num_operations_total=11009116 first_time_thread_terminate_by_thread=10000146 10000146 10000146 10000146 10000146 10000146 10000145 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 min_time_thread_terminate_total=10000145 max_time_thread_terminate_total=10000146 first_time_thread_start_by_thread=3 3 3 3 3 3 4 3 3 4 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 4 3 3 3 3 4 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=10007968195 threads_final_size=100049 final_keysum=10007968195 final_size=100049 validate_result=success Validation OK. Structural validation OK. total_find=470471253 total_rq=0 total_inserts=26137695 total_deletes=26137730 total_updates=52275425 total_queries=470471253 total_ops=522746678 find_throughput=47047125 rq_throughput=0 update_throughput=5227542 query_throughput=47047125 total_throughput=52274667 total find : 470471253 total rq : 0 total inserts : 26137695 total deletes : 26137730 total updates : 52275425 total queries : 470471253 total ops : 522746678 find throughput : 47047125 rq throughput : 0 update throughput : 5227542 query throughput : 47047125 total throughput : 52274667 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=5.84831 PAPI_L3_TCM=0.0433037 PAPI_TOT_CYC=2537.9 PAPI_TOT_INS=1896.79 garbage=23520969428786 total_execution_walltime=11.398s REALTIME_MAIN_END_PERF_FORMAT=7001398.163251987 HyTM1 system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 534353479 [slow htm] [fallback] total fallback commit : 476840 seconds global lock is held : 4.67284 [time_cmd_output] time_elapsed_sec=11.57, faults_major=0, faults_minor=232733, mem_maxresident_kb=870820, user_cputime=557.66, sys_cputime=3.45, percent_cpu=4849% timeout=false algorithm=brown_abtree_tm_auto.debra.hytm1 thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1