2025-04-04 01:44:18 Using queries from '/repo/tests/queries' directory 2025-04-04 01:44:18 Connecting to ClickHouse server... OK 2025-04-04 01:44:18 Connected to server 24.12.2.20231.altinityantalya @ ce78a8d28fd802b17cf3c7e092a948b4a453d18a HEAD 2025-04-04 01:44:18 Won't run stateless tests because they were manually disabled. 2025-04-04 01:44:18 Found 133 parallel tests and 6 sequential tests 2025-04-04 01:44:18 Running about 66 stateful tests (Process-4). 2025-04-04 01:44:18 00087_where_0: [ OK ] 0.17 sec. 2025-04-04 01:44:20 Running about 66 stateful tests (Process-3). 2025-04-04 01:44:20 00167_read_bytes_from_fs: [ OK ] 1.58 sec. 2025-04-04 01:44:20 00166_explain_estimate: [ OK ] 0.17 sec. 2025-04-04 01:44:20 00043_any_left_join: [ OK ] 1.89 sec. 2025-04-04 01:44:23 00020_distinct_order_by_distributed: [ OK ] 3.68 sec. 2025-04-04 01:44:24 00046_uniq_upto_distributed: [ OK ] 3.73 sec. 2025-04-04 01:44:24 00042_any_left_join: [ OK ] 0.42 sec. 2025-04-04 01:44:24 00147_global_in_aggregate_function: [ OK ] 0.67 sec. 2025-04-04 01:44:25 00076_system_columns_bytes: [ OK ] 0.12 sec. 2025-04-04 01:44:25 00094_order_by_array_join_limit: [ OK ] 0.82 sec. 2025-04-04 01:44:25 00093_prewhere_array_join: [ OK ] 0.52 sec. 2025-04-04 01:44:25 00005_filtering: [ OK ] 0.12 sec. 2025-04-04 01:44:25 00031_array_enumerate_uniq: [ OK ] 0.17 sec. 2025-04-04 01:44:26 00095_hyperscan_profiler: [ OK ] 1.22 sec. 2025-04-04 01:44:28 00161_parallel_parsing_with_names: [ OK ] 1.87 sec. 2025-04-04 01:44:29 00013_sorting_of_nested: [ OK ] 1.42 sec. 2025-04-04 01:44:29 00088_global_in_one_shard_and_rows_before_limit: [ OK ] 0.32 sec. 2025-04-04 01:44:31 00165_jit_aggregate_functions: [ OK ] 1.07 sec. 2025-04-04 01:44:32 00169_contingency: [ OK ] 6.53 sec. 2025-04-04 01:44:32 00050_min_max: [ OK ] 0.22 sec. 2025-04-04 01:44:34 00139_like: [ OK ] 2.02 sec. 2025-04-04 01:44:35 00060_move_to_prewhere_and_sets: [ OK ] 0.17 sec. 2025-04-04 01:44:35 00006_agregates: [ OK ] 0.12 sec. 2025-04-04 01:44:35 00055_index_and_not: [ OK ] 0.12 sec. 2025-04-04 01:44:35 00145_aggregate_functions_statistics: [ OK ] 0.57 sec. 2025-04-04 01:44:36 00065_loyalty_with_storage_join: [ OK ] 0.37 sec. 2025-04-04 01:44:37 00173_group_by_use_nulls: [ OK ] 0.82 sec. 2025-04-04 01:44:38 00159_parallel_formatting_json_and_friends_2: [ OK ] 6.94 sec. 2025-04-04 01:44:38 00068_subquery_in_prewhere: [ OK ] 0.17 sec. 2025-04-04 01:44:42 00159_parallel_formatting_tsv_and_friends: [ OK ] 4.23 sec. 2025-04-04 01:44:42 00154_avro: [ OK ] 0.42 sec. 2025-04-04 01:44:44 00078_group_by_arrays: [ OK ] 1.17 sec. 2025-04-04 01:44:44 00159_parallel_formatting_csv_and_friends: [ OK ] 7.38 sec. 2025-04-04 01:44:44 00067_union_all: [ OK ] 0.17 sec. 2025-04-04 01:44:45 00182_simple_squashing_transform_bug: [ OK ] 0.57 sec. 2025-04-04 01:44:45 00144_functions_of_aggregation_states: [ OK ] 0.22 sec. 2025-04-04 01:44:45 00015_totals_and_no_aggregate_functions: [ OK ] 0.12 sec. 2025-04-04 01:44:45 00097_constexpr_in_index: [ OK ] 0.12 sec. 2025-04-04 01:44:46 00074_full_join: [ OK ] 2.32 sec. 2025-04-04 01:44:46 00141_transform: [ OK ] 0.17 sec. 2025-04-04 01:44:46 00063_loyalty_joins: [ OK ] 1.27 sec. 2025-04-04 01:44:47 00160_decode_xml_component: [ OK ] 0.77 sec. 2025-04-04 01:44:47 00143_transform_non_const_default: [ OK ] 0.17 sec. 2025-04-04 01:44:47 00061_storage_buffer: [ OK ] 0.47 sec. 2025-04-04 01:44:48 00142_system_columns: [ OK ] 0.12 sec. 2025-04-04 01:44:48 00036_aggregate_hashed: [ OK ] 0.22 sec. 2025-04-04 01:44:48 00085_monotonic_evaluation_segfault: [ OK ] 0.12 sec. 2025-04-04 01:44:52 00167_parallel_parsing_with_names_and_types: [ OK ] 4.23 sec. 2025-04-04 01:44:52 00016_any_if_distributed_cond_always_false: [ OK ] 0.22 sec. 2025-04-04 01:44:55 00178_quantile_ddsketch: [ OK ] 2.67 sec. 2025-04-04 01:44:55 00172_early_constant_folding: [ OK ] 0.12 sec. 2025-04-04 01:44:56 00037_uniq_state_merge1: [ OK ] 1.22 sec. 2025-04-04 01:45:02 00044_any_left_join_string: [ OK ] 5.63 sec. 2025-04-04 01:45:05 00090_thread_pool_deadlock: [ OK ] 2.78 sec. 2025-04-04 01:45:06 00071_merge_tree_optimize_aio: [ OK ] 0.87 sec. 2025-04-04 01:45:06 00009_uniq_distributed: [ OK ] 0.27 sec. 2025-04-04 01:45:07 00153_aggregate_arena_race: [ OK ] 0.52 sec. 2025-04-04 01:45:07 00001_count_hits: [ OK ] 0.12 sec. 2025-04-04 01:45:07 00021_2_select_with_in: [ OK ] 0.52 sec. 2025-04-04 01:45:08 00175_counting_resources_in_subqueries: [ OK ] 0.22 sec. 2025-04-04 01:45:08 00012_sorting_distributed: [ OK ] 0.17 sec. 2025-04-04 01:45:09 00082_quantiles: [ OK ] 1.57 sec. 2025-04-04 01:45:10 00176_distinct_limit_by_limit_bug_43377: [ OK ] 0.12 sec. 2025-04-04 01:45:10 00059_merge_sorting_empty_array_joined: [ OK ] 0.92 sec. 2025-04-04 01:45:11 00032_aggregate_key64: [ OK ] 0.17 sec. 2025-04-04 01:45:11 00014_filtering_arrays: [ OK ] 0.22 sec. 2025-04-04 01:45:12 00089_position_functions_with_non_constant_arg: [ OK ] 1.17 sec. 2025-04-04 01:45:13 00096_obfuscator_save_load: [ OK ] 0.62 sec. 2025-04-04 01:45:13 00054_merge_tree_partitions: [ OK ] 0.27 sec. 2025-04-04 01:45:14 00086_array_reduce: [ OK ] 0.52 sec. 2025-04-04 01:45:14 00069_duplicate_aggregation_keys: [ OK ] 0.12 sec. 2025-04-04 01:45:14 00081_group_by_without_key_and_totals: [ OK ] 0.17 sec. 2025-04-04 01:45:14 00021_3_select_with_in: [ OK ] 0.12 sec. 2025-04-04 01:45:14 00151_order_by_read_in_order: [ OK ] 0.17 sec. 2025-04-04 01:45:14 00048_min_max: [ OK ] 0.12 sec. 2025-04-04 01:45:18 00084_external_aggregation: [ OK ] 3.38 sec. 2025-04-04 01:45:18 00072_compare_date_and_string_index: [ OK ] 0.67 sec. 2025-04-04 01:45:19 00056_view: [ OK ] 0.27 sec. 2025-04-04 01:45:19 00176_bson_parallel_parsing: [ SKIPPED ] 0.00 sec. 2025-04-04 01:45:19 Reason: disabled 2025-04-04 01:45:19 00007_uniq: [ OK ] 0.12 sec. 2025-04-04 01:45:19 00149_quantiles_timing_distributed: [ OK ] 0.62 sec. 2025-04-04 01:45:19 00183_prewhere_conditions_order: [ OK ] 0.12 sec. 2025-04-04 01:45:20 00002_count_visits: [ OK ] 0.12 sec. 2025-04-04 01:45:20 00021_1_select_with_in: [ OK ] 0.27 sec. 2025-04-04 01:45:24 00156_max_execution_speed_sample_merge: [ OK ] 4.28 sec. 2025-04-04 01:45:24 00010_quantiles_segfault: [ OK ] 0.17 sec. 2025-04-04 01:45:24 00039_primary_key: [ OK ] 0.12 sec. 2025-04-04 01:45:25 00008_uniq: [ OK ] 0.22 sec. 2025-04-04 01:45:25 00049_max_string_if: [ OK ] 0.32 sec. 2025-04-04 01:45:25 00083_array_filter: [ OK ] 0.22 sec. 2025-04-04 01:45:26 00051_min_max_array: [ OK ] 0.52 sec. 2025-04-04 01:45:26 00073_uniq_array: [ OK ] 0.12 sec. 2025-04-04 01:45:28 00163_column_oriented_formats: [ OK ] 2.12 sec. 2025-04-04 01:45:29 00148_monotonic_functions_and_index: [ OK ] 0.47 sec. 2025-04-04 01:45:29 00092_obfuscator: [ OK ] 0.77 sec. 2025-04-04 01:45:30 00035_aggregate_keys128: [ OK ] 0.22 sec. 2025-04-04 01:45:30 00062_loyalty: [ OK ] 0.22 sec. 2025-04-04 01:45:31 00164_quantileBfloat16: [ OK ] 1.12 sec. 2025-04-04 01:45:36 00159_parallel_formatting_http: [ OK ] 4.83 sec. 2025-04-04 01:45:36 00017_aggregation_uninitialized_memory: [ OK ] 0.12 sec. 2025-04-04 01:45:37 00023_totals_limit: [ OK ] 1.42 sec. 2025-04-04 01:45:38 00034_aggregate_key_fixed_string: [ OK ] 0.22 sec. 2025-04-04 01:45:38 00030_array_enumerate_uniq: [ OK ] 0.42 sec. 2025-04-04 01:45:38 00098_primary_key_memory_allocated: [ OK ] 0.12 sec. 2025-04-04 01:45:39 00175_obfuscator_schema_inference: [ OK ] 0.82 sec. 2025-04-04 01:45:39 00022_merge_prewhere: [ OK ] 0.12 sec. 2025-04-04 01:45:40 00038_uniq_state_merge2: [ OK ] 0.57 sec. 2025-04-04 01:45:42 00177_memory_bound_merging: [ OK ] 2.12 sec. 2025-04-04 01:45:45 00162_mmap_compression_none: [ OK ] 3.17 sec. 2025-04-04 01:45:47 00146_aggregate_function_uniq: [ OK ] 1.67 sec. 2025-04-04 01:45:48 00053_replicate_segfault: [ OK ] 1.12 sec. 2025-04-04 01:45:52 00080_array_join_and_union: [ OK ] 4.68 sec. 2025-04-04 01:45:53 00011_sorting: [ OK ] 0.17 sec. 2025-04-04 01:45:53 00066_sorting_distributed_many_replicas: [ OK ] 0.07 sec. 2025-04-04 01:45:57 00024_random_counters: [ OK ] 70.95 sec. 2025-04-04 01:45:58 00033_aggregate_key_string: [ OK ] 0.22 sec. 2025-04-04 01:45:58 00172_hits_joins: [ OK ] 5.48 sec. 2025-04-04 01:46:00 00077_log_tinylog_stripelog: [ OK ] 1.97 sec. 2025-04-04 01:46:00 00047_bar: [ OK ] 0.17 sec. 2025-04-04 01:46:01 00171_grouping_aggregated_transform_bug: [ OK ] 0.72 sec. 2025-04-04 01:46:01 00045_uniq_upto: [ OK ] 0.22 sec. 2025-04-04 01:46:03 00184_parallel_hash_returns_same_res_as_hash: [ OK ] 2.07 sec. 2025-04-04 01:46:03 00004_top_counters: [ OK ] 0.17 sec. 2025-04-04 01:46:04 00041_aggregating_materialized_view: [ OK ] 0.57 sec. 2025-04-04 01:46:04 00079_array_join_not_used_joined_column: [ OK ] 0.37 sec. 2025-04-04 01:46:04 00181_cross_join_compression: [ OK ] 0.42 sec. 2025-04-04 01:46:05 00052_group_by_in: [ OK ] 0.67 sec. 2025-04-04 01:46:07 00159_parallel_formatting_json_and_friends_1: [ OK ] 8.29 sec. 2025-04-04 01:46:07 00091_prewhere_two_conditions: [ OK ] 1.42 sec. 2025-04-04 01:46:07 00150_quantiles_timing_precision: [ OK ] 0.22 sec. 2025-04-04 01:46:07 00040_aggregating_materialized_view: [ OK ] 0.47 sec. 2025-04-04 01:46:07 00075_left_array_join: [ OK ] 0.37 sec. 2025-04-04 01:46:07 2025-04-04 01:46:07 39 tests passed. 0 tests skipped. 109.30 s elapsed (Process-3). 2025-04-04 01:46:08 00174_distinct_in_order: [ OK ] 0.67 sec. 2025-04-04 01:46:08 2025-04-04 01:46:08 93 tests passed. 1 tests skipped. 109.81 s elapsed (Process-4). 2025-04-04 01:46:08 Running 6 stateful tests (MainProcess). 2025-04-04 01:46:14 00180_no_seek_avoiding_when_reading_from_cache: [ OK ] 5.89 sec. 2025-04-04 01:46:18 00170_s3_cache: [ OK ] 4.08 sec. 2025-04-04 01:47:00 00158_cache_dictionary_has: [ OK ] 42.49 sec. 2025-04-04 01:52:55 00157_cache_dictionary: [ FAIL ] 354.48 sec. 2025-04-04 01:52:55 Reason: return code: 160 2025-04-04 01:52:55 [0f30ca7d00a3] 2025.04.03 07:52:55.169510 [ 5005 ] {d2afb0b8-15bd-4d2f-862f-8afeec03db25} executeQuery: Code: 160. DB::Exception: Estimated query execution time (758.86776 seconds) is too long. Maximum: 600. Estimated rows to process: 1000000 (466944 read in 354.34875 seconds).: While executing MergeTreeSelect(pool: ReadPool, algorithm: Thread). (TOO_SLOW) (version 24.12.2.20231.altinityantalya (altinity build)) (from [::1]:48626) (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-04 01:52:55 2025-04-04 01:52:55 0. ./build_docker/./src/Common/Exception.cpp:107: DB::Exception::Exception(DB::Exception::MessageMasked&&, int, bool) @ 0x000000000d78f79b 2025-04-04 01:52:55 1. DB::Exception::Exception(PreformattedMessage&&, int) @ 0x000000000867b58c 2025-04-04 01:52:55 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&) @ 0x00000000112dd884 2025-04-04 01:52:55 3. ./build_docker/./src/QueryPipeline/ExecutionSpeedLimits.cpp:87: DB::ExecutionSpeedLimits::throttle(unsigned long, unsigned long, unsigned long, unsigned long, DB::OverflowMode) const @ 0x00000000112dcfad 2025-04-04 01:52:55 4. ./build_docker/./src/QueryPipeline/ReadProgressCallback.cpp:131: DB::ReadProgressCallback::onProgress(unsigned long, unsigned long, std::list> const&) @ 0x00000000112dc469 2025-04-04 01:52:55 5. ./build_docker/./src/Processors/Executors/ExecutionThreadContext.cpp:64: DB::ExecutionThreadContext::executeTask() @ 0x00000000137a8266 2025-04-04 01:52:55 6. ./build_docker/./src/Processors/Executors/PipelineExecutor.cpp:290: DB::PipelineExecutor::executeStepImpl(unsigned long, std::atomic*) @ 0x000000001379b4e5 2025-04-04 01:52:55 7. ./build_docker/./src/Processors/Executors/PipelineExecutor.cpp:256: DB::PipelineExecutor::execute(unsigned long, bool) @ 0x000000001379a876 2025-04-04 01:52:55 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*) @ 0x00000000137999b7 2025-04-04 01:52:55 9. ./contrib/llvm-project/libcxx/include/__functional/function.h:848: ? @ 0x000000000d86c102 2025-04-04 01:52:55 10. ./contrib/llvm-project/libcxx/include/__functional/invoke.h:359: ? @ 0x000000000d8734ba 2025-04-04 01:52:55 11. ? @ 0x00007fce9a52fac3 2025-04-04 01:52:55 12. ? @ 0x00007fce9a5c1850 2025-04-04 01:52:55 2025-04-04 01:52:55 Received exception from server (version 24.12.2): 2025-04-04 01:52:55 Code: 160. DB::Exception: Received from localhost:9000. DB::Exception: Estimated query execution time (758.86776 seconds) is too long. Maximum: 600. Estimated rows to process: 1000000 (466944 read in 354.34875 seconds).: While executing MergeTreeSelect(pool: ReadPool, algorithm: Thread). (TOO_SLOW) 2025-04-04 01:52:55 (query: INSERT INTO test.hits_1m SELECT * FROM test.hits LIMIT 1000000 2025-04-04 01:52:55 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-04 01:52:55 , result: 2025-04-04 01:52:55 2025-04-04 01:52:55 2025-04-04 01:52:55 2025-04-04 01:52:55 stdout: 2025-04-04 01:52:55 2025-04-04 01:52:55 2025-04-04 01:52:55 Settings used in the test: --max_insert_threads 3 --group_by_two_level_threshold 968604 --group_by_two_level_threshold_bytes 1 --distributed_aggregation_memory_efficient 0 --fsync_metadata 0 --output_format_parallel_formatting 0 --input_format_parallel_parsing 0 --min_chunk_bytes_for_parallel_parsing 10754853 --max_read_buffer_size 519766 --prefer_localhost_replica 0 --max_block_size 15522 --max_joined_block_size_rows 53467 --max_threads 2 --optimize_append_index 0 --optimize_if_chain_to_multiif 0 --optimize_if_transform_strings_to_enum 1 --optimize_read_in_order 0 --optimize_or_like_chain 0 --optimize_substitute_columns 1 --enable_multiple_prewhere_read_steps 0 --read_in_order_two_level_merge_threshold 45 --optimize_aggregation_in_order 0 --aggregation_in_order_max_block_bytes 47993863 --use_uncompressed_cache 1 --min_bytes_to_use_direct_io 5938721698 --min_bytes_to_use_mmap_io 10737418240 --local_filesystem_read_method pread --remote_filesystem_read_method read --local_filesystem_read_prefetch 0 --filesystem_cache_segments_batch_size 2 --read_from_filesystem_cache_if_exists_otherwise_bypass_cache 1 --throw_on_error_from_cache_on_write_operations 1 --remote_filesystem_read_prefetch 1 --allow_prefetched_read_pool_for_remote_filesystem 1 --filesystem_prefetch_max_memory_usage 32Mi --filesystem_prefetches_limit 0 --filesystem_prefetch_min_bytes_for_single_read_task 16Mi --filesystem_prefetch_step_marks 0 --filesystem_prefetch_step_bytes 0 --compile_aggregate_expressions 0 --compile_sort_description 1 --merge_tree_coarse_index_granularity 6 --optimize_distinct_in_order 0 --max_bytes_before_external_sort 3335513042 --max_bytes_before_external_group_by 10737418240 --max_bytes_before_remerge_sort 2082865756 --min_compress_block_size 614726 --max_compress_block_size 1027065 --merge_tree_compact_parts_min_granules_to_multibuffer_read 107 --optimize_sorting_by_input_stream_properties 1 --http_response_buffer_size 5080767 --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 3 --session_timezone America/Hermosillo --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.61 --prefer_external_sort_block_bytes 1 --cross_join_min_rows_to_compress 0 --cross_join_min_bytes_to_compress 100000000 --min_external_table_block_size_bytes 100000000 --max_parsing_threads 0 --optimize_functions_to_subcolumns 0 --parallel_replicas_local_plan 1 --query_plan_join_swap_table auto --output_format_native_write_json_as_string 1 --enable_vertical_final 0 2025-04-04 01:52:55 2025-04-04 01:52:55 MergeTree settings used in test: --ratio_of_defaults_for_sparse_serialization 1.0 --prefer_fetch_merged_part_size_threshold 10737418240 --vertical_merge_algorithm_min_rows_to_activate 1000000 --vertical_merge_algorithm_min_columns_to_activate 1 --allow_vertical_merges_from_compact_to_wide_parts 1 --min_merge_bytes_to_use_direct_io 10737418240 --index_granularity_bytes 24365280 --merge_max_block_size 450 --index_granularity 23807 --min_bytes_for_wide_part 0 --marks_compress_block_size 17448 --primary_key_compress_block_size 60529 --replace_long_file_name_to_hash 1 --max_file_name_length 107 --min_bytes_for_full_part_storage 536870912 --compact_parts_max_bytes_to_buffer 46214154 --compact_parts_max_granules_to_buffer 240 --compact_parts_merge_max_bytes_to_prefetch_part 18037935 --cache_populated_by_fetch 0 --concurrent_part_removal_threshold 69 --old_parts_lifetime 480 --prewarm_mark_cache 0 --use_const_adaptive_granularity 0 --enable_index_granularity_compression 1 --use_primary_key_cache 0 --prewarm_primary_key_cache 1 2025-04-04 01:52:55 2025-04-04 01:52:55 Database: test_fo18tczm 2025-04-04 01:54:23 00152_insert_different_granularity: [ OK ] 88.23 sec. 2025-04-04 01:54:24 00140_rename: [ OK ] 0.67 sec. 2025-04-04 01:54:24 2025-04-04 01:54:24 Having 1 errors! 5 tests passed. 0 tests skipped. 495.90 s elapsed (MainProcess). 2025-04-04 01:54:24 Checking the hung queries: done 2025-04-04 01:54:24 2025-04-04 01:54:24 No queries hung. 2025-04-04 01:54:24 All tests have finished.