2025-10-26 15:55:11,187 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:55:11,187 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[plan-jointest/join0.test]
2025-10-26 15:55:11,187 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:55:11,187 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:55:11,268 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/31923
2025-10-26 15:55:11,268 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:55:11,268 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:55:11,269 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/00076e/r3tmp/kikimr__tfe_vxlz
2025-10-26 15:55:11,315 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/61108
2025-10-26 15:55:11,315 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/14350
2025-10-26 15:55:11,316 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/64326
2025-10-26 15:55:11,316 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd
2025-10-26 15:55:11,317 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join0.test]/kikimr_configs"
2025-10-26 15:55:11,317 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join0.test]/node_1/logfile_cxc3dm56.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=61108 --mon-port=14350 --ic-port=31923
2025-10-26 15:55:11,317 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:61108/1, disk_size 68719476736
2025-10-26 15:55:11,318 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join0.test]/node_1/logfile_cxc3dm56.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join0.test]/kikimr_configs/config.yaml', '--grpc-port=61108', '--mon-port=14350', '--ic-port=31923')' with input '/home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/stdin' in '/home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-jointest/join0.test]/node_1' (no wait)
2025-10-26 15:55:11,320 - DEBUG - ya.test - execute: Command pid: 1601773
2025-10-26 15:55:11,320 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:61108/1
2025-10-26 15:55:13,065 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd
2025-10-26 15:55:13,066 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61108', '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: 31923\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:55:13,066 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:55:13,067 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:55:13,067 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61108', '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: 31923\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:55:13,068 - DEBUG - ya.test - execute: Command pid: 1601944
2025-10-26 15:55:13,431 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) rc: 0
2025-10-26 15:55:13,431 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) elapsed time (sec): 0.36218738555908203
2025-10-26 15:55:13,431 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) idrss: 0
2025-10-26 15:55:13,432 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) inblock: 0
2025-10-26 15:55:13,432 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) isrss: 0
2025-10-26 15:55:13,432 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) ixrss: 0
2025-10-26 15:55:13,432 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) majflt: 0
2025-10-26 15:55:13,432 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) maxrss: 523200
2025-10-26 15:55:13,432 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) minflt: 19023
2025-10-26 15:55:13,433 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) msgrcv: 0
2025-10-26 15:55:13,433 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) msgsnd: 0
2025-10-26 15:55:13,433 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) nivcsw: 1
2025-10-26 15:55:13,433 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) nsignals: 0
2025-10-26 15:55:13,433 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) nswap: 0
2025-10-26 15:55:13,433 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) nvcsw: 138
2025-10-26 15:55:13,434 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) oublock: 8
2025-10-26 15:55:13,434 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) stime: 0.219723
2025-10-26 15:55:13,434 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) utime: 0.09987399999999999
2025-10-26 15:55:13,434 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) wtime: 0.363
2025-10-26 15:55:13,434 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-26 15:55:13,435 - DEBUG - ya.test - _save_outputs: Command (pid 1601944) errors:

2025-10-26 15:55:13,435 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd
2025-10-26 15:55:13,436 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61108', '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:55:13,437 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:55:13,437 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:55:13,437 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/00076e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61108', '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:55:13,438 - DEBUG - ya.test - execute: Command pid: 1601988
2025-10-26 15:55:13,754 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) rc: 0
2025-10-26 15:55:13,754 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) elapsed time (sec): 0.3146538734436035
2025-10-26 15:55:13,754 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) idrss: 0
2025-10-26 15:55:13,754 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) inblock: 0
2025-10-26 15:55:13,755 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) isrss: 0
2025-10-26 15:55:13,755 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) ixrss: 0
2025-10-26 15:55:13,755 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) majflt: 0
2025-10-26 15:55:13,755 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) maxrss: 523196
2025-10-26 15:55:13,755 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) minflt: 19023
2025-10-26 15:55:13,755 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) msgrcv: 0
2025-10-26 15:55:13,756 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) msgsnd: 0
2025-10-26 15:55:13,756 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) nivcsw: 1
2025-10-26 15:55:13,756 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) nsignals: 0
2025-10-26 15:55:13,756 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) nswap: 0
2025-10-26 15:55:13,756 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) nvcsw: 126
2025-10-26 15:55:13,756 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) oublock: 8
2025-10-26 15:55:13,757 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) stime: 0.17379799999999998
2025-10-26 15:55:13,757 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) utime: 0.09700299999999999
2025-10-26 15:55:13,757 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) wtime: 0.316
2025-10-26 15:55:13,757 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:55:13,757 - DEBUG - ya.test - _save_outputs: Command (pid 1601988) errors:

2025-10-26 15:55:13,770 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:55:13,795 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_postgres.py::TestPGSQL, test_name: test_sql_suite[plan-jointest/join0.test])
2025-10-26 15:55:13,889 - INFO - root - assert_statement: Executed statement at line 12, suite from postgres/jointest/join0.test, in 0.093 seconds
2025-10-26 15:56:57,151 - INFO - root - assert_statement: Executed statement at line 33, suite from postgres/jointest/join0.test, in 103.262 seconds
2025-10-26 15:56:57,185 - INFO - root - assert_statement: Executed statement at line 36, suite from postgres/jointest/join0.test, in 0.033 seconds
2025-10-26 15:56:57,213 - INFO - root - assert_statement: Executed statement at line 40, suite from postgres/jointest/join0.test, in 0.028 seconds
2025-10-26 15:56:57,240 - INFO - root - assert_statement: Executed statement at line 43, suite from postgres/jointest/join0.test, in 0.026 seconds
2025-10-26 15:56:57,268 - INFO - root - assert_statement: Executed statement at line 46, suite from postgres/jointest/join0.test, in 0.028 seconds
2025-10-26 15:56:57,295 - INFO - root - assert_statement: Executed statement at line 49, suite from postgres/jointest/join0.test, in 0.027 seconds
2025-10-26 15:56:57,324 - INFO - root - assert_statement: Executed statement at line 52, suite from postgres/jointest/join0.test, in 0.028 seconds
2025-10-26 15:56:57,355 - INFO - root - assert_statement: Executed statement at line 55, suite from postgres/jointest/join0.test, in 0.030 seconds
2025-10-26 15:56:57,381 - INFO - root - assert_statement: Executed statement at line 59, suite from postgres/jointest/join0.test, in 0.026 seconds
2025-10-26 15:56:57,411 - INFO - root - assert_statement: Executed statement at line 62, suite from postgres/jointest/join0.test, in 0.029 seconds
2025-10-26 15:56:57,437 - INFO - root - assert_statement: Executed statement at line 65, suite from postgres/jointest/join0.test, in 0.026 seconds
2025-10-26 15:56:57,462 - INFO - root - assert_statement: Executed statement at line 68, suite from postgres/jointest/join0.test, in 0.025 seconds
2025-10-26 15:56:57,489 - INFO - root - assert_statement: Executed statement at line 71, suite from postgres/jointest/join0.test, in 0.027 seconds
2025-10-26 15:56:57,490 - INFO - root - assert_statement: Executed statement at line 79, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:56:57,490 - INFO - root - assert_statement: Executed statement at line 93, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:56:57,544 - INFO - root - assert_statement: Executed statement at line 107, suite from postgres/jointest/join0.test, in 0.054 seconds
2025-10-26 15:56:57,636 - INFO - root - assert_statement: Executed statement at line 120, suite from postgres/jointest/join0.test, in 0.092 seconds
2025-10-26 15:56:57,713 - INFO - root - assert_statement: Executed statement at line 129, suite from postgres/jointest/join0.test, in 0.077 seconds
2025-10-26 15:56:57,769 - INFO - root - assert_statement: Executed statement at line 140, suite from postgres/jointest/join0.test, in 0.055 seconds
2025-10-26 15:56:57,843 - INFO - root - assert_statement: Executed statement at line 151, suite from postgres/jointest/join0.test, in 0.074 seconds
2025-10-26 15:56:57,843 - INFO - root - assert_statement: Executed statement at line 169, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:56:57,914 - INFO - root - assert_statement: Executed statement at line 197, suite from postgres/jointest/join0.test, in 0.071 seconds
2025-10-26 15:56:57,939 - INFO - root - assert_statement: Executed statement at line 210, suite from postgres/jointest/join0.test, in 0.025 seconds
2025-10-26 15:56:57,966 - INFO - root - assert_statement: Executed statement at line 213, suite from postgres/jointest/join0.test, in 0.027 seconds
2025-10-26 15:56:58,138 - INFO - root - assert_statement: Executed statement at line 216, suite from postgres/jointest/join0.test, in 0.171 seconds
2025-10-26 15:56:58,138 - INFO - root - assert_statement: Executed statement at line 232, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:56:58,138 - INFO - root - assert_statement: Executed statement at line 236, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:56:58,138 - INFO - root - assert_statement: Executed statement at line 240, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:56:58,223 - INFO - root - assert_statement: Executed statement at line 249, suite from postgres/jointest/join0.test, in 0.085 seconds
2025-10-26 15:56:58,348 - INFO - root - assert_statement: Executed statement at line 255, suite from postgres/jointest/join0.test, in 0.124 seconds
2025-10-26 15:56:58,348 - INFO - root - assert_statement: Executed statement at line 263, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:56:58,398 - INFO - root - assert_statement: Executed statement at line 275, suite from postgres/jointest/join0.test, in 0.049 seconds
2025-10-26 15:56:58,451 - INFO - root - assert_statement: Executed statement at line 288, suite from postgres/jointest/join0.test, in 0.052 seconds
2025-10-26 15:56:58,492 - INFO - root - assert_statement: Executed statement at line 293, suite from postgres/jointest/join0.test, in 0.040 seconds
2025-10-26 15:56:58,611 - INFO - root - assert_statement: Executed statement at line 302, suite from postgres/jointest/join0.test, in 0.119 seconds
2025-10-26 15:56:58,702 - INFO - root - assert_statement: Executed statement at line 316, suite from postgres/jointest/join0.test, in 0.091 seconds
2025-10-26 15:56:58,787 - INFO - root - assert_statement: Executed statement at line 336, suite from postgres/jointest/join0.test, in 0.085 seconds
2025-10-26 15:56:58,829 - INFO - root - assert_statement: Executed statement at line 347, suite from postgres/jointest/join0.test, in 0.041 seconds
2025-10-26 15:56:58,899 - INFO - root - assert_statement: Executed statement at line 350, suite from postgres/jointest/join0.test, in 0.070 seconds
2025-10-26 15:56:58,901 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:57:04,830 - DEBUG - ya.test - _verify_test: Verifying [test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join0.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:57:04,882 - DEBUG - ya.test - verify: Test test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join0.test] status: good
