__step=000250 __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.hytm1 -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000250.txt __path_data=data/data000250.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6997564.344014301 binary=./brown_int_bst_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_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=2000000 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 HyTM1 system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.007s prefilling_size=999764 prefilling_elapsed_millis=1.001s finished prefilling to size 999752 for expected size 1000000 keysum=999116089893, performing 31767616 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999752 pref_millis=1102 prefill_elapsed_ms=1122 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=1477 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6997565.658037996 REALTIME_END_PERF_FORMAT=6997575.658170840 ############################################################################### ################################## 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 802 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.033s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 121 228 423 746 1306 2284 3776 6011 9128 13336 18723 25202 32043 38369 43789 47934 50489 50994 50019 47237 43370 38321 32420 26320 20685 15627 11389 8154 5583 3717 2478 1689 1042 617 339 165 80 48 23 19 11 4 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 7 15 56 101 175 411 837 1572 2662 4310 6651 9819 13781 17575 21019 24080 26469 27380 27886 26898 25518 23285 20183 16730 13505 10418 7564 5646 3857 2548 1660 1199 744 470 265 120 53 41 10 13 10 4 tree_stats_numNodesAtDepth=1 2 4 8 16 32 63 122 235 438 802 1407 2459 4187 6848 10700 15998 23033 31853 41862 52150 61364 68953 74569 77463 77399 75123 70268 63839 55705 46503 37415 29132 21807 15718 11229 7574 5026 3349 2241 1361 809 430 200 101 64 29 24 14 4 tree_stats_numKeysAtDepth=1 2 4 8 16 32 63 122 235 438 802 1407 2459 4187 6848 10700 15998 23033 31853 41862 52150 61364 68953 74569 77463 77399 75123 70268 63839 55705 46503 37415 29132 21807 15718 11229 7574 5026 3349 2241 1361 809 430 200 101 64 29 24 14 4 tree_stats_avgDegreeAtDepth=2 2 2 2 2 1.96875 1.93651 1.93443 1.89362 1.8653 1.82419 1.81947 1.77389 1.7337 1.68473 1.64206 1.60614 1.57005 1.52303 1.48032 1.44094 1.41008 1.38628 1.36173 1.34087 1.32435 1.30658 1.2913 1.27231 1.25281 1.23859 1.22577 1.21214 1.19851 1.19564 1.17731 1.17283 1.1733 1.16483 1.14235 1.14107 1.11248 1.0814 1.105 1.15842 1.09375 1.17241 1.125 1 1 tree_stats_height=50 tree_stats_numInternals=654385 tree_stats_numLeaves=345548 tree_stats_numNodes=999933 tree_stats_numKeys=999933 tree_stats_avgDegreeInternal=1.52805 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34557 tree_stats_avgKeyDepth=24.9327 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=22032 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:75 2:1753 4:57418 8:369725 16:50927 [2^00, 2^01]: 75 (2^01, 2^02]: 1753 (2^02, 2^03]: 57418 (2^03, 2^04]: 369725 (2^04, 2^05]: 50927 sum_limbo_reclamation_event_size_total=5413476 count_limbo_reclamation_event_size_by_thread=9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9996 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 count_limbo_reclamation_event_size_total=479898 sum_limbo_reclamation_event_count_total=986640 first_thread_announced_epoch_by_thread=44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 44064 sum_num_inserts_by_thread=464863 469216 470516 471341 456041 460144 463464 466527 471717 473562 455659 469561 441574 447354 430331 473035 474271 464003 460931 471002 460017 474145 467681 464711 470638 471263 452576 473365 440855 469209 443924 474873 458880 474663 468683 473071 461603 463038 468916 476833 471849 459254 452326 469238 438941 476763 451297 470739 sum_num_inserts_total=22254463 sum_num_deletes_by_thread=465571 468240 470001 473023 456574 462597 464399 467019 471866 473576 455278 469362 441872 447258 430921 472740 475100 465924 461503 469472 458960 474174 467314 464218 471169 471225 453374 473560 440835 470199 444788 475336 459382 476208 468996 470678 460679 462884 470428 477278 471044 458998 452145 470018 437607 475617 451896 469798 sum_num_deletes_total=22261104 sum_num_searches_by_thread=8382702 8436801 8466741 8506943 8224365 8302777 8345002 8415214 8484011 8528486 8192492 8466293 7936158 8037862 7739392 8518601 8553519 8344542 8285478 8474482 8271496 8530944 8401148 8356730 8486013 8475905 8152043 8531942 7933808 8455010 8008894 8543750 8265377 8557991 8448891 8478091 8292541 8332029 8455805 8608153 8484856 8272111 8131779 8459386 7889980 8571285 8115553 8451837 sum_num_searches_total=400605209 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9313136 9374257 9407258 9451307 9136980 9225518 9272865 9348760 9427594 9475624 9103429 9405216 8819604 8932474 8600644 9464376 9502890 9274469 9207912 9414956 9190473 9479263 9336143 9285659 9427820 9418393 9057993 9478867 8815498 9394418 8897606 9493959 9183639 9508862 9386570 9421840 9214823 9257951 9395149 9562264 9427749 9190363 9036250 9398642 8766528 9523665 9018746 9392374 average_num_operations_total=9273349 stdev_num_operations_total=32889 sum_num_operations_total=445120776 min_num_operations_total=8600644 max_num_operations_total=9562264 first_time_thread_terminate_by_thread=10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 min_time_thread_terminate_total=10000132 max_time_thread_terminate_total=10000132 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=999453209038 threads_final_size=999933 final_keysum=999453209038 final_size=999933 validate_result=success Validation OK. Structural validation OK. total_find=400605209 total_rq=0 total_inserts=22254463 total_deletes=22261104 total_updates=44515567 total_queries=400605209 total_ops=445120776 find_throughput=40060520 rq_throughput=0 update_throughput=4451556 query_throughput=40060520 total_throughput=44512077 total find : 400605209 total rq : 0 total inserts : 22254463 total deletes : 22261104 total updates : 44515567 total queries : 400605209 total ops : 445120776 find throughput : 40060520 rq throughput : 0 update throughput : 4451556 query throughput : 40060520 total throughput : 44512077 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=8.38417 PAPI_L3_TCM=0.248795 PAPI_TOT_CYC=2829.66 PAPI_TOT_INS=1488.13 garbage=200327782685439 total_execution_walltime=11.442s REALTIME_MAIN_END_PERF_FORMAT=6997575.796980349 HyTM1 system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 476857459 [slow htm] [fallback] total fallback commit : 30934 seconds global lock is held : 0.272727 [time_cmd_output] time_elapsed_sec=11.57, faults_major=0, faults_minor=93017, mem_maxresident_kb=342372, user_cputime=561.92, sys_cputime=1.79, percent_cpu=4870% timeout=false algorithm=brown_int_bst_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