__step=000441 __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_int_bst_tm_auto.debra.hybridnorec -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=data000441.txt __path_data=data/data000441.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7000113.128200637 binary=./brown_int_bst_tm_auto.debra.hybridnorec parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_int_bst_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=32 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 Hybrid noREC system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.006s prefilling_size=100252 prefilling_elapsed_millis=1.001s finished prefilling to size 100246 for expected size 100000 keysum=10036726091, performing 1662434 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100246 pref_millis=1102 prefill_elapsed_ms=1125 4NodeIxPvL9tx_safety0EE_object_size=32 4NodeIxPvL9tx_safety0EE_allocated_count=0 4NodeIxPvL9tx_safety0EE_allocated_size=0MB 4NodeIxPvL9tx_safety0EE_get_from_pool=0 4NodeIxPvL9tx_safety0EE_deallocated=0 4NodeIxPvL9tx_safety0EE_limbo_count=0 4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 global_epoch_counter=85 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7000114.465783194 REALTIME_END_PERF_FORMAT=7000124.465912241 ############################################################################### ################################## 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 826 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.034s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 242 441 754 1245 1854 2706 3589 4479 5121 5502 5729 5690 5450 5032 4376 3650 2877 2163 1535 1056 685 400 216 124 64 23 7 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 3 25 72 136 368 548 967 1458 2029 2546 2821 3083 3144 3122 3012 2737 2317 1877 1433 1039 733 499 309 163 98 57 18 6 3 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 125 245 466 826 1381 2222 3254 4556 5937 7150 8048 8550 8773 8594 8154 7388 6387 5194 4040 2968 2095 1418 899 525 287 162 80 25 8 3 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 125 245 466 826 1381 2222 3254 4556 5937 7150 8048 8550 8773 8594 8154 7388 6387 5194 4040 2968 2095 1418 899 525 287 162 80 25 8 3 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.95312 1.96 1.91429 1.82618 1.75908 1.70746 1.63006 1.56853 1.51536 1.44989 1.40937 1.37873 1.35602 1.33102 1.31464 1.28894 1.2722 1.24174 1.22391 1.19926 1.18868 1.17279 1.15092 1.13904 1.13524 1.1324 1.09877 1.025 1.04 1.125 1 tree_stats_height=37 tree_stats_numInternals=65264 tree_stats_numLeaves=34623 tree_stats_numNodes=99887 tree_stats_numKeys=99887 tree_stats_avgDegreeInternal=1.53051 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34662 tree_stats_avgKeyDepth=19.3834 4NodeIxPvL9tx_safety0EE_object_size=32 4NodeIxPvL9tx_safety0EE_allocated_count=0 4NodeIxPvL9tx_safety0EE_allocated_size=0MB 4NodeIxPvL9tx_safety0EE_get_from_pool=0 4NodeIxPvL9tx_safety0EE_deallocated=0 4NodeIxPvL9tx_safety0EE_limbo_count=0 4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 global_epoch_counter=12039 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:474 2:10717 4:190091 8:276685 16:1889 [2^00, 2^01]: 474 (2^01, 2^02]: 10717 (2^02, 2^03]: 190091 (2^03, 2^04]: 276685 (2^04, 2^05]: 1889 sum_limbo_reclamation_event_size_total=3898551 count_limbo_reclamation_event_size_by_thread=9998 9996 9997 9995 9996 9997 9996 9998 9996 9997 9996 9998 9997 9996 9996 9995 9998 9998 9998 9998 9997 9998 9998 9998 9997 9998 9997 9995 9996 9997 9997 9998 9997 9997 9996 9997 9997 9998 9998 9998 9995 9997 9997 9998 9998 9997 9997 9997 count_limbo_reclamation_event_size_total=479856 sum_limbo_reclamation_event_count_total=573792 first_thread_announced_epoch_by_thread=24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 sum_num_inserts_by_thread=196694 185041 184950 191640 183904 188139 201064 202271 190602 190139 193580 195043 190938 192453 189180 188730 194087 186452 198927 201303 182829 194022 184483 195298 191392 193834 197342 196336 196953 190893 193256 206567 200197 193583 196728 197972 196295 196594 197438 196391 193702 189914 196679 202190 204819 201659 200718 192114 sum_num_inserts_total=9325335 sum_num_deletes_by_thread=195788 184392 184829 191876 183116 188096 201281 202674 190798 190608 194324 194030 191230 191853 190590 187956 194042 186076 197490 200196 182354 193414 184268 195955 191036 193100 197986 194986 198090 190704 193127 205835 200861 194254 196844 198891 197223 196554 197031 196230 194006 189780 196984 201586 205267 201791 199480 191982 sum_num_deletes_total=9320864 sum_num_searches_by_thread=3522613 3328589 3328390 3456170 3292084 3383383 3620307 3649387 3420215 3418717 3497762 3497043 3431073 3453466 3408878 3405811 3496184 3352435 3554881 3616610 3285734 3484172 3321331 3523130 3444308 3476037 3552156 3526582 3554970 3443673 3477987 3715270 3603639 3495444 3537165 3570662 3552298 3538256 3544434 3532492 3483972 3406356 3544187 3638662 3685395 3641189 3605332 3461509 sum_num_searches_total=167780340 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3915095 3698022 3698169 3839686 3659104 3759618 4022652 4054332 3801615 3799464 3885666 3886116 3813241 3837772 3788648 3782497 3884313 3724963 3951298 4018109 3650917 3871608 3690082 3914383 3826736 3862971 3947484 3917904 3950013 3825270 3864370 4127672 4004697 3883281 3930737 3967525 3945816 3931404 3938903 3925113 3871680 3786050 3937850 4042438 4095481 4044639 4005530 3845605 average_num_operations_total=3883886 stdev_num_operations_total=16523 sum_num_operations_total=186426539 min_num_operations_total=3650917 max_num_operations_total=4127672 first_time_thread_terminate_by_thread=10000127 10000138 10000129 10000129 10000138 10000147 10000150 10000128 10000127 10000128 10000150 10000128 10000146 10000137 10000143 10000127 10000150 10000128 10000144 10000140 10000138 10000131 10000148 10000138 10000131 10000132 10000129 10000135 10000147 10000128 10000127 10000145 10000151 10000143 10000127 10000147 10000128 10000140 10000139 10000128 10000127 10000128 10000149 10000133 10000130 10000141 10000148 10000146 min_time_thread_terminate_total=10000127 max_time_thread_terminate_total=10000151 first_time_thread_start_by_thread=3 2 3 2 3 2 3 3 3 3 2 2 3 3 3 3 3 3 2 3 3 3 2 3 3 2 3 2 3 2 3 3 3 3 2 2 3 3 3 3 3 3 2 3 3 3 2 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=9986744166 threads_final_size=99887 final_keysum=9986744166 final_size=99887 validate_result=success Validation OK. Structural validation OK. total_find=167780340 total_rq=0 total_inserts=9325335 total_deletes=9320864 total_updates=18646199 total_queries=167780340 total_ops=186426539 find_throughput=16778034 rq_throughput=0 update_throughput=1864619 query_throughput=16778034 total_throughput=18642653 total find : 167780340 total rq : 0 total inserts : 9325335 total deletes : 9320864 total updates : 18646199 total queries : 167780340 total ops : 186426539 find throughput : 16778034 rq throughput : 0 update throughput : 1864619 query throughput : 16778034 total throughput : 18642653 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=8.46692 PAPI_L3_TCM=0.000638938 PAPI_TOT_CYC=7136.13 PAPI_TOT_INS=2358.87 garbage=8390618156120 total_execution_walltime=11.48s REALTIME_MAIN_END_PERF_FORMAT=7000124.609886382 Hybrid noREC system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 184405305 [slow htm] [fallback] total fallback commit : 3683669 seconds global lock is held : 0.211389 [time_cmd_output] time_elapsed_sec=11.61, faults_major=0, faults_minor=76697, mem_maxresident_kb=308724, user_cputime=559.91, sys_cputime=3.49, percent_cpu=4850% timeout=false algorithm=brown_int_bst_tm_auto.debra.hybridnorec thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1