__step=000145 __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_sigouin_abtree_kcas_validate_htm.debra -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=data000145.txt __path_data=data/data000145.txt __hostname=jax sizeof(kcasdesc_t)=1920 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x5608ac785480 addrof(kcasDescriptors[1])=0x5608ac785c00 addrof(rdcssDescriptors[0])=0x5608ac875480 addrof(rdcssDescriptors[1])=0x5608ac875540 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6996121.742772676 binary=./brown_sigouin_abtree_kcas_validate_htm.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_sigouin_abtree_kcas_validate_htm 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=40 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.005s prefilling_size=8920645 prefilling_elapsed_millis=1.001s prefilling_size=9126828 prefilling_elapsed_millis=1.101s prefilling_size=9292719 prefilling_elapsed_millis=1.201s prefilling_size=9424540 prefilling_elapsed_millis=1.301s prefilling_size=9532426 prefilling_elapsed_millis=1.401s prefilling_size=9619027 prefilling_elapsed_millis=1.502s prefilling_size=9688695 prefilling_elapsed_millis=1.602s prefilling_size=9744848 prefilling_elapsed_millis=1.702s prefilling_size=9789716 prefilling_elapsed_millis=1.802s prefilling_size=9826344 prefilling_elapsed_millis=1.902s finished prefilling to size 9826434 for expected size 10000000 keysum=98281675212884, performing 83973963 updates; total_prefilling_elapsed_ms=2004 ms) pref_size=9826434 pref_millis=2004 prefill_elapsed_ms=2027 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6996123.941716433 REALTIME_END_PERF_FORMAT=6996133.941858308 ############################################################################### ################################## 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 3772 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.051s tree_stats_numInternalsAtDepth=1 1 9 68 512 3772 28627 222571 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1751707 tree_stats_numNodesAtDepth=1 1 9 68 512 3772 28627 222571 1751707 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9986425 tree_stats_avgDegreeAtDepth=1 9 7.55556 7.52941 7.36719 7.58934 7.77486 7.87033 5.70097 tree_stats_height=9 tree_stats_numInternals=255561 tree_stats_numLeaves=1751707 tree_stats_numNodes=2007268 tree_stats_numKeys=9986425 tree_stats_avgDegreeInternal=7.85436 tree_stats_avgDegreeLeaves=5.70097 tree_stats_avgDegree=5.97513 tree_stats_avgKeyDepth=8 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:51992 2:57530 4:10221 8:398 16:3 [2^00, 2^01]: 51992 (2^01, 2^02]: 57530 (2^02, 2^03]: 10221 (2^03, 2^04]: 398 (2^04, 2^05]: 3 sum_limbo_reclamation_event_size_total=221513 count_limbo_reclamation_event_size_by_thread=2346 2492 2470 2322 2422 2414 2394 2792 2388 2446 2612 2406 2408 2570 2608 2724 2590 2642 2536 2566 2660 2568 2392 2420 2502 2348 2608 2586 2398 2506 2492 2616 2478 2494 2344 2668 2538 2654 2500 2454 2372 2476 2600 2562 2464 2536 2418 2342 count_limbo_reclamation_event_size_total=120144 sum_limbo_reclamation_event_count_total=2056608 first_thread_announced_epoch_by_thread=50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 50602 sum_commit_tx_reader_total= sum_commit_tx_reader_by_thread= sum_commit_tx_writer_total= sum_commit_tx_writer_by_thread= sum_abort_tx_total= sum_abort_tx_by_thread= max_ht_capacity_by_thread= linear_histogram_of_none_tx_rdset_size_full_data= linear_histogram_of_none_tx_wrset_size_full_data= 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=62 sum_abtree_erase_repeat_total=8 sum_abtree_search_repeat_total= sum_abtree_search_complete_total=47814006 sum_abtree_fix_weight_viol_attempt_total=185763 sum_abtree_fix_degree_viol_attempt_total=120693 sum_abtree_rebalancing_kcas_attempt_total=306453 sum_abtree_update_kcas_attempt_total=23753489 sum_abtree_internal_complex_search_while_iters_total=47814007 sum_abtree_internal_basic_search_while_iters_total=427480416 sum_kcas_desc_new_total= sum_num_inserts_by_thread=494022 495140 496866 497187 496049 489939 493625 494563 499578 499101 494497 491415 492700 496721 499346 498518 497404 492693 491008 493926 495809 499370 495506 490435 491854 494645 497956 497496 495714 490980 494506 493288 498269 498523 494739 491033 492238 496775 499233 498816 495712 494371 490576 492507 495017 497155 495732 488640 sum_num_inserts_total=23761193 sum_num_deletes_by_thread=492170 493538 497025 495841 495238 491634 491519 494079 498487 499856 494149 489510 495189 497097 499289 498837 495172 494603 490317 492643 495916 497742 493570 490162 492804 494309 496978 497175 494461 491445 493033 494621 499742 498388 493213 492237 490932 495175 500011 499920 495603 493743 489069 493791 495895 497556 493565 489004 sum_num_deletes_total=23746253 sum_num_searches_by_thread=8862393 8902817 8957289 8945829 8916189 8831612 8876535 8891751 8973385 8990825 8903180 8831730 8891133 8929920 8978991 8973670 8922487 8871363 8836272 8882642 8914872 8971648 8900094 8832137 8853149 8893597 8954244 8951946 8889613 8833114 8886688 8891319 8978908 8988877 8892420 8851918 8848370 8940931 8986262 8988411 8934254 8876914 8812014 8869166 8925213 8955106 8887324 8801305 sum_num_searches_total=427479827 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9848585 9891495 9951180 9938857 9907476 9813185 9861679 9880393 9971450 9989782 9891826 9812655 9879022 9923738 9977626 9971025 9915063 9858659 9817597 9869211 9906597 9968760 9889170 9812734 9837807 9882551 9949178 9946617 9879788 9815539 9874227 9879228 9976919 9985788 9880372 9835188 9831540 9932881 9985506 9987147 9925569 9865028 9791659 9855464 9916125 9949817 9876621 9778949 average_num_operations_total=9895568 stdev_num_operations_total=8509 sum_num_operations_total=474987273 min_num_operations_total=9778949 max_num_operations_total=9989782 first_time_thread_terminate_by_thread=10000140 10000141 10000140 10000141 10000140 10000140 10000140 10000141 10000140 10000141 10000140 10000140 10000140 10000141 10000141 10000141 10000140 10000140 10000141 10000140 10000141 10000140 10000140 10000141 10000140 10000141 10000140 10000141 10000140 10000140 10000140 10000141 10000140 10000141 10000140 10000140 10000140 10000141 10000141 10000141 10000140 10000140 10000141 10000140 10000141 10000140 10000140 10000141 min_time_thread_terminate_total=10000140 max_time_thread_terminate_total=10000141 first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 min_time_thread_start_total=4 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=99869994851882 threads_final_size=9986425 final_keysum=99869994851882 final_size=9986425 validate_result=success Validation OK. Structural validation OK. total_find=427479827 total_rq=0 total_inserts=23761193 total_deletes=23746253 total_updates=47507446 total_queries=427479827 total_ops=474987273 find_throughput=42747982 rq_throughput=0 update_throughput=4750744 query_throughput=42747982 total_throughput=47498727 total find : 427479827 total rq : 0 total inserts : 23761193 total deletes : 23746253 total updates : 47507446 total queries : 427479827 total ops : 474987273 find throughput : 42747982 rq throughput : 0 update throughput : 4750744 query throughput : 42747982 total throughput : 47498727 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=18.3925 PAPI_L3_TCM=6.36517 PAPI_TOT_CYC=2811.51 PAPI_TOT_INS=945.04 garbage=2080034410303163 total_execution_walltime=12.32s REALTIME_MAIN_END_PERF_FORMAT=6996134.68608863 [time_cmd_output] time_elapsed_sec=12.48, faults_major=0, faults_minor=185445, mem_maxresident_kb=742292, user_cputime=603.30, sys_cputime=1.84, percent_cpu=4848% timeout=false algorithm=brown_sigouin_abtree_kcas_validate_htm.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1