2025-10-26 15:54:25,088 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:54:25,088 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[plan-create_table.test]
2025-10-26 15:54:25,088 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:54:25,088 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:54:25,138 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/22405
2025-10-26 15:54:25,138 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:54:25,138 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:54:25,139 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/000774/r3tmp/kikimr__f0c2nq5m
2025-10-26 15:54:25,163 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/24762
2025-10-26 15:54:25,164 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/18659
2025-10-26 15:54:25,164 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/6419
2025-10-26 15:54:25,165 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd
2025-10-26 15:54:25,166 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/000774/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk1/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-create_table.test]/kikimr_configs"
2025-10-26 15:54:25,166 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/000774/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk1/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-create_table.test]/node_1/logfile__pjegy63.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=24762 --mon-port=18659 --ic-port=22405
2025-10-26 15:54:25,166 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:24762/1, disk_size 68719476736
2025-10-26 15:54:25,166 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/000774/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk1/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-create_table.test]/node_1/logfile__pjegy63.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/000774/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk1/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-create_table.test]/kikimr_configs/config.yaml', '--grpc-port=24762', '--mon-port=18659', '--ic-port=22405')' with input '/home/runner/.ya/build/build_root/muiu/000774/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk1/stdin' in '/home/runner/.ya/build/build_root/muiu/000774/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk1/testing_out_stuff/test_postgres.py__TestPGSQL__test_sql_suite[plan-create_table.test]/node_1' (no wait)
2025-10-26 15:54:25,167 - DEBUG - ya.test - execute: Command pid: 1595129
2025-10-26 15:54:25,168 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:24762/1
2025-10-26 15:54:26,796 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd
2025-10-26 15:54:26,796 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:24762', '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: 22405\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:54:26,796 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:54:26,797 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:54:26,797 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:24762', '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: 22405\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:54:26,798 - DEBUG - ya.test - execute: Command pid: 1595303
2025-10-26 15:54:27,073 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) rc: 0
2025-10-26 15:54:27,073 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) elapsed time (sec): 0.27457380294799805
2025-10-26 15:54:27,073 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) idrss: 0
2025-10-26 15:54:27,073 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) inblock: 0
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) isrss: 0
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) ixrss: 0
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) majflt: 0
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) maxrss: 523212
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) minflt: 19022
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) msgrcv: 0
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) msgsnd: 0
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) nivcsw: 0
2025-10-26 15:54:27,074 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) nsignals: 0
2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) nswap: 0
2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) nvcsw: 141
2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) oublock: 8
2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) stime: 0.158355
2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) utime: 0.073087
2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) wtime: 0.275
2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:54:27,075 - DEBUG - ya.test - _save_outputs: Command (pid 1595303) errors:

2025-10-26 15:54:27,076 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd
2025-10-26 15:54:27,076 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:24762', '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:27,077 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:54:27,077 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:54:27,077 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000774/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:24762', '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:27,078 - DEBUG - ya.test - execute: Command pid: 1595345
2025-10-26 15:54:27,419 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) rc: 0
2025-10-26 15:54:27,419 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) elapsed time (sec): 0.3407094478607178
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) idrss: 0
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) inblock: 0
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) isrss: 0
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) ixrss: 0
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) majflt: 0
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) maxrss: 523204
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) minflt: 19022
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) msgrcv: 0
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) msgsnd: 0
2025-10-26 15:54:27,420 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) nivcsw: 2
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) nsignals: 0
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) nswap: 0
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) nvcsw: 130
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) oublock: 8
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) stime: 0.219604
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) utime: 0.078166
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) wtime: 0.342
2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 15:54:27,421 - DEBUG - ya.test - _save_outputs: Command (pid 1595345) errors:

2025-10-26 15:54:27,436 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:54:27,460 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_postgres.py::TestPGSQL, test_name: test_sql_suite[plan-create_table.test])
2025-10-26 15:54:27,593 - INFO - root - assert_statement: Executed statement at line 4, suite from postgres/create_table.test, in 0.132 seconds
2025-10-26 15:54:27,623 - INFO - root - assert_statement: Executed statement at line 11, suite from postgres/create_table.test, in 0.029 seconds
2025-10-26 15:54:27,663 - INFO - root - assert_statement: Executed statement at line 18, suite from postgres/create_table.test, in 0.040 seconds
2025-10-26 15:54:27,703 - INFO - root - assert_statement: Executed statement at line 39, suite from postgres/create_table.test, in 0.039 seconds
2025-10-26 15:54:27,743 - INFO - root - assert_statement: Executed statement at line 60, suite from postgres/create_table.test, in 0.039 seconds
2025-10-26 15:54:27,783 - INFO - root - assert_statement: Executed statement at line 81, suite from postgres/create_table.test, in 0.039 seconds
2025-10-26 15:54:27,783 - INFO - root - assert_statement: Executed statement at line 90, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,783 - INFO - root - assert_statement: Executed statement at line 96, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,824 - INFO - root - assert_statement: Executed statement at line 102, suite from postgres/create_table.test, in 0.040 seconds
2025-10-26 15:54:27,863 - INFO - root - assert_statement: Executed statement at line 113, suite from postgres/create_table.test, in 0.039 seconds
2025-10-26 15:54:27,903 - INFO - root - assert_statement: Executed statement at line 123, suite from postgres/create_table.test, in 0.040 seconds
2025-10-26 15:54:27,904 - INFO - root - assert_statement: Executed statement at line 136, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,943 - INFO - root - assert_statement: Executed statement at line 144, suite from postgres/create_table.test, in 0.039 seconds
2025-10-26 15:54:27,944 - INFO - root - assert_statement: Executed statement at line 157, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,944 - INFO - root - assert_statement: Executed statement at line 162, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,944 - INFO - root - assert_statement: Executed statement at line 168, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,944 - INFO - root - assert_statement: Executed statement at line 171, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,944 - INFO - root - assert_statement: Executed statement at line 176, suite from postgres/create_table.test, in 0.000 seconds
2025-10-26 15:54:27,946 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:54:36,112 - DEBUG - ya.test - _verify_test: Verifying [test_postgres.TestPGSQL.test_sql_suite[plan-create_table.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:54:36,153 - DEBUG - ya.test - verify: Test test_postgres.TestPGSQL.test_sql_suite[plan-create_table.test] status: good
