2025-10-26 15:54:05,924 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:54:05,924 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[plan-jointest/join3.test]
2025-10-26 15:54:05,924 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:54:05,924 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:54:05,969 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/28570
2025-10-26 15:54:05,969 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:54:05,969 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:54:05,970 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/000776/r3tmp/kikimr___coqg1vr
2025-10-26 15:54:05,994 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/26552
2025-10-26 15:54:05,994 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/4326
2025-10-26 15:54:05,995 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/12907
2025-10-26 15:54:05,995 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd
2025-10-26 15:54:05,995 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/000776/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk3/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join3.test]/kikimr_configs"
2025-10-26 15:54:05,995 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/000776/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk3/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join3.test]/node_1/logfile_snwmxjsa.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=26552 --mon-port=4326 --ic-port=28570
2025-10-26 15:54:05,996 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:26552/1, disk_size 68719476736
2025-10-26 15:54:05,996 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/000776/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk3/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join3.test]/node_1/logfile_snwmxjsa.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/000776/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk3/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join3.test]/kikimr_configs/config.yaml', '--grpc-port=26552', '--mon-port=4326', '--ic-port=28570')' with input '/home/runner/.ya/build/build_root/muiu/000776/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk3/stdin' in '/home/runner/.ya/build/build_root/muiu/000776/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk3/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join3.test]/node_1' (no wait)
2025-10-26 15:54:05,997 - DEBUG - ya.test - execute: Command pid: 1591008
2025-10-26 15:54:05,998 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:26552/1
2025-10-26 15:54:07,723 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd
2025-10-26 15:54:07,724 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:26552', '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: 28570\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:54:07,724 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:54:07,725 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:54:07,725 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:26552', '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: 28570\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:54:07,726 - DEBUG - ya.test - execute: Command pid: 1591386
2025-10-26 15:54:08,020 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) rc: 0
2025-10-26 15:54:08,020 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) elapsed time (sec): 0.2936129570007324
2025-10-26 15:54:08,021 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) idrss: 0
2025-10-26 15:54:08,021 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) inblock: 0
2025-10-26 15:54:08,021 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) isrss: 0
2025-10-26 15:54:08,021 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) ixrss: 0
2025-10-26 15:54:08,021 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) majflt: 0
2025-10-26 15:54:08,021 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) maxrss: 523212
2025-10-26 15:54:08,022 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) minflt: 19024
2025-10-26 15:54:08,022 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) msgrcv: 0
2025-10-26 15:54:08,022 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) msgsnd: 0
2025-10-26 15:54:08,022 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) nivcsw: 0
2025-10-26 15:54:08,022 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) nsignals: 0
2025-10-26 15:54:08,022 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) nswap: 0
2025-10-26 15:54:08,022 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) nvcsw: 132
2025-10-26 15:54:08,023 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) oublock: 8
2025-10-26 15:54:08,023 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) stime: 0.15625799999999998
2025-10-26 15:54:08,023 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) utime: 0.094577
2025-10-26 15:54:08,023 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) wtime: 0.295
2025-10-26 15:54:08,023 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:54:08,023 - DEBUG - ya.test - _save_outputs: Command (pid 1591386) errors:

2025-10-26 15:54:08,024 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd
2025-10-26 15:54:08,024 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:26552', '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:54:08,025 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:54:08,026 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:54:08,026 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000776/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:26552', '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:54:08,027 - DEBUG - ya.test - execute: Command pid: 1591425
2025-10-26 15:54:08,318 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) rc: 0
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) elapsed time (sec): 0.29128360748291016
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) idrss: 0
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) inblock: 0
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) isrss: 0
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) ixrss: 0
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) majflt: 0
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) maxrss: 523196
2025-10-26 15:54:08,319 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) minflt: 19021
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) msgrcv: 0
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) msgsnd: 0
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) nivcsw: 0
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) nsignals: 0
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) nswap: 0
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) nvcsw: 130
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) oublock: 8
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) stime: 0.171017
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) utime: 0.073293
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) wtime: 0.292
2025-10-26 15:54:08,320 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 15:54:08,321 - DEBUG - ya.test - _save_outputs: Command (pid 1591425) errors:

2025-10-26 15:54:08,332 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:54:08,351 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_postgres.py::TestPGSQL, test_name: test_sql_suite[plan-jointest/join3.test])
2025-10-26 15:54:08,483 - INFO - root - assert_statement: Executed statement at line 4, suite from postgres/jointest/join3.test, in 0.131 seconds
2025-10-26 15:54:08,505 - INFO - root - assert_statement: Executed statement at line 7, suite from postgres/jointest/join3.test, in 0.022 seconds
2025-10-26 15:54:08,525 - INFO - root - assert_statement: Executed statement at line 10, suite from postgres/jointest/join3.test, in 0.020 seconds
2025-10-26 15:54:08,550 - INFO - root - assert_statement: Executed statement at line 13, suite from postgres/jointest/join3.test, in 0.024 seconds
2025-10-26 15:54:08,567 - INFO - root - assert_statement: Executed statement at line 16, suite from postgres/jointest/join3.test, in 0.017 seconds
2025-10-26 15:54:08,586 - INFO - root - assert_statement: Executed statement at line 19, suite from postgres/jointest/join3.test, in 0.018 seconds
2025-10-26 15:54:08,621 - INFO - root - assert_statement: Executed statement at line 29, suite from postgres/jointest/join3.test, in 0.035 seconds
2025-10-26 15:54:08,693 - INFO - root - assert_statement: Executed statement at line 36, suite from postgres/jointest/join3.test, in 0.072 seconds
2025-10-26 15:54:08,721 - INFO - root - assert_statement: Executed statement at line 40, suite from postgres/jointest/join3.test, in 0.027 seconds
2025-10-26 15:54:08,746 - INFO - root - assert_statement: Executed statement at line 43, suite from postgres/jointest/join3.test, in 0.025 seconds
2025-10-26 15:54:08,791 - INFO - root - assert_statement: Executed statement at line 46, suite from postgres/jointest/join3.test, in 0.045 seconds
2025-10-26 15:54:08,836 - INFO - root - assert_statement: Executed statement at line 50, suite from postgres/jointest/join3.test, in 0.045 seconds
2025-10-26 15:54:08,892 - INFO - root - assert_statement: Executed statement at line 55, suite from postgres/jointest/join3.test, in 0.056 seconds
2025-10-26 15:54:08,983 - INFO - root - assert_statement: Executed statement at line 60, suite from postgres/jointest/join3.test, in 0.090 seconds
2025-10-26 15:54:09,043 - INFO - root - assert_statement: Executed statement at line 66, suite from postgres/jointest/join3.test, in 0.060 seconds
2025-10-26 15:54:09,068 - INFO - root - assert_statement: Executed statement at line 71, suite from postgres/jointest/join3.test, in 0.025 seconds
2025-10-26 15:54:09,094 - INFO - root - assert_statement: Executed statement at line 74, suite from postgres/jointest/join3.test, in 0.025 seconds
2025-10-26 15:54:09,149 - INFO - root - assert_statement: Executed statement at line 77, suite from postgres/jointest/join3.test, in 0.055 seconds
2025-10-26 15:54:09,199 - INFO - root - assert_statement: Executed statement at line 80, suite from postgres/jointest/join3.test, in 0.050 seconds
2025-10-26 15:54:09,253 - INFO - root - assert_statement: Executed statement at line 83, suite from postgres/jointest/join3.test, in 0.053 seconds
2025-10-26 15:54:09,255 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:54:24,304 - DEBUG - ya.test - _verify_test: Verifying [test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join3.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:54:24,335 - DEBUG - ya.test - verify: Test test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join3.test] status: good
