2025-12-12 14:17:04,675 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 14:17:04,676 - INFO - ya.test - pytest_runtest_setup: test[column]
2025-12-12 14:17:04,682 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 14:17:04,683 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-12 14:17:04,804 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd
2025-12-12 14:17:04,806 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29559
2025-12-12 14:17:04,821 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/tscf/0001ad/r3tmp/kikimr_cluster_5o5p4f9c
2025-12-12 14:17:04,886 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd
2025-12-12 14:17:04,894 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3435
2025-12-12 14:17:04,895 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18229
2025-12-12 14:17:04,895 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1303
2025-12-12 14:17:04,896 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1303
2025-12-12 14:17:04,896 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1304
2025-12-12 14:17:04,897 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20500
2025-12-12 14:17:04,902 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/tscf/0001ad/ydb/tests/stress/log/tests/test-results/py3test/testing_out_stuff/test_workload.py.TestYdbLogWorkload.test.column/cluster/kikimr_configs"
2025-12-12 14:17:04,902 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/tscf/0001ad/ydb/tests/stress/log/tests/test-results/py3test/testing_out_stuff/test_workload.py.TestYdbLogWorkload.test.column/cluster/node_1/logfile_mlxxcm36.log --grpc-port=3435 --mon-port=18229 --ic-port=29559
2025-12-12 14:17:04,903 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:3435/1, disk_size 68719476736
2025-12-12 14:17:04,904 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/tscf/0001ad/ydb/tests/stress/log/tests/test-results/py3test/testing_out_stuff/test_workload.py.TestYdbLogWorkload.test.column/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/tscf/0001ad/ydb/tests/stress/log/tests/test-results/py3test/testing_out_stuff/test_workload.py.TestYdbLogWorkload.test.column/cluster/node_1/logfile_mlxxcm36.log', '--grpc-port=3435', '--mon-port=18229', '--ic-port=29559')' in '/home/runner/.ya/build/build_root/tscf/0001ad/ydb/tests/stress/log/tests/test-results/py3test/testing_out_stuff/test_workload.py.TestYdbLogWorkload.test.column/cluster/node_1' (no wait)
2025-12-12 14:17:04,907 - DEBUG - ya.test - execute: Command pid: 1164703
2025-12-12 14:17:04,909 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:3435/1
2025-12-12 14:17:09,201 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd
2025-12-12 14:17:09,201 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3435', '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: 29559\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-12 14:17:09,205 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-12 14:17:09,207 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-12 14:17:09,223 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3435', '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: 29559\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-12 14:17:09,239 - DEBUG - ya.test - execute: Command pid: 1166772
2025-12-12 14:17:10,037 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) rc: 0
2025-12-12 14:17:10,037 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) elapsed time (sec): 0.7969198226928711
2025-12-12 14:17:10,038 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) idrss: 0
2025-12-12 14:17:10,038 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) inblock: 0
2025-12-12 14:17:10,039 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) isrss: 0
2025-12-12 14:17:10,039 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) ixrss: 0
2025-12-12 14:17:10,039 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) majflt: 0
2025-12-12 14:17:10,039 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) maxrss: 570952
2025-12-12 14:17:10,040 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) minflt: 44137
2025-12-12 14:17:10,040 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) msgrcv: 0
2025-12-12 14:17:10,040 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) msgsnd: 0
2025-12-12 14:17:10,040 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) nivcsw: 146
2025-12-12 14:17:10,041 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) nsignals: 0
2025-12-12 14:17:10,041 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) nswap: 0
2025-12-12 14:17:10,042 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) nvcsw: 755
2025-12-12 14:17:10,042 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) oublock: 8
2025-12-12 14:17:10,042 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) stime: 0.22514699999999999
2025-12-12 14:17:10,042 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) utime: 0.497326
2025-12-12 14:17:10,043 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) wtime: 0.802
2025-12-12 14:17:10,043 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-12 14:17:10,043 - DEBUG - ya.test - _save_outputs: Command (pid 1166772) errors:

2025-12-12 14:17:10,045 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd
2025-12-12 14:17:10,045 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3435', '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-12-12 14:17:10,047 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-12 14:17:10,048 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-12 14:17:10,048 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3435', '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-12-12 14:17:10,049 - DEBUG - ya.test - execute: Command pid: 1166856
2025-12-12 14:17:10,824 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) rc: 0
2025-12-12 14:17:10,830 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) elapsed time (sec): 0.7724392414093018
2025-12-12 14:17:10,831 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) idrss: 0
2025-12-12 14:17:10,832 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) inblock: 0
2025-12-12 14:17:10,833 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) isrss: 0
2025-12-12 14:17:10,833 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) ixrss: 0
2025-12-12 14:17:10,834 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) majflt: 0
2025-12-12 14:17:10,835 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) maxrss: 570952
2025-12-12 14:17:10,836 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) minflt: 44112
2025-12-12 14:17:10,838 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) msgrcv: 0
2025-12-12 14:17:10,838 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) msgsnd: 0
2025-12-12 14:17:10,839 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) nivcsw: 59
2025-12-12 14:17:10,840 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) nsignals: 0
2025-12-12 14:17:10,841 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) nswap: 0
2025-12-12 14:17:10,841 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) nvcsw: 635
2025-12-12 14:17:10,842 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) oublock: 8
2025-12-12 14:17:10,842 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) stime: 0.22421
2025-12-12 14:17:10,843 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) utime: 0.49732699999999996
2025-12-12 14:17:10,844 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) wtime: 0.775
2025-12-12 14:17:10,844 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-12 14:17:10,845 - DEBUG - ya.test - _save_outputs: Command (pid 1166856) errors:

2025-12-12 14:17:10,845 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-12 14:17:10,916 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-12 14:17:10,948 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_workload.py::TestYdbLogWorkload, test_name: test[column])
2025-12-12 14:17:10,949 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb
2025-12-12 14:17:10,951 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb
2025-12-12 14:17:10,952 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb
2025-12-12 14:17:10,953 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb
2025-12-12 14:17:10,953 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb
2025-12-12 14:17:10,957 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-12-12 14:17:10,959 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-12-12 14:17:10,960 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:3435', '--database=/Root', 'workload', 'log', 'init', '--path', 'column', '--int-cols', '2', '--str-cols', '5', '--key-cols', '4', '--len', '200', '--store', 'column', '--min-partitions', '100', '--partition-size', '10', '--auto-partition', '0']' in 'None' (waiting)
2025-12-12 14:17:10,962 - DEBUG - ya.test - execute: Command pid: 1167005
2025-12-12 14:17:14,447 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) rc: 0
2025-12-12 14:17:14,447 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) elapsed time (sec): 3.4838225841522217
2025-12-12 14:17:14,448 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) idrss: 0
2025-12-12 14:17:14,448 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) inblock: 0
2025-12-12 14:17:14,449 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) isrss: 0
2025-12-12 14:17:14,454 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) ixrss: 0
2025-12-12 14:17:14,455 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) majflt: 0
2025-12-12 14:17:14,456 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) maxrss: 581328
2025-12-12 14:17:14,456 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) minflt: 99603
2025-12-12 14:17:14,456 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) msgrcv: 0
2025-12-12 14:17:14,456 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) msgsnd: 0
2025-12-12 14:17:14,457 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) nivcsw: 97
2025-12-12 14:17:14,457 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) nsignals: 0
2025-12-12 14:17:14,457 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) nswap: 0
2025-12-12 14:17:14,458 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) nvcsw: 2777
2025-12-12 14:17:14,458 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) oublock: 16
2025-12-12 14:17:14,458 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) stime: 0.220018
2025-12-12 14:17:14,458 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) utime: 0.195107
2025-12-12 14:17:14,458 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) wtime: 3.486
2025-12-12 14:17:14,459 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) output:
Init tables ...
Init tables ...Ok

2025-12-12 14:17:14,459 - DEBUG - ya.test - _save_outputs: Command (pid 1167005) errors:
(!) New version of YDB CLI is available. Run 'ydb update' command for update. You can also disable further version checks with 'ydb version --disable-checks' command

2025-12-12 14:17:14,460 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-12-12 14:17:14,460 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb
2025-12-12 14:17:14,464 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-12-12 14:17:14,465 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-12-12 14:17:14,466 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:3435', '--database=/Root', 'workload', 'log', 'run', 'select', '--path', 'column', '--client-timeout', '10000', '--threads', '10', '--seconds', '40']' in 'None' (no wait)
2025-12-12 14:17:14,468 - DEBUG - ya.test - execute: Command pid: 1168004
2025-12-12 14:17:14,470 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-12-12 14:17:14,472 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-12-12 14:17:14,473 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/tscf/0001ad/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:3435', '--database=/Root', 'workload', 'log', 'import', '--bulk-size', '1000', '-t', '1', 'generator', '--path', 'column', '--int-cols', '2', '--str-cols', '5', '--key-cols', '4', '--len', '200', '--rows', '100000']' in 'None' (waiting)
2025-12-12 14:17:14,477 - DEBUG - ya.test - execute: Command pid: 1168005
