2025-12-12 09:09:38,370 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 09:09:38,370 - INFO - ya.test - pytest_runtest_setup: test_tpcds[CheckCanonicalPolicy.NO]
2025-12-12 09:09:38,370 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 09:09:38,371 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-12 09:09:38,467 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd
2025-12-12 09:09:38,473 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10048
2025-12-12 09:09:38,475 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/yq5c/0003dd/r3tmp/kikimr_cluster_cbqm684r
2025-12-12 09:09:38,511 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd
2025-12-12 09:09:38,512 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8039
2025-12-12 09:09:38,513 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13587
2025-12-12 09:09:38,514 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10649
2025-12-12 09:09:38,515 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8113
2025-12-12 09:09:38,516 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/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.NO/cluster/kikimr_configs"
2025-12-12 09:09:38,516 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/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.NO/cluster/node_1/logfile_amq56z04.log --grpc-port=8039 --mon-port=13587 --ic-port=10048
2025-12-12 09:09:38,517 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:8039/1, disk_size 68719476736
2025-12-12 09:09:38,522 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/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.NO/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/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.NO/cluster/node_1/logfile_amq56z04.log', '--grpc-port=8039', '--mon-port=13587', '--ic-port=10048')' in '/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.NO/cluster/node_1' (no wait)
2025-12-12 09:09:38,537 - DEBUG - ya.test - execute: Command pid: 1203553
2025-12-12 09:09:38,538 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:8039/1
2025-12-12 09:09:43,215 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd
2025-12-12 09:09:43,215 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:8039', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 10048\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-12 09:09:43,219 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-12 09:09:43,222 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-12 09:09:43,223 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:8039', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 10048\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-12 09:09:43,225 - DEBUG - ya.test - execute: Command pid: 1204540
2025-12-12 09:09:44,069 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) rc: 0
2025-12-12 09:09:44,070 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) elapsed time (sec): 0.8436522483825684
2025-12-12 09:09:44,070 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) idrss: 0
2025-12-12 09:09:44,071 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) inblock: 0
2025-12-12 09:09:44,071 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) isrss: 0
2025-12-12 09:09:44,071 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) ixrss: 0
2025-12-12 09:09:44,071 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) majflt: 0
2025-12-12 09:09:44,072 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) maxrss: 643156
2025-12-12 09:09:44,072 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) minflt: 42337
2025-12-12 09:09:44,072 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) msgrcv: 0
2025-12-12 09:09:44,073 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) msgsnd: 0
2025-12-12 09:09:44,073 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) nivcsw: 167
2025-12-12 09:09:44,073 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) nsignals: 0
2025-12-12 09:09:44,074 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) nswap: 0
2025-12-12 09:09:44,074 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) nvcsw: 907
2025-12-12 09:09:44,074 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) oublock: 8
2025-12-12 09:09:44,075 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) stime: 0.230233
2025-12-12 09:09:44,075 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) utime: 0.472867
2025-12-12 09:09:44,075 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) wtime: 0.845
2025-12-12 09:09:44,076 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-12 09:09:44,076 - DEBUG - ya.test - _save_outputs: Command (pid 1204540) errors:

2025-12-12 09:09:44,078 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd
2025-12-12 09:09:44,078 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:8039', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-12 09:09:44,080 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-12 09:09:44,080 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-12 09:09:44,081 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:8039', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-12 09:09:44,083 - DEBUG - ya.test - execute: Command pid: 1204742
2025-12-12 09:09:44,865 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) rc: 0
2025-12-12 09:09:44,866 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) elapsed time (sec): 0.7811975479125977
2025-12-12 09:09:44,866 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) idrss: 0
2025-12-12 09:09:44,866 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) inblock: 0
2025-12-12 09:09:44,867 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) isrss: 0
2025-12-12 09:09:44,867 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) ixrss: 0
2025-12-12 09:09:44,867 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) majflt: 0
2025-12-12 09:09:44,868 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) maxrss: 643156
2025-12-12 09:09:44,868 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) minflt: 42206
2025-12-12 09:09:44,868 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) msgrcv: 0
2025-12-12 09:09:44,868 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) msgsnd: 0
2025-12-12 09:09:44,869 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) nivcsw: 113
2025-12-12 09:09:44,869 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) nsignals: 0
2025-12-12 09:09:44,869 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) nswap: 0
2025-12-12 09:09:44,870 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) nvcsw: 1220
2025-12-12 09:09:44,870 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) oublock: 8
2025-12-12 09:09:44,870 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) stime: 0.24487499999999998
2025-12-12 09:09:44,870 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) utime: 0.434805
2025-12-12 09:09:44,871 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) wtime: 0.783
2025-12-12 09:09:44,871 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-12 09:09:44,871 - DEBUG - ya.test - _save_outputs: Command (pid 1204742) errors:

2025-12-12 09:09:44,962 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-12 09:09:45,584 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd
2025-12-12 09:09:45,585 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14049
2025-12-12 09:09:45,586 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1143
2025-12-12 09:09:45,587 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5176
2025-12-12 09:09:45,588 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26343
2025-12-12 09:09:45,589 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61048
2025-12-12 09:09:45,591 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/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.NO/cluster/kikimr_configs"
2025-12-12 09:09:45,592 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8039 --tenant=/local/test_db --log-file-name=/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.NO/cluster/slot_1/logfile_w8pd8u1x.log --grpc-port=14049 --mon-port=1143 --ic-port=5176
2025-12-12 09:09:45,594 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/yq5c/0003dd/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/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.NO/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:8039', '--tenant=/local/test_db', '--log-file-name=/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.NO/cluster/slot_1/logfile_w8pd8u1x.log', '--grpc-port=14049', '--mon-port=1143', '--ic-port=5176')' in '/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.NO/cluster/slot_1' (no wait)
2025-12-12 09:09:45,611 - DEBUG - ya.test - execute: Command pid: 1205143
2025-12-12 09:09:45,618 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14049/1
2025-12-12 09:09:51,656 - 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:09:51,659 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-12-12 09:09:51,661 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-12-12 09:09:51,661 - 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', '-p', 'olap_yatests/tpcds/s1', 'init', '--store=column']' in 'None' (waiting)
2025-12-12 09:09:51,664 - DEBUG - ya.test - execute: Command pid: 1206488
2025-12-12 09:10:52,796 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 09:10:52,797 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-12-12 09:10:52,829 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-12-12 09:10:52,830 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 0a2a60e9-eadb-4b6a-9880-56d69a8a02e4, localhost:24532): creating call state
2025-12-12 09:10:52,831 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 0a2a60e9-eadb-4b6a-9880-56d69a8a02e4, localhost:24532): request = { database: "local/test_db" }
2025-12-12 09:10:52,841 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 0a2a60e9-eadb-4b6a-9880-56d69a8a02e4, localhost:24532): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7uza2v6l2u.auto.internal" port: 29396 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" node_id: 50000 } self_location: "1" } } } }
2025-12-12 09:10:52,842 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7uza2v6l2u.auto.internal:29396, location , ssl: False>]>
2025-12-12 09:10:52,842 - INFO - ydb.connection - close: Closing channel for endpoint localhost:24532
2025-12-12 09:10:57,838 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) rc: 0
2025-12-12 09:10:57,839 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) elapsed time (sec): 66.17386889457703
2025-12-12 09:10:57,839 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) idrss: 0
2025-12-12 09:10:57,840 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) inblock: 0
2025-12-12 09:10:57,840 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) isrss: 0
2025-12-12 09:10:57,840 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) ixrss: 0
2025-12-12 09:10:57,840 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) majflt: 0
2025-12-12 09:10:57,841 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) maxrss: 645292
2025-12-12 09:10:57,841 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) minflt: 105495
2025-12-12 09:10:57,841 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) msgrcv: 0
2025-12-12 09:10:57,842 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) msgsnd: 0
2025-12-12 09:10:57,842 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) nivcsw: 156
2025-12-12 09:10:57,842 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) nsignals: 0
2025-12-12 09:10:57,843 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) nswap: 0
2025-12-12 09:10:57,843 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) nvcsw: 5067
2025-12-12 09:10:57,843 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) oublock: 24
2025-12-12 09:10:57,843 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) stime: 0.26832
2025-12-12 09:10:57,844 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) utime: 0.443675
2025-12-12 09:10:57,844 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) wtime: 66.176
2025-12-12 09:10:57,844 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) output:
Init tables ...
Init tables ...Ok

2025-12-12 09:10:57,845 - DEBUG - ya.test - _save_outputs: Command (pid 1206488) 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

2025-12-12 09:10:57,845 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-12-12 09:10:57,850 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:57,864 - 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:10:58,009 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-12-12 09:10:58,012 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-12-12 09:10:58,037 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:8039 to local/test_db ydb_access_token is set False
2025-12-12 09:10:58,038 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-12-12 09:10:58,050 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 09:10:58,050 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-12-12 09:10:58,075 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-12-12 09:10:58,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 1b852671-8e8f-44f8-835c-5690351bfe48, localhost:8039): creating call state
2025-12-12 09:10:58,077 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 1b852671-8e8f-44f8-835c-5690351bfe48, localhost:8039): request = { database: "local/test_db" }
2025-12-12 09:10:58,085 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 1b852671-8e8f-44f8-835c-5690351bfe48, localhost:8039): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7uza2v6l2u.auto.internal" port: 14049 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" node_id: 50000 } self_location: "1" } } } }
2025-12-12 09:10:58,085 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7uza2v6l2u.auto.internal:14049, location , ssl: False>]>
2025-12-12 09:10:58,088 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-12-12 09:10:58,098 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8039
2025-12-12 09:10:58,102 - INFO - root - _create_ydb_driver: Connected
2025-12-12 09:10:58,104 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, ecf05c69-9db3-4e0b-b310-fdb332cd7c0b, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:10:58,104 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, ecf05c69-9db3-4e0b-b310-fdb332cd7c0b, ghrun-7uza2v6l2u.auto.internal:14049): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-12 09:10:58,114 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, ecf05c69-9db3-4e0b-b310-fdb332cd7c0b, ghrun-7uza2v6l2u.auto.internal:14049): response = { <_MultiThreadedRendezvous object> }
2025-12-12 09:10:58,243 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,270 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes HTTP/1.1" 200 771
2025-12-12 09:10:58,283 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,310 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes HTTP/1.1" 200 771
2025-12-12 09:10:58,319 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:8039/1
2025-12-12 09:10:58,321 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14049/1
2025-12-12 09:10:58,336 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_diff_processing.py::TestTpcdsDiffProcessing, test_name: test_tpcds[CheckCanonicalPolicy.NO])
2025-12-12 09:10:58,339 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,345 - 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:10:58,362 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-12-12 09:10:58,368 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,378 - 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:10:58,397 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-12-12 09:10:58,400 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-12-12 09:10:58,400 - INFO - root - _get_tables: get_tables /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:10:58,400 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:10:58,401 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, f53109f0-c73b-4e59-b30e-0dac64a3bd11, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:10:58,402 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, f53109f0-c73b-4e59-b30e-0dac64a3bd11, 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:10:58,415 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, f53109f0-c73b-4e59-b30e-0dac64a3bd11, 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:10:58,417 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpcds/s1
2025-12-12 09:10:58,417 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 397600e6-039b-4ec4-8350-1d2b0fa46e46, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:10:58,418 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 397600e6-039b-4ec4-8350-1d2b0fa46e46, 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:10:58,430 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 397600e6-039b-4ec4-8350-1d2b0fa46e46, 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:10:58,436 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,441 - 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:10:58,466 - 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 577
2025-12-12 09:10:58,470 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/call_center balance: 64-64 shards.
2025-12-12 09:10:58,478 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,483 - 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:10:58,500 - 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 577
2025-12-12 09:10:58,503 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/catalog_page balance: 64-64 shards.
2025-12-12 09:10:58,507 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,510 - 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:10:58,610 - 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 577
2025-12-12 09:10:58,612 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/catalog_returns balance: 64-64 shards.
2025-12-12 09:10:58,616 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,620 - 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:10:58,655 - 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 577
2025-12-12 09:10:58,658 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/catalog_sales balance: 64-64 shards.
2025-12-12 09:10:58,663 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,667 - 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:10:58,700 - 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 577
2025-12-12 09:10:58,702 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/customer balance: 64-64 shards.
2025-12-12 09:10:58,707 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,711 - 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:10:58,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/customer_address&tablets=true HTTP/1.1" 200 577
2025-12-12 09:10:58,771 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/customer_address balance: 64-64 shards.
2025-12-12 09:10:58,786 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,792 - 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:10:58,817 - 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 578
2025-12-12 09:10:58,820 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/customer_demographics balance: 64-64 shards.
2025-12-12 09:10:58,824 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,828 - 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:10:58,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/date_dim&tablets=true HTTP/1.1" 200 578
2025-12-12 09:10:58,844 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/date_dim balance: 64-64 shards.
2025-12-12 09:10:58,854 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,859 - 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:10:58,875 - 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 578
2025-12-12 09:10:58,878 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/household_demographics balance: 64-64 shards.
2025-12-12 09:10:58,883 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,887 - 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:10:58,915 - 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 578
2025-12-12 09:10:58,922 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/income_band balance: 64-64 shards.
2025-12-12 09:10:58,926 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,930 - 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:10:58,946 - 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 578
2025-12-12 09:10:58,950 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/inventory balance: 64-64 shards.
2025-12-12 09:10:58,956 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:58,972 - 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:10:59,004 - 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 577
2025-12-12 09:10:59,007 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/item balance: 64-64 shards.
2025-12-12 09:10:59,011 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,015 - 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:10:59,030 - 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 577
2025-12-12 09:10:59,033 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/promotion balance: 64-64 shards.
2025-12-12 09:10:59,042 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,050 - 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:10:59,077 - 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 577
2025-12-12 09:10:59,080 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/reason balance: 64-64 shards.
2025-12-12 09:10:59,089 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,095 - 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:10:59,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/ship_mode&tablets=true HTTP/1.1" 200 577
2025-12-12 09:10:59,113 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/ship_mode balance: 64-64 shards.
2025-12-12 09:10:59,117 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,121 - 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:10:59,136 - 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 577
2025-12-12 09:10:59,139 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/store balance: 64-64 shards.
2025-12-12 09:10:59,142 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,146 - 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:10:59,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/store_returns&tablets=true HTTP/1.1" 200 577
2025-12-12 09:10:59,162 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/store_returns balance: 64-64 shards.
2025-12-12 09:10:59,165 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,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/store_sales&tablets=true HTTP/1.1" 307 0
2025-12-12 09:10:59,182 - 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 577
2025-12-12 09:10:59,184 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/store_sales balance: 64-64 shards.
2025-12-12 09:10:59,188 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,194 - 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:10:59,210 - 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 577
2025-12-12 09:10:59,213 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/time_dim balance: 64-64 shards.
2025-12-12 09:10:59,217 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,221 - 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:10:59,235 - 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 577
2025-12-12 09:10:59,238 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/warehouse balance: 64-64 shards.
2025-12-12 09:10:59,242 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,245 - 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:10:59,260 - 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 577
2025-12-12 09:10:59,262 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_page balance: 64-64 shards.
2025-12-12 09:10:59,266 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,269 - 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:10:59,286 - 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 577
2025-12-12 09:10:59,289 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_returns balance: 64-64 shards.
2025-12-12 09:10:59,292 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,296 - 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:10:59,308 - 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 577
2025-12-12 09:10:59,311 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_sales balance: 64-64 shards.
2025-12-12 09:10:59,314 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:10:59,326 - 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:10:59,341 - 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 577
2025-12-12 09:10:59,344 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpcds/s1/web_site balance: 64-64 shards.
2025-12-12 09:10:59,345 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 986be5a9-2cb8-41ca-b2ac-1cc6a33e9756, ghrun-7uza2v6l2u.auto.internal:14049): creating call state
2025-12-12 09:10:59,346 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 986be5a9-2cb8-41ca-b2ac-1cc6a33e9756, ghrun-7uza2v6l2u.auto.internal:14049): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-12 09:10:59,349 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 986be5a9-2cb8-41ca-b2ac-1cc6a33e9756, ghrun-7uza2v6l2u.auto.internal:14049): response = { <_MultiThreadedRendezvous object> }
2025-12-12 09:10:59,405 - 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:10:59,407 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-12-12 09:10:59,408 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-12-12 09:10:59,409 - 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.NO/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.NO/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.NO/q1.plan', '--global-timeout', '1800.0s', '--verbose', '--scale', '1']' in 'None' (waiting)
2025-12-12 09:10:59,416 - DEBUG - ya.test - execute: Command pid: 1215295
2025-12-12 09:11:15,477 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) rc: 0
2025-12-12 09:11:15,478 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) elapsed time (sec): 16.060280561447144
2025-12-12 09:11:15,478 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) idrss: 0
2025-12-12 09:11:15,479 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) inblock: 0
2025-12-12 09:11:15,479 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) isrss: 0
2025-12-12 09:11:15,479 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) ixrss: 0
2025-12-12 09:11:15,480 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) majflt: 0
2025-12-12 09:11:15,480 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) maxrss: 645292
2025-12-12 09:11:15,480 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) minflt: 185564
2025-12-12 09:11:15,481 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) msgrcv: 0
2025-12-12 09:11:15,481 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) msgsnd: 0
2025-12-12 09:11:15,482 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) nivcsw: 229
2025-12-12 09:11:15,482 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) nsignals: 0
2025-12-12 09:11:15,482 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) nswap: 0
2025-12-12 09:11:15,482 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) nvcsw: 2178
2025-12-12 09:11:15,483 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) oublock: 1848
2025-12-12 09:11:15,483 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) stime: 0.5991949999999999
2025-12-12 09:11:15,483 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) utime: 5.318973
2025-12-12 09:11:15,483 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) wtime: 16.063
2025-12-12 09:11:15,484 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) 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	5.541470s seconds

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

2025-12-12 09:11:15,484 - DEBUG - ya.test - _save_outputs: Command (pid 1215295) 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.
[K
2025-12-12 09:11:15,485 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-12-12 09:11:15,548 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,552 - 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,563 - 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,569 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:13587
2025-12-12 09:11:15,576 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:13587 "GET /viewer/json/nodes HTTP/1.1" 200 775
2025-12-12 09:11:15,585 - INFO - ya.test - pytest_runtest_teardown: Test teardown
