__step=000457 __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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000457.txt __path_data=data/data000457.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7000311.94283186 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=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 TL2 system ready: GV=GV4 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.007s prefilling_size=100016 prefilling_elapsed_millis=1.001s finished prefilling to size 100024 for expected size 100000 keysum=9989467815, performing 17676281 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100024 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=815 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7000312.398545513 REALTIME_END_PERF_FORMAT=7000322.398697732 ############################################################################### ################################## 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 870 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 128 242 453 819 1405 2246 3311 4500 5602 6378 6560 6421 6080 5410 4566 3671 2766 1895 1211 733 392 208 105 48 15 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 8 16 51 122 316 641 1199 1864 2587 3367 3592 3525 3561 3298 2857 2366 1908 1319 886 552 298 169 80 46 17 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 250 469 870 1527 2562 3952 5699 7466 8965 9927 10013 9605 8971 7864 6528 5132 3803 2530 1619 944 506 274 128 61 19 2 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 250 469 870 1527 2562 3952 5699 7466 8965 9927 10013 9605 8971 7864 6528 5132 3803 2530 1619 944 506 274 128 61 19 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.95312 1.908 1.88913 1.81379 1.75769 1.66589 1.60425 1.52044 1.45044 1.39587 1.34784 1.31799 1.30099 1.27355 1.24949 1.22381 1.20207 1.16697 1.16126 1.13033 1.12076 1.13043 1.08394 1.10156 1.06557 1 1 tree_stats_height=34 tree_stats_numInternals=65294 tree_stats_numLeaves=34647 tree_stats_numNodes=99941 tree_stats_numKeys=99941 tree_stats_avgDegreeInternal=1.53063 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34667 tree_stats_avgKeyDepth=18.4 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=15660 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:65 2:1343 4:49695 8:372368 16:56427 [2^00, 2^01]: 65 (2^01, 2^02]: 1343 (2^02, 2^03]: 49695 (2^03, 2^04]: 372368 (2^04, 2^05]: 56427 sum_limbo_reclamation_event_size_total=5533801 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9997 count_limbo_reclamation_event_size_total=479898 sum_limbo_reclamation_event_count_total=712560 first_thread_announced_epoch_by_thread=31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 31320 sum_num_inserts_by_thread=338589 339920 342769 343894 342226 337957 339261 342582 344606 345655 345229 340531 342088 341261 343949 344090 346357 340188 338735 342393 343403 345281 341261 339208 339170 341467 342202 345050 344130 339676 340224 340986 346606 346483 343096 340337 342560 341644 344456 347002 344517 341599 339952 342546 341782 345355 341838 338499 sum_num_inserts_total=16432610 sum_num_deletes_by_thread=337452 341361 342557 345525 343632 337646 340867 342079 345473 346549 343150 340144 341653 339827 343083 344402 344953 340697 338684 341784 342642 347196 342469 340178 340467 340223 342454 344441 344485 340586 340554 341748 347241 346990 342763 340420 342481 343486 342503 345978 344542 341269 339144 341851 342395 345550 342863 338203 sum_num_deletes_total=16436640 sum_num_searches_by_thread=6086450 6151300 6179485 6221677 6175115 6091261 6130847 6173733 6216043 6227128 6198384 6131327 6160775 6132228 6186779 6201524 6219589 6132450 6093567 6155956 6173684 6225866 6147885 6116813 6124889 6135824 6166783 6216951 6191063 6136828 6120025 6145518 6240405 6232348 6170942 6129113 6165331 6164378 6180569 6240363 6205502 6141231 6124608 6157453 6166065 6219599 6171075 6095909 sum_num_searches_total=295972638 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=6762491 6832581 6864811 6911096 6860973 6766864 6810975 6858394 6906122 6919332 6886763 6812002 6844516 6813316 6873811 6890016 6910899 6813335 6770986 6840133 6859729 6918343 6831615 6796199 6804526 6817514 6851439 6906442 6879678 6817090 6800803 6828252 6934252 6925821 6856801 6809870 6850372 6849508 6867528 6933343 6894561 6824099 6803704 6841850 6850242 6910504 6855776 6772611 average_num_operations_total=6850872 stdev_num_operations_total=6784 sum_num_operations_total=328841888 min_num_operations_total=6762491 max_num_operations_total=6934252 first_time_thread_terminate_by_thread=10000150 10000150 10000150 10000150 10000149 10000150 10000150 10000150 10000151 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000151 10000151 10000150 10000149 10000149 10000150 10000150 10000150 10000151 10000150 10000150 10000150 10000149 10000150 10000150 10000150 10000151 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000151 10000150 10000150 10000149 10000149 10000151 10000150 10000150 min_time_thread_terminate_total=10000149 max_time_thread_terminate_total=10000151 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=9977164103 threads_final_size=99941 final_keysum=9977164103 final_size=99941 validate_result=success Validation OK. Structural validation OK. total_find=295972638 total_rq=0 total_inserts=16432610 total_deletes=16436640 total_updates=32869250 total_queries=295972638 total_ops=328841888 find_throughput=29597263 rq_throughput=0 update_throughput=3286925 query_throughput=29597263 total_throughput=32884188 total find : 295972638 total rq : 0 total inserts : 16432610 total deletes : 16436640 total updates : 32869250 total queries : 295972638 total ops : 328841888 find throughput : 29597263 rq throughput : 0 update throughput : 3286925 query throughput : 29597263 total throughput : 32884188 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=8.67359 PAPI_L3_TCM=0.000373496 PAPI_TOT_CYC=2488.81 PAPI_TOT_INS=1957.25 garbage=14800549044986 total_execution_walltime=11.443s REALTIME_MAIN_END_PERF_FORMAT=7000322.538304894 TL2 system shutdown: GCLOCK=0x2134BF0 Starts=347454075 Aborts=935905 Overflows: R=0 W=0 L=0 [time_cmd_output] time_elapsed_sec=11.55, faults_major=0, faults_minor=78204, mem_maxresident_kb=308916, user_cputime=483.26, sys_cputime=76.24, percent_cpu=4842% 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=1