2025-10-26 15:45:59,101 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:45:59,102 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[results-jointest/join0.test]
2025-10-26 15:45:59,102 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:45:59,102 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:45:59,146 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/3520
2025-10-26 15:45:59,146 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:45:59,146 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:45:59,147 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/000792/r3tmp/kikimr__7h67c5ab
2025-10-26 15:45:59,172 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/16223
2025-10-26 15:45:59,173 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/24262
2025-10-26 15:45:59,173 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/18185
2025-10-26 15:45:59,174 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd
2025-10-26 15:45:59,174 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/000792/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk7/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join0.test]/kikimr_configs"
2025-10-26 15:45:59,174 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/000792/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk7/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join0.test]/node_1/logfile_3qnixk0r.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=16223 --mon-port=24262 --ic-port=3520
2025-10-26 15:45:59,174 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:16223/1, disk_size 68719476736
2025-10-26 15:45:59,175 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/000792/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk7/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join0.test]/node_1/logfile_3qnixk0r.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/000792/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk7/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join0.test]/kikimr_configs/config.yaml', '--grpc-port=16223', '--mon-port=24262', '--ic-port=3520')' with input '/home/runner/.ya/build/build_root/muiu/000792/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk7/stdin' in '/home/runner/.ya/build/build_root/muiu/000792/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk7/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[results-jointest/join0.test]/node_1' (no wait)
2025-10-26 15:45:59,176 - DEBUG - ya.test - execute: Command pid: 1501178
2025-10-26 15:45:59,176 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16223/1
2025-10-26 15:46:00,796 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd
2025-10-26 15:46:00,796 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:16223', '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: 3520\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:46:00,797 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:46:00,797 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:46:00,797 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:16223', '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: 3520\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:46:00,798 - DEBUG - ya.test - execute: Command pid: 1501941
2025-10-26 15:46:01,131 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) rc: 0
2025-10-26 15:46:01,131 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) elapsed time (sec): 0.33188915252685547
2025-10-26 15:46:01,131 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) idrss: 0
2025-10-26 15:46:01,131 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) inblock: 0
2025-10-26 15:46:01,131 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) isrss: 0
2025-10-26 15:46:01,131 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) ixrss: 0
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) majflt: 0
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) maxrss: 523204
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) minflt: 19023
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) msgrcv: 0
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) msgsnd: 0
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) nivcsw: 0
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) nsignals: 0
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) nswap: 0
2025-10-26 15:46:01,132 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) nvcsw: 136
2025-10-26 15:46:01,133 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) oublock: 8
2025-10-26 15:46:01,133 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) stime: 0.1784
2025-10-26 15:46:01,133 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) utime: 0.109999
2025-10-26 15:46:01,133 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) wtime: 0.333
2025-10-26 15:46:01,133 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-26 15:46:01,133 - DEBUG - ya.test - _save_outputs: Command (pid 1501941) errors:

2025-10-26 15:46:01,134 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd
2025-10-26 15:46:01,134 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:16223', '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:46:01,135 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:46:01,135 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:46:01,135 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000792/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:16223', '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:46:01,136 - DEBUG - ya.test - execute: Command pid: 1502295
2025-10-26 15:46:01,435 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) rc: 0
2025-10-26 15:46:01,435 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) elapsed time (sec): 0.2977440357208252
2025-10-26 15:46:01,435 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) idrss: 0
2025-10-26 15:46:01,435 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) inblock: 0
2025-10-26 15:46:01,435 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) isrss: 0
2025-10-26 15:46:01,435 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) ixrss: 0
2025-10-26 15:46:01,435 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) majflt: 0
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) maxrss: 523196
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) minflt: 19023
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) msgrcv: 0
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) msgsnd: 0
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) nivcsw: 0
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) nsignals: 0
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) nswap: 0
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) nvcsw: 132
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) oublock: 8
2025-10-26 15:46:01,436 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) stime: 0.168786
2025-10-26 15:46:01,437 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) utime: 0.086519
2025-10-26 15:46:01,437 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) wtime: 0.299
2025-10-26 15:46:01,437 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:46:01,437 - DEBUG - ya.test - _save_outputs: Command (pid 1502295) errors:

2025-10-26 15:46:01,449 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:46:01,472 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_postgres.py::TestPGSQL, test_name: test_sql_suite[results-jointest/join0.test])
2025-10-26 15:46:01,559 - INFO - root - assert_statement: Executed statement at line 12, suite from postgres/jointest/join0.test, in 0.085 seconds
2025-10-26 15:47:28,990 - INFO - root - assert_statement: Executed statement at line 33, suite from postgres/jointest/join0.test, in 87.431 seconds
2025-10-26 15:47:29,022 - INFO - root - assert_statement: Executed statement at line 36, suite from postgres/jointest/join0.test, in 0.031 seconds
2025-10-26 15:47:29,043 - INFO - root - assert_statement: Executed statement at line 40, suite from postgres/jointest/join0.test, in 0.020 seconds
2025-10-26 15:47:29,063 - INFO - root - assert_statement: Executed statement at line 43, suite from postgres/jointest/join0.test, in 0.020 seconds
2025-10-26 15:47:29,082 - INFO - root - assert_statement: Executed statement at line 46, suite from postgres/jointest/join0.test, in 0.019 seconds
2025-10-26 15:47:29,106 - INFO - root - assert_statement: Executed statement at line 49, suite from postgres/jointest/join0.test, in 0.023 seconds
2025-10-26 15:47:29,128 - INFO - root - assert_statement: Executed statement at line 52, suite from postgres/jointest/join0.test, in 0.021 seconds
2025-10-26 15:47:29,155 - INFO - root - assert_statement: Executed statement at line 55, suite from postgres/jointest/join0.test, in 0.027 seconds
2025-10-26 15:47:29,182 - INFO - root - assert_statement: Executed statement at line 59, suite from postgres/jointest/join0.test, in 0.026 seconds
2025-10-26 15:47:29,208 - INFO - root - assert_statement: Executed statement at line 62, suite from postgres/jointest/join0.test, in 0.026 seconds
2025-10-26 15:47:29,230 - INFO - root - assert_statement: Executed statement at line 65, suite from postgres/jointest/join0.test, in 0.021 seconds
2025-10-26 15:47:29,252 - INFO - root - assert_statement: Executed statement at line 68, suite from postgres/jointest/join0.test, in 0.021 seconds
2025-10-26 15:47:29,275 - INFO - root - assert_statement: Executed statement at line 71, suite from postgres/jointest/join0.test, in 0.023 seconds
2025-10-26 15:47:29,275 - INFO - root - assert_statement: Executed statement at line 79, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:47:29,276 - INFO - root - assert_statement: Executed statement at line 93, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:47:29,376 - INFO - root - assert_statement: Executed statement at line 107, suite from postgres/jointest/join0.test, in 0.100 seconds
2025-10-26 15:47:29,721 - INFO - root - assert_statement: Executed statement at line 120, suite from postgres/jointest/join0.test, in 0.345 seconds
2025-10-26 15:47:29,999 - INFO - root - assert_statement: Executed statement at line 129, suite from postgres/jointest/join0.test, in 0.278 seconds
2025-10-26 15:47:30,125 - INFO - root - assert_statement: Executed statement at line 140, suite from postgres/jointest/join0.test, in 0.126 seconds
2025-10-26 15:47:30,319 - INFO - root - assert_statement: Executed statement at line 151, suite from postgres/jointest/join0.test, in 0.193 seconds
2025-10-26 15:47:30,319 - INFO - root - assert_statement: Executed statement at line 169, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:47:30,383 - INFO - root - assert_statement: Executed statement at line 197, suite from postgres/jointest/join0.test, in 0.064 seconds
2025-10-26 15:47:30,406 - INFO - root - assert_statement: Executed statement at line 210, suite from postgres/jointest/join0.test, in 0.022 seconds
2025-10-26 15:47:30,429 - INFO - root - assert_statement: Executed statement at line 213, suite from postgres/jointest/join0.test, in 0.023 seconds
2025-10-26 15:47:30,769 - INFO - root - assert_statement: Executed statement at line 216, suite from postgres/jointest/join0.test, in 0.339 seconds
2025-10-26 15:47:30,769 - INFO - root - assert_statement: Executed statement at line 232, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:47:30,769 - INFO - root - assert_statement: Executed statement at line 236, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:47:30,770 - INFO - root - assert_statement: Executed statement at line 240, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:47:31,172 - INFO - root - assert_statement: Executed statement at line 249, suite from postgres/jointest/join0.test, in 0.403 seconds
2025-10-26 15:47:31,624 - INFO - root - assert_statement: Executed statement at line 255, suite from postgres/jointest/join0.test, in 0.451 seconds
2025-10-26 15:47:31,624 - INFO - root - assert_statement: Executed statement at line 263, suite from postgres/jointest/join0.test, in 0.000 seconds
2025-10-26 15:47:31,769 - INFO - root - assert_statement: Executed statement at line 275, suite from postgres/jointest/join0.test, in 0.145 seconds
2025-10-26 15:47:31,902 - INFO - root - assert_statement: Executed statement at line 288, suite from postgres/jointest/join0.test, in 0.132 seconds
2025-10-26 15:47:32,026 - INFO - root - assert_statement: Executed statement at line 293, suite from postgres/jointest/join0.test, in 0.124 seconds
2025-10-26 15:47:32,274 - INFO - root - assert_statement: Executed statement at line 302, suite from postgres/jointest/join0.test, in 0.247 seconds
2025-10-26 15:47:32,473 - INFO - root - assert_statement: Executed statement at line 316, suite from postgres/jointest/join0.test, in 0.198 seconds
2025-10-26 15:47:32,852 - INFO - root - assert_statement: Executed statement at line 336, suite from postgres/jointest/join0.test, in 0.378 seconds
2025-10-26 15:47:32,976 - INFO - root - assert_statement: Executed statement at line 347, suite from postgres/jointest/join0.test, in 0.124 seconds
2025-10-26 15:47:33,242 - INFO - root - assert_statement: Executed statement at line 350, suite from postgres/jointest/join0.test, in 0.267 seconds
2025-10-26 15:47:33,245 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:47:44,269 - DEBUG - ya.test - _verify_test: Verifying [test_postgres.TestPGSQL.test_sql_suite[results-jointest/join0.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:47:44,302 - DEBUG - ya.test - verify: Test test_postgres.TestPGSQL.test_sql_suite[results-jointest/join0.test] status: good
