2025-02-27 00:59:07 Using queries from '/repo/tests/queries' directory 2025-02-27 00:59:07 Connecting to ClickHouse server... OK 2025-02-27 00:59:07 Connected to server 24.12.2.20033.altinitytest @ 2372cfbfa08a929eb229680244e0ad141e894390 HEAD 2025-02-27 00:59:07 Won't run stateless tests because they were manually disabled. 2025-02-27 00:59:07 Found 133 parallel tests and 6 sequential tests 2025-02-27 00:59:08 Running about 66 stateful tests (Process-4). 2025-02-27 00:59:08 00175_obfuscator_schema_inference: [ OK ] 1.23 sec. 2025-02-27 00:59:08 00015_totals_and_no_aggregate_functions: [ OK ] 0.24 sec. 2025-02-27 00:59:08 00001_count_hits: [ OK ] 0.13 sec. 2025-02-27 00:59:09 00088_global_in_one_shard_and_rows_before_limit: [ OK ] 0.63 sec. 2025-02-27 00:59:09 Running about 66 stateful tests (Process-3). 2025-02-27 00:59:09 00054_merge_tree_partitions: [ OK ] 2.34 sec. 2025-02-27 00:59:10 00002_count_visits: [ OK ] 0.53 sec. 2025-02-27 00:59:10 00096_obfuscator_save_load: [ OK ] 0.87 sec. 2025-02-27 00:59:10 00021_3_select_with_in: [ OK ] 0.18 sec. 2025-02-27 00:59:10 00047_bar: [ OK ] 0.32 sec. 2025-02-27 00:59:11 00075_left_array_join: [ OK ] 0.47 sec. 2025-02-27 00:59:13 00091_prewhere_two_conditions: [ OK ] 2.14 sec. 2025-02-27 00:59:13 00090_thread_pool_deadlock: [ OK ] 3.84 sec. 2025-02-27 00:59:14 00093_prewhere_array_join: [ OK ] 0.62 sec. 2025-02-27 00:59:16 00053_replicate_segfault: [ OK ] 2.23 sec. 2025-02-27 00:59:16 00172_early_constant_folding: [ OK ] 0.17 sec. 2025-02-27 00:59:16 00061_storage_buffer: [ OK ] 0.57 sec. 2025-02-27 00:59:17 00051_min_max_array: [ OK ] 0.62 sec. 2025-02-27 00:59:17 00183_prewhere_conditions_order: [ OK ] 0.17 sec. 2025-02-27 00:59:18 00156_max_execution_speed_sample_merge: [ OK ] 4.28 sec. 2025-02-27 00:59:18 00034_aggregate_key_fixed_string: [ OK ] 0.32 sec. 2025-02-27 00:59:19 00177_memory_bound_merging: [ OK ] 1.48 sec. 2025-02-27 00:59:19 00081_group_by_without_key_and_totals: [ OK ] 0.27 sec. 2025-02-27 00:59:19 00016_any_if_distributed_cond_always_false: [ OK ] 0.28 sec. 2025-02-27 00:59:21 00095_hyperscan_profiler: [ OK ] 2.18 sec. 2025-02-27 00:59:24 00146_aggregate_function_uniq: [ OK ] 3.03 sec. 2025-02-27 00:59:24 00151_order_by_read_in_order: [ OK ] 0.22 sec. 2025-02-27 00:59:24 00031_array_enumerate_uniq: [ OK ] 0.17 sec. 2025-02-27 00:59:24 00084_external_aggregation: [ OK ] 4.68 sec. 2025-02-27 00:59:24 00176_bson_parallel_parsing: [ SKIPPED ] 0.00 sec. 2025-02-27 00:59:24 Reason: disabled 2025-02-27 00:59:26 00072_compare_date_and_string_index: [ OK ] 2.13 sec. 2025-02-27 00:59:26 00167_read_bytes_from_fs: [ OK ] 2.23 sec. 2025-02-27 00:59:27 00144_functions_of_aggregation_states: [ OK ] 0.27 sec. 2025-02-27 00:59:27 00175_counting_resources_in_subqueries: [ OK ] 0.27 sec. 2025-02-27 00:59:27 00066_sorting_distributed_many_replicas: [ OK ] 0.12 sec. 2025-02-27 00:59:28 00094_order_by_array_join_limit: [ OK ] 1.17 sec. 2025-02-27 00:59:29 00059_merge_sorting_empty_array_joined: [ OK ] 1.93 sec. 2025-02-27 00:59:29 00083_array_filter: [ OK ] 0.27 sec. 2025-02-27 00:59:29 00085_monotonic_evaluation_segfault: [ OK ] 0.52 sec. 2025-02-27 00:59:30 00043_any_left_join: [ OK ] 0.37 sec. 2025-02-27 00:59:31 00173_group_by_use_nulls: [ OK ] 1.12 sec. 2025-02-27 00:59:33 00082_quantiles: [ OK ] 2.23 sec. 2025-02-27 00:59:33 00098_primary_key_memory_allocated: [ OK ] 0.17 sec. 2025-02-27 00:59:34 00042_any_left_join: [ OK ] 0.27 sec. 2025-02-27 00:59:34 00037_uniq_state_merge1: [ OK ] 0.72 sec. 2025-02-27 00:59:41 00159_parallel_formatting_json_and_friends_1: [ OK ] 13.11 sec. 2025-02-27 00:59:41 00004_top_counters: [ OK ] 0.27 sec. 2025-02-27 00:59:43 00038_uniq_state_merge2: [ OK ] 1.57 sec. 2025-02-27 00:59:45 00024_random_counters: [ OK ] 10.42 sec. 2025-02-27 00:59:45 00065_loyalty_with_storage_join: [ OK ] 0.52 sec. 2025-02-27 00:59:46 00036_aggregate_hashed: [ OK ] 0.42 sec. 2025-02-27 00:59:46 00009_uniq_distributed: [ OK ] 0.27 sec. 2025-02-27 00:59:47 00041_aggregating_materialized_view: [ OK ] 0.77 sec. 2025-02-27 00:59:47 00069_duplicate_aggregation_keys: [ OK ] 0.17 sec. 2025-02-27 00:59:47 00044_any_left_join_string: [ OK ] 4.64 sec. 2025-02-27 00:59:47 00050_min_max: [ OK ] 0.27 sec. 2025-02-27 00:59:48 00035_aggregate_keys128: [ OK ] 0.23 sec. 2025-02-27 00:59:48 00055_index_and_not: [ OK ] 0.17 sec. 2025-02-27 00:59:48 00165_jit_aggregate_functions: [ OK ] 0.97 sec. 2025-02-27 00:59:49 00164_quantileBfloat16: [ OK ] 0.97 sec. 2025-02-27 00:59:49 00171_grouping_aggregated_transform_bug: [ OK ] 0.68 sec. 2025-02-27 00:59:50 00092_obfuscator: [ OK ] 0.97 sec. 2025-02-27 00:59:52 00063_loyalty_joins: [ OK ] 1.63 sec. 2025-02-27 00:59:52 00014_filtering_arrays: [ OK ] 0.17 sec. 2025-02-27 00:59:52 00020_distinct_order_by_distributed: [ OK ] 3.58 sec. 2025-02-27 00:59:56 00162_mmap_compression_none: [ OK ] 3.18 sec. 2025-02-27 00:59:56 00068_subquery_in_prewhere: [ OK ] 0.17 sec. 2025-02-27 00:59:56 00048_min_max: [ OK ] 0.17 sec. 2025-02-27 00:59:56 00067_union_all: [ OK ] 0.27 sec. 2025-02-27 00:59:57 00143_transform_non_const_default: [ OK ] 0.22 sec. 2025-02-27 00:59:57 00017_aggregation_uninitialized_memory: [ OK ] 0.17 sec. 2025-02-27 00:59:57 00007_uniq: [ OK ] 0.17 sec. 2025-02-27 00:59:58 00139_like: [ OK ] 1.22 sec. 2025-02-27 00:59:59 00033_aggregate_key_string: [ OK ] 0.37 sec. 2025-02-27 01:00:00 00159_parallel_formatting_http: [ OK ] 8.15 sec. 2025-02-27 01:00:00 00150_quantiles_timing_precision: [ OK ] 0.37 sec. 2025-02-27 01:00:01 00097_constexpr_in_index: [ OK ] 0.17 sec. 2025-02-27 01:00:01 00153_aggregate_arena_race: [ OK ] 0.24 sec. 2025-02-27 01:00:01 00184_parallel_hash_returns_same_res_as_hash: [ OK ] 2.23 sec. 2025-02-27 01:00:01 00176_distinct_limit_by_limit_bug_43377: [ OK ] 0.17 sec. 2025-02-27 01:00:02 00076_system_columns_bytes: [ OK ] 0.42 sec. 2025-02-27 01:00:02 00012_sorting_distributed: [ OK ] 0.22 sec. 2025-02-27 01:00:03 00079_array_join_not_used_joined_column: [ OK ] 0.87 sec. 2025-02-27 01:00:03 00005_filtering: [ OK ] 0.17 sec. 2025-02-27 01:00:03 00077_log_tinylog_stripelog: [ OK ] 2.53 sec. 2025-02-27 01:00:04 00086_array_reduce: [ OK ] 0.72 sec. 2025-02-27 01:00:06 00178_quantile_ddsketch: [ OK ] 2.08 sec. 2025-02-27 01:00:07 00080_array_join_and_union: [ OK ] 3.73 sec. 2025-02-27 01:00:07 00182_simple_squashing_transform_bug: [ OK ] 0.82 sec. 2025-02-27 01:00:09 00161_parallel_parsing_with_names: [ OK ] 2.63 sec. 2025-02-27 01:00:10 00147_global_in_aggregate_function: [ OK ] 1.17 sec. 2025-02-27 01:00:10 00078_group_by_arrays: [ OK ] 2.98 sec. 2025-02-27 01:00:11 00087_where_0: [ OK ] 0.17 sec. 2025-02-27 01:00:11 00030_array_enumerate_uniq: [ OK ] 0.67 sec. 2025-02-27 01:00:12 00160_decode_xml_component: [ OK ] 0.82 sec. 2025-02-27 01:00:12 00032_aggregate_key64: [ OK ] 0.22 sec. 2025-02-27 01:00:13 00008_uniq: [ OK ] 0.32 sec. 2025-02-27 01:00:13 00062_loyalty: [ OK ] 0.17 sec. 2025-02-27 01:00:13 00142_system_columns: [ OK ] 0.17 sec. 2025-02-27 01:00:13 00011_sorting: [ OK ] 0.17 sec. 2025-02-27 01:00:17 00181_cross_join_compression: [ OK ] 3.38 sec. 2025-02-27 01:00:19 00046_uniq_upto_distributed: [ OK ] 1.93 sec. 2025-02-27 01:00:19 00045_uniq_upto: [ OK ] 0.22 sec. 2025-02-27 01:00:19 00166_explain_estimate: [ OK ] 0.17 sec. 2025-02-27 01:00:20 00049_max_string_if: [ OK ] 0.42 sec. 2025-02-27 01:00:21 00172_hits_joins: [ OK ] 10.56 sec. 2025-02-27 01:00:21 00056_view: [ OK ] 0.32 sec. 2025-02-27 01:00:22 00154_avro: [ OK ] 0.52 sec. 2025-02-27 01:00:22 00071_merge_tree_optimize_aio: [ OK ] 2.33 sec. 2025-02-27 01:00:22 00145_aggregate_functions_statistics: [ OK ] 0.67 sec. 2025-02-27 01:00:22 00022_merge_prewhere: [ OK ] 0.22 sec. 2025-02-27 01:00:23 00013_sorting_of_nested: [ OK ] 1.37 sec. 2025-02-27 01:00:25 00089_position_functions_with_non_constant_arg: [ OK ] 2.07 sec. 2025-02-27 01:00:25 00060_move_to_prewhere_and_sets: [ OK ] 0.22 sec. 2025-02-27 01:00:28 00163_column_oriented_formats: [ OK ] 3.04 sec. 2025-02-27 01:00:30 00159_parallel_formatting_tsv_and_friends: [ OK ] 6.35 sec. 2025-02-27 01:00:34 00169_contingency: [ OK ] 6.10 sec. 2025-02-27 01:00:35 00174_distinct_in_order: [ OK ] 0.92 sec. 2025-02-27 01:00:35 00073_uniq_array: [ OK ] 0.17 sec. 2025-02-27 01:00:36 00074_full_join: [ OK ] 0.62 sec. 2025-02-27 01:00:36 00010_quantiles_segfault: [ OK ] 0.27 sec. 2025-02-27 01:00:37 00149_quantiles_timing_distributed: [ OK ] 0.83 sec. 2025-02-27 01:00:38 00148_monotonic_functions_and_index: [ OK ] 0.72 sec. 2025-02-27 01:00:41 00159_parallel_formatting_json_and_friends_2: [ OK ] 10.82 sec. 2025-02-27 01:00:41 00040_aggregating_materialized_view: [ OK ] 0.77 sec. 2025-02-27 01:00:41 00039_primary_key: [ OK ] 0.17 sec. 2025-02-27 01:00:44 00023_totals_limit: [ OK ] 2.23 sec. 2025-02-27 01:00:44 00021_2_select_with_in: [ OK ] 0.17 sec. 2025-02-27 01:00:48 00159_parallel_formatting_csv_and_friends: [ OK ] 10.11 sec. 2025-02-27 01:00:48 00006_agregates: [ OK ] 0.17 sec. 2025-02-27 01:00:48 00141_transform: [ OK ] 0.22 sec. 2025-02-27 01:00:49 00052_group_by_in: [ OK ] 0.62 sec. 2025-02-27 01:00:49 00021_1_select_with_in: [ OK ] 0.17 sec. 2025-02-27 01:00:49 2025-02-27 01:00:49 53 tests passed. 1 tests skipped. 102.35 s elapsed (Process-3). 2025-02-27 01:00:49 00167_parallel_parsing_with_names_and_types: [ OK ] 5.49 sec. 2025-02-27 01:00:49 2025-02-27 01:00:49 79 tests passed. 0 tests skipped. 102.77 s elapsed (Process-4). 2025-02-27 01:00:49 Running 6 stateful tests (MainProcess). 2025-02-27 01:00:58 00180_no_seek_avoiding_when_reading_from_cache: [ OK ] 8.97 sec. 2025-02-27 01:01:04 00170_s3_cache: [ OK ] 5.70 sec. 2025-02-27 01:01:49 00158_cache_dictionary_has: [ OK ] 45.12 sec. 2025-02-27 01:07:10 00157_cache_dictionary: [ FAIL ] 320.48 sec. 2025-02-27 01:07:10 Reason: return code: 160 2025-02-27 01:07:10 [1d039ab2f0b7] 2025.02.26 18:07:10.199766 [ 2301 ] {dce8ad2e-c497-4c14-95f5-e4db717a5f96} executeQuery: Code: 160. DB::Exception: Estimated query execution time (953.64214 seconds) is too long. Maximum: 600. Estimated rows to process: 1000000 (335872 read in 320.30169 seconds).: While executing MergeTreeSelect(pool: ReadPool, algorithm: Thread). (TOO_SLOW) (version 24.12.2.20033.altinitytest (altinity build)) (from [::1]:59270) (comment: 00157_cache_dictionary.sql) (in query: INSERT INTO test.hits_1m SELECT * FROM test.hits LIMIT 1000000 SETTINGS min_insert_block_size_rows = 0, min_insert_block_size_bytes = 0, max_block_size = 8192, max_insert_threads = 1, max_threads = 1, max_parallel_replicas=1;), Stack trace (when copying this message, always include the lines below): 2025-02-27 01:07:10 2025-02-27 01:07:10 0. ./contrib/llvm-project/libcxx/include/vector:676: DB::Exception::Exception(DB::Exception::MessageMasked&&, int, bool) @ 0x000000000c169544 2025-02-27 01:07:10 1. DB::Exception::Exception(PreformattedMessage&&, int) @ 0x0000000007d87f5c 2025-02-27 01:07:10 2. ./src/Common/Exception.h:123: DB::Exception::Exception(int, FormatStringHelperImpl::type, std::type_identity::type, std::type_identity::type, std::type_identity::type, std::type_identity::type>, double&, int&&, unsigned long&, unsigned long&, double&) @ 0x000000000f926774 2025-02-27 01:07:10 3. ./build_docker/./src/QueryPipeline/ExecutionSpeedLimits.cpp:87: DB::ExecutionSpeedLimits::throttle(unsigned long, unsigned long, unsigned long, unsigned long, DB::OverflowMode) const @ 0x000000000f925fb8 2025-02-27 01:07:10 4. ./build_docker/./src/QueryPipeline/ReadProgressCallback.cpp:131: DB::ReadProgressCallback::onProgress(unsigned long, unsigned long, std::list> const&) @ 0x000000000f92561c 2025-02-27 01:07:10 5. ./build_docker/./src/Processors/Executors/ExecutionThreadContext.cpp:64: DB::ExecutionThreadContext::executeTask() @ 0x00000000118ff22c 2025-02-27 01:07:10 6. ./build_docker/./src/Processors/Executors/PipelineExecutor.cpp:290: DB::PipelineExecutor::executeStepImpl(unsigned long, std::atomic*) @ 0x00000000118f46f4 2025-02-27 01:07:10 7. ./build_docker/./src/Processors/Executors/PipelineExecutor.cpp:256: DB::PipelineExecutor::execute(unsigned long, bool) @ 0x00000000118f3c6c 2025-02-27 01:07:10 8. ./build_docker/./src/Processors/Executors/CompletedPipelineExecutor.cpp:49: void std::__function::__policy_invoker::__call_impl::ThreadFromGlobalPoolImpl(DB::CompletedPipelineExecutor::execute()::$_0&&)::'lambda'(), void ()>>(std::__function::__policy_storage const*) @ 0x00000000118f2d30 2025-02-27 01:07:10 9. ./contrib/llvm-project/libcxx/include/__functional/function.h:848: ? @ 0x000000000c231528 2025-02-27 01:07:10 10. ./contrib/llvm-project/libcxx/include/__functional/invoke.h:359: ? @ 0x000000000c237e9c 2025-02-27 01:07:10 11. ? @ 0x000000000007d5c8 2025-02-27 01:07:10 12. ? @ 0x00000000000e5edc 2025-02-27 01:07:10 2025-02-27 01:07:10 Received exception from server (version 24.12.2): 2025-02-27 01:07:10 Code: 160. DB::Exception: Received from localhost:9000. DB::Exception: Estimated query execution time (953.64214 seconds) is too long. Maximum: 600. Estimated rows to process: 1000000 (335872 read in 320.30169 seconds).: While executing MergeTreeSelect(pool: ReadPool, algorithm: Thread). (TOO_SLOW) 2025-02-27 01:07:10 (query: INSERT INTO test.hits_1m SELECT * FROM test.hits LIMIT 1000000 2025-02-27 01:07:10 SETTINGS min_insert_block_size_rows = 0, min_insert_block_size_bytes = 0, max_block_size = 8192, max_insert_threads = 1, max_threads = 1, max_parallel_replicas=1;) 2025-02-27 01:07:10 , result: 2025-02-27 01:07:10 2025-02-27 01:07:10 2025-02-27 01:07:10 2025-02-27 01:07:10 stdout: 2025-02-27 01:07:10 2025-02-27 01:07:10 2025-02-27 01:07:10 Settings used in the test: --max_insert_threads 3 --group_by_two_level_threshold 803737 --group_by_two_level_threshold_bytes 48806608 --distributed_aggregation_memory_efficient 1 --fsync_metadata 0 --output_format_parallel_formatting 0 --input_format_parallel_parsing 1 --min_chunk_bytes_for_parallel_parsing 8019115 --max_read_buffer_size 990952 --prefer_localhost_replica 0 --max_block_size 99340 --max_joined_block_size_rows 28250 --max_threads 1 --optimize_append_index 0 --optimize_if_chain_to_multiif 1 --optimize_if_transform_strings_to_enum 1 --optimize_read_in_order 1 --optimize_or_like_chain 0 --optimize_substitute_columns 0 --enable_multiple_prewhere_read_steps 0 --read_in_order_two_level_merge_threshold 95 --optimize_aggregation_in_order 0 --aggregation_in_order_max_block_bytes 7846168 --use_uncompressed_cache 0 --min_bytes_to_use_direct_io 4822665734 --min_bytes_to_use_mmap_io 10737418240 --local_filesystem_read_method io_uring --remote_filesystem_read_method threadpool --local_filesystem_read_prefetch 0 --filesystem_cache_segments_batch_size 1 --read_from_filesystem_cache_if_exists_otherwise_bypass_cache 1 --throw_on_error_from_cache_on_write_operations 1 --remote_filesystem_read_prefetch 0 --allow_prefetched_read_pool_for_remote_filesystem 1 --filesystem_prefetch_max_memory_usage 128Mi --filesystem_prefetches_limit 10 --filesystem_prefetch_min_bytes_for_single_read_task 8Mi --filesystem_prefetch_step_marks 50 --filesystem_prefetch_step_bytes 0 --compile_aggregate_expressions 0 --compile_sort_description 1 --merge_tree_coarse_index_granularity 29 --optimize_distinct_in_order 1 --max_bytes_before_external_sort 0 --max_bytes_before_external_group_by 10737418240 --max_bytes_before_remerge_sort 1268919591 --min_compress_block_size 1657116 --max_compress_block_size 2659148 --merge_tree_compact_parts_min_granules_to_multibuffer_read 80 --optimize_sorting_by_input_stream_properties 1 --http_response_buffer_size 6676964 --http_wait_end_of_query True --enable_memory_bound_merging_of_aggregation_results 1 --min_count_to_compile_expression 0 --min_count_to_compile_aggregate_expression 0 --min_count_to_compile_sort_description 0 --session_timezone America/Mazatlan --use_page_cache_for_disks_without_file_cache False --page_cache_inject_eviction True --merge_tree_read_split_ranges_into_intersecting_and_non_intersecting_injection_probability 0.98 --prefer_external_sort_block_bytes 1 --cross_join_min_rows_to_compress 1 --cross_join_min_bytes_to_compress 100000000 --min_external_table_block_size_bytes 1 --max_parsing_threads 0 --optimize_functions_to_subcolumns 1 --parallel_replicas_local_plan 0 --query_plan_join_swap_table auto --output_format_native_write_json_as_string 1 --enable_vertical_final 0 2025-02-27 01:07:10 2025-02-27 01:07:10 MergeTree settings used in test: --ratio_of_defaults_for_sparse_serialization 1.0 --prefer_fetch_merged_part_size_threshold 4243261880 --vertical_merge_algorithm_min_rows_to_activate 1 --vertical_merge_algorithm_min_columns_to_activate 100 --allow_vertical_merges_from_compact_to_wide_parts 1 --min_merge_bytes_to_use_direct_io 1 --index_granularity_bytes 30037799 --merge_max_block_size 3345 --index_granularity 19344 --min_bytes_for_wide_part 1073741824 --marks_compress_block_size 63595 --primary_key_compress_block_size 42130 --replace_long_file_name_to_hash 1 --max_file_name_length 1 --min_bytes_for_full_part_storage 0 --compact_parts_max_bytes_to_buffer 234261212 --compact_parts_max_granules_to_buffer 126 --compact_parts_merge_max_bytes_to_prefetch_part 3402863 --cache_populated_by_fetch 0 --concurrent_part_removal_threshold 17 --old_parts_lifetime 480 --prewarm_mark_cache 0 --use_const_adaptive_granularity 1 --enable_index_granularity_compression 1 --use_primary_key_cache 0 --prewarm_primary_key_cache 1 2025-02-27 01:07:10 2025-02-27 01:07:10 Database: test_31gfoocy 2025-02-27 01:09:27 00152_insert_different_granularity: [ OK ] 137.06 sec. 2025-02-27 01:09:41 00140_rename: [ OK ] 14.52 sec. 2025-02-27 01:09:41 2025-02-27 01:09:41 Having 1 errors! 5 tests passed. 0 tests skipped. 531.95 s elapsed (MainProcess). 2025-02-27 01:09:41 Checking the hung queries: done 2025-02-27 01:09:41 2025-02-27 01:09:41 No queries hung. 2025-02-27 01:09:41 All tests have finished.