2025-10-26 15:56:03,918 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:56:03,918 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[plan-select_distinct.test]
2025-10-26 15:56:03,918 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:56:03,918 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:56:03,979 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/23888
2025-10-26 15:56:03,979 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/23889
2025-10-26 15:56:03,979 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:56:03,980 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:56:03,980 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/000772/r3tmp/kikimr__4txdomc1
2025-10-26 15:56:04,005 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/20776
2025-10-26 15:56:04,005 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/1267
2025-10-26 15:56:04,006 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/1268
2025-10-26 15:56:04,006 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/27070
2025-10-26 15:56:04,006 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd
2025-10-26 15:56:04,007 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/000772/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk4/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-select_distinct.test]/kikimr_configs"
2025-10-26 15:56:04,007 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/000772/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk4/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-select_distinct.test]/node_1/logfile_hhxm69je.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=20776 --mon-port=1268 --ic-port=23889
2025-10-26 15:56:04,007 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:20776/1, disk_size 68719476736
2025-10-26 15:56:04,008 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/000772/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk4/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-select_distinct.test]/node_1/logfile_hhxm69je.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/000772/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk4/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-select_distinct.test]/kikimr_configs/config.yaml', '--grpc-port=20776', '--mon-port=1268', '--ic-port=23889')' with input '/home/runner/.ya/build/build_root/muiu/000772/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk4/stdin' in '/home/runner/.ya/build/build_root/muiu/000772/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk4/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-select_distinct.test]/node_1' (no wait)
2025-10-26 15:56:04,009 - DEBUG - ya.test - execute: Command pid: 1608884
2025-10-26 15:56:04,009 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20776/1
2025-10-26 15:56:05,671 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd
2025-10-26 15:56:05,672 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20776', '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: 23889\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:56:05,673 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:56:05,673 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:56:05,673 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20776', '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: 23889\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:56:05,674 - DEBUG - ya.test - execute: Command pid: 1609047
2025-10-26 15:56:05,977 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) rc: 0
2025-10-26 15:56:05,978 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) elapsed time (sec): 0.30228257179260254
2025-10-26 15:56:05,978 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) idrss: 0
2025-10-26 15:56:05,978 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) inblock: 0
2025-10-26 15:56:05,978 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) isrss: 0
2025-10-26 15:56:05,978 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) ixrss: 0
2025-10-26 15:56:05,978 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) majflt: 0
2025-10-26 15:56:05,979 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) maxrss: 523192
2025-10-26 15:56:05,979 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) minflt: 19023
2025-10-26 15:56:05,979 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) msgrcv: 0
2025-10-26 15:56:05,979 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) msgsnd: 0
2025-10-26 15:56:05,979 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) nivcsw: 1
2025-10-26 15:56:05,979 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) nsignals: 0
2025-10-26 15:56:05,979 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) nswap: 0
2025-10-26 15:56:05,980 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) nvcsw: 127
2025-10-26 15:56:05,980 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) oublock: 8
2025-10-26 15:56:05,980 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) stime: 0.17801599999999998
2025-10-26 15:56:05,980 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) utime: 0.080916
2025-10-26 15:56:05,980 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) wtime: 0.303
2025-10-26 15:56:05,980 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:56:05,981 - DEBUG - ya.test - _save_outputs: Command (pid 1609047) errors:

2025-10-26 15:56:05,982 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd
2025-10-26 15:56:05,982 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20776', '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-10-26 15:56:05,983 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:56:05,983 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:56:05,984 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000772/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20776', '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-10-26 15:56:05,984 - DEBUG - ya.test - execute: Command pid: 1609087
2025-10-26 15:56:06,325 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) rc: 0
2025-10-26 15:56:06,326 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) elapsed time (sec): 0.3404395580291748
2025-10-26 15:56:06,326 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) idrss: 0
2025-10-26 15:56:06,326 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) inblock: 0
2025-10-26 15:56:06,326 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) isrss: 0
2025-10-26 15:56:06,326 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) ixrss: 0
2025-10-26 15:56:06,326 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) majflt: 0
2025-10-26 15:56:06,326 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) maxrss: 523196
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) minflt: 19022
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) msgrcv: 0
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) msgsnd: 0
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) nivcsw: 2
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) nsignals: 0
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) nswap: 0
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) nvcsw: 135
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) oublock: 8
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) stime: 0.20351999999999998
2025-10-26 15:56:06,327 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) utime: 0.09769
2025-10-26 15:56:06,328 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) wtime: 0.341
2025-10-26 15:56:06,328 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 15:56:06,328 - DEBUG - ya.test - _save_outputs: Command (pid 1609087) errors:

2025-10-26 15:56:06,340 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:56:06,364 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_postgres.py::TestPGSQL, test_name: test_sql_suite[plan-select_distinct.test])
2025-10-26 15:56:06,455 - INFO - root - assert_statement: Executed statement at line 5, suite from postgres/select_distinct.test, in 0.090 seconds
2025-10-26 15:56:15,758 - INFO - root - assert_statement: Executed statement at line 26, suite from postgres/select_distinct.test, in 9.302 seconds
2025-10-26 15:56:15,795 - INFO - root - assert_statement: Executed statement at line 29, suite from postgres/select_distinct.test, in 0.037 seconds
2025-10-26 15:57:53,211 - INFO - root - assert_statement: Executed statement at line 50, suite from postgres/select_distinct.test, in 97.415 seconds
2025-10-26 15:57:53,237 - INFO - root - assert_statement: Executed statement at line 54, suite from postgres/select_distinct.test, in 0.025 seconds
2025-10-26 15:57:53,256 - INFO - root - assert_statement: Executed statement at line 57, suite from postgres/select_distinct.test, in 0.019 seconds
2025-10-26 15:57:53,276 - INFO - root - assert_statement: Executed statement at line 60, suite from postgres/select_distinct.test, in 0.019 seconds
2025-10-26 15:57:53,302 - INFO - root - assert_statement: Executed statement at line 63, suite from postgres/select_distinct.test, in 0.025 seconds
2025-10-26 15:57:53,350 - INFO - root - assert_statement: Executed statement at line 93, suite from postgres/select_distinct.test, in 0.048 seconds
2025-10-26 15:57:53,383 - INFO - root - assert_statement: Executed statement at line 97, suite from postgres/select_distinct.test, in 0.033 seconds
2025-10-26 15:57:53,406 - INFO - root - assert_statement: Executed statement at line 100, suite from postgres/select_distinct.test, in 0.022 seconds
2025-10-26 15:57:53,432 - INFO - root - assert_statement: Executed statement at line 103, suite from postgres/select_distinct.test, in 0.026 seconds
2025-10-26 15:57:53,458 - INFO - root - assert_statement: Executed statement at line 106, suite from postgres/select_distinct.test, in 0.025 seconds
2025-10-26 15:57:53,481 - INFO - root - assert_statement: Executed statement at line 109, suite from postgres/select_distinct.test, in 0.023 seconds
2025-10-26 15:57:53,482 - INFO - root - assert_statement: Executed statement at line 112, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,482 - INFO - root - assert_statement: Executed statement at line 122, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,482 - INFO - root - assert_statement: Executed statement at line 131, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,482 - INFO - root - assert_statement: Executed statement at line 140, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,482 - INFO - root - assert_statement: Executed statement at line 150, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,483 - INFO - root - assert_statement: Executed statement at line 156, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,483 - INFO - root - assert_statement: Executed statement at line 162, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,483 - INFO - root - assert_statement: Executed statement at line 168, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,483 - INFO - root - assert_statement: Executed statement at line 174, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,483 - INFO - root - assert_statement: Executed statement at line 180, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,484 - INFO - root - assert_statement: Executed statement at line 186, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,484 - INFO - root - assert_statement: Executed statement at line 192, suite from postgres/select_distinct.test, in 0.000 seconds
2025-10-26 15:57:53,486 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:57:58,017 - DEBUG - ya.test - _verify_test: Verifying [test_postgres.TestPGSQL.test_sql_suite[plan-select_distinct.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:57:58,062 - DEBUG - ya.test - verify: Test test_postgres.TestPGSQL.test_sql_suite[plan-select_distinct.test] status: good
