Analyzing build trace from './analyze-after'... **** Time summary: Compilation (1265 times): Parsing (frontend): 1298.6 s Codegen & opts (backend): 541.5 s **** Files that took longest to parse (compiler frontend): 6329 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/vector_select_k.cc.o 5636 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/util/CMakeFiles/arrow-async-utility-test.dir/async_generator_test.cc.o 5623 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/dataset/CMakeFiles/arrow-dataset-scanner-test.dir/scanner_test.cc.o 5488 ms: ../../arrow/cpp/build-for-analyzer-after/src/parquet/CMakeFiles/parquet-arrow-test.dir/arrow/arrow_reader_writer_test.cc.o 5220 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/dataset/CMakeFiles/arrow-dataset-file-parquet-test.dir/file_parquet_test.cc.o 5197 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/compute/kernels/CMakeFiles/arrow-compute-scalar-math-test.dir/scalar_arithmetic_test.cc.o 5076 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/engine/CMakeFiles/arrow-substrait-substrait-test.dir/substrait/serde_test.cc.o 4888 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/dataset/CMakeFiles/arrow-dataset-file-json-test.dir/file_json_test.cc.o 4836 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/acero/CMakeFiles/arrow_acero_objlib.dir/tpch_node.cc.o 4755 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/dataset/CMakeFiles/arrow-dataset-dataset-writer-test.dir/test_util_internal.cc.o **** Files that took longest to codegen (compiler backend): 8104 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/compute/kernels/CMakeFiles/arrow-compute-aggregate-test.dir/aggregate_test.cc.o 7726 ms: ../../arrow/cpp/build-for-analyzer-after/src/parquet/CMakeFiles/parquet-arrow-test.dir/arrow/arrow_reader_writer_test.cc.o 7363 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/compute/kernels/CMakeFiles/arrow-compute-scalar-if-else-test.dir/scalar_if_else_test.cc.o 7235 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/CMakeFiles/arrow-array-test.dir/array/array_test.cc.o 7169 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/vector_select_k.cc.o 6971 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/compute/kernels/CMakeFiles/arrow-compute-scalar-math-test.dir/scalar_arithmetic_test.cc.o 6460 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/util/CMakeFiles/arrow-async-utility-test.dir/async_generator_test.cc.o 6434 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/hash_aggregate.cc.o 6392 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/compute/kernels/CMakeFiles/arrow-compute-vector-test.dir/vector_replace_test.cc.o 5864 ms: ../../arrow/cpp/build-for-analyzer-after/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/scalar_arithmetic.cc.o **** Templates that took longest to instantiate: 13671 ms: std::__function::__func<(lambda at /Users/zanmato/dev/arrow/cpp/src/... (1050 times, avg 13 ms) 11066 ms: std::function, ... (30 times, avg 317 ms) 7968 ms: std::__function::__alloc_func<(lambda at /Users/zanmato/dev/arrow/cp... (1050 times, avg 7 ms) 7317 ms: arrow::MakeBackgroundGenerator::operator ParamGenerator> (31 times, avg 216 ms) 6001 ms: std::__function::__func> ()>... (31 times, avg 188 ms) 5855 ms: std::__function::__value_func... (77 times, avg 67 ms) 5210 ms: std::__function::__value_func> (306 times, avg 16 ms) 4977 ms: std::function... (75 times, avg 66 ms) 4972 ms: std::__function::__value_func::function<$> (2107 times, avg 38 ms) 80084 ms: std::__function::__value_func<$>::__value_func<$> (2107 times, avg 38 ms) 69046 ms: std::__function::__func<$>::__func (2107 times, avg 32 ms) 52564 ms: std::__function::__alloc_func<$>::__alloc_func (6879 times, avg 7 ms) 38584 ms: std::forward_as_tuple<$> (9357 times, avg 4 ms) 33734 ms: std::vector<$>::vector (8315 times, avg 4 ms) 33449 ms: std::vector<$> (17332 times, avg 1 ms) 32175 ms: std::tuple<$> (12237 times, avg 2 ms) 30861 ms: std::__function::__func<$>::__clone (4587 times, avg 6 ms) 28542 ms: std::copy<$> (6470 times, avg 4 ms) 27163 ms: std::__copy<$> (6514 times, avg 4 ms) 26722 ms: std::__dispatch_copy_or_move<$> (8033 times, avg 3 ms) 25670 ms: std::unique_ptr<$> (21440 times, avg 1 ms) 24033 ms: std::make_shared<$> (5298 times, avg 4 ms) 23764 ms: std::vector<$>::__construct_at_end<$> (6413 times, avg 3 ms) 22776 ms: std::__unwrap_and_dispatch<$> (11556 times, avg 1 ms) 22006 ms: std::allocate_shared<$> (5273 times, avg 4 ms) 19832 ms: testing::internal::TypeParameterizedTest<$>::Register (915 times, avg 21 ms) 18737 ms: std::__compressed_pair<$>::__compressed_pair<$> (8120 times, avg 2 ms) 18051 ms: std::__uninitialized_allocator_copy<$> (5707 times, avg 3 ms) 17878 ms: testing::internal::MatcherBase<$>::MatcherBase<$> (2101 times, avg 8 ms) 17803 ms: testing::internal::MatcherBase<$>::Init<$> (2101 times, avg 8 ms) 16911 ms: testing::internal::TestFactoryImpl<$>::CreateTest (2556 times, avg 6 ms) 16674 ms: std::vector<$>::__swap_out_circular_buffer (3348 times, avg 4 ms) 14916 ms: std::__invoke_void_return_wrapper<$>::__call<$> (608 times, avg 24 ms) 14248 ms: std::vector<$>::push_back (1678 times, avg 8 ms) 14232 ms: std::allocator_traits<$> (5814 times, avg 2 ms) 13243 ms: std::__uninitialized_allocator_move_if_noexcept<$> (3350 times, avg 3 ms) 12933 ms: testing::internal::MatcherBase<$>::GetVTable<$> (2101 times, avg 6 ms) 12725 ms: std::copy_n<$> (2849 times, avg 4 ms) **** Functions that took longest to compile: 265 ms: arrow::compute::internal::RegisterScalarArithmetic(arrow::compute::F... (/Users/zanmato/dev/arrow/cpp/src/arrow/compute/kernels/scalar_arithmetic.cc) 208 ms: arrow::acero::(anonymous namespace)::MakeIntegerBatch(std::__1::vect... (/Users/zanmato/dev/arrow/cpp/src/arrow/acero/test_util_internal.cc) 172 ms: arrow::internal::BinaryMemoTable::Lookup(unsig... (/Users/zanmato/dev/arrow/cpp/src/arrow/array/array_dict.cc) 162 ms: arrow::Status arrow::internal::BinaryMemoTable... (/Users/zanmato/dev/arrow/cpp/src/arrow/array/array_dict.cc) 145 ms: arrow::compute::TestReplaceNumeric_ReplaceWithMask_Test, std::__1::... (/Users/zanmato/dev/arrow/cpp/src/arrow/util/io_util_test.cc) 119 ms: std::__1::basic_ostream >& arrow_v... (/Users/zanmato/dev/arrow/cpp/src/arrow/array/diff.cc) 119 ms: arrow::compute::TestReplaceNumeric_ReplaceWithMask_Test >... (/Users/zanmato/dev/arrow/cpp/src/arrow/ipc/tensor_test.cc) 107 ms: arrow::Result > arrow::MakeReadahead... (/Users/zanmato/dev/arrow/cpp/src/arrow/util/iterator_test.cc) 105 ms: std::__1::_AllocatorDestroyRangeReverse::Cre... (/Users/zanmato/dev/arrow/cpp/src/arrow/util/bit_util_test.cc) **** Function sets that took longest to compile / optimize: 7107 ms: testing::internal::TypeParameterizedTest<$>::Register(char const*, t... (4308 times, avg 1 ms) 1672 ms: std::__1::vector<$>::vector[abi:v160006](std::initializer_list<$>) (1377 times, avg 1 ms) 1656 ms: testing::internal::SuiteApiResolver<$>::GetSetUpCaseOrSuite(char con... (1347 times, avg 1 ms) 1624 ms: std::__1::vector<$>::__swap_out_circular_buffer(std::__1::__split_bu... (1495 times, avg 1 ms) 1511 ms: std::__1::reverse_iterator<$> std::__1::__uninitialized_allocator_mo... (1095 times, avg 1 ms) 1491 ms: std::__1::__split_buffer<$>::__split_buffer(unsigned long, unsigned ... (1499 times, avg 0 ms) 1425 ms: arrow::compute::TestReplaceNumeric_ReplaceWithMask_Test<$>::TestBody() (16 times, avg 89 ms) 1418 ms: std::__1::__compressed_pair<$>::__compressed_pair[abi:v160006]<$>(st... (360 times, avg 3 ms) 1400 ms: testing::internal::SuiteApiResolver<$>::GetTearDownCaseOrSuite(char ... (548 times, avg 2 ms) 1231 ms: std::__1::vector<$>::vector[abi:v160006](std::__1::vector<$>&&) (883 times, avg 1 ms) 1227 ms: arrow::Status arrow::internal::VisitBitBlocks<$>(unsigned char const... (504 times, avg 2 ms) 1071 ms: std::__1::vector<$>::vector(std::__1::vector<$> const&) (761 times, avg 1 ms) 916 ms: std::__1::ostreambuf_iterator<$> std::__1::__pad_and_output[abi:v160... (497 times, avg 1 ms) 753 ms: std::__1::vector<$>::_ConstructTransaction::~_ConstructTransaction[a... (170 times, avg 4 ms) 743 ms: std::__1::shared_ptr<$>::~shared_ptr[abi:v160006]() (211 times, avg 3 ms) 725 ms: std::__1::__exception_guard_exceptions<$>::__exception_guard_excepti... (142 times, avg 5 ms) 716 ms: std::__1::basic_ostream<$>& std::__1::__put_character_sequence[abi:v... (497 times, avg 1 ms) 708 ms: std::__1::vector<$>::max_size() const (185 times, avg 3 ms) 701 ms: std::__1::__function::__func<$>::__clone() const (344 times, avg 2 ms) 697 ms: arrow::Status arrow::internal::DictionaryBuilderBase<$>::AppendScala... (504 times, avg 1 ms) 695 ms: testing::internal::TestFactoryImpl<$>::TestFactoryImpl() (194 times, avg 3 ms) 684 ms: std::__1::vector<$>::__vallocate[abi:v160006](unsigned long) (225 times, avg 3 ms) 672 ms: testing::internal::TestFactoryImpl<$>::~TestFactoryImpl() (140 times, avg 4 ms) 670 ms: std::__1::vector<$>::__recommend[abi:v160006](unsigned long) const (155 times, avg 4 ms) 666 ms: std::__1::basic_ostream<$>& arrow_vendored::date::to_stream<$>(std::... (12 times, avg 55 ms) 644 ms: std::__1::pair<$> std::__1::__unwrap_and_dispatch[abi:v160006]<$>(st... (479 times, avg 1 ms) 618 ms: std::__1::__allocation_result<$> std::__1::__allocate_at_least[abi:v... (163 times, avg 3 ms) 613 ms: arrow::NumericBuilder<$>::FinishInternal(std::__1::shared_ptr<$>*) (216 times, avg 2 ms) 597 ms: std::__1::vector<$>::~vector[abi:v160006]() (186 times, avg 3 ms) 553 ms: std::__1::allocator<$>::allocator[abi:v160006]<$>(std::__1::allocato... (90 times, avg 6 ms) **** Expensive headers: 165018 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/algorithm (included 620 times, avg 266 ms), included via: 66x: 43x: benchmark.h 30x: test_util_internal.h gtest_util.h 29x: gtest.h ostream bitset string string_view 22x: string string_view 15x: sstream istream ostream bitset string string_view ... 104198 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/string_view (included 623 times, avg 167 ms), included via: 66x: algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h 43x: benchmark.h algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h 30x: test_util_internal.h gtest_util.h algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h 29x: gtest.h ostream bitset string 22x: string 15x: sstream istream ostream bitset string ... 91147 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/functional (included 617 times, avg 147 ms), included via: 66x: algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h string_view 43x: benchmark.h algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h string_view 30x: test_util_internal.h gtest_util.h algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h string_view 29x: gtest.h ostream bitset string string_view 22x: string string_view 15x: sstream istream ostream bitset string string_view ... 81440 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/string (included 614 times, avg 132 ms), included via: 48x: 48x: benchmark.h 40x: gtest.h ostream bitset 30x: test_util_internal.h gtest_util.h 25x: mutex __mutex_base system_error 21x: sstream istream ostream bitset ... 78542 ms: /Users/zanmato/dev/arrow/cpp/build-for-analyzer-after/googletest_ep-prefix/include/gtest/gtest.h (included 354 times, avg 221 ms), included via: 106x: 34x: test_util_internal.h gtest_util.h 34x: gmock.h gmock-actions.h gmock-internal-utils.h 32x: gtest_util.h 25x: gmock-matchers.h gmock-internal-utils.h 23x: test_util.h gmock.h gmock-actions.h gmock-internal-utils.h ... 71090 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/ostream (included 610 times, avg 116 ms), included via: 78x: gtest.h 35x: sstream istream 31x: test_util_internal.h gtest_util.h gtest.h 22x: gmock.h gmock-actions.h gmock-internal-utils.h 16x: gmock-matchers.h 15x: api_scalar.h options.h result.h status.h string_builder.h ... 64557 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/chrono (included 617 times, avg 104 ms), included via: 66x: algorithm 43x: benchmark.h algorithm 30x: test_util_internal.h gtest_util.h algorithm 29x: gtest.h ostream bitset string string_view algorithm 22x: string string_view algorithm 15x: sstream istream ostream bitset string string_view algorithm ... 62886 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/memory (included 618 times, avg 101 ms), included via: 66x: algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h string_view functional boyer_moore_searcher.h unordered_map __node_handle optional 43x: benchmark.h algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h string_view functional boyer_moore_searcher.h unordered_map __node_handle optional 39x: 30x: test_util_internal.h gtest_util.h algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h string_view functional boyer_moore_searcher.h unordered_map __node_handle optional 29x: gtest.h 12x: test_util_internal.h algorithm chrono convert_to_tm.h statically_widen.h concepts.h format_parse_context.h string_view functional boyer_moore_searcher.h unordered_map __node_handle optional ... 49961 ms: /Users/zanmato/dev/arrow/cpp/src/arrow/acero/test_util_internal.h (included 54 times, avg 925 ms), included via: 40x: 10x: test_nodes.h 4x: benchmark_util.h 49690 ms: /Users/zanmato/dev/arrow/cpp/src/arrow/testing/gtest_util.h (included 275 times, avg 180 ms), included via: 109x: 40x: test_util_internal.h 39x: test_util.h builder.h 21x: test_util_internal.h future_util.h 20x: builder.h 11x: future_util.h ... done in 2.1s.