Analyzing build trace from './analyze-before'... **** Time summary: Compilation (1264 times): Parsing (frontend): 1350.7 s Codegen & opts (backend): 570.8 s **** Files that took longest to parse (compiler frontend): 6142 ms: ../../arrow/cpp/build-for-analyzer/src/parquet/CMakeFiles/parquet-arrow-test.dir/arrow/arrow_reader_writer_test.cc.o 5956 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/dataset/CMakeFiles/arrow-dataset-scanner-test.dir/scanner_test.cc.o 5938 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/util/CMakeFiles/arrow-async-utility-test.dir/async_generator_test.cc.o 5797 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/vector_select_k.cc.o 5316 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/dataset/CMakeFiles/arrow-dataset-file-parquet-test.dir/file_parquet_test.cc.o 5274 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/compute/kernels/CMakeFiles/arrow-compute-scalar-math-test.dir/scalar_arithmetic_test.cc.o 5100 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/engine/CMakeFiles/arrow-substrait-substrait-test.dir/substrait/serde_test.cc.o 4888 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/acero/CMakeFiles/arrow_acero_objlib.dir/tpch_node.cc.o 4869 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/dataset/CMakeFiles/arrow-dataset-partition-test.dir/partition_test.cc.o 4798 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/dataset/CMakeFiles/arrow_dataset_objlib.dir/scanner.cc.o **** Files that took longest to codegen (compiler backend): 7881 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/CMakeFiles/arrow-array-test.dir/array/array_test.cc.o 7762 ms: ../../arrow/cpp/build-for-analyzer/src/parquet/CMakeFiles/parquet-arrow-test.dir/arrow/arrow_reader_writer_test.cc.o 7710 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/compute/kernels/CMakeFiles/arrow-compute-scalar-if-else-test.dir/scalar_if_else_test.cc.o 7355 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/compute/kernels/CMakeFiles/arrow-compute-scalar-math-test.dir/scalar_arithmetic_test.cc.o 7274 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/util/CMakeFiles/arrow-async-utility-test.dir/async_generator_test.cc.o 7212 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/vector_select_k.cc.o 6999 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/vector_sort.cc.o 6569 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/compute/kernels/CMakeFiles/arrow-compute-aggregate-test.dir/aggregate_test.cc.o 5291 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/hash_aggregate.cc.o 5120 ms: ../../arrow/cpp/build-for-analyzer/src/arrow/CMakeFiles/arrow_objlib.dir/compute/kernels/scalar_arithmetic.cc.o **** Templates that took longest to instantiate: 14443 ms: std::__function::__func<(lambda at /Users/zanmato/dev/arrow/cpp/src/... (1050 times, avg 13 ms) 11823 ms: std::function, ... (30 times, avg 314 ms) 8476 ms: std::__function::__alloc_func<(lambda at /Users/zanmato/dev/arrow/cp... (1050 times, avg 8 ms) 7745 ms: arrow::MakeBackgroundGenerator::operator ParamGenerator> (31 times, avg 205 ms) 6315 ms: std::__function::__func... (77 times, avg 73 ms) 5617 ms: std::__function::__value_func,... (54 times, avg 102 ms) 5526 ms: arrow::MakeTransferredGenerator> ()>... (31 times, avg 176 ms) 5481 ms: std::__function::__value_func... (75 times, avg 72 ms) 5465 ms: std::__function::__value_func> (306 times, avg 16 ms) 4779 ms: std::vector>::operator= (535 times, avg 8 ms) 4769 ms: testing::ValuesIn, true>> (306 times, avg 15 ms) **** Template sets that took longest to instantiate: 85129 ms: std::function<$>::function<$> (2107 times, avg 40 ms) 84765 ms: std::__function::__value_func<$>::__value_func<$> (2107 times, avg 40 ms) 72788 ms: std::__function::__func<$>::__func (2107 times, avg 34 ms) 55501 ms: std::__function::__alloc_func<$>::__alloc_func (6879 times, avg 8 ms) 40500 ms: std::forward_as_tuple<$> (9358 times, avg 4 ms) 36157 ms: std::vector<$> (17678 times, avg 2 ms) 35724 ms: std::vector<$>::vector (8523 times, avg 4 ms) 33803 ms: std::__function::__func<$>::__clone (4587 times, avg 7 ms) 33477 ms: std::tuple<$> (12237 times, avg 2 ms) 29348 ms: std::copy<$> (6470 times, avg 4 ms) 28133 ms: std::__copy<$> (6514 times, avg 4 ms) 28048 ms: std::unique_ptr<$> (21542 times, avg 1 ms) 27653 ms: std::__dispatch_copy_or_move<$> (8033 times, avg 3 ms) 24267 ms: std::vector<$>::__construct_at_end<$> (6438 times, avg 3 ms) 23897 ms: std::make_shared<$> (5276 times, avg 4 ms) 23798 ms: std::__unwrap_and_dispatch<$> (11719 times, avg 2 ms) 22011 ms: std::allocate_shared<$> (5259 times, avg 4 ms) 20793 ms: testing::internal::TypeParameterizedTest<$>::Register (918 times, avg 22 ms) 20188 ms: std::__compressed_pair<$>::__compressed_pair<$> (8334 times, avg 2 ms) 18467 ms: std::__uninitialized_allocator_copy<$> (5729 times, avg 3 ms) 18114 ms: testing::internal::MatcherBase<$>::MatcherBase<$> (2101 times, avg 8 ms) 18009 ms: testing::internal::MatcherBase<$>::Init<$> (2101 times, avg 8 ms) 17528 ms: testing::internal::TestFactoryImpl<$>::CreateTest (2562 times, avg 6 ms) 17354 ms: std::vector<$>::__swap_out_circular_buffer (3359 times, avg 5 ms) 16486 ms: std::allocator_traits<$> (6593 times, avg 2 ms) 15257 ms: std::__invoke_void_return_wrapper<$>::__call<$> (614 times, avg 24 ms) 14608 ms: std::vector<$>::push_back (1679 times, avg 8 ms) 13647 ms: std::__uninitialized_allocator_move_if_noexcept<$> (3362 times, avg 4 ms) 13292 ms: std::__compressed_pair<$> (4406 times, avg 3 ms) 13281 ms: std::copy_n<$> (2851 times, avg 4 ms) **** Functions that took longest to compile: 288 ms: arrow::acero::(anonymous namespace)::MakeIntegerBatch(std::__1::vect... (/Users/zanmato/dev/arrow/cpp/src/arrow/acero/test_util_internal.cc) 235 ms: arrow::compute::internal::RegisterScalarArithmetic(arrow::compute::F... (/Users/zanmato/dev/arrow/cpp/src/arrow/compute/kernels/scalar_arithmetic.cc) 207 ms: arrow::Iterator > arrow::in... (/Users/zanmato/dev/arrow/cpp/src/arrow/acero/exec_plan.cc) 201 ms: arrow::compute::TestCaseWhenFixedSize(std::__1::shared_ptr::AppendEmptyValues(long long) (/Users/zanmato/dev/arrow/cpp/src/parquet/encoding_test.cc) 171 ms: arrow::acero::MakeRandomTimeSeriesTable(arrow::acero::TableGeneratio... (/Users/zanmato/dev/arrow/cpp/src/arrow/acero/test_util_internal.cc) 165 ms: std::__1::__compressed_pair_elem::FnImpl::FinishInternal(std::__1::sh... (/Users/zanmato/dev/arrow/cpp/src/arrow/acero/test_util_internal.cc) 130 ms: arrow::(anonymous namespace)::TestRunEndEncodedArray_Validate_Test::... (/Users/zanmato/dev/arrow/cpp/src/arrow/array/array_run_end_test.cc) 122 ms: arrow::engine::FromProto(substrait::Rel const&, arrow::engine::Exten... (/Users/zanmato/dev/arrow/cpp/src/arrow/engine/substrait/relation_internal.cc) 121 ms: arrow::engine::FunctionMapping_ValidCases_Test::TestBody() (/Users/zanmato/dev/arrow/cpp/src/arrow/engine/substrait/function_test.cc) 116 ms: arrow::internal::DictionaryBuilderBase::TestExtendCurrent() (/Users/zanmato/dev/arrow/cpp/src/arrow/array/array_binary_test.cc) 108 ms: arrow::ipc::internal::IoRecordedRandomAccessFile::Read(long long, vo... (/Users/zanmato/dev/arrow/cpp/src/arrow/ipc/reader.cc) 105 ms: std::__1::basic_ostream >& arrow_v... (/Users/zanmato/dev/arrow/cpp/src/arrow/compute/kernels/scalar_cast_string.cc) 104 ms: arrow::compute::internal::StringSplitExec::Convert(std::__1::... (/Users/zanmato/dev/arrow/cpp/src/arrow/json/converter.cc) 103 ms: std::__1::enable_if::val... (/Users/zanmato/dev/arrow/cpp/src/arrow/compute/kernels/aggregate_basic.cc) 101 ms: std::__1::vector::Register(char const*, t... (4305 times, avg 1 ms) 2166 ms: testing::internal::SuiteApiResolver<$>::GetSetUpCaseOrSuite(char con... (1475 times, avg 1 ms) 1927 ms: std::__1::__split_buffer<$>::__split_buffer(unsigned long, unsigned ... (1509 times, avg 1 ms) 1917 ms: std::__1::vector<$>::__swap_out_circular_buffer(std::__1::__split_bu... (1497 times, avg 1 ms) 1814 ms: std::__1::__compressed_pair<$>::__compressed_pair[abi:v160006]<$>(st... (428 times, avg 4 ms) 1651 ms: std::__1::reverse_iterator<$> std::__1::__uninitialized_allocator_mo... (1092 times, avg 1 ms) 1553 ms: std::__1::vector<$>::vector[abi:v160006](std::initializer_list<$>) (1379 times, avg 1 ms) 1527 ms: arrow::Status arrow::internal::VisitBitBlocks<$>(unsigned char const... (504 times, avg 3 ms) 1432 ms: testing::internal::SuiteApiResolver<$>::GetTearDownCaseOrSuite(char ... (668 times, avg 2 ms) 1086 ms: std::__1::vector<$>::vector[abi:v160006](std::__1::vector<$>&&) (901 times, avg 1 ms) 1078 ms: std::__1::vector<$>::vector(std::__1::vector<$> const&) (759 times, avg 1 ms) 998 ms: std::__1::shared_ptr<$>::~shared_ptr[abi:v160006]() (250 times, avg 3 ms) 963 ms: std::__1::vector<$>::~vector[abi:v160006]() (233 times, avg 4 ms) 961 ms: arrow::compute::TestReplaceNumeric_ReplaceWithMask_Test<$>::TestBody() (16 times, avg 60 ms) 920 ms: std::__1::__compressed_pair_elem<$>::__get[abi:v160006]() (171 times, avg 5 ms) 881 ms: testing::internal::TestFactoryImpl<$>::TestFactoryImpl() (230 times, avg 3 ms) 869 ms: std::__1::basic_ostream<$>& std::__1::__put_character_sequence[abi:v... (498 times, avg 1 ms) 851 ms: std::__1::__exception_guard_exceptions<$>::__exception_guard_excepti... (171 times, avg 4 ms) 848 ms: arrow::Result<$>::Result(arrow::Status const&) (175 times, avg 4 ms) 831 ms: std::__1::vector<$>::__vallocate[abi:v160006](unsigned long) (256 times, avg 3 ms) 831 ms: std::__1::vector<$>::_ConstructTransaction::_ConstructTransaction[ab... (168 times, avg 4 ms) 824 ms: arrow::Status arrow::internal::DictionaryBuilderBase<$>::AppendScala... (504 times, avg 1 ms) 766 ms: std::__1::ostreambuf_iterator<$> std::__1::__pad_and_output[abi:v160... (499 times, avg 1 ms) 758 ms: std::__1::vector<$>::max_size() const (215 times, avg 3 ms) 702 ms: std::__1::__compressed_pair_elem<$>::__compressed_pair_elem[abi:v160... (248 times, avg 2 ms) 692 ms: std::__1::vector<$>::vector[abi:v160006]() (183 times, avg 3 ms) 690 ms: arrow::NumericBuilder<$>::FinishInternal(std::__1::shared_ptr<$>*) (216 times, avg 3 ms) 685 ms: std::__1::basic_ostream<$>& arrow_vendored::date::to_stream<$>(std::... (12 times, avg 57 ms) 681 ms: std::__1::__allocation_result<$> std::__1::__allocate_at_least[abi:v... (190 times, avg 3 ms) 640 ms: std::__1::allocator<$>::allocator[abi:v160006]() (134 times, avg 4 ms) **** Expensive headers: 165222 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/algorithm (included 617 times, avg 267 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 ... 107566 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/string_view (included 627 times, avg 171 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 ... 93277 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/functional (included 617 times, avg 151 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 ... 83558 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/string (included 614 times, avg 136 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 ... 81178 ms: /Users/zanmato/dev/arrow/cpp/build-for-analyzer/googletest_ep-prefix/include/gtest/gtest.h (included 351 times, avg 231 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 ... 73728 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/ostream (included 610 times, avg 120 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 function.h kernel.h buffer.h status.h string_builder.h ... 66097 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/chrono (included 618 times, avg 106 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 ... 64116 ms: /Library/Developer/CommandLineTools/SDKs/MacOSX14.0.sdk/usr/include/c++/v1/memory (included 618 times, avg 103 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 ... 55756 ms: /Users/zanmato/dev/arrow/cpp/src/arrow/compute/function.h (included 244 times, avg 228 ms), included via: 31x: test_util_internal.h exec_plan.h api_vector.h 29x: api_scalar.h 23x: api_vector.h 22x: exec_plan.h api_vector.h 18x: api.h api_aggregate.h 13x: test_util_internal.h exec_plan.h api_vector.h ... 52565 ms: /Users/zanmato/dev/arrow/cpp/src/arrow/compute/kernel.h (included 251 times, avg 209 ms), included via: 31x: test_util_internal.h exec_plan.h api_vector.h function.h 29x: api_scalar.h function.h 22x: exec_plan.h api_vector.h function.h 22x: api_vector.h function.h 18x: api.h api_aggregate.h function.h 13x: test_util_internal.h exec_plan.h api_vector.h function.h ... done in 2.2s.