__step=000074 __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_sigouin_int_avl_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 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000074.txt __path_data=data/data000074.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6993855.140940579 binary=./brown_sigouin_int_avl_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_sigouin_int_avl_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=20000000 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=48 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.006s prefilling_size=2472610 prefilling_elapsed_millis=1.001s prefilling_size=2675340 prefilling_elapsed_millis=1.101s prefilling_size=2872316 prefilling_elapsed_millis=1.201s prefilling_size=3065306 prefilling_elapsed_millis=1.301s prefilling_size=3253420 prefilling_elapsed_millis=1.402s prefilling_size=3438029 prefilling_elapsed_millis=1.502s prefilling_size=3619692 prefilling_elapsed_millis=1.602s prefilling_size=3841416 prefilling_elapsed_millis=1.702s prefilling_size=4012900 prefilling_elapsed_millis=1.803s prefilling_size=4179669 prefilling_elapsed_millis=1.903s prefilling_size=4342034 prefilling_elapsed_millis=2.003s prefilling_size=4501925 prefilling_elapsed_millis=2.103s prefilling_size=4657592 prefilling_elapsed_millis=2.203s prefilling_size=4808596 prefilling_elapsed_millis=2.304s prefilling_size=4957000 prefilling_elapsed_millis=2.404s prefilling_size=5101000 prefilling_elapsed_millis=2.504s prefilling_size=5242015 prefilling_elapsed_millis=2.604s prefilling_size=5379222 prefilling_elapsed_millis=2.704s prefilling_size=5513587 prefilling_elapsed_millis=2.805s prefilling_size=5644291 prefilling_elapsed_millis=2.905s prefilling_size=5771590 prefilling_elapsed_millis=3.005s prefilling_size=5895756 prefilling_elapsed_millis=3.105s prefilling_size=6017919 prefilling_elapsed_millis=3.205s prefilling_size=6135447 prefilling_elapsed_millis=3.306s prefilling_size=6250734 prefilling_elapsed_millis=3.406s prefilling_size=6361275 prefilling_elapsed_millis=3.506s prefilling_size=6479291 prefilling_elapsed_millis=3.606s prefilling_size=6604005 prefilling_elapsed_millis=3.706s prefilling_size=6735514 prefilling_elapsed_millis=3.807s prefilling_size=6839022 prefilling_elapsed_millis=3.907s prefilling_size=6935371 prefilling_elapsed_millis=4.007s prefilling_size=7029901 prefilling_elapsed_millis=4.107s prefilling_size=7120867 prefilling_elapsed_millis=4.207s prefilling_size=7209871 prefilling_elapsed_millis=4.308s prefilling_size=7295876 prefilling_elapsed_millis=4.408s prefilling_size=7379614 prefilling_elapsed_millis=4.508s prefilling_size=7461187 prefilling_elapsed_millis=4.608s prefilling_size=7540282 prefilling_elapsed_millis=4.708s prefilling_size=7616996 prefilling_elapsed_millis=4.809s prefilling_size=7691106 prefilling_elapsed_millis=4.909s prefilling_size=7762684 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 2237233 keys with 48 threads... tid=0 thread_prefill_amount=154142 percent_done_estimate=330.7 elapsed_s=5 est_remaining_s=-8 / -3 tid=0 thread_prefill_amount=158308 percent_done_estimate=339.7 elapsed_s=5 est_remaining_s=-8 / -3 tid=0 thread_prefill_amount=162474 percent_done_estimate=348.6 elapsed_s=5 est_remaining_s=-8 / -3 tid=0 thread_prefill_amount=166640 percent_done_estimate=357.5 elapsed_s=5 est_remaining_s=-9 / -3 tid=0 thread_prefill_amount=170806 percent_done_estimate=366.5 elapsed_s=5 est_remaining_s=-9 / -3 tid=0 thread_prefill_amount=174972 percent_done_estimate=375.4 elapsed_s=6 est_remaining_s=-9 / -4 tid=0 thread_prefill_amount=179138 percent_done_estimate=384.3 elapsed_s=6 est_remaining_s=-9 / -4 tid=0 thread_prefill_amount=183304 percent_done_estimate=393.3 elapsed_s=6 est_remaining_s=-10 / -4 tid=0 thread_prefill_amount=187470 percent_done_estimate=402.2 elapsed_s=6 est_remaining_s=-10 / -4 tid=0 thread_prefill_amount=191636 percent_done_estimate=411.2 elapsed_s=6 est_remaining_s=-10 / -4 tid=0 thread_prefill_amount=195802 percent_done_estimate=420.1 elapsed_s=6 est_remaining_s=-10 / -4 timing_elapsed 0.814263s prefilling_size=10000000 prefilling_elapsed_millis=0.816s finished prefilling to size 10000000 for expected size 10000000 keysum=100002125882024, performing 34320223 updates; total_prefilling_elapsed_ms=816 ms) pref_size=10000000 pref_millis=816 prefill_elapsed_ms=816 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6993861.236164343 REALTIME_END_PERF_FORMAT=6993871.236237939 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10.1s PRODUCING OUTPUT computing tree_stats in PARALLEL... bounded depth BFS to partition into subtrees for parallel computation (48 threads)... partitioned into 256 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.048s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131070 262021 520172 974188 1464227 1397572 691998 147041 9545 37 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 111 3493 57494 399119 1191212 1541286 856126 186666 11934 44 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523665 1031682 1863346 2588784 2233284 1003167 196211 11971 44 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523665 1031682 1863346 2588784 2233284 1003167 196211 11971 44 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99814 1.97679 1.86185 1.60351 1.32282 1.13933 1.04901 1.01236 1.00058 1 tree_stats_height=28 tree_stats_numInternals=5728942 tree_stats_numLeaves=4247487 tree_stats_numNodes=9976429 tree_stats_numKeys=9976429 tree_stats_avgDegreeInternal=1.74141 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42575 tree_stats_avgKeyDepth=21.675 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=419903 first_thread_announced_epoch_by_thread=20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 sum_num_inserts_by_thread=199747 199932 201891 201245 199712 198924 199297 199829 203202 201328 199369 198544 199687 200087 200742 201801 201552 200574 199006 200641 201307 201095 199914 198552 200322 200513 202166 201099 200703 199492 197933 199542 202504 197272 200018 198305 200336 199729 201455 200962 200123 200398 198553 199392 201754 201867 200079 198979 sum_num_inserts_total=9611474 sum_num_deletes_by_thread=199622 199942 201557 201010 201801 199555 197808 200017 201503 202421 198804 199049 200805 199735 200018 203165 200991 200313 198950 199287 200712 201486 200097 197197 199412 200222 201737 201250 201702 198288 199112 200284 203470 196872 200211 199739 200487 199062 202396 201715 201153 200749 197617 199465 201650 201451 199762 198431 sum_num_deletes_total=9612082 sum_num_searches_by_thread=3591491 3608419 3635134 3625191 3611888 3574818 3571901 3606246 3643469 3630019 3590724 3584269 3606513 3601508 3609251 3641511 3620945 3607486 3579665 3605316 3619265 3629998 3597839 3560442 3590479 3605761 3632370 3626766 3618736 3577812 3568063 3596934 3647635 3551577 3610900 3583464 3608695 3596741 3629043 3639651 3620090 3598946 3562019 3599136 3620161 3629039 3593085 3583136 sum_num_searches_total=173043547 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3990860 4008293 4038582 4027446 4013401 3973297 3969006 4006092 4048174 4033768 3988897 3981862 4007005 4001330 4010011 4046477 4023488 4008373 3977621 4005244 4021284 4032579 3997850 3956191 3990213 4006496 4036273 4029115 4021141 3975592 3965108 3996760 4053609 3945721 4011129 3981508 4009518 3995532 4032894 4042328 4021366 4000093 3958189 3997993 4023565 4032357 3992926 3980546 average_num_operations_total=4005564 stdev_num_operations_total=3787 sum_num_operations_total=192267103 min_num_operations_total=3945721 max_num_operations_total=4053609 first_time_thread_terminate_by_thread=10000126 10000073 10000073 10000072 10000074 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000072 10000073 10000073 10000073 10000072 10000073 10000073 10000074 10000074 10000073 10000073 10000074 10000074 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000072 10000073 10000073 10000073 10000072 10000073 10000073 10000073 10000072 10000073 10000073 min_time_thread_terminate_total=10000072 max_time_thread_terminate_total=10000126 first_time_thread_start_by_thread=8 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=8 sum_duration_all_ops_total= threads_final_keysum=99756859309485 threads_final_size=9976429 final_keysum=99756859309485 final_size=9976429 validate_result=success Validation OK. Structural validation OK. total_find=173043547 total_rq=0 total_inserts=9611474 total_deletes=9612082 total_updates=19223556 total_queries=173043547 total_ops=192267103 find_throughput=17304354 rq_throughput=0 update_throughput=1922355 query_throughput=17304354 total_throughput=19226710 total find : 173043547 total rq : 0 total inserts : 9611474 total deletes : 9612082 total updates : 19223556 total queries : 173043547 total ops : 192267103 find throughput : 17304354 rq throughput : 0 update throughput : 1922355 query throughput : 17304354 total throughput : 19226710 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=21.5911 PAPI_L3_TCM=7.15182 PAPI_TOT_CYC=6904.16 PAPI_TOT_INS=2763.13 garbage=848920752985868 total_execution_walltime=16.294s REALTIME_MAIN_END_PERF_FORMAT=6993871.436063110 HyTM1 system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 226311323 [slow htm] [fallback] total fallback commit : 276004 seconds global lock is held : 5.7107 [time_cmd_output] time_elapsed_sec=16.49, faults_major=0, faults_minor=501131, mem_maxresident_kb=1198976, user_cputime=770.78, sys_cputime=6.12, percent_cpu=4709% timeout=false algorithm=brown_sigouin_int_avl_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=2