2025-10-26 15:50:21,980 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:50:21,980 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[results-jointest/join3.test]
2025-10-26 15:50:21,980 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:50:21,980 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:50:22,029 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/26530
2025-10-26 15:50:22,029 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:50:22,029 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:50:22,030 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/000789/r3tmp/kikimr__w4ept39c
2025-10-26 15:50:22,055 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/4931
2025-10-26 15:50:22,055 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/2146
2025-10-26 15:50:22,056 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/24186
2025-10-26 15:50:22,056 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd
2025-10-26 15:50:22,057 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/000789/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk8/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join3.test]/kikimr_configs"
2025-10-26 15:50:22,057 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/000789/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk8/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join3.test]/node_1/logfile_czqpmiam.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=4931 --mon-port=2146 --ic-port=26530
2025-10-26 15:50:22,057 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:4931/1, disk_size 68719476736
2025-10-26 15:50:22,057 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/000789/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk8/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join3.test]/node_1/logfile_czqpmiam.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/000789/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk8/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join3.test]/kikimr_configs/config.yaml', '--grpc-port=4931', '--mon-port=2146', '--ic-port=26530')' with input '/home/runner/.ya/build/build_root/muiu/000789/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk8/stdin' in '/home/runner/.ya/build/build_root/muiu/000789/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk8/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join3.test]/node_1' (no wait)
2025-10-26 15:50:22,058 - DEBUG - ya.test - execute: Command pid: 1558503
2025-10-26 15:50:22,059 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:4931/1
2025-10-26 15:50:23,665 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd
2025-10-26 15:50:23,666 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:4931', '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: 26530\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:50:23,666 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:50:23,667 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:50:23,667 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:4931', '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: 26530\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:50:23,668 - DEBUG - ya.test - execute: Command pid: 1558910
2025-10-26 15:50:23,963 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) rc: 0
2025-10-26 15:50:23,964 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) elapsed time (sec): 0.29529356956481934
2025-10-26 15:50:23,964 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) idrss: 0
2025-10-26 15:50:23,964 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) inblock: 0
2025-10-26 15:50:23,964 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) isrss: 0
2025-10-26 15:50:23,964 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) ixrss: 0
2025-10-26 15:50:23,964 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) majflt: 0
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) maxrss: 523204
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) minflt: 19022
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) msgrcv: 0
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) msgsnd: 0
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) nivcsw: 1
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) nsignals: 0
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) nswap: 0
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) nvcsw: 133
2025-10-26 15:50:23,965 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) oublock: 8
2025-10-26 15:50:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) stime: 0.18950399999999998
2025-10-26 15:50:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) utime: 0.061793999999999995
2025-10-26 15:50:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) wtime: 0.296
2025-10-26 15:50:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:50:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 1558910) errors:

2025-10-26 15:50:23,967 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd
2025-10-26 15:50:23,967 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:4931', '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:50:23,968 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:50:23,968 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:50:23,968 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000789/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:4931', '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:50:23,969 - DEBUG - ya.test - execute: Command pid: 1559004
2025-10-26 15:50:24,272 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) rc: 0
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) elapsed time (sec): 0.30286669731140137
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) idrss: 0
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) inblock: 0
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) isrss: 0
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) ixrss: 0
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) majflt: 0
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) maxrss: 523192
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) minflt: 19023
2025-10-26 15:50:24,273 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) msgrcv: 0
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) msgsnd: 0
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) nivcsw: 0
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) nsignals: 0
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) nswap: 0
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) nvcsw: 135
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) oublock: 8
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) stime: 0.17451799999999998
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) utime: 0.08531899999999999
2025-10-26 15:50:24,274 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) wtime: 0.304
2025-10-26 15:50:24,275 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 15:50:24,275 - DEBUG - ya.test - _save_outputs: Command (pid 1559004) errors:

2025-10-26 15:50:24,290 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:50:24,310 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_postgres.py::TestPGSQL, test_name: test_sql_suite[results-jointest/join3.test])
2025-10-26 15:50:24,393 - INFO - root - assert_statement: Executed statement at line 4, suite from postgres/jointest/join3.test, in 0.081 seconds
2025-10-26 15:50:24,416 - INFO - root - assert_statement: Executed statement at line 7, suite from postgres/jointest/join3.test, in 0.023 seconds
2025-10-26 15:50:24,438 - INFO - root - assert_statement: Executed statement at line 10, suite from postgres/jointest/join3.test, in 0.021 seconds
2025-10-26 15:50:24,459 - INFO - root - assert_statement: Executed statement at line 13, suite from postgres/jointest/join3.test, in 0.021 seconds
2025-10-26 15:50:24,480 - INFO - root - assert_statement: Executed statement at line 16, suite from postgres/jointest/join3.test, in 0.021 seconds
2025-10-26 15:50:24,503 - INFO - root - assert_statement: Executed statement at line 19, suite from postgres/jointest/join3.test, in 0.023 seconds
2025-10-26 15:50:24,585 - INFO - root - assert_statement: Executed statement at line 29, suite from postgres/jointest/join3.test, in 0.082 seconds
2025-10-26 15:50:24,643 - INFO - root - assert_statement: Executed statement at line 36, suite from postgres/jointest/join3.test, in 0.058 seconds
2025-10-26 15:50:24,667 - INFO - root - assert_statement: Executed statement at line 40, suite from postgres/jointest/join3.test, in 0.023 seconds
2025-10-26 15:50:24,688 - INFO - root - assert_statement: Executed statement at line 43, suite from postgres/jointest/join3.test, in 0.021 seconds
2025-10-26 15:50:24,760 - INFO - root - assert_statement: Executed statement at line 46, suite from postgres/jointest/join3.test, in 0.071 seconds
2025-10-26 15:50:24,838 - INFO - root - assert_statement: Executed statement at line 50, suite from postgres/jointest/join3.test, in 0.078 seconds
2025-10-26 15:50:24,934 - INFO - root - assert_statement: Executed statement at line 55, suite from postgres/jointest/join3.test, in 0.096 seconds
2025-10-26 15:50:25,077 - INFO - root - assert_statement: Executed statement at line 60, suite from postgres/jointest/join3.test, in 0.142 seconds
2025-10-26 15:50:25,133 - INFO - root - assert_statement: Executed statement at line 66, suite from postgres/jointest/join3.test, in 0.056 seconds
2025-10-26 15:50:25,154 - INFO - root - assert_statement: Executed statement at line 71, suite from postgres/jointest/join3.test, in 0.021 seconds
2025-10-26 15:50:25,175 - INFO - root - assert_statement: Executed statement at line 74, suite from postgres/jointest/join3.test, in 0.020 seconds
2025-10-26 15:50:25,259 - INFO - root - assert_statement: Executed statement at line 77, suite from postgres/jointest/join3.test, in 0.084 seconds
2025-10-26 15:50:25,337 - INFO - root - assert_statement: Executed statement at line 80, suite from postgres/jointest/join3.test, in 0.078 seconds
2025-10-26 15:50:25,436 - INFO - root - assert_statement: Executed statement at line 83, suite from postgres/jointest/join3.test, in 0.098 seconds
2025-10-26 15:50:25,438 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:50:40,137 - DEBUG - ya.test - _verify_test: Verifying [test_postgres.TestPGSQL.test_sql_suite[results-jointest/join3.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:50:40,164 - DEBUG - ya.test - verify: Test test_postgres.TestPGSQL.test_sql_suite[results-jointest/join3.test] status: good
