2025-04-04 23:52:01 Using queries from '/repo/tests/queries' directory 2025-04-04 23:52:01 Connecting to ClickHouse server... OK 2025-04-04 23:52:01 Connected to server 24.12.2.20271.altinityantalya @ 8ca0cbce1dcc0c1a96e56b9f8e755dc359787a80 HEAD 2025-04-04 23:52:01 Won't run stateless tests because they were manually disabled. 2025-04-04 23:52:01 Found 133 parallel tests and 6 sequential tests 2025-04-04 23:52:02 Running about 66 stateful tests (Process-3). 2025-04-04 23:52:02 00032_aggregate_key64: [ OK ] 0.28 sec. 2025-04-04 23:52:02 00004_top_counters: [ OK ] 0.27 sec. 2025-04-04 23:52:08 Running about 66 stateful tests (Process-4). 2025-04-04 23:52:08 00084_external_aggregation: [ OK ] 6.21 sec. 2025-04-04 23:52:15 00040_aggregating_materialized_view: [ OK ] 7.18 sec. 2025-04-04 23:52:15 00143_transform_non_const_default: [ OK ] 0.22 sec. 2025-04-04 23:52:15 00159_parallel_formatting_json_and_friends_1: [ OK ] 13.09 sec. 2025-04-04 23:52:15 00166_explain_estimate: [ OK ] 0.17 sec. 2025-04-04 23:52:16 00175_counting_resources_in_subqueries: [ OK ] 0.27 sec. 2025-04-04 23:52:17 00046_uniq_upto_distributed: [ OK ] 2.44 sec. 2025-04-04 23:52:18 00081_group_by_without_key_and_totals: [ OK ] 0.27 sec. 2025-04-04 23:52:18 00079_array_join_not_used_joined_column: [ OK ] 0.47 sec. 2025-04-04 23:52:18 00077_log_tinylog_stripelog: [ OK ] 2.74 sec. 2025-04-04 23:52:19 00050_min_max: [ OK ] 0.32 sec. 2025-04-04 23:52:20 00094_order_by_array_join_limit: [ OK ] 1.03 sec. 2025-04-04 23:52:21 00161_parallel_parsing_with_names: [ OK ] 2.73 sec. 2025-04-04 23:52:21 00053_replicate_segfault: [ OK ] 1.43 sec. 2025-04-04 23:52:21 00062_loyalty: [ OK ] 0.22 sec. 2025-04-04 23:52:22 00144_functions_of_aggregation_states: [ OK ] 0.32 sec. 2025-04-04 23:52:23 00171_grouping_aggregated_transform_bug: [ OK ] 0.78 sec. 2025-04-04 23:52:24 00162_mmap_compression_none: [ OK ] 2.79 sec. 2025-04-04 23:52:24 00069_duplicate_aggregation_keys: [ OK ] 0.17 sec. 2025-04-04 23:52:25 00175_obfuscator_schema_inference: [ OK ] 1.18 sec. 2025-04-04 23:52:25 00097_constexpr_in_index: [ OK ] 0.12 sec. 2025-04-04 23:52:26 00090_thread_pool_deadlock: [ OK ] 3.39 sec. 2025-04-04 23:52:26 00145_aggregate_functions_statistics: [ OK ] 0.72 sec. 2025-04-04 23:52:26 00183_prewhere_conditions_order: [ OK ] 0.17 sec. 2025-04-04 23:52:26 00176_distinct_limit_by_limit_bug_43377: [ OK ] 0.17 sec. 2025-04-04 23:52:26 00021_1_select_with_in: [ OK ] 0.17 sec. 2025-04-04 23:52:27 00010_quantiles_segfault: [ OK ] 0.28 sec. 2025-04-04 23:52:28 00078_group_by_arrays: [ OK ] 1.48 sec. 2025-04-04 23:52:29 00160_decode_xml_component: [ OK ] 0.72 sec. 2025-04-04 23:52:32 00020_distinct_order_by_distributed: [ OK ] 3.44 sec. 2025-04-04 23:52:32 00005_filtering: [ OK ] 0.17 sec. 2025-04-04 23:52:33 00061_storage_buffer: [ OK ] 0.67 sec. 2025-04-04 23:52:34 00072_compare_date_and_string_index: [ OK ] 1.13 sec. 2025-04-04 23:52:40 00167_parallel_parsing_with_names_and_types: [ OK ] 5.66 sec. 2025-04-04 23:52:42 00023_totals_limit: [ OK ] 2.68 sec. 2025-04-04 23:52:45 00167_read_bytes_from_fs: [ OK ] 2.33 sec. 2025-04-04 23:52:45 00012_sorting_distributed: [ OK ] 0.22 sec. 2025-04-04 23:52:48 00091_prewhere_two_conditions: [ OK ] 3.33 sec. 2025-04-04 23:52:49 00154_avro: [ OK ] 0.52 sec. 2025-04-04 23:52:49 00055_index_and_not: [ OK ] 0.17 sec. 2025-04-04 23:52:49 00039_primary_key: [ OK ] 0.17 sec. 2025-04-04 23:52:50 00173_group_by_use_nulls: [ OK ] 0.92 sec. 2025-04-04 23:52:51 00181_cross_join_compression: [ OK ] 24.32 sec. 2025-04-04 23:52:53 00178_quantile_ddsketch: [ OK ] 2.29 sec. 2025-04-04 23:52:56 00139_like: [ OK ] 3.08 sec. 2025-04-04 23:52:57 00001_count_hits: [ OK ] 0.17 sec. 2025-04-04 23:52:57 00043_any_left_join: [ OK ] 0.22 sec. 2025-04-04 23:52:57 00141_transform: [ OK ] 0.22 sec. 2025-04-04 23:52:57 00169_contingency: [ OK ] 7.05 sec. 2025-04-04 23:52:58 00034_aggregate_key_fixed_string: [ OK ] 0.42 sec. 2025-04-04 23:52:58 00009_uniq_distributed: [ OK ] 0.32 sec. 2025-04-04 23:52:59 00052_group_by_in: [ OK ] 1.42 sec. 2025-04-04 23:52:59 00036_aggregate_hashed: [ OK ] 0.62 sec. 2025-04-04 23:53:09 00159_parallel_formatting_csv_and_friends: [ OK ] 10.12 sec. 2025-04-04 23:53:09 00031_array_enumerate_uniq: [ OK ] 0.17 sec. 2025-04-04 23:53:09 00022_merge_prewhere: [ OK ] 0.27 sec. 2025-04-04 23:53:09 00021_2_select_with_in: [ OK ] 0.17 sec. 2025-04-04 23:53:10 00159_parallel_formatting_json_and_friends_2: [ OK ] 10.82 sec. 2025-04-04 23:53:10 00151_order_by_read_in_order: [ OK ] 0.22 sec. 2025-04-04 23:53:10 00083_array_filter: [ OK ] 0.32 sec. 2025-04-04 23:53:10 00037_uniq_state_merge1: [ OK ] 0.92 sec. 2025-04-04 23:53:10 00035_aggregate_keys128: [ OK ] 0.17 sec. 2025-04-04 23:53:11 00049_max_string_if: [ OK ] 0.48 sec. 2025-04-04 23:53:11 00044_any_left_join_string: [ OK ] 0.63 sec. 2025-04-04 23:53:11 00054_merge_tree_partitions: [ OK ] 0.52 sec. 2025-04-04 23:53:12 00153_aggregate_arena_race: [ OK ] 0.68 sec. 2025-04-04 23:53:12 00006_agregates: [ OK ] 0.17 sec. 2025-04-04 23:53:12 00096_obfuscator_save_load: [ OK ] 0.82 sec. 2025-04-04 23:53:13 00013_sorting_of_nested: [ OK ] 1.72 sec. 2025-04-04 23:53:14 00042_any_left_join: [ OK ] 0.17 sec. 2025-04-04 23:53:14 00093_prewhere_array_join: [ OK ] 1.37 sec. 2025-04-04 23:53:15 00063_loyalty_joins: [ OK ] 1.73 sec. 2025-04-04 23:53:16 00076_system_columns_bytes: [ OK ] 0.18 sec. 2025-04-04 23:53:16 00073_uniq_array: [ OK ] 0.17 sec. 2025-04-04 23:53:16 00089_position_functions_with_non_constant_arg: [ OK ] 1.98 sec. 2025-04-04 23:53:17 00149_quantiles_timing_distributed: [ OK ] 0.82 sec. 2025-04-04 23:53:17 00174_distinct_in_order: [ OK ] 0.87 sec. 2025-04-04 23:53:17 00045_uniq_upto: [ OK ] 0.22 sec. 2025-04-04 23:53:18 00177_memory_bound_merging: [ OK ] 1.52 sec. 2025-04-04 23:53:19 00021_3_select_with_in: [ OK ] 0.17 sec. 2025-04-04 23:53:19 00011_sorting: [ OK ] 0.27 sec. 2025-04-04 23:53:19 00008_uniq: [ OK ] 0.32 sec. 2025-04-04 23:53:20 00163_column_oriented_formats: [ OK ] 2.98 sec. 2025-04-04 23:53:20 00051_min_max_array: [ OK ] 0.57 sec. 2025-04-04 23:53:22 00071_merge_tree_optimize_aio: [ OK ] 2.28 sec. 2025-04-04 23:53:28 00159_parallel_formatting_tsv_and_friends: [ OK ] 6.09 sec. 2025-04-04 23:53:28 00086_array_reduce: [ OK ] 0.42 sec. 2025-04-04 23:53:30 00059_merge_sorting_empty_array_joined: [ OK ] 1.07 sec. 2025-04-04 23:53:31 00024_random_counters: [ OK ] 11.07 sec. 2025-04-04 23:53:31 00047_bar: [ OK ] 0.17 sec. 2025-04-04 23:53:32 00065_loyalty_with_storage_join: [ OK ] 0.62 sec. 2025-04-04 23:53:34 00082_quantiles: [ OK ] 1.77 sec. 2025-04-04 23:53:34 00172_early_constant_folding: [ OK ] 0.17 sec. 2025-04-04 23:53:34 00159_parallel_formatting_http: [ OK ] 4.54 sec. 2025-04-04 23:53:35 00147_global_in_aggregate_function: [ OK ] 1.08 sec. 2025-04-04 23:53:35 00148_monotonic_functions_and_index: [ OK ] 0.82 sec. 2025-04-04 23:53:35 00098_primary_key_memory_allocated: [ OK ] 0.17 sec. 2025-04-04 23:53:35 00150_quantiles_timing_precision: [ OK ] 0.37 sec. 2025-04-04 23:53:36 00182_simple_squashing_transform_bug: [ OK ] 0.78 sec. 2025-04-04 23:53:37 00038_uniq_state_merge2: [ OK ] 1.73 sec. 2025-04-04 23:53:37 00068_subquery_in_prewhere: [ OK ] 0.17 sec. 2025-04-04 23:53:37 00087_where_0: [ OK ] 0.17 sec. 2025-04-04 23:53:40 00184_parallel_hash_returns_same_res_as_hash: [ OK ] 2.28 sec. 2025-04-04 23:53:40 00056_view: [ OK ] 0.27 sec. 2025-04-04 23:53:41 00172_hits_joins: [ OK ] 4.49 sec. 2025-04-04 23:53:41 00015_totals_and_no_aggregate_functions: [ OK ] 0.22 sec. 2025-04-04 23:53:41 00092_obfuscator: [ OK ] 0.97 sec. 2025-04-04 23:53:41 00142_system_columns: [ OK ] 0.17 sec. 2025-04-04 23:53:41 00007_uniq: [ OK ] 0.17 sec. 2025-04-04 23:53:41 00075_left_array_join: [ OK ] 0.42 sec. 2025-04-04 23:53:41 00017_aggregation_uninitialized_memory: [ OK ] 0.17 sec. 2025-04-04 23:53:41 00066_sorting_distributed_many_replicas: [ OK ] 0.12 sec. 2025-04-04 23:53:42 00165_jit_aggregate_functions: [ OK ] 0.87 sec. 2025-04-04 23:53:42 00088_global_in_one_shard_and_rows_before_limit: [ OK ] 0.32 sec. 2025-04-04 23:53:43 00030_array_enumerate_uniq: [ OK ] 0.47 sec. 2025-04-04 23:53:43 00067_union_all: [ OK ] 0.22 sec. 2025-04-04 23:53:44 00041_aggregating_materialized_view: [ OK ] 0.92 sec. 2025-04-04 23:53:45 00164_quantileBfloat16: [ OK ] 0.97 sec. 2025-04-04 23:53:45 00002_count_visits: [ OK ] 0.17 sec. 2025-04-04 23:53:45 00080_array_join_and_union: [ OK ] 3.99 sec. 2025-04-04 23:53:46 00048_min_max: [ OK ] 0.17 sec. 2025-04-04 23:53:46 00176_bson_parallel_parsing: [ SKIPPED ] 0.00 sec. 2025-04-04 23:53:46 Reason: disabled 2025-04-04 23:53:46 00060_move_to_prewhere_and_sets: [ OK ] 0.27 sec. 2025-04-04 23:53:46 00074_full_join: [ OK ] 0.52 sec. 2025-04-04 23:53:49 00095_hyperscan_profiler: [ OK ] 3.83 sec. 2025-04-04 23:53:50 00014_filtering_arrays: [ OK ] 0.22 sec. 2025-04-04 23:53:50 00085_monotonic_evaluation_segfault: [ OK ] 0.17 sec. 2025-04-04 23:53:51 00156_max_execution_speed_sample_merge: [ OK ] 4.33 sec. 2025-04-04 23:53:51 00016_any_if_distributed_cond_always_false: [ OK ] 0.22 sec. 2025-04-04 23:53:51 00033_aggregate_key_string: [ OK ] 0.37 sec. 2025-04-04 23:53:51 2025-04-04 23:53:51 68 tests passed. 0 tests skipped. 109.96 s elapsed (Process-4). 2025-04-04 23:53:51 00146_aggregate_function_uniq: [ OK ] 1.47 sec. 2025-04-04 23:53:51 2025-04-04 23:53:51 64 tests passed. 1 tests skipped. 109.98 s elapsed (Process-3). 2025-04-04 23:53:51 Running 6 stateful tests (MainProcess). 2025-04-04 23:53:59 00180_no_seek_avoiding_when_reading_from_cache: [ OK ] 8.15 sec. 2025-04-04 23:54:05 00170_s3_cache: [ OK ] 5.59 sec. 2025-04-04 23:55:08 00158_cache_dictionary_has: [ OK ] 62.73 sec. 2025-04-05 00:00:26 00157_cache_dictionary: [ FAIL ] 317.87 sec. 2025-04-05 00:00:26 Reason: return code: 160 2025-04-05 00:00:26 [d3300aa66f73] 2025.04.04 10:00:26.162301 [ 349 ] {983ed6c0-be74-446a-a548-5e4a4947c0ca} executeQuery: Code: 160. DB::Exception: Estimated query execution time (680.39295 seconds) is too long. Maximum: 600. Estimated rows to process: 1000000 (466944 read in 317.70541 seconds).: While executing MergeTreeSelect(pool: ReadPool, algorithm: Thread). (TOO_SLOW) (version 24.12.2.20271.altinityantalya (altinity build)) (from [::1]:45478) (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-04-05 00:00:26 2025-04-05 00:00:26 0. ./contrib/llvm-project/libcxx/include/vector:676: DB::Exception::Exception(DB::Exception::MessageMasked&&, int, bool) @ 0x000000000c16e664 2025-04-05 00:00:26 1. DB::Exception::Exception(PreformattedMessage&&, int) @ 0x0000000007d8cf5c 2025-04-05 00:00:26 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&) @ 0x000000000f939114 2025-04-05 00:00:26 3. ./build_docker/./src/QueryPipeline/ExecutionSpeedLimits.cpp:87: DB::ExecutionSpeedLimits::throttle(unsigned long, unsigned long, unsigned long, unsigned long, DB::OverflowMode) const @ 0x000000000f938958 2025-04-05 00:00:26 4. ./build_docker/./src/QueryPipeline/ReadProgressCallback.cpp:131: DB::ReadProgressCallback::onProgress(unsigned long, unsigned long, std::list> const&) @ 0x000000000f937fbc 2025-04-05 00:00:26 5. ./build_docker/./src/Processors/Executors/ExecutionThreadContext.cpp:64: DB::ExecutionThreadContext::executeTask() @ 0x000000001191decc 2025-04-05 00:00:26 6. ./build_docker/./src/Processors/Executors/PipelineExecutor.cpp:290: DB::PipelineExecutor::executeStepImpl(unsigned long, std::atomic*) @ 0x0000000011913394 2025-04-05 00:00:26 7. ./build_docker/./src/Processors/Executors/PipelineExecutor.cpp:256: DB::PipelineExecutor::execute(unsigned long, bool) @ 0x000000001191290c 2025-04-05 00:00:26 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*) @ 0x00000000119119d0 2025-04-05 00:00:26 9. ./contrib/llvm-project/libcxx/include/__functional/function.h:848: ? @ 0x000000000c236648 2025-04-05 00:00:26 10. ./contrib/llvm-project/libcxx/include/__functional/invoke.h:359: ? @ 0x000000000c23cfbc 2025-04-05 00:00:26 11. ? @ 0x000000000007d5c8 2025-04-05 00:00:26 12. ? @ 0x00000000000e5edc 2025-04-05 00:00:26 2025-04-05 00:00:26 Received exception from server (version 24.12.2): 2025-04-05 00:00:26 Code: 160. DB::Exception: Received from localhost:9000. DB::Exception: Estimated query execution time (680.39295 seconds) is too long. Maximum: 600. Estimated rows to process: 1000000 (466944 read in 317.70541 seconds).: While executing MergeTreeSelect(pool: ReadPool, algorithm: Thread). (TOO_SLOW) 2025-04-05 00:00:26 (query: INSERT INTO test.hits_1m SELECT * FROM test.hits LIMIT 1000000 2025-04-05 00:00:26 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-04-05 00:00:26 , result: 2025-04-05 00:00:26 2025-04-05 00:00:26 2025-04-05 00:00:26 2025-04-05 00:00:26 stdout: 2025-04-05 00:00:26 2025-04-05 00:00:26 2025-04-05 00:00:26 Settings used in the test: --max_insert_threads 2 --group_by_two_level_threshold 1 --group_by_two_level_threshold_bytes 23638548 --distributed_aggregation_memory_efficient 0 --fsync_metadata 1 --output_format_parallel_formatting 1 --input_format_parallel_parsing 1 --min_chunk_bytes_for_parallel_parsing 4393056 --max_read_buffer_size 805236 --prefer_localhost_replica 1 --max_block_size 42995 --max_joined_block_size_rows 63921 --max_threads 3 --optimize_append_index 1 --optimize_if_chain_to_multiif 0 --optimize_if_transform_strings_to_enum 0 --optimize_read_in_order 0 --optimize_or_like_chain 0 --optimize_substitute_columns 0 --enable_multiple_prewhere_read_steps 0 --read_in_order_two_level_merge_threshold 92 --optimize_aggregation_in_order 0 --aggregation_in_order_max_block_bytes 33446521 --use_uncompressed_cache 0 --min_bytes_to_use_direct_io 10737418240 --min_bytes_to_use_mmap_io 10737418240 --local_filesystem_read_method mmap --remote_filesystem_read_method read --local_filesystem_read_prefetch 0 --filesystem_cache_segments_batch_size 10 --read_from_filesystem_cache_if_exists_otherwise_bypass_cache 1 --throw_on_error_from_cache_on_write_operations 0 --remote_filesystem_read_prefetch 0 --allow_prefetched_read_pool_for_remote_filesystem 0 --filesystem_prefetch_max_memory_usage 64Mi --filesystem_prefetches_limit 0 --filesystem_prefetch_min_bytes_for_single_read_task 16Mi --filesystem_prefetch_step_marks 50 --filesystem_prefetch_step_bytes 0 --compile_aggregate_expressions 1 --compile_sort_description 0 --merge_tree_coarse_index_granularity 21 --optimize_distinct_in_order 1 --max_bytes_before_external_sort 10621547391 --max_bytes_before_external_group_by 10737418240 --max_bytes_before_remerge_sort 320628302 --min_compress_block_size 1213456 --max_compress_block_size 808431 --merge_tree_compact_parts_min_granules_to_multibuffer_read 84 --optimize_sorting_by_input_stream_properties 1 --http_response_buffer_size 4006955 --http_wait_end_of_query False --enable_memory_bound_merging_of_aggregation_results 0 --min_count_to_compile_expression 3 --min_count_to_compile_aggregate_expression 3 --min_count_to_compile_sort_description 3 --session_timezone America/Hermosillo --use_page_cache_for_disks_without_file_cache True --page_cache_inject_eviction False --merge_tree_read_split_ranges_into_intersecting_and_non_intersecting_injection_probability 0.45 --prefer_external_sort_block_bytes 1 --cross_join_min_rows_to_compress 100000000 --cross_join_min_bytes_to_compress 0 --min_external_table_block_size_bytes 100000000 --max_parsing_threads 10 --optimize_functions_to_subcolumns 1 --parallel_replicas_local_plan 1 --query_plan_join_swap_table true --output_format_native_write_json_as_string 0 --enable_vertical_final 0 2025-04-05 00:00:26 2025-04-05 00:00:26 MergeTree settings used in test: --ratio_of_defaults_for_sparse_serialization 0.2705501278875655 --prefer_fetch_merged_part_size_threshold 7790813992 --vertical_merge_algorithm_min_rows_to_activate 1 --vertical_merge_algorithm_min_columns_to_activate 47 --allow_vertical_merges_from_compact_to_wide_parts 1 --min_merge_bytes_to_use_direct_io 10737418240 --index_granularity_bytes 19320016 --merge_max_block_size 8276 --index_granularity 26888 --min_bytes_for_wide_part 0 --marks_compress_block_size 82950 --primary_key_compress_block_size 69353 --replace_long_file_name_to_hash 1 --max_file_name_length 33 --min_bytes_for_full_part_storage 536870912 --compact_parts_max_bytes_to_buffer 425861884 --compact_parts_max_granules_to_buffer 256 --compact_parts_merge_max_bytes_to_prefetch_part 13978190 --cache_populated_by_fetch 0 --concurrent_part_removal_threshold 0 --old_parts_lifetime 259 --prewarm_mark_cache 0 --use_const_adaptive_granularity 0 --enable_index_granularity_compression 0 --use_primary_key_cache 1 --prewarm_primary_key_cache 0 2025-04-05 00:00:26 2025-04-05 00:00:26 Database: test_eel11q44 2025-04-05 00:00:28 00152_insert_different_granularity: [ OK ] 2.58 sec. 2025-04-05 00:00:29 00140_rename: [ OK ] 0.77 sec. 2025-04-05 00:00:29 2025-04-05 00:00:29 Having 1 errors! 5 tests passed. 0 tests skipped. 397.80 s elapsed (MainProcess). 2025-04-05 00:00:29 Checking the hung queries: done 2025-04-05 00:00:29 2025-04-05 00:00:29 No queries hung. 2025-04-05 00:00:29 All tests have finished.