2025-12-12 09:11:15,593 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 09:11:15,594 - INFO - ya.test - pytest_runtest_setup: test_tpcds[CheckCanonicalPolicy.WARNING]
2025-12-12 09:11:15,594 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 09:11:15,594 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-12 09:11:15,596 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:8039/1
2025-12-12 09:11:15,596 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14049/1
2025-12-12 09:11:15,602 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_diff_processing.py::TestTpcdsDiffProcessing, test_name: test_tpcds[CheckCanonicalPolicy.WARNING])
2025-12-12 09:11:15,605 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,609 - 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:15,619 - 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:15,625 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,628 - 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:15,639 - 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:15,641 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-12-12 09:11:15,642 - INFO - root - _get_tables: get_tables /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:11:15,642 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:11:15,643 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, e2bdaf95-afce-4ab0-a433-64bc42770b29, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:11:15,644 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, e2bdaf95-afce-4ab0-a433-64bc42770b29, 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:15,652 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, e2bdaf95-afce-4ab0-a433-64bc42770b29, 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:15,653 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:11:15,653 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, ee1d2ac0-7519-42a0-82bd-9061b84be34a, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:11:15,654 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, ee1d2ac0-7519-42a0-82bd-9061b84be34a, 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:15,663 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, ee1d2ac0-7519-42a0-82bd-9061b84be34a, 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:15,669 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,672 - 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:15,686 - 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:15,688 - 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:15,692 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,696 - 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:15,709 - 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 578
2025-12-12 09:11:15,713 - 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:15,717 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,721 - 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:15,738 - 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 578
2025-12-12 09:11:15,741 - 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:15,746 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,750 - 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:15,769 - 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 578
2025-12-12 09:11:15,771 - 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:15,777 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,781 - 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:15,809 - 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 579
2025-12-12 09:11:15,812 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/customer balance: 64-64 shards.
2025-12-12 09:11:15,817 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,830 - 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:15,844 - 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 579
2025-12-12 09:11:15,847 - 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:15,850 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,854 - 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:15,872 - 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 579
2025-12-12 09:11:15,875 - 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:15,879 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,883 - 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:15,900 - 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 579
2025-12-12 09:11:15,903 - 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:15,907 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,910 - 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:15,926 - 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 579
2025-12-12 09:11:15,930 - 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:15,934 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,944 - 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:15,970 - 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 579
2025-12-12 09:11:15,973 - 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:15,976 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,985 - 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:16,020 - 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 579
2025-12-12 09:11:16,023 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/inventory balance: 64-64 shards.
2025-12-12 09:11:16,027 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,032 - 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:16,046 - 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 579
2025-12-12 09:11:16,048 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/item balance: 64-64 shards.
2025-12-12 09:11:16,051 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,054 - 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:16,069 - 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 579
2025-12-12 09:11:16,072 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/promotion balance: 64-64 shards.
2025-12-12 09:11:16,075 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,078 - 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:16,094 - 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 579
2025-12-12 09:11:16,096 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/reason balance: 64-64 shards.
2025-12-12 09:11:16,102 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,106 - 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:16,119 - 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 579
2025-12-12 09:11:16,122 - 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:16,125 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,130 - 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:16,150 - 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 580
2025-12-12 09:11:16,153 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/store balance: 64-64 shards.
2025-12-12 09:11:16,156 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,164 - 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:16,177 - 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 580
2025-12-12 09:11:16,180 - 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:16,183 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,189 - 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:16,203 - 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 580
2025-12-12 09:11:16,205 - 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:16,208 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,212 - 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:16,230 - 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 580
2025-12-12 09:11:16,233 - 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:16,240 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,255 - 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:16,268 - 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 580
2025-12-12 09:11:16,270 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/warehouse balance: 64-64 shards.
2025-12-12 09:11:16,274 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,277 - 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:16,292 - 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 580
2025-12-12 09:11:16,294 - 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:16,297 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,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/web_returns&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:16,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/web_returns&tablets=true HTTP/1.1" 200 580
2025-12-12 09:11:16,314 - 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:16,317 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,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_sales&tablets=true HTTP/1.1" 307 0
2025-12-12 09:11:16,332 - 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 580
2025-12-12 09:11:16,334 - 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:16,337 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:16,340 - 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:16,351 - 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 581
2025-12-12 09:11:16,353 - 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:16,354 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, eb5944d6-3648-48eb-8873-c73fcc4ab49c, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:11:16,355 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, eb5944d6-3648-48eb-8873-c73fcc4ab49c, ghrun-7uza2v6l2u.auto.internal:14049): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-12 09:11:16,357 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, eb5944d6-3648-48eb-8873-c73fcc4ab49c, ghrun-7uza2v6l2u.auto.internal:14049): response = { <_MultiThreadedRendezvous object> }
2025-12-12 09:11:16,426 - 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:16,428 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-12-12 09:11:16,428 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-12-12 09:11:16,429 - 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.WARNING/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.WARNING/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.WARNING/q1.plan', '--global-timeout', '1800.0s', '--verbose', '--check-canonical', '--scale', '1']' in 'None' (waiting)
2025-12-12 09:11:16,430 - DEBUG - ya.test - execute: Command pid: 1216987
2025-12-12 09:11:23,566 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) rc: 1
2025-12-12 09:11:23,567 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) elapsed time (sec): 7.135243654251099
2025-12-12 09:11:23,567 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) idrss: 0
2025-12-12 09:11:23,567 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) inblock: 0
2025-12-12 09:11:23,568 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) isrss: 0
2025-12-12 09:11:23,568 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) ixrss: 0
2025-12-12 09:11:23,568 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) majflt: 0
2025-12-12 09:11:23,569 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) maxrss: 645292
2025-12-12 09:11:23,569 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) minflt: 188267
2025-12-12 09:11:23,569 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) msgrcv: 0
2025-12-12 09:11:23,570 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) msgsnd: 0
2025-12-12 09:11:23,570 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) nivcsw: 290
2025-12-12 09:11:23,570 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) nsignals: 0
2025-12-12 09:11:23,571 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) nswap: 0
2025-12-12 09:11:23,571 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) nvcsw: 2185
2025-12-12 09:11:23,571 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) oublock: 1848
2025-12-12 09:11:23,571 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) stime: 0.5717
2025-12-12 09:11:23,572 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) utime: 5.660802
2025-12-12 09:11:23,572 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) wtime: 7.137
2025-12-12 09:11:23,572 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) 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.841308s seconds

Results for 1 iterations
┌─────────┬──────────┬─────────┬─────────┬─────────┬─────────┬───────────┬─────────┬─────────┬─────────┬─────────┬──────────────┬────────────┬────────────┐
│ Query # │ ColdTime │ Min     │ Max     │ Mean    │ Median  │ UnixBench │ Std     │ RttMin  │ RttMax  │ RttAvg  │ SuccessCount │ FailsCount │ DiffsCount │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Query01 │   0.562  │   0.562 │   0.562 │   0.562 │   0.562 │   0.562   │   0.000 │   0.279 │   0.279 │   0.278 │ 1            │            │ 1          │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Sum     │   0.562  │   0.562 │   0.562 │   0.562 │   0.562 │   0.562   │   0.000 │   0.279 │   0.279 │   0.278 │ 1            │            │ 1          │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Avg     │   0.562  │   0.562 │   0.562 │   0.562 │   0.562 │   0.562   │   0.000 │   0.279 │   0.279 │   0.278 │ 1            │            │ 1          │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ GAvg    │   0.562  │   0.562 │   0.562 │   0.562 │   0.562 │   0.562   │   0.000 │   0.279 │   0.279 │   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.WARNING/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.WARNING/q1.json

2025-12-12 09:11:23,573 - DEBUG - ya.test - _save_outputs: Command (pid 1216987) 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:23,573 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-12-12 09:11:23,602 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,605 - 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,618 - 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,624 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:23,630 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes HTTP/1.1" 200 772
2025-12-12 09:11:23,636 - INFO - ya.test - pytest_runtest_teardown: Test teardown
