2025-12-12 09:11:23,643 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 09:11:23,643 - INFO - ya.test - pytest_runtest_setup: test_tpcds[CheckCanonicalPolicy.ERROR]
2025-12-12 09:11:23,644 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 09:11:23,644 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-12 09:11:23,645 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:8039/1
2025-12-12 09:11:23,646 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14049/1
2025-12-12 09:11:23,651 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_diff_processing.py::TestTpcdsDiffProcessing, test_name: test_tpcds[CheckCanonicalPolicy.ERROR])
2025-12-12 09:11:23,654 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,657 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-12 09:11:23,690 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-12-12 09:11:23,695 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,698 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-12 09:11:23,708 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-12-12 09:11:23,711 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-12-12 09:11:23,711 - INFO - root - _get_tables: get_tables /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:11:23,711 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:11:23,712 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, e6e82168-1314-4adc-9ec8-b1a70a8304e6, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:11:23,713 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, e6e82168-1314-4adc-9ec8-b1a70a8304e6, ghrun-7uza2v6l2u.auto.internal:14049): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpcds/s1" }
2025-12-12 09:11:23,719 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, e6e82168-1314-4adc-9ec8-b1a70a8304e6, ghrun-7uza2v6l2u.auto.internal:14049): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1765530595470 tx_id: 281474976720659 } } } } } }
2025-12-12 09:11:23,720 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:11:23,720 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 3728d5bf-5bb9-4d1a-a869-25fe8567d279, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:11:23,721 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 3728d5bf-5bb9-4d1a-a869-25fe8567d279, ghrun-7uza2v6l2u.auto.internal:14049): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpcds/s1" }
2025-12-12 09:11:23,731 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 3728d5bf-5bb9-4d1a-a869-25fe8567d279, ghrun-7uza2v6l2u.auto.internal:14049): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1765530595470 tx_id: 281474976720659 } } children { name: "call_center" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530623210 tx_id: 281474976720669 } } children { name: "catalog_page" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530641270 tx_id: 281474976720676 } } children { name: "catalog_returns" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530646240 tx_id: 281474976720678 } } children { name: "catalog_sales" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530654190 tx_id: 281474976720681 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530625980 tx_id: 281474976720670 } } children { name: "customer_address" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530595470 tx_id: 281474976720659 } } children { name: "customer_demographics" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530597860 tx_id: 281474976720660 } } children { name: "date_dim" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530600590 tx_id: 281474976720661 } } children { name: "household_demographics" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530634160 tx_id: 281474976720673 } } children { name: "income_band" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530614580 tx_id: 281474976720666 } } children { name: "inventory" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530643670 tx_id: 281474976720677 } } children { name: "item" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530617370 tx_id: 281474976720667 } } children { name: "promotion" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530638740 tx_id: 281474976720675 } } children { name: "reason" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530611240 tx_id: 281474976720665 } } children { name: "ship_mode" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530605460 tx_id: 281474976720663 } } children { name: "store" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530620320 tx_id: 281474976720668 } } children { name: "store_returns" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530631560 tx_id: 281474976720672 } } children { name: "store_sales" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530657240 tx_id: 281474976720682 } } children { name: "time_dim" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530608370 tx_id: 281474976720664 } } children { name: "warehouse" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530602880 tx_id: 281474976720662 } } children { name: "web_page" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530636320 tx_id: 281474976720674 } } children { name: "web_returns" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530648800 tx_id: 281474976720679 } } children { name: "web_sales" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530651520 tx_id: 281474976720680 } } children { name: "web_site" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1765530628720 tx_id: 281474976720671 } } } } } }
2025-12-12 09:11:23,737 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,741 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/call_center&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,757 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/call_center&tablets=true HTTP/1.1" 200 578
2025-12-12 09:11:23,761 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/call_center balance: 64-64 shards.
2025-12-12 09:11:23,766 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,770 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/catalog_page&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,790 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/catalog_page&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:23,793 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/catalog_page balance: 64-64 shards.
2025-12-12 09:11:23,797 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,801 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/catalog_returns&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,813 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/catalog_returns&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:23,817 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/catalog_returns balance: 64-64 shards.
2025-12-12 09:11:23,822 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,826 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/catalog_sales&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,841 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/catalog_sales&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:23,844 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/catalog_sales balance: 64-64 shards.
2025-12-12 09:11:23,847 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,851 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/customer&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,865 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/customer&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:23,868 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/customer balance: 64-64 shards.
2025-12-12 09:11:23,872 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,876 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/customer_address&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,892 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/customer_address&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:23,895 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/customer_address balance: 64-64 shards.
2025-12-12 09:11:23,899 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,903 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/customer_demographics&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,917 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/customer_demographics&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:23,920 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/customer_demographics balance: 64-64 shards.
2025-12-12 09:11:23,924 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,934 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/date_dim&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:23,971 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/date_dim&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:23,974 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/date_dim balance: 64-64 shards.
2025-12-12 09:11:23,978 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,991 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/household_demographics&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,009 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/household_demographics&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,012 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/household_demographics balance: 64-64 shards.
2025-12-12 09:11:24,015 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,021 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/income_band&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,038 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/income_band&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,041 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/income_band balance: 64-64 shards.
2025-12-12 09:11:24,053 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,060 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/inventory&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,074 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/inventory&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,076 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/inventory balance: 64-64 shards.
2025-12-12 09:11:24,079 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,083 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/item&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,110 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/item&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,113 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/item balance: 64-64 shards.
2025-12-12 09:11:24,116 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,119 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/promotion&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,133 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/promotion&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,136 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/promotion balance: 64-64 shards.
2025-12-12 09:11:24,139 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,143 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/reason&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,159 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/reason&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,162 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/reason balance: 64-64 shards.
2025-12-12 09:11:24,165 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,169 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/ship_mode&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,185 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/ship_mode&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,187 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/ship_mode balance: 64-64 shards.
2025-12-12 09:11:24,192 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,200 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/store&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,219 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/store&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,222 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/store balance: 64-64 shards.
2025-12-12 09:11:24,226 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,229 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/store_returns&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,243 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/store_returns&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,246 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/store_returns balance: 64-64 shards.
2025-12-12 09:11:24,253 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,257 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/store_sales&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,270 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/store_sales&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,272 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/store_sales balance: 64-64 shards.
2025-12-12 09:11:24,275 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,279 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/time_dim&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,291 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/time_dim&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,294 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/time_dim balance: 64-64 shards.
2025-12-12 09:11:24,297 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,300 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/warehouse&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,312 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/warehouse&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,314 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/warehouse balance: 64-64 shards.
2025-12-12 09:11:24,317 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,321 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_page&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,335 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_page&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,338 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_page balance: 64-64 shards.
2025-12-12 09:11:24,342 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,346 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_returns&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,359 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_returns&tablets=true HTTP/1.1" 200 581
2025-12-12 09:11:24,362 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_returns balance: 64-64 shards.
2025-12-12 09:11:24,365 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,368 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_sales&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,381 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_sales&tablets=true HTTP/1.1" 200 582
2025-12-12 09:11:24,383 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_sales balance: 64-64 shards.
2025-12-12 09:11:24,387 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:24,392 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_site&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:24,405 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpcds/s1/web_site&tablets=true HTTP/1.1" 200 582
2025-12-12 09:11:24,408 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_site balance: 64-64 shards.
2025-12-12 09:11:24,409 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b2660e89-136c-4ae1-b2b5-f014bf316b62, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:11:24,410 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b2660e89-136c-4ae1-b2b5-f014bf316b62, ghrun-7uza2v6l2u.auto.internal:14049): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-12 09:11:24,412 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b2660e89-136c-4ae1-b2b5-f014bf316b62, ghrun-7uza2v6l2u.auto.internal:14049): response = { <_MultiThreadedRendezvous object> }
2025-12-12 09:11:24,479 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydb/ydb
2025-12-12 09:11:24,482 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-12-12 09:11:24,483 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-12-12 09:11:24,484 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:8039', '-d', '/local/test_db', 'workload', 'tpcds', '--path', 'olap_yatests/tpcds/s1', 'run', '--json', '/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_diff_processing/testing_out_stuff/test_diff_processing.py.TestTpcdsDiffProcessing.test_tpcds.CheckCanonicalPolicy.ERROR/q1.json', '--output', '/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_diff_processing/testing_out_stuff/test_diff_processing.py.TestTpcdsDiffProcessing.test_tpcds.CheckCanonicalPolicy.ERROR/q1.out', '--executer', 'generic', '--include', '1', '--iterations', '1', '--plan', '/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_diff_processing/testing_out_stuff/test_diff_processing.py.TestTpcdsDiffProcessing.test_tpcds.CheckCanonicalPolicy.ERROR/q1.plan', '--global-timeout', '1800.0s', '--verbose', '--check-canonical', '--scale', '1']' in 'None' (waiting)
2025-12-12 09:11:24,486 - DEBUG - ya.test - execute: Command pid: 1218370
2025-12-12 09:11:31,358 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) rc: 1
2025-12-12 09:11:31,358 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) elapsed time (sec): 6.871070384979248
2025-12-12 09:11:31,359 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) idrss: 0
2025-12-12 09:11:31,359 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) inblock: 0
2025-12-12 09:11:31,359 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) isrss: 0
2025-12-12 09:11:31,360 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) ixrss: 0
2025-12-12 09:11:31,360 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) majflt: 0
2025-12-12 09:11:31,360 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) maxrss: 646704
2025-12-12 09:11:31,361 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) minflt: 188298
2025-12-12 09:11:31,361 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) msgrcv: 0
2025-12-12 09:11:31,361 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) msgsnd: 0
2025-12-12 09:11:31,362 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) nivcsw: 118
2025-12-12 09:11:31,362 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) nsignals: 0
2025-12-12 09:11:31,362 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) nswap: 0
2025-12-12 09:11:31,363 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) nvcsw: 2550
2025-12-12 09:11:31,363 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) oublock: 1832
2025-12-12 09:11:31,363 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) stime: 0.57019
2025-12-12 09:11:31,364 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) utime: 5.487884
2025-12-12 09:11:31,364 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) wtime: 6.873
2025-12-12 09:11:31,364 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) output:
Query01:
Query text:
$z1_2_35 = 1.2;
$customer_total_return =
(select a.sr_customer_sk as ctr_customer_sk
,a.sr_store_sk as ctr_store_sk
,sum(a.sr_return_amt) as ctr_total_return
from `olap_yatests/tpcds/s1/store_returns` as a cross join `olap_yatests/tpcds/s1/date_dim` as b
where sr_returned_date_sk = d_date_sk
and d_year =2000
group by a.sr_customer_sk
,a.sr_store_sk);
$avg_total_returns = (
    select ctr_store_sk, avg(ctr_total_return)*$z1_2_35 as ctr_avg
    from $customer_total_return
    group by ctr_store_sk
);
 select  c_customer_id
from $customer_total_return ctr1
cross join `olap_yatests/tpcds/s1/store`
cross join `olap_yatests/tpcds/s1/customer`
join $avg_total_returns ctr2 on ctr1.ctr_store_sk = ctr2.ctr_store_sk
where ctr_total_return > ctr_avg
and s_store_sk = ctr1.ctr_store_sk
and s_state = 'TN'
and ctr_customer_sk = c_customer_sk
order by c_customer_id
limit 100;

	iteration 0:	ok	0.805463s seconds

Results for 1 iterations
┌─────────┬──────────┬─────────┬─────────┬─────────┬─────────┬───────────┬─────────┬─────────┬─────────┬─────────┬──────────────┬────────────┬────────────┐
│ Query # │ ColdTime │ Min     │ Max     │ Mean    │ Median  │ UnixBench │ Std     │ RttMin  │ RttMax  │ RttAvg  │ SuccessCount │ FailsCount │ DiffsCount │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Query01 │   0.524  │   0.524 │   0.524 │   0.524 │   0.524 │   0.524   │   0.000 │   0.281 │   0.281 │   0.281 │ 1            │            │ 1          │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Sum     │   0.524  │   0.524 │   0.524 │   0.524 │   0.524 │   0.524   │   0.000 │   0.281 │   0.281 │   0.281 │ 1            │            │ 1          │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Avg     │   0.524  │   0.524 │   0.524 │   0.524 │   0.524 │   0.524   │   0.000 │   0.281 │   0.281 │   0.281 │ 1            │            │ 1          │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ GAvg    │   0.524  │   0.524 │   0.524 │   0.524 │   0.524 │   0.524   │   0.000 │   0.281 │   0.281 │   0.001 │ 1            │            │ 1          │
└─────────┴──────────┴─────────┴─────────┴─────────┴─────────┴───────────┴─────────┴─────────┴─────────┴─────────┴──────────────┴────────────┴────────────┘

Results saved to /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_diff_processing/testing_out_stuff/test_diff_processing.py.TestTpcdsDiffProcessing.test_tpcds.CheckCanonicalPolicy.ERROR/q1.out
Json report saved to /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_diff_processing/testing_out_stuff/test_diff_processing.py.TestTpcdsDiffProcessing.test_tpcds.CheckCanonicalPolicy.ERROR/q1.json

2025-12-12 09:11:31,365 - DEBUG - ya.test - _save_outputs: Command (pid 1218370) errors:
(!) New version of YDB CLI is available. Run 'ydb update' command for update. You can also disable further version checks with 'ydb version --disable-checks' command

[K|Progress: 0 rows read, 0 B read.
[K
[K|Progress: 0 rows read, 0 B read.
[K/Progress: 0 rows read, 0 B read.
[KResult 0: incorrect lines count (0 in result, but 100 expected).

2025-12-12 09:11:31,373 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-12-12 09:11:31,425 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:31,432 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-12 09:11:31,448 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-12-12 09:11:31,453 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:31,465 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes HTTP/1.1" 200 776
2025-12-12 09:11:31,471 - INFO - ya.test - pytest_runtest_teardown: Test teardown
