__step=000299 __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_abtree_tm_auto.debra.norec -nwork 96 -nprefill 96 -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=data000299.txt __path_data=data/data000299.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6998289.680581923 binary=./brown_abtree_tm_auto.debra.norec parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_abtree_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=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 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=200 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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143 noREC system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.055s prefilling_size=335909 prefilling_elapsed_millis=1s prefilling_size=362281 prefilling_elapsed_millis=1.1s prefilling_size=387624 prefilling_elapsed_millis=1.201s prefilling_size=412082 prefilling_elapsed_millis=1.301s prefilling_size=435501 prefilling_elapsed_millis=1.401s prefilling_size=458436 prefilling_elapsed_millis=1.501s prefilling_size=479914 prefilling_elapsed_millis=1.601s prefilling_size=500732 prefilling_elapsed_millis=1.701s prefilling_size=520526 prefilling_elapsed_millis=1.802s prefilling_size=539681 prefilling_elapsed_millis=1.902s prefilling_size=557829 prefilling_elapsed_millis=2.002s prefilling_size=575664 prefilling_elapsed_millis=2.102s prefilling_size=592833 prefilling_elapsed_millis=2.202s prefilling_size=608992 prefilling_elapsed_millis=2.302s prefilling_size=624209 prefilling_elapsed_millis=2.403s prefilling_size=639368 prefilling_elapsed_millis=2.503s prefilling_size=653517 prefilling_elapsed_millis=2.603s prefilling_size=667403 prefilling_elapsed_millis=2.703s prefilling_size=680417 prefilling_elapsed_millis=2.803s prefilling_size=692728 prefilling_elapsed_millis=2.903s prefilling_size=704635 prefilling_elapsed_millis=3.004s prefilling_size=716305 prefilling_elapsed_millis=3.104s prefilling_size=727124 prefilling_elapsed_millis=3.204s prefilling_size=737985 prefilling_elapsed_millis=3.304s prefilling_size=748505 prefilling_elapsed_millis=3.404s prefilling_size=758383 prefilling_elapsed_millis=3.504s prefilling_size=767972 prefilling_elapsed_millis=3.605s prefilling_size=777249 prefilling_elapsed_millis=3.705s prefilling_size=785816 prefilling_elapsed_millis=3.805s prefilling_size=794078 prefilling_elapsed_millis=3.905s prefilling_size=802483 prefilling_elapsed_millis=4.005s prefilling_size=809961 prefilling_elapsed_millis=4.105s prefilling_size=817818 prefilling_elapsed_millis=4.206s prefilling_size=825157 prefilling_elapsed_millis=4.306s prefilling_size=831960 prefilling_elapsed_millis=4.406s prefilling_size=838284 prefilling_elapsed_millis=4.506s prefilling_size=844382 prefilling_elapsed_millis=4.606s prefilling_size=850786 prefilling_elapsed_millis=4.707s prefilling_size=856676 prefilling_elapsed_millis=4.807s prefilling_size=861947 prefilling_elapsed_millis=4.907s prefilling_size=867175 prefilling_elapsed_millis=5.007s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 132816 keys with 96 threads... tid=0 thread_prefill_amount=10415 percent_done_estimate=752.8 elapsed_s=5 est_remaining_s=-10 / -5 timing_elapsed 0.320932s prefilling_size=1000000 prefilling_elapsed_millis=0.321s finished prefilling to size 1000000 for expected size 1000000 keysum=999840307158, performing 4334880 updates; total_prefilling_elapsed_ms=321 ms) pref_size=1000000 pref_millis=321 prefill_elapsed_ms=321 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6998295.340487666 REALTIME_END_PERF_FORMAT=6998305.340567302 ############################################################################### ################################## 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 (96 threads)... partitioned into 398 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.009s tree_stats_numInternalsAtDepth=1 1 8 55 398 3061 23671 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 185022 tree_stats_numNodesAtDepth=1 1 8 55 398 3061 23671 185022 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1000276 tree_stats_avgDegreeAtDepth=1 8 6.875 7.23636 7.69095 7.73309 7.8164 5.40625 tree_stats_height=8 tree_stats_numInternals=27195 tree_stats_numLeaves=185022 tree_stats_numNodes=212217 tree_stats_numKeys=1000276 tree_stats_avgDegreeInternal=7.80353 tree_stats_avgDegreeLeaves=5.40625 tree_stats_avgDegree=5.71346 tree_stats_avgKeyDepth=7 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=145633 first_thread_announced_epoch_by_thread=3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 3140 sum_commit_tx_reader_total=127740785 sum_commit_tx_reader_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1329254 1320994 1333271 1332705 1316039 1352854 1353775 1329335 1330701 1331963 1332007 1350371 1325574 1324744 1335338 1351475 1326279 1329416 1323543 1330400 1316931 1340244 1325165 1324168 1325654 1324344 1330453 1328703 1340530 1332583 1328181 1340995 1334977 1321606 1326894 1321169 1342773 1327118 1330761 1325815 1326678 1363817 1357701 1323577 1322312 1339639 1333531 1338222 1326870 1323408 1318197 1333110 1329187 1326044 1324565 1324272 1321472 1345949 1350083 1318240 1319150 1324098 1319094 1335487 1327174 1336250 1325643 1326845 1325330 1324045 1327091 1324589 1322224 1328562 1309904 1323512 1344697 1318623 1331053 1344186 1318948 1321337 1340466 1328026 1346716 1362695 1325801 1329653 1352205 1322021 1321568 1329325 1325175 1327431 1325797 1330088 sum_commit_tx_writer_total=6723550 sum_commit_tx_writer_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69563 69370 70509 70116 69325 71204 71330 70116 70020 70108 69967 70858 69810 69845 70398 70995 69644 70037 69779 70090 69926 70649 69632 69813 69710 69602 69574 70385 70426 69940 69728 70587 70105 69911 69875 69423 70583 69408 70119 70134 69532 71724 71315 69830 70010 70449 69893 70656 69313 70245 69115 69778 70133 69646 69727 69607 69503 71123 71126 70016 69356 70290 69333 70606 69861 70543 69470 69563 69478 69356 69752 69805 69711 69783 69136 69713 70803 69463 70071 70737 69137 69863 70751 69846 70562 71530 69846 70191 71493 69784 69311 69951 69275 69844 69704 70277 sum_abort_tx_total=1247 sum_abort_tx_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 13 10 12 10 11 16 14 12 12 12 19 15 14 11 10 16 20 10 9 7 7 12 9 9 8 16 15 14 18 8 14 13 10 10 12 14 9 19 19 14 13 9 11 11 14 11 12 8 15 14 15 14 10 11 17 15 11 20 18 16 12 16 18 9 10 15 10 19 11 15 14 16 18 8 17 16 14 15 12 10 7 14 11 12 15 17 16 12 12 9 13 12 15 13 13 13 max_ht_capacity_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 linear_histogram_of_none_tx_rdset_size_full_data=31.1:814 39.2:12744 47.3:40387 55.4:34660 63.5:7009 71.6:285 79.7:64 87.8:27 95.9:6 104:3 [ 23.00, 31.10]: 814 ( 31.10, 39.20]: 12744 ( 39.20, 47.30]: 40387 ( 47.30, 55.40]: 34660 ( 55.40, 63.50]: 7009 ( 63.50, 71.60]: 285 ( 71.60, 79.70]: 64 ( 79.70, 87.80]: 27 ( 87.80, 95.90]: 6 ( 95.90, 104.00]: 3 linear_histogram_of_none_tx_wrset_size_full_data=24.6:92346 69.8:28 92.4:3151 137.6:418 182.8:53 228:2 [ 2.00, 24.60]: 92346 ( 47.20, 69.80]: 28 ( 69.80, 92.40]: 3151 ( 115.00, 137.60]: 418 ( 160.20, 182.80]: 53 ( 205.40, 228.00]: 2 sum_fasthtm_commit_total= sum_fasthtm_abort_total= sum_htmpostfix_commit_total= sum_htmpostfix_abort_total= sum_htmprefix_commit_total= sum_htmprefix_abort_total= sum_htmprefix_abort_explicit_total= sum_htmprefix_abort_conflict_total= sum_htmprefix_abort_capacity_total= sum_htmprefix_abort_nesting_total= sum_htmprefix_abort_illegal_total= sum_htmprefix_abort_zero_total= sum_stmprefix_abort_total= sum_stmprefix_commit_total= sum_slow_commit_total= sum_abtree_insert_repeat_total= sum_abtree_erase_repeat_total= sum_abtree_search_repeat_total= sum_abtree_search_complete_total= sum_abtree_fix_weight_viol_attempt_total= sum_abtree_fix_degree_viol_attempt_total= sum_abtree_rebalancing_kcas_attempt_total= sum_abtree_update_kcas_attempt_total= sum_abtree_internal_complex_search_while_iters_total= sum_abtree_internal_basic_search_while_iters_total= sum_kcas_desc_new_total= sum_num_inserts_by_thread=70047 69901 69696 69610 69520 69582 71169 70048 70241 70661 69859 69711 71406 69880 70093 69823 69876 69813 71386 69264 69599 70507 70272 69429 70207 69516 69895 69646 69626 69251 70746 69906 70920 70486 69972 69896 71376 70548 70052 69530 70418 69735 70735 69565 69557 70007 69626 69368 70765 69592 70114 69586 69780 69191 71227 69631 70218 70264 70520 69499 71536 69658 69718 69328 69573 69295 70767 69203 70196 69317 69920 69440 71097 70098 69794 69505 69930 69379 70911 69867 70392 69739 70347 70118 71708 69861 70058 70088 69871 69119 70456 68907 69855 69540 70062 69225 sum_num_inserts_total=6720242 sum_num_deletes_by_thread=70247 69979 69669 69738 69899 69995 71039 70517 70000 70634 69603 69418 71674 69954 69612 69527 69877 69577 71016 69523 70031 70021 70340 69583 70631 69917 70037 69165 69443 69272 71034 69928 70105 70086 70550 69977 71899 70354 70271 69950 70577 69953 71182 69442 69850 70046 70170 69579 70621 69315 70145 69518 69304 69225 71203 69969 70154 69852 70654 70227 71545 69346 69708 69300 69251 69844 70472 69180 69943 69813 69780 69127 70792 69587 69757 69423 69740 70061 70928 69602 70498 69710 70458 69875 71462 69771 70097 69556 70263 69379 70937 69266 69777 69686 70089 69292 sum_num_deletes_total=6722393 sum_num_searches_by_thread=1260071 1256069 1257161 1252169 1257350 1255012 1282897 1262256 1264841 1269598 1261259 1254817 1285936 1260656 1258204 1256014 1262770 1259427 1281656 1248070 1259823 1258923 1265124 1254310 1269250 1256490 1255452 1251553 1251523 1246841 1279449 1262140 1270557 1272784 1261549 1260154 1292266 1262878 1258765 1254501 1269961 1255109 1280553 1255443 1256094 1263371 1261084 1257263 1269831 1254972 1259061 1255704 1257324 1252463 1278779 1252205 1258904 1257159 1265619 1254668 1291144 1257179 1258446 1249458 1249603 1249117 1273684 1249702 1259705 1254271 1251500 1252408 1275389 1253722 1263337 1254297 1257365 1254852 1275233 1252853 1267988 1256241 1265288 1256850 1280528 1254021 1260969 1252291 1254254 1250008 1274107 1240867 1256015 1255887 1258194 1248795 sum_num_searches_total=121021700 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=1400365 1395949 1396526 1391517 1396769 1394589 1425105 1402821 1405082 1410893 1400721 1393946 1429016 1400490 1397909 1395364 1402523 1398817 1424058 1386857 1399453 1399451 1405736 1393322 1410088 1395923 1395384 1390364 1390592 1385364 1421229 1401974 1411582 1413356 1402071 1400027 1435541 1403780 1399088 1393981 1410956 1394797 1422470 1394450 1395501 1403424 1400880 1396210 1411217 1393879 1399320 1394808 1396408 1390879 1421209 1391805 1399276 1397275 1406793 1394394 1434225 1396183 1397872 1388086 1388427 1388256 1414923 1388085 1399844 1393401 1391200 1390975 1417278 1393407 1402888 1393225 1397035 1394292 1417072 1392322 1408878 1395690 1406093 1396843 1423698 1393653 1401124 1391935 1394388 1388506 1415500 1379040 1395647 1395113 1398345 1387312 average_num_operations_total=1400670 stdev_num_operations_total=1137 sum_num_operations_total=134464335 min_num_operations_total=1379040 max_num_operations_total=1435541 first_time_thread_terminate_by_thread=10000139 10000082 10000081 10000086 10000080 10000081 10000083 10000085 10000087 10000080 10000081 10000082 10000088 10000082 10000083 10000085 10000080 10000088 10000088 10000082 10000083 10000082 10000085 10000084 10000086 10000089 10000082 10000081 10000086 10000082 10000082 10000086 10000087 10000082 10000080 10000080 10000088 10000080 10000083 10000085 10000080 10000088 10000088 10000080 10000083 10000085 10000087 10000091 10000089 10000080 10000091 10000082 10000080 10000082 10000080 10000080 10000084 10000083 10000080 10000085 10000085 10000081 10000080 10000080 10000080 10000082 10000080 10000085 10000084 10000080 10000084 10000081 10000089 10000080 10000086 10000080 10000081 10000085 10000084 10000080 10000083 10000080 10000088 10000085 10000080 10000081 10000080 10000080 10000082 10000080 10000080 10000085 10000080 10000086 10000083 10000084 min_time_thread_terminate_total=10000080 max_time_thread_terminate_total=10000139 first_time_thread_start_by_thread=11 5 5 5 5 5 6 5 5 5 5 5 5 5 5 6 5 6 5 5 5 6 5 5 4 5 5 5 5 5 6 5 5 5 5 5 5 5 5 6 5 6 5 5 5 6 5 5 5 6 6 5 6 5 6 5 5 6 6 5 6 6 6 5 6 6 5 6 6 6 5 5 5 6 6 5 6 5 6 5 5 6 6 5 6 6 6 5 6 6 5 6 6 6 5 5 min_time_thread_start_total=4 max_time_thread_start_total=11 sum_duration_all_ops_total= threads_final_keysum=999950067606 threads_final_size=1000276 final_keysum=999950067606 final_size=1000276 validate_result=success Validation OK. Structural validation OK. total_find=121021700 total_rq=0 total_inserts=6720242 total_deletes=6722393 total_updates=13442635 total_queries=121021700 total_ops=134464335 find_throughput=12102170 rq_throughput=0 update_throughput=1344263 query_throughput=12102170 total_throughput=13446433 total find : 121021700 total rq : 0 total inserts : 6720242 total deletes : 6722393 total updates : 13442635 total queries : 121021700 total ops : 134464335 find throughput : 12102170 rq throughput : 0 update throughput : 1344263 query throughput : 12102170 total throughput : 13446433 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=18.4997 PAPI_L3_TCM=2.38054 PAPI_TOT_CYC=17652.2 PAPI_TOT_INS=6803.85 garbage=59875427346390 total_execution_walltime=16.135s REALTIME_MAIN_END_PERF_FORMAT=6998305.816227854 noREC system shutdown: [time_cmd_output] time_elapsed_sec=16.27, faults_major=0, faults_minor=137946, mem_maxresident_kb=549256, user_cputime=1462.14, sys_cputime=32.88, percent_cpu=9186% timeout=false algorithm=brown_abtree_tm_auto.debra.norec thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1