__step=000033 __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 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000033.txt __path_data=data/data000033.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6992286.664023126 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=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=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.005s prefilling_size=3447252 prefilling_elapsed_millis=1.001s prefilling_size=3735665 prefilling_elapsed_millis=1.101s prefilling_size=4016776 prefilling_elapsed_millis=1.201s prefilling_size=4289471 prefilling_elapsed_millis=1.301s prefilling_size=4557472 prefilling_elapsed_millis=1.402s prefilling_size=4806077 prefilling_elapsed_millis=1.502s prefilling_size=5046423 prefilling_elapsed_millis=1.602s prefilling_size=5295449 prefilling_elapsed_millis=1.702s prefilling_size=5521935 prefilling_elapsed_millis=1.802s prefilling_size=5737843 prefilling_elapsed_millis=1.903s prefilling_size=5949663 prefilling_elapsed_millis=2.003s prefilling_size=6155963 prefilling_elapsed_millis=2.103s prefilling_size=6356012 prefilling_elapsed_millis=2.203s prefilling_size=6550918 prefilling_elapsed_millis=2.303s prefilling_size=6739985 prefilling_elapsed_millis=2.404s prefilling_size=6923812 prefilling_elapsed_millis=2.504s prefilling_size=7101431 prefilling_elapsed_millis=2.604s prefilling_size=7274684 prefilling_elapsed_millis=2.704s prefilling_size=7440156 prefilling_elapsed_millis=2.804s prefilling_size=7599686 prefilling_elapsed_millis=2.905s prefilling_size=7752598 prefilling_elapsed_millis=3.005s prefilling_size=7900501 prefilling_elapsed_millis=3.105s prefilling_size=8041069 prefilling_elapsed_millis=3.205s prefilling_size=8175984 prefilling_elapsed_millis=3.305s prefilling_size=8303497 prefilling_elapsed_millis=3.406s prefilling_size=8425264 prefilling_elapsed_millis=3.506s prefilling_size=8539962 prefilling_elapsed_millis=3.606s prefilling_size=8648444 prefilling_elapsed_millis=3.706s prefilling_size=8750550 prefilling_elapsed_millis=3.806s prefilling_size=8846503 prefilling_elapsed_millis=3.907s prefilling_size=8937463 prefilling_elapsed_millis=4.007s prefilling_size=9023234 prefilling_elapsed_millis=4.107s prefilling_size=9102869 prefilling_elapsed_millis=4.207s prefilling_size=9176969 prefilling_elapsed_millis=4.307s prefilling_size=9243628 prefilling_elapsed_millis=4.408s prefilling_size=9306863 prefilling_elapsed_millis=4.508s prefilling_size=9366463 prefilling_elapsed_millis=4.608s prefilling_size=9420633 prefilling_elapsed_millis=4.708s prefilling_size=9471853 prefilling_elapsed_millis=4.808s prefilling_size=9518917 prefilling_elapsed_millis=4.909s prefilling_size=9562088 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 437902 keys with 48 threads... tid=0 thread_prefill_amount=187470 percent_done_estimate=2054.9 elapsed_s=5 est_remaining_s=-11 / -6 tid=0 thread_prefill_amount=191636 percent_done_estimate=2100.6 elapsed_s=5 est_remaining_s=-11 / -6 timing_elapsed 0.144017s prefilling_size=10000000 prefilling_elapsed_millis=0.144s finished prefilling to size 10000000 for expected size 10000000 keysum=100001213883483, performing 64279717 updates; total_prefilling_elapsed_ms=144 ms) pref_size=10000000 pref_millis=144 prefill_elapsed_ms=144 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=3005 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6992292.68158313 REALTIME_END_PERF_FORMAT=6992302.68237261 ############################################################################### ################################## 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 254 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.128s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 504 987 1893 3513 6299 10710 17319 27142 40910 59668 84373 115860 155533 203944 259804 320889 382292 437938 482803 511746 518966 505866 473649 425466 367138 304155 242032 184752 135785 96344 66283 43556 28315 17669 10567 6123 3435 1905 1019 544 267 112 56 22 10 5 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 13 50 158 393 1034 2238 4134 7183 11920 18590 28301 40724 57108 78929 105656 137908 172983 208494 239380 265981 279523 281968 273431 252683 224127 190700 155428 121257 90967 65338 46148 30311 20031 12809 7745 4513 2543 1404 769 437 224 84 45 17 7 6 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 506 1000 1943 3671 6692 11744 19557 31276 48093 71588 102963 144161 196257 261052 338733 426545 520200 610921 691297 751126 784947 785389 755617 698897 619821 528282 432732 340180 257042 187311 131621 89704 58626 37700 23376 13868 7948 4448 2423 1313 704 336 140 67 27 12 6 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 254 506 1000 1943 3671 6692 11744 19557 31276 48093 71588 102963 144161 196257 261052 338733 426545 520200 610921 691297 751126 784947 785389 755617 698897 619821 528282 432732 340180 257042 187311 131621 89704 58626 37700 23376 13868 7948 4448 2423 1313 704 336 140 67 27 12 6 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.98438 1.99213 1.98024 1.956 1.91508 1.86598 1.81366 1.75332 1.71366 1.66987 1.63789 1.60478 1.58067 1.55769 1.53766 1.51633 1.49225 1.46727 1.4395 1.41472 1.38814 1.36372 1.33942 1.318 1.2981 1.27809 1.25998 1.24339 1.22681 1.21251 1.20046 1.18833 1.17794 1.168 1.16008 1.15138 1.14121 1.1316 1.12745 1.11646 1.12134 1.12186 1.09801 1.08333 1.07857 1.07463 1.07407 1.08333 1 tree_stats_height=56 tree_stats_numInternals=6558677 tree_stats_numLeaves=3443694 tree_stats_numNodes=10002371 tree_stats_numKeys=10002371 tree_stats_avgDegreeInternal=1.52506 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34429 tree_stats_avgKeyDepth=29.416 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=13488 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:58 2:1671 4:55208 8:369454 16:53424 [2^00, 2^01]: 58 (2^01, 2^02]: 1671 (2^02, 2^03]: 55208 (2^03, 2^04]: 369454 (2^04, 2^05]: 53424 sum_limbo_reclamation_event_size_total=5456735 count_limbo_reclamation_event_size_by_thread=9997 9996 9996 9996 9996 9996 9996 9997 9996 9996 9997 9996 9997 9996 9996 9996 9998 9995 9996 9997 9996 9996 9996 9996 9997 9997 9996 9996 9996 9997 9996 9996 9996 9996 9995 9996 9996 9996 9995 9995 9996 9996 9997 9996 9996 9996 9996 9996 count_limbo_reclamation_event_size_total=479815 sum_limbo_reclamation_event_count_total=503223 first_thread_announced_epoch_by_thread=26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 sum_num_inserts_by_thread=236242 240094 242326 237905 241825 239809 239503 240660 238919 238528 226607 240160 236964 224400 233770 239401 239323 240934 240266 240451 239025 243415 238345 236258 223467 239840 238717 242315 241729 239513 237919 241119 242444 236332 236513 239701 235795 240464 239432 235961 241414 238334 232677 240356 242589 243325 240302 238630 sum_num_inserts_total=11444018 sum_num_deletes_by_thread=235804 239473 243011 237852 241165 239244 240498 240423 239287 237779 225824 239723 237676 225179 233664 239758 240153 240460 240207 239491 239791 242967 238994 236394 224340 240409 238146 240416 241985 238339 238152 240580 242159 234825 234449 239973 236467 240589 239590 234774 242789 237695 232425 241476 242525 243288 240212 238394 sum_num_deletes_total=11438814 sum_num_searches_by_thread=4257268 4321792 4380245 4284505 4355734 4315155 4316977 4334549 4311981 4293406 4072749 4311610 4277603 4045795 4209478 4313812 4323198 4329907 4322672 4318337 4313905 4366613 4291859 4256821 4025635 4308927 4285218 4364959 4350785 4300741 4279241 4336674 4362283 4256150 4236986 4311819 4252085 4322622 4303935 4237290 4357582 4293385 4177921 4343697 4357407 4379824 4329611 4291027 sum_num_searches_total=205991775 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=4729314 4801359 4865582 4760262 4838724 4794208 4796978 4815632 4790187 4769713 4525180 4791493 4752243 4495374 4676912 4792971 4802674 4811301 4803145 4798279 4792721 4852995 4769198 4729473 4473442 4789176 4762081 4847690 4834499 4778593 4755312 4818373 4846886 4727307 4707948 4791493 4724347 4803675 4782957 4708025 4841785 4769414 4643023 4825529 4842521 4866437 4810125 4768051 average_num_operations_total=4768220 stdev_num_operations_total=12411 sum_num_operations_total=228874607 min_num_operations_total=4473442 max_num_operations_total=4866437 first_time_thread_terminate_by_thread=10000132 10000079 10000079 10000078 10000079 10000079 10000080 10000079 10000079 10000081 10000079 10000079 10000079 10000079 10000079 10000081 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000081 10000079 10000080 10000079 10000079 10000079 10000081 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 min_time_thread_terminate_total=10000078 max_time_thread_terminate_total=10000132 first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=100033603791546 threads_final_size=10002371 final_keysum=100033603791546 final_size=10002371 validate_result=success Validation OK. Structural validation OK. total_find=205991775 total_rq=0 total_inserts=11444018 total_deletes=11438814 total_updates=22882832 total_queries=205991775 total_ops=228874607 find_throughput=20599177 rq_throughput=0 update_throughput=2288283 query_throughput=20599177 total_throughput=22887460 total find : 205991775 total rq : 0 total inserts : 11444018 total deletes : 11438814 total updates : 22882832 total queries : 205991775 total ops : 228874607 find throughput : 20599177 rq throughput : 0 update throughput : 2288283 query throughput : 20599177 total throughput : 22887460 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=18.9507 PAPI_L3_TCM=5.8711 PAPI_TOT_CYC=5848.42 PAPI_TOT_INS=1561 garbage=1029978999657586 total_execution_walltime=15.706s REALTIME_MAIN_END_PERF_FORMAT=6992302.371085297 HyTM1 system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 292984869 [slow htm] [fallback] total fallback commit : 169456 seconds global lock is held : 2.2691 [time_cmd_output] time_elapsed_sec=15.84, faults_major=0, faults_minor=234239, mem_maxresident_kb=633960, user_cputime=740.02, sys_cputime=6.34, percent_cpu=4711% 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=1