__step=000242 __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.tl2 -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=data000242.txt __path_data=data/data000242.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6997470.898294262 binary=./brown_int_bst_tm_auto.debra.tl2 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 TL2 system ready: GV=GV4 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.006s prefilling_size=998786 prefilling_elapsed_millis=1.001s finished prefilling to size 998775 for expected size 1000000 keysum=998462536113, performing 16988853 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=998775 pref_millis=1102 prefill_elapsed_ms=1123 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=778 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6997472.226314657 REALTIME_END_PERF_FORMAT=6997482.226468989 ############################################################################### ################################## 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 889 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.036s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 126 247 463 837 1464 2512 4216 6790 10430 15304 21465 28110 34943 41263 46514 49806 51165 50558 48619 45072 40435 35088 29466 24100 19136 14692 10741 7530 5067 3295 1993 1144 634 320 143 68 16 4 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 2 17 52 116 232 433 904 1709 3032 4963 7995 11445 15385 19290 22973 25702 27400 27637 27503 26098 23999 21439 18428 15165 12366 9696 7252 5181 3560 2415 1475 859 496 255 108 65 14 5 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 249 480 889 1580 2744 4649 7694 12139 18336 26428 36105 46388 56648 65804 72779 76867 77958 76256 72575 66533 59087 50905 42528 34301 27058 20437 14782 10248 6855 4408 2619 1493 816 398 176 81 18 5 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 249 480 889 1580 2744 4649 7694 12139 18336 26428 36105 46388 56648 65804 72779 76867 77958 76256 72575 66533 59087 50905 42528 34301 27058 20437 14782 10248 6855 4408 2619 1493 816 398 176 81 18 5 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.9685 1.93574 1.8875 1.83577 1.81013 1.77879 1.74812 1.69522 1.65129 1.60668 1.55396 1.50625 1.4679 1.43322 1.39914 1.37182 1.34856 1.32964 1.31415 1.29571 1.28034 1.26769 1.2566 1.23987 1.23096 1.21232 1.19773 1.18387 1.17447 1.16236 1.14201 1.13326 1.1219 1.09559 1.08291 1.07386 1.02469 1.05556 1 tree_stats_height=46 tree_stats_numInternals=653903 tree_stats_numLeaves=345667 tree_stats_numNodes=999570 tree_stats_numKeys=999570 tree_stats_avgDegreeInternal=1.52862 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34582 tree_stats_avgKeyDepth=24.5463 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=12931 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:52 2:1364 4:50386 8:372281 16:55816 [2^00, 2^01]: 52 (2^01, 2^02]: 1364 (2^02, 2^03]: 50386 (2^03, 2^04]: 372281 (2^04, 2^05]: 55816 sum_limbo_reclamation_event_size_total=5520864 count_limbo_reclamation_event_size_by_thread=9998 9998 9997 9998 9998 9998 9998 9997 9998 9997 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 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 count_limbo_reclamation_event_size_total=479899 sum_limbo_reclamation_event_count_total=583344 first_thread_announced_epoch_by_thread=25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 25862 sum_num_inserts_by_thread=273747 276987 280775 282658 281686 277359 278139 278823 283419 284263 280830 277780 276801 277122 281013 282493 283033 278893 274170 277681 280953 282555 280862 276981 274558 278077 280808 281557 281666 276885 276069 278440 282750 283288 280792 280556 275192 280578 281142 282199 284103 279672 275918 277582 279658 282473 280999 279260 sum_num_inserts_total=13423245 sum_num_deletes_by_thread=275491 276598 280190 282598 281563 277053 276663 278055 283253 285006 281504 277847 276355 278185 281522 281244 281825 278715 274113 278408 279417 282413 280815 276977 274605 278804 281090 281264 282699 277335 276547 277961 284123 283463 280862 280381 274879 279635 281968 282236 282990 279282 274806 276614 280437 281848 282808 279017 sum_num_deletes_total=13421464 sum_num_searches_by_thread=4935265 4984454 5052270 5085359 5072345 4976465 4983127 5021393 5108889 5120332 5049744 5018013 4970313 5003484 5070261 5073027 5072802 5024755 4933322 5007291 5033873 5084244 5061049 4972881 4951497 5012068 5053559 5069617 5075266 4998773 4961880 5001469 5106902 5106905 5056214 5036973 4968813 5046664 5056359 5081221 5095278 5037739 4945595 4991385 5034655 5080649 5059774 5020996 sum_num_searches_total=241565209 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5484503 5538039 5613235 5650615 5635594 5530877 5537929 5578271 5675561 5689601 5612078 5573640 5523469 5558791 5632796 5636764 5637660 5582363 5481605 5563380 5594243 5649212 5622726 5526839 5500660 5568949 5615457 5632438 5639631 5552993 5514496 5557870 5673775 5673656 5617868 5597910 5518884 5606877 5619469 5645656 5662371 5596693 5496319 5545581 5594750 5644970 5623581 5579273 average_num_operations_total=5591873 stdev_num_operations_total=8046 sum_num_operations_total=268409918 min_num_operations_total=5481605 max_num_operations_total=5689601 first_time_thread_terminate_by_thread=10000155 10000154 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000154 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000154 10000155 10000155 10000154 10000155 10000155 10000154 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000154 10000155 10000155 10000155 10000155 10000155 10000155 10000155 10000154 10000155 10000155 10000154 10000155 min_time_thread_terminate_total=10000154 max_time_thread_terminate_total=10000155 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=998947781839 threads_final_size=999570 final_keysum=998947781839 final_size=999570 validate_result=success Validation OK. Structural validation OK. total_find=241565209 total_rq=0 total_inserts=13423245 total_deletes=13421464 total_updates=26844709 total_queries=241565209 total_ops=268409918 find_throughput=24156520 rq_throughput=0 update_throughput=2684470 query_throughput=24156520 total_throughput=26840991 total find : 241565209 total rq : 0 total inserts : 13423245 total deletes : 13421464 total updates : 26844709 total queries : 241565209 total ops : 268409918 find throughput : 24156520 rq throughput : 0 update throughput : 2684470 query throughput : 24156520 total throughput : 26840991 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=20.4243 PAPI_L3_TCM=0.476773 PAPI_TOT_CYC=3468.95 PAPI_TOT_INS=2433.78 garbage=120713027058422 total_execution_walltime=11.446s REALTIME_MAIN_END_PERF_FORMAT=6997482.357260886 TL2 system shutdown: GCLOCK=0x1DEF0A2 Starts=285624706 Aborts=225934 Overflows: R=0 W=0 L=0 [time_cmd_output] time_elapsed_sec=11.59, faults_major=0, faults_minor=94501, mem_maxresident_kb=343496, user_cputime=504.99, sys_cputime=59.65, percent_cpu=4871% timeout=false algorithm=brown_int_bst_tm_auto.debra.tl2 thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2