2025-10-26 15:54:22,072 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:54:22,072 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[plan-insert.test]
2025-10-26 15:54:22,072 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:54:22,072 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:54:22,120 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/30577
2025-10-26 15:54:22,120 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:54:22,120 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:54:22,121 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/000773/r3tmp/kikimr__e_tq8476
2025-10-26 15:54:22,147 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/65495
2025-10-26 15:54:22,148 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/24638
2025-10-26 15:54:22,148 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/3889
2025-10-26 15:54:22,148 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd
2025-10-26 15:54:22,149 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/000773/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_sql_logic/chunk0/testing_out_stuff/test_sql_logic.py__TestSQLLogic__test_sql_suite[plan-insert.test]/kikimr_configs"
2025-10-26 15:54:22,149 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/000773/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_sql_logic/chunk0/testing_out_stuff/test_sql_logic.py__TestSQLLogic__test_sql_suite[plan-insert.test]/node_1/logfile__w498m1x.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=65495 --mon-port=24638 --ic-port=30577
2025-10-26 15:54:22,149 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:65495/1, disk_size 68719476736
2025-10-26 15:54:22,150 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/000773/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_sql_logic/chunk0/testing_out_stuff/test_sql_logic.py__TestSQLLogic__test_sql_suite[plan-insert.test]/node_1/logfile__w498m1x.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/000773/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_sql_logic/chunk0/testing_out_stuff/test_sql_logic.py__TestSQLLogic__test_sql_suite[plan-insert.test]/kikimr_configs/config.yaml', '--grpc-port=65495', '--mon-port=24638', '--ic-port=30577')' with input '/home/runner/.ya/build/build_root/muiu/000773/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_sql_logic/chunk0/stdin' in '/home/runner/.ya/build/build_root/muiu/000773/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_sql_logic/chunk0/testing_out_stuff/test_sql_logic.py__TestSQLLogic__test_sql_suite[plan-insert.test]/node_1' (no wait)
2025-10-26 15:54:22,151 - DEBUG - ya.test - execute: Command pid: 1594503
2025-10-26 15:54:22,151 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:65495/1
2025-10-26 15:54:23,777 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd
2025-10-26 15:54:23,777 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:65495', '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: 30577\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:54:23,778 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:54:23,778 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:54:23,778 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:65495', '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: 30577\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:54:23,779 - DEBUG - ya.test - execute: Command pid: 1594774
2025-10-26 15:54:24,089 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) rc: 0
2025-10-26 15:54:24,090 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) elapsed time (sec): 0.3097822666168213
2025-10-26 15:54:24,090 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) idrss: 0
2025-10-26 15:54:24,090 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) inblock: 0
2025-10-26 15:54:24,090 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) isrss: 0
2025-10-26 15:54:24,090 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) ixrss: 0
2025-10-26 15:54:24,091 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) majflt: 0
2025-10-26 15:54:24,091 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) maxrss: 523216
2025-10-26 15:54:24,091 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) minflt: 19024
2025-10-26 15:54:24,091 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) msgrcv: 0
2025-10-26 15:54:24,091 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) msgsnd: 0
2025-10-26 15:54:24,091 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) nivcsw: 0
2025-10-26 15:54:24,092 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) nsignals: 0
2025-10-26 15:54:24,092 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) nswap: 0
2025-10-26 15:54:24,092 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) nvcsw: 137
2025-10-26 15:54:24,092 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) oublock: 8
2025-10-26 15:54:24,092 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) stime: 0.194993
2025-10-26 15:54:24,092 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) utime: 0.07163
2025-10-26 15:54:24,092 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) wtime: 0.311
2025-10-26 15:54:24,093 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:54:24,093 - DEBUG - ya.test - _save_outputs: Command (pid 1594774) errors:

2025-10-26 15:54:24,094 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd
2025-10-26 15:54:24,094 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:65495', '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:24,095 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:54:24,095 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:54:24,095 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/000773/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:65495', '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:24,096 - DEBUG - ya.test - execute: Command pid: 1594851
2025-10-26 15:54:24,396 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) rc: 0
2025-10-26 15:54:24,396 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) elapsed time (sec): 0.29912590980529785
2025-10-26 15:54:24,396 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) idrss: 0
2025-10-26 15:54:24,396 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) inblock: 0
2025-10-26 15:54:24,396 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) isrss: 0
2025-10-26 15:54:24,397 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) ixrss: 0
2025-10-26 15:54:24,397 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) majflt: 0
2025-10-26 15:54:24,397 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) maxrss: 523208
2025-10-26 15:54:24,397 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) minflt: 19021
2025-10-26 15:54:24,397 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) msgrcv: 0
2025-10-26 15:54:24,397 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) msgsnd: 0
2025-10-26 15:54:24,398 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) nivcsw: 2
2025-10-26 15:54:24,398 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) nsignals: 0
2025-10-26 15:54:24,398 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) nswap: 0
2025-10-26 15:54:24,398 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) nvcsw: 137
2025-10-26 15:54:24,398 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) oublock: 8
2025-10-26 15:54:24,398 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) stime: 0.17671599999999998
2025-10-26 15:54:24,398 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) utime: 0.080325
2025-10-26 15:54:24,399 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) wtime: 0.3
2025-10-26 15:54:24,399 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 15:54:24,399 - DEBUG - ya.test - _save_outputs: Command (pid 1594851) errors:

2025-10-26 15:54:24,410 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:54:24,431 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_sql_logic.py::TestSQLLogic, test_name: test_sql_suite[plan-insert.test])
2025-10-26 15:54:24,521 - INFO - root - assert_statement: Executed statement at line 0, suite from sqllogictest/insert.test, in 0.089 seconds
2025-10-26 15:54:24,558 - INFO - root - assert_statement: Executed statement at line 3, suite from sqllogictest/insert.test, in 0.036 seconds
2025-10-26 15:54:24,558 - INFO - root - assert_statement: Executed statement at line 6, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,558 - INFO - root - assert_statement: Executed statement at line 9, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,589 - INFO - root - assert_statement: Executed statement at line 12, suite from sqllogictest/insert.test, in 0.030 seconds
2025-10-26 15:54:24,589 - INFO - root - assert_statement: Executed statement at line 15, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,589 - INFO - root - assert_statement: Executed statement at line 20, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,589 - INFO - root - assert_statement: Executed statement at line 25, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,604 - INFO - root - assert_statement: Executed statement at line 30, suite from sqllogictest/insert.test, in 0.015 seconds
2025-10-26 15:54:24,617 - INFO - root - assert_statement: Executed statement at line 33, suite from sqllogictest/insert.test, in 0.012 seconds
2025-10-26 15:54:24,645 - INFO - root - assert_statement: Executed statement at line 36, suite from sqllogictest/insert.test, in 0.028 seconds
2025-10-26 15:54:24,646 - INFO - root - assert_statement: Executed statement at line 39, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,646 - INFO - root - assert_statement: Executed statement at line 44, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,646 - INFO - root - assert_statement: Executed statement at line 49, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,661 - INFO - root - assert_statement: Executed statement at line 54, suite from sqllogictest/insert.test, in 0.015 seconds
2025-10-26 15:54:24,674 - INFO - root - assert_statement: Executed statement at line 57, suite from sqllogictest/insert.test, in 0.012 seconds
2025-10-26 15:54:24,702 - INFO - root - assert_statement: Executed statement at line 60, suite from sqllogictest/insert.test, in 0.028 seconds
2025-10-26 15:54:24,725 - INFO - root - assert_statement: Executed statement at line 63, suite from sqllogictest/insert.test, in 0.023 seconds
2025-10-26 15:54:24,752 - INFO - root - assert_statement: Executed statement at line 66, suite from sqllogictest/insert.test, in 0.026 seconds
2025-10-26 15:54:24,784 - INFO - root - assert_statement: Executed statement at line 69, suite from sqllogictest/insert.test, in 0.032 seconds
2025-10-26 15:54:24,799 - INFO - root - assert_statement: Executed statement at line 72, suite from sqllogictest/insert.test, in 0.014 seconds
2025-10-26 15:54:24,816 - INFO - root - assert_statement: Executed statement at line 75, suite from sqllogictest/insert.test, in 0.017 seconds
2025-10-26 15:54:24,845 - INFO - root - assert_statement: Executed statement at line 78, suite from sqllogictest/insert.test, in 0.028 seconds
2025-10-26 15:54:24,876 - INFO - root - assert_statement: Executed statement at line 81, suite from sqllogictest/insert.test, in 0.031 seconds
2025-10-26 15:54:24,902 - INFO - root - assert_statement: Executed statement at line 84, suite from sqllogictest/insert.test, in 0.025 seconds
2025-10-26 15:54:24,932 - INFO - root - assert_statement: Executed statement at line 87, suite from sqllogictest/insert.test, in 0.030 seconds
2025-10-26 15:54:24,949 - INFO - root - assert_statement: Executed statement at line 90, suite from sqllogictest/insert.test, in 0.017 seconds
2025-10-26 15:54:24,965 - INFO - root - assert_statement: Executed statement at line 93, suite from sqllogictest/insert.test, in 0.016 seconds
2025-10-26 15:54:24,966 - INFO - root - assert_statement: Executed statement at line 96, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,966 - INFO - root - assert_statement: Executed statement at line 101, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,966 - INFO - root - assert_statement: Executed statement at line 106, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,966 - INFO - root - assert_statement: Executed statement at line 111, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:24,980 - INFO - root - assert_statement: Executed statement at line 116, suite from sqllogictest/insert.test, in 0.014 seconds
2025-10-26 15:54:24,996 - INFO - root - assert_statement: Executed statement at line 119, suite from sqllogictest/insert.test, in 0.016 seconds
2025-10-26 15:54:25,024 - INFO - root - assert_statement: Executed statement at line 122, suite from sqllogictest/insert.test, in 0.027 seconds
2025-10-26 15:54:25,049 - INFO - root - assert_statement: Executed statement at line 125, suite from sqllogictest/insert.test, in 0.024 seconds
2025-10-26 15:54:25,077 - INFO - root - assert_statement: Executed statement at line 128, suite from sqllogictest/insert.test, in 0.027 seconds
2025-10-26 15:54:25,077 - INFO - root - assert_statement: Executed statement at line 131, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,077 - INFO - root - assert_statement: Executed statement at line 136, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,078 - INFO - root - assert_statement: Executed statement at line 141, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,078 - INFO - root - assert_statement: Executed statement at line 144, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,078 - INFO - root - assert_statement: Executed statement at line 149, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,078 - INFO - root - assert_statement: Executed statement at line 154, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,078 - INFO - root - assert_statement: Executed statement at line 159, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,079 - INFO - root - assert_statement: Executed statement at line 164, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,079 - INFO - root - assert_statement: Executed statement at line 169, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,079 - INFO - root - assert_statement: Executed statement at line 174, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,079 - INFO - root - assert_statement: Executed statement at line 179, suite from sqllogictest/insert.test, in 0.000 seconds
2025-10-26 15:54:25,093 - INFO - root - assert_statement: Executed statement at line 184, suite from sqllogictest/insert.test, in 0.014 seconds
2025-10-26 15:54:25,095 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:55:02,211 - DEBUG - ya.test - _verify_test: Verifying [test_sql_logic.TestSQLLogic.test_sql_suite[plan-insert.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:55:02,237 - DEBUG - ya.test - verify: Test test_sql_logic.TestSQLLogic.test_sql_suite[plan-insert.test] status: good
