2025-10-31 15:33:18,687 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:18,687 - INFO - ya.test - pytest_runtest_setup: test_single_table_with_data_backup_restore
2025-10-31 15:33:18,687 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:18,687 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:33:18,738 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd
2025-10-31 15:33:18,739 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23177
2025-10-31 15:33:18,739 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23177
2025-10-31 15:33:18,739 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23178
2025-10-31 15:33:18,741 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/stm2/002e2f/r3tmp/kikimr_cluster_xyyoq1fm
2025-10-31 15:33:18,772 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd
2025-10-31 15:33:18,773 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/65481
2025-10-31 15:33:18,774 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62668
2025-10-31 15:33:18,775 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2810
2025-10-31 15:33:18,776 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16977
2025-10-31 15:33:18,777 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/test_ydb_backup.py.TestSingleBackupRestore.test_single_table_with_data_backup_restore/cluster/kikimr_configs"
2025-10-31 15:33:18,778 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/002e2f/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/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/test_ydb_backup.py.TestSingleBackupRestore.test_single_table_with_data_backup_restore/cluster/node_1/logfile_nm16_to7.log --grpc-port=65481 --mon-port=62668 --ic-port=23178
2025-10-31 15:33:18,778 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:65481/1, disk_size 68719476736
2025-10-31 15:33:18,778 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/test_ydb_backup.py.TestSingleBackupRestore.test_single_table_with_data_backup_restore/cluster/kikimr_configs"
2025-10-31 15:33:18,778 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/002e2f/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/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/test_ydb_backup.py.TestSingleBackupRestore.test_single_table_with_data_backup_restore/cluster/node_1/logfile_nm16_to7.log --grpc-port=65481 --mon-port=62668 --ic-port=23178
2025-10-31 15:33:18,779 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/test_ydb_backup.py.TestSingleBackupRestore.test_single_table_with_data_backup_restore/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/test_ydb_backup.py.TestSingleBackupRestore.test_single_table_with_data_backup_restore/cluster/node_1/logfile_nm16_to7.log', '--grpc-port=65481', '--mon-port=62668', '--ic-port=23178')' in '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/test_ydb_backup.py.TestSingleBackupRestore.test_single_table_with_data_backup_restore/cluster/node_1' (no wait)
2025-10-31 15:33:18,781 - DEBUG - ya.test - execute: Command pid: 2752461
2025-10-31 15:33:18,782 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:65481/1
2025-10-31 15:33:18,783 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 15:33:18,783 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 15:33:18,783 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 15:33:18,784 - DEBUG - ya.test - execute: Command pid: 2752462
2025-10-31 15:33:19,201 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) rc: 0
2025-10-31 15:33:19,202 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) elapsed time (sec): 0.41655921936035156
2025-10-31 15:33:19,202 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) idrss: 0
2025-10-31 15:33:19,202 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) inblock: 0
2025-10-31 15:33:19,202 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) isrss: 0
2025-10-31 15:33:19,202 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) ixrss: 0
2025-10-31 15:33:19,202 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) majflt: 4
2025-10-31 15:33:19,202 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) maxrss: 538384
2025-10-31 15:33:19,203 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) minflt: 18797
2025-10-31 15:33:19,203 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) msgrcv: 0
2025-10-31 15:33:19,203 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) msgsnd: 0
2025-10-31 15:33:19,203 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) nivcsw: 1
2025-10-31 15:33:19,203 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) nsignals: 0
2025-10-31 15:33:19,203 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) nswap: 0
2025-10-31 15:33:19,203 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) nvcsw: 16
2025-10-31 15:33:19,204 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) oublock: 8
2025-10-31 15:33:19,204 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) stime: 0.26436499999999996
2025-10-31 15:33:19,204 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) utime: 0.104143
2025-10-31 15:33:19,204 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) wtime: 0.418
2025-10-31 15:33:19,204 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) output:
Git info:
    Commit: b78d702ce2f71e40f285a60704f6a28670ea8340
    Branch: 
    Author: kungurtsev <alexey66rus@gmail.com>
    Summary: Merge f10023174585e1f3e64dac76a090d0c070f4c2a5 into 4af0266bcdc7df80879f060afb0976424f2118ed

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-upzbhs6y6e
    Host information: 
        Linux ghrun-upzbhs6y6e 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-31 15:33:19,204 - DEBUG - ya.test - _save_outputs: Command (pid 2752462) errors:

2025-10-31 15:33:19,205 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b78d702ce2f71e40f285a60704f6a28670ea8340
    Branch: 
    Author: kungurtsev <alexey66rus@gmail.com>
    Summary: Merge f10023174585e1f3e64dac76a090d0c070f4c2a5 into 4af0266bcdc7df80879f060afb0976424f2118ed
2025-10-31 15:33:20,923 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd
2025-10-31 15:33:20,923 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65481', '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: 23178\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 15:33:20,924 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 15:33:20,925 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 15:33:20,925 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65481', '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: 23178\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 15:33:20,926 - DEBUG - ya.test - execute: Command pid: 2753330
2025-10-31 15:33:21,384 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) rc: 0
2025-10-31 15:33:21,385 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) elapsed time (sec): 0.4573802947998047
2025-10-31 15:33:21,385 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) idrss: 0
2025-10-31 15:33:21,385 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) inblock: 0
2025-10-31 15:33:21,385 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) isrss: 0
2025-10-31 15:33:21,385 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) ixrss: 0
2025-10-31 15:33:21,385 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) majflt: 0
2025-10-31 15:33:21,385 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) maxrss: 546812
2025-10-31 15:33:21,386 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) minflt: 18275
2025-10-31 15:33:21,386 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) msgrcv: 0
2025-10-31 15:33:21,386 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) msgsnd: 0
2025-10-31 15:33:21,386 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) nivcsw: 5
2025-10-31 15:33:21,386 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) nsignals: 0
2025-10-31 15:33:21,386 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) nswap: 0
2025-10-31 15:33:21,386 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) nvcsw: 154
2025-10-31 15:33:21,387 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) oublock: 8
2025-10-31 15:33:21,387 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) stime: 0.316121
2025-10-31 15:33:21,387 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) utime: 0.098788
2025-10-31 15:33:21,387 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) wtime: 0.458
2025-10-31 15:33:21,387 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 15:33:21,387 - DEBUG - ya.test - _save_outputs: Command (pid 2753330) errors:

2025-10-31 15:33:21,388 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd
2025-10-31 15:33:21,388 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65481', '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-31 15:33:21,389 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 15:33:21,390 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 15:33:21,390 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65481', '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-31 15:33:21,391 - DEBUG - ya.test - execute: Command pid: 2753503
2025-10-31 15:33:21,789 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) rc: 0
2025-10-31 15:33:21,790 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) elapsed time (sec): 0.39786267280578613
2025-10-31 15:33:21,790 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) idrss: 0
2025-10-31 15:33:21,790 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) inblock: 0
2025-10-31 15:33:21,790 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) isrss: 0
2025-10-31 15:33:21,790 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) ixrss: 0
2025-10-31 15:33:21,790 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) majflt: 0
2025-10-31 15:33:21,790 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) maxrss: 546836
2025-10-31 15:33:21,791 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) minflt: 18273
2025-10-31 15:33:21,791 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) msgrcv: 0
2025-10-31 15:33:21,791 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) msgsnd: 0
2025-10-31 15:33:21,791 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) nivcsw: 2
2025-10-31 15:33:21,791 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) nsignals: 0
2025-10-31 15:33:21,791 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) nswap: 0
2025-10-31 15:33:21,792 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) nvcsw: 136
2025-10-31 15:33:21,792 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) oublock: 8
2025-10-31 15:33:21,792 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) stime: 0.260652
2025-10-31 15:33:21,792 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) utime: 0.09404699999999999
2025-10-31 15:33:21,792 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) wtime: 0.399
2025-10-31 15:33:21,792 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 15:33:21,792 - DEBUG - ya.test - _save_outputs: Command (pid 2753503) errors:

2025-10-31 15:33:21,793 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 15:33:21,805 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 15:33:21,813 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:33:21,815 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 15:33:21,817 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:33:21,825 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 15:33:21,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 1a863684-c888-442f-986d-483be91cddc5, localhost:65481): creating call state
2025-10-31 15:33:21,826 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 1a863684-c888-442f-986d-483be91cddc5, localhost:65481): request = { database: "/Root" }
2025-10-31 15:33:21,828 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 1a863684-c888-442f-986d-483be91cddc5, localhost:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-upzbhs6y6e.auto.internal" port: 65481 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-31 15:33:21,829 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-upzbhs6y6e.auto.internal:65481, location 1, ssl: False>]>
2025-10-31 15:33:21,829 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:33:21,837 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_backup.py::TestSingleBackupRestore, test_name: test_single_table_with_data_backup_restore)
2025-10-31 15:33:21,838 - DEBUG - ydb.connection - _prepare_call: RpcState(MakeDirectory, 273a4783-3c37-4278-b1d0-b5bd6f1fddd6, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:21,838 - DEBUG - ydb.connection - _log_request: RpcState(MakeDirectory, 273a4783-3c37-4278-b1d0-b5bd6f1fddd6, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" }
2025-10-31 15:33:21,843 - DEBUG - ydb.connection - _log_response: RpcState(MakeDirectory, 273a4783-3c37-4278-b1d0-b5bd6f1fddd6, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:21,844 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, fce0dcbc-cf46-4634-b3e6-63c7f48355e3, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:21,844 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, fce0dcbc-cf46-4634-b3e6-63c7f48355e3, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:21,846 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, fce0dcbc-cf46-4634-b3e6-63c7f48355e3, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" } } } }
2025-10-31 15:33:21,846 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 4bae2771-305e-418f-8b71-bc0c48850ce4, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:21,847 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 4bae2771-305e-418f-8b71-bc0c48850ce4, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" path: "/Root/folder/table" columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:21,869 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 4bae2771-305e-418f-8b71-bc0c48850ce4, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:21,870 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 150058f3-42e0-465f-9aa3-6790849df9b7, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:21,871 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 150058f3-42e0-465f-9aa3-6790849df9b7, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n        PRAGMA TablePathPrefix(\"/Root/folder\");\n        UPSERT INTO table (`id`, `number`, `string`, `fixed_point`) VALUES (2, 6,  \"pen\",       CAST(\"2.4\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`,           `string`, `fixed_point`) VALUES (3,     \"pineapple\", CAST(\"3.5\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`,          `fixed_point`) VALUES (5, 12,              CAST(\"512.6\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`, `string`             ) VALUES (7, 15, \"pen\"          );\n        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:21,912 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 150058f3-42e0-465f-9aa3-6790849df9b7, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:33:21,913 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:21,914 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 15:33:21,914 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 15:33:21,915 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'dump', '--path', '/Root/folder', '--output', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:33:21,916 - DEBUG - ya.test - execute: Command pid: 2753593
2025-10-31 15:33:22,083 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) rc: 0
2025-10-31 15:33:22,083 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) elapsed time (sec): 0.16654372215270996
2025-10-31 15:33:22,083 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) idrss: 0
2025-10-31 15:33:22,083 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) inblock: 0
2025-10-31 15:33:22,084 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) isrss: 0
2025-10-31 15:33:22,084 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) ixrss: 0
2025-10-31 15:33:22,084 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) majflt: 0
2025-10-31 15:33:22,084 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) maxrss: 165588
2025-10-31 15:33:22,084 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) minflt: 3606
2025-10-31 15:33:22,084 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) msgrcv: 0
2025-10-31 15:33:22,084 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) msgsnd: 0
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) nivcsw: 2
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) nsignals: 0
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) nswap: 0
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) nvcsw: 277
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) oublock: 32
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) stime: 0.024657
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) utime: 0.049314
2025-10-31 15:33:22,085 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) wtime: 0.168
2025-10-31 15:33:22,086 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) output:

2025-10-31 15:33:22,086 - DEBUG - ya.test - _save_outputs: Command (pid 2753593) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/folder"
Path after adjusting: "/Root/folder"

2025-10-31 15:33:22,086 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 0d30eb57-d8f1-4c74-94f3-af76ed96f410, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,087 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 0d30eb57-d8f1-4c74-94f3-af76ed96f410, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:22,089 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 0d30eb57-d8f1-4c74-94f3-af76ed96f410, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:22,090 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:22,091 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-31 15:33:22,091 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 15:33:22,092 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'restore', '--path', '/Root/restored', '--input', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:33:22,093 - DEBUG - ya.test - execute: Command pid: 2753637
2025-10-31 15:33:22,262 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) rc: 0
2025-10-31 15:33:22,263 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) elapsed time (sec): 0.1692945957183838
2025-10-31 15:33:22,263 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) idrss: 0
2025-10-31 15:33:22,263 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) inblock: 0
2025-10-31 15:33:22,263 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) isrss: 0
2025-10-31 15:33:22,263 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) ixrss: 0
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) majflt: 0
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) maxrss: 165588
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) minflt: 4165
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) msgrcv: 0
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) msgsnd: 0
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) nivcsw: 4
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) nsignals: 0
2025-10-31 15:33:22,264 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) nswap: 0
2025-10-31 15:33:22,265 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) nvcsw: 598
2025-10-31 15:33:22,265 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) oublock: 8
2025-10-31 15:33:22,265 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) stime: 0.046151
2025-10-31 15:33:22,265 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) utime: 0.046151
2025-10-31 15:33:22,265 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) wtime: 0.17
2025-10-31 15:33:22,265 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) output:

2025-10-31 15:33:22,266 - DEBUG - ya.test - _save_outputs: Command (pid 2753637) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/restored"
Path after adjusting: "/Root/restored"

2025-10-31 15:33:22,266 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 79f255c1-dd7a-47c8-ac42-79820ac187c9, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,266 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 79f255c1-dd7a-47c8-ac42-79820ac187c9, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:22,269 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 79f255c1-dd7a-47c8-ac42-79820ac187c9, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: "restored" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924802250 tx_id: 281474976710669 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:22,270 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, a7a32814-41dd-4b3d-b5c1-f8d4b2f88646, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,270 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, a7a32814-41dd-4b3d-b5c1-f8d4b2f88646, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored" }
2025-10-31 15:33:22,272 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, a7a32814-41dd-4b3d-b5c1-f8d4b2f88646, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "restored" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924802250 tx_id: 281474976710669 } } children { name: "table" owner: "root@builtin" type: TABLE created_at { plan_step: 1761924802270 tx_id: 281474976710670 } } } } } }
2025-10-31 15:33:22,273 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, 019c473b-1a2a-4334-a2b8-31a4f4f00652, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,273 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, 019c473b-1a2a-4334-a2b8-31a4f4f00652, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" path: "/Root/folder/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,275 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, 019c473b-1a2a-4334-a2b8-31a4f4f00652, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:22,276 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, c8a48ba3-b174-4838-91cd-9e50356f103f, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,277 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, c8a48ba3-b174-4838-91cd-9e50356f103f, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" path: "/Root/restored/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,279 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, c8a48ba3-b174-4838-91cd-9e50356f103f, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924802270 tx_id: 281474976710670 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:22,279 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, d986db9b-ea48-4526-bc0c-ce3a52acea34, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,279 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, d986db9b-ea48-4526-bc0c-ce3a52acea34, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder/table" }
2025-10-31 15:33:22,281 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, d986db9b-ea48-4526-bc0c-ce3a52acea34, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } } } } }
2025-10-31 15:33:22,282 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 0ff2c302-21b8-43a0-86a3-d213c05536fa, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,282 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 0ff2c302-21b8-43a0-86a3-d213c05536fa, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored/table" }
2025-10-31 15:33:22,284 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 0ff2c302-21b8-43a0-86a3-d213c05536fa, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924802270 tx_id: 281474976710670 } } } } } }
2025-10-31 15:33:22,284 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, 0f6c0406-44c8-427e-84da-540333f43378, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,284 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, 0f6c0406-44c8-427e-84da-540333f43378, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" path: "/Root/folder/table" ordered: true }
2025-10-31 15:33:22,285 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, 0f6c0406-44c8-427e-84da-540333f43378, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:22,286 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, 8cc5d0a3-72bc-4484-be5d-15d33a30cc37, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,286 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, 8cc5d0a3-72bc-4484-be5d-15d33a30cc37, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" path: "/Root/restored/table" ordered: true }
2025-10-31 15:33:22,286 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, 8cc5d0a3-72bc-4484-be5d-15d33a30cc37, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:22,292 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, e913fde6-583b-4c33-b383-a14b53870326, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,293 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, e913fde6-583b-4c33-b383-a14b53870326, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZDllODMxMGMtOGUxNDZhODUtNjg0ZjQwYWEtNTNiZjcyM2E=" path: "/Root/restored/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,303 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, e913fde6-583b-4c33-b383-a14b53870326, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:22,303 - DEBUG - ydb.connection - _prepare_call: RpcState(RemoveDirectory, 7ecc7766-5598-438b-adc4-e1b0957bcfeb, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,304 - DEBUG - ydb.connection - _log_request: RpcState(RemoveDirectory, 7ecc7766-5598-438b-adc4-e1b0957bcfeb, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored" }
2025-10-31 15:33:22,312 - DEBUG - ydb.connection - _log_response: RpcState(RemoveDirectory, 7ecc7766-5598-438b-adc4-e1b0957bcfeb, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:22,312 - DEBUG - ydb.connection - _prepare_call: RpcState(MakeDirectory, 42961fb2-e6fd-431d-8fee-ba2767aa3b02, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,313 - DEBUG - ydb.connection - _log_request: RpcState(MakeDirectory, 42961fb2-e6fd-431d-8fee-ba2767aa3b02, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" }
2025-10-31 15:33:22,315 - DEBUG - ydb.connection - _log_response: RpcState(MakeDirectory, 42961fb2-e6fd-431d-8fee-ba2767aa3b02, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS issues { message: "Check failed: path: \'/Root/folder\', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 6], type: EPathTypeDir, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_mkdir.cpp:155" severity: 1 } } }
2025-10-31 15:33:22,315 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0ed2bb39-5be0-4920-9574-86891e4a3c7b, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,316 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0ed2bb39-5be0-4920-9574-86891e4a3c7b, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,318 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0ed2bb39-5be0-4920-9574-86891e4a3c7b, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" } } } }
2025-10-31 15:33:22,318 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, a5a66930-8328-4345-b9a0-8a759c7fb791, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,319 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, a5a66930-8328-4345-b9a0-8a759c7fb791, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" path: "/Root/folder/table" columns { name: "id" type { type_id: UINT32 } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,322 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, a5a66930-8328-4345-b9a0-8a759c7fb791, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS issues { message: "Check failed: path: \'/Root/folder/table\', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 7], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } }
2025-10-31 15:33:22,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6e22d7e7-d596-45f3-942f-d7735d30225f, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,323 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6e22d7e7-d596-45f3-942f-d7735d30225f, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n        PRAGMA TablePathPrefix(\"/Root/folder\");\n        UPSERT INTO table (`id`, `number`, `string`, `fixed_point`) VALUES (2, 6,  \"pen\",       CAST(\"2.4\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`,           `string`, `fixed_point`) VALUES (3,     \"pineapple\", CAST(\"3.5\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`,          `fixed_point`) VALUES (5, 12,              CAST(\"512.6\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`, `string`             ) VALUES (7, 15, \"pen\"          );\n        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6e22d7e7-d596-45f3-942f-d7735d30225f, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:33:22,343 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:22,344 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 15:33:22,344 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-10-31 15:33:22,344 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'dump', '--path', '/Root/folder', '--output', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore_not_null/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:33:22,345 - DEBUG - ya.test - execute: Command pid: 2753849
2025-10-31 15:33:22,514 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) rc: 0
2025-10-31 15:33:22,514 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) elapsed time (sec): 0.16764593124389648
2025-10-31 15:33:22,514 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) idrss: 0
2025-10-31 15:33:22,514 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) inblock: 0
2025-10-31 15:33:22,514 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) isrss: 0
2025-10-31 15:33:22,515 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) ixrss: 0
2025-10-31 15:33:22,515 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) majflt: 0
2025-10-31 15:33:22,515 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) maxrss: 165588
2025-10-31 15:33:22,515 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) minflt: 3612
2025-10-31 15:33:22,515 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) msgrcv: 0
2025-10-31 15:33:22,515 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) msgsnd: 0
2025-10-31 15:33:22,515 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) nivcsw: 2
2025-10-31 15:33:22,516 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) nsignals: 0
2025-10-31 15:33:22,516 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) nswap: 0
2025-10-31 15:33:22,516 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) nvcsw: 287
2025-10-31 15:33:22,516 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) oublock: 32
2025-10-31 15:33:22,516 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) stime: 0.025117
2025-10-31 15:33:22,516 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) utime: 0.046049
2025-10-31 15:33:22,516 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) wtime: 0.169
2025-10-31 15:33:22,517 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) output:

2025-10-31 15:33:22,517 - DEBUG - ya.test - _save_outputs: Command (pid 2753849) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/folder"
Path after adjusting: "/Root/folder"

2025-10-31 15:33:22,517 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 84e31241-ba57-457d-94ea-8426fe5164a0, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,518 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 84e31241-ba57-457d-94ea-8426fe5164a0, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:22,521 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 84e31241-ba57-457d-94ea-8426fe5164a0, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:22,522 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:22,523 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-10-31 15:33:22,523 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-10-31 15:33:22,523 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'restore', '--path', '/Root/restored_not_null', '--input', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore_not_null/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:33:22,524 - DEBUG - ya.test - execute: Command pid: 2753951
2025-10-31 15:33:22,696 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) rc: 0
2025-10-31 15:33:22,696 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) elapsed time (sec): 0.1707901954650879
2025-10-31 15:33:22,696 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) idrss: 0
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) inblock: 0
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) isrss: 0
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) ixrss: 0
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) majflt: 0
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) maxrss: 165588
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) minflt: 4182
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) msgrcv: 0
2025-10-31 15:33:22,697 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) msgsnd: 0
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) nivcsw: 1
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) nsignals: 0
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) nswap: 0
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) nvcsw: 591
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) oublock: 8
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) stime: 0.040333
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) utime: 0.055458
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) wtime: 0.172
2025-10-31 15:33:22,698 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) output:

2025-10-31 15:33:22,699 - DEBUG - ya.test - _save_outputs: Command (pid 2753951) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/restored_not_null"
Path after adjusting: "/Root/restored_not_null"

2025-10-31 15:33:22,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 33d43b33-6284-4e37-9d8a-e6b19d5d48fa, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,699 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 33d43b33-6284-4e37-9d8a-e6b19d5d48fa, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:22,703 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 33d43b33-6284-4e37-9d8a-e6b19d5d48fa, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: "restored_not_null" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924802680 tx_id: 281474976710688 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:22,704 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 6a2028a2-da36-4655-84b2-18621e85cf44, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,704 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 6a2028a2-da36-4655-84b2-18621e85cf44, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_not_null" }
2025-10-31 15:33:22,707 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 6a2028a2-da36-4655-84b2-18621e85cf44, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "restored_not_null" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924802680 tx_id: 281474976710688 } } children { name: "table" owner: "root@builtin" type: TABLE created_at { plan_step: 1761924802700 tx_id: 281474976710689 } } } } } }
2025-10-31 15:33:22,707 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, a146ff43-deaf-4165-9f18-66a3b2c07b8a, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,708 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, a146ff43-deaf-4165-9f18-66a3b2c07b8a, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" path: "/Root/folder/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,710 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, a146ff43-deaf-4165-9f18-66a3b2c07b8a, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:22,711 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, cf4d1ceb-ed44-4ccf-b54a-9444c442e024, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,711 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, cf4d1ceb-ed44-4ccf-b54a-9444c442e024, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" path: "/Root/restored_not_null/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,714 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, cf4d1ceb-ed44-4ccf-b54a-9444c442e024, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924802700 tx_id: 281474976710689 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:22,715 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 8e698de0-2776-4a1f-9bef-4cd8f69824b0, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,716 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 8e698de0-2776-4a1f-9bef-4cd8f69824b0, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder/table" }
2025-10-31 15:33:22,718 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 8e698de0-2776-4a1f-9bef-4cd8f69824b0, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } } } } }
2025-10-31 15:33:22,718 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 1e42673e-907b-4899-9755-dedf37af3e06, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,718 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 1e42673e-907b-4899-9755-dedf37af3e06, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_not_null/table" }
2025-10-31 15:33:22,720 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 1e42673e-907b-4899-9755-dedf37af3e06, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924802700 tx_id: 281474976710689 } } } } } }
2025-10-31 15:33:22,721 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, d2660bb4-3bcb-4279-84f7-9b8ecfcf4ff1, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,721 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, d2660bb4-3bcb-4279-84f7-9b8ecfcf4ff1, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" path: "/Root/folder/table" ordered: true }
2025-10-31 15:33:22,722 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, d2660bb4-3bcb-4279-84f7-9b8ecfcf4ff1, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:22,723 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, bfed0a4c-fa70-45c8-b179-f9c444b0337d, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,723 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, bfed0a4c-fa70-45c8-b179-f9c444b0337d, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" path: "/Root/restored_not_null/table" ordered: true }
2025-10-31 15:33:22,724 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, bfed0a4c-fa70-45c8-b179-f9c444b0337d, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:22,733 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 26164669-0f31-497d-8e13-58bef3838079, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,734 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 26164669-0f31-497d-8e13-58bef3838079, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=ZmE0ODJkYzctMjM5NmU3MjItNzg2NTNmMy0xZmQwODcwOA==" path: "/Root/restored_not_null/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,744 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 26164669-0f31-497d-8e13-58bef3838079, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:22,744 - DEBUG - ydb.connection - _prepare_call: RpcState(RemoveDirectory, b29024c5-257b-4d5e-9f3f-e93eff9d835f, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,745 - DEBUG - ydb.connection - _log_request: RpcState(RemoveDirectory, b29024c5-257b-4d5e-9f3f-e93eff9d835f, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_not_null" }
2025-10-31 15:33:22,752 - DEBUG - ydb.connection - _log_response: RpcState(RemoveDirectory, b29024c5-257b-4d5e-9f3f-e93eff9d835f, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:22,753 - DEBUG - ydb.connection - _prepare_call: RpcState(MakeDirectory, 04812a44-4c73-4763-ae88-460716674762, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,753 - DEBUG - ydb.connection - _log_request: RpcState(MakeDirectory, 04812a44-4c73-4763-ae88-460716674762, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" }
2025-10-31 15:33:22,756 - DEBUG - ydb.connection - _log_response: RpcState(MakeDirectory, 04812a44-4c73-4763-ae88-460716674762, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS issues { message: "Check failed: path: \'/Root/folder\', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 6], type: EPathTypeDir, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_mkdir.cpp:155" severity: 1 } } }
2025-10-31 15:33:22,756 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cd442aa9-ea0c-4876-b28e-ef0274f79de6, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,756 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cd442aa9-ea0c-4876-b28e-ef0274f79de6, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,758 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cd442aa9-ea0c-4876-b28e-ef0274f79de6, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" } } } }
2025-10-31 15:33:22,758 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 4628b3fd-36db-4887-8747-1d454f1b3ea4, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,759 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 4628b3fd-36db-4887-8747-1d454f1b3ea4, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" path: "/Root/folder/table" columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,762 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 4628b3fd-36db-4887-8747-1d454f1b3ea4, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS issues { message: "Check failed: path: \'/Root/folder/table\', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 7], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } }
2025-10-31 15:33:22,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, da735faf-3c57-4100-b047-394fea41d9aa, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, da735faf-3c57-4100-b047-394fea41d9aa, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n        PRAGMA TablePathPrefix(\"/Root/folder\");\n        UPSERT INTO table (`id`, `number`, `string`, `fixed_point`) VALUES (2, 6,  \"pen\",       CAST(\"2.4\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`,           `string`, `fixed_point`) VALUES (3,     \"pineapple\", CAST(\"3.5\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`,          `fixed_point`) VALUES (5, 12,              CAST(\"512.6\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`, `string`             ) VALUES (7, 15, \"pen\"          );\n        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:22,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, da735faf-3c57-4100-b047-394fea41d9aa, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:33:22,788 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:22,789 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-10-31 15:33:22,789 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 15:33:22,790 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'dump', '--path', '/Root/folder', '--output', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore_bulk_upsert/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:33:22,790 - DEBUG - ya.test - execute: Command pid: 2754463
2025-10-31 15:33:22,968 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) rc: 0
2025-10-31 15:33:22,968 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) elapsed time (sec): 0.17710399627685547
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) idrss: 0
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) inblock: 0
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) isrss: 0
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) ixrss: 0
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) majflt: 0
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) maxrss: 165588
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) minflt: 3613
2025-10-31 15:33:22,969 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) msgrcv: 0
2025-10-31 15:33:22,970 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) msgsnd: 0
2025-10-31 15:33:22,970 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) nivcsw: 1
2025-10-31 15:33:22,970 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) nsignals: 0
2025-10-31 15:33:22,970 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) nswap: 0
2025-10-31 15:33:22,970 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) nvcsw: 295
2025-10-31 15:33:22,970 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) oublock: 32
2025-10-31 15:33:22,970 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) stime: 0.035838
2025-10-31 15:33:22,971 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) utime: 0.035838
2025-10-31 15:33:22,971 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) wtime: 0.178
2025-10-31 15:33:22,971 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) output:

2025-10-31 15:33:22,971 - DEBUG - ya.test - _save_outputs: Command (pid 2754463) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/folder"
Path after adjusting: "/Root/folder"

2025-10-31 15:33:22,972 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 387bc004-e23b-4a52-a2fa-f89f007d6f76, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:22,972 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 387bc004-e23b-4a52-a2fa-f89f007d6f76, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:22,975 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 387bc004-e23b-4a52-a2fa-f89f007d6f76, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:22,976 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:22,977 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-10-31 15:33:22,977 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 15:33:22,978 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'restore', '--path', '/Root/restored_bulk_upsert', '--input', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore_bulk_upsert/backup_files_dir', '--bulk-upsert']' in 'None' (waiting)
2025-10-31 15:33:22,978 - DEBUG - ya.test - execute: Command pid: 2754587
2025-10-31 15:33:23,135 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) rc: 0
2025-10-31 15:33:23,135 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) elapsed time (sec): 0.15561842918395996
2025-10-31 15:33:23,135 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) idrss: 0
2025-10-31 15:33:23,136 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) inblock: 0
2025-10-31 15:33:23,136 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) isrss: 0
2025-10-31 15:33:23,136 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) ixrss: 0
2025-10-31 15:33:23,136 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) majflt: 0
2025-10-31 15:33:23,136 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) maxrss: 165588
2025-10-31 15:33:23,136 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) minflt: 4163
2025-10-31 15:33:23,136 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) msgrcv: 0
2025-10-31 15:33:23,137 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) msgsnd: 0
2025-10-31 15:33:23,137 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) nivcsw: 1
2025-10-31 15:33:23,137 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) nsignals: 0
2025-10-31 15:33:23,137 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) nswap: 0
2025-10-31 15:33:23,137 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) nvcsw: 619
2025-10-31 15:33:23,137 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) oublock: 8
2025-10-31 15:33:23,137 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) stime: 0.040271
2025-10-31 15:33:23,138 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) utime: 0.048326
2025-10-31 15:33:23,138 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) wtime: 0.157
2025-10-31 15:33:23,138 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) output:

2025-10-31 15:33:23,138 - DEBUG - ya.test - _save_outputs: Command (pid 2754587) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/restored_bulk_upsert"
Path after adjusting: "/Root/restored_bulk_upsert"

2025-10-31 15:33:23,138 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 072cba0f-da20-4276-be12-64fe585c56d2, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,139 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 072cba0f-da20-4276-be12-64fe585c56d2, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:23,143 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 072cba0f-da20-4276-be12-64fe585c56d2, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: "restored_bulk_upsert" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924803130 tx_id: 281474976710707 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:23,144 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 2a363adf-c291-4733-868c-7627a1e40f7d, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,144 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 2a363adf-c291-4733-868c-7627a1e40f7d, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_bulk_upsert" }
2025-10-31 15:33:23,146 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 2a363adf-c291-4733-868c-7627a1e40f7d, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "restored_bulk_upsert" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924803130 tx_id: 281474976710707 } } children { name: "table" owner: "root@builtin" type: TABLE created_at { plan_step: 1761924803150 tx_id: 281474976710708 } } } } } }
2025-10-31 15:33:23,147 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, 0ac4c6fa-ae85-446d-b94f-4bcb6a005a50, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,147 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, 0ac4c6fa-ae85-446d-b94f-4bcb6a005a50, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" path: "/Root/folder/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,150 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, 0ac4c6fa-ae85-446d-b94f-4bcb6a005a50, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:23,151 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, e5bc7f9f-edca-4101-8262-a0f6d5cfd6f7, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,151 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, e5bc7f9f-edca-4101-8262-a0f6d5cfd6f7, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" path: "/Root/restored_bulk_upsert/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,154 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, e5bc7f9f-edca-4101-8262-a0f6d5cfd6f7, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924803150 tx_id: 281474976710708 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:23,154 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 1d50403c-de6f-4306-b3d9-15ce24b4061e, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,155 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 1d50403c-de6f-4306-b3d9-15ce24b4061e, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder/table" }
2025-10-31 15:33:23,157 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 1d50403c-de6f-4306-b3d9-15ce24b4061e, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } } } } }
2025-10-31 15:33:23,157 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, b29f0871-6ece-4c70-a404-12828563341a, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,157 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, b29f0871-6ece-4c70-a404-12828563341a, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_bulk_upsert/table" }
2025-10-31 15:33:23,159 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, b29f0871-6ece-4c70-a404-12828563341a, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924803150 tx_id: 281474976710708 } } } } } }
2025-10-31 15:33:23,160 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, 87d31548-5c4b-47b0-877e-50f3838ef356, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,160 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, 87d31548-5c4b-47b0-877e-50f3838ef356, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" path: "/Root/folder/table" ordered: true }
2025-10-31 15:33:23,161 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, 87d31548-5c4b-47b0-877e-50f3838ef356, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:23,162 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, 8a219f3e-1941-4ae4-aa26-8b3ad0b8f3c9, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,162 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, 8a219f3e-1941-4ae4-aa26-8b3ad0b8f3c9, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" path: "/Root/restored_bulk_upsert/table" ordered: true }
2025-10-31 15:33:23,163 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, 8a219f3e-1941-4ae4-aa26-8b3ad0b8f3c9, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:23,173 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 196dbdf0-f626-42bc-a6ea-06494b1e685f, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,174 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 196dbdf0-f626-42bc-a6ea-06494b1e685f, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=Yzc4ODI4NWEtNjU1NWY1OGMtMzQ5YWE0MGItODc5Yjk2NTY=" path: "/Root/restored_bulk_upsert/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,184 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 196dbdf0-f626-42bc-a6ea-06494b1e685f, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:23,184 - DEBUG - ydb.connection - _prepare_call: RpcState(RemoveDirectory, caf49ea6-6976-4251-84f7-7d8a325a0e97, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,185 - DEBUG - ydb.connection - _log_request: RpcState(RemoveDirectory, caf49ea6-6976-4251-84f7-7d8a325a0e97, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_bulk_upsert" }
2025-10-31 15:33:23,193 - DEBUG - ydb.connection - _log_response: RpcState(RemoveDirectory, caf49ea6-6976-4251-84f7-7d8a325a0e97, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:23,193 - DEBUG - ydb.connection - _prepare_call: RpcState(MakeDirectory, d943a7e6-1fe5-4561-a3e3-a509379987df, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,194 - DEBUG - ydb.connection - _log_request: RpcState(MakeDirectory, d943a7e6-1fe5-4561-a3e3-a509379987df, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" }
2025-10-31 15:33:23,196 - DEBUG - ydb.connection - _log_response: RpcState(MakeDirectory, d943a7e6-1fe5-4561-a3e3-a509379987df, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS issues { message: "Check failed: path: \'/Root/folder\', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 6], type: EPathTypeDir, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_mkdir.cpp:155" severity: 1 } } }
2025-10-31 15:33:23,197 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, eda70d8b-8099-45fa-8e3e-024414163473, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,197 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, eda70d8b-8099-45fa-8e3e-024414163473, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,199 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, eda70d8b-8099-45fa-8e3e-024414163473, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" } } } }
2025-10-31 15:33:23,199 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 4badd9db-3b2b-4766-a183-8208618ecd8a, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,200 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 4badd9db-3b2b-4766-a183-8208618ecd8a, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" path: "/Root/folder/table" columns { name: "id" type { type_id: UINT32 } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,203 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 4badd9db-3b2b-4766-a183-8208618ecd8a, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS issues { message: "Check failed: path: \'/Root/folder/table\', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 7], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } }
2025-10-31 15:33:23,204 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 814c0cd1-fe02-4315-8082-c6e14a61a935, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,205 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 814c0cd1-fe02-4315-8082-c6e14a61a935, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n        PRAGMA TablePathPrefix(\"/Root/folder\");\n        UPSERT INTO table (`id`, `number`, `string`, `fixed_point`) VALUES (2, 6,  \"pen\",       CAST(\"2.4\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`,           `string`, `fixed_point`) VALUES (3,     \"pineapple\", CAST(\"3.5\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`,          `fixed_point`) VALUES (5, 12,              CAST(\"512.6\" AS Decimal(22,9)));\n        UPSERT INTO table (`id`, `number`, `string`             ) VALUES (7, 15, \"pen\"          );\n        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,229 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 814c0cd1-fe02-4315-8082-c6e14a61a935, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:33:23,230 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:23,231 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-10-31 15:33:23,231 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-10-31 15:33:23,232 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'dump', '--path', '/Root/folder', '--output', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore_bulk_upsert_not_null/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:33:23,232 - DEBUG - ya.test - execute: Command pid: 2754963
2025-10-31 15:33:23,405 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) rc: 0
2025-10-31 15:33:23,406 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) elapsed time (sec): 0.17170166969299316
2025-10-31 15:33:23,406 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) idrss: 0
2025-10-31 15:33:23,406 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) inblock: 0
2025-10-31 15:33:23,406 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) isrss: 0
2025-10-31 15:33:23,406 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) ixrss: 0
2025-10-31 15:33:23,406 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) majflt: 0
2025-10-31 15:33:23,407 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) maxrss: 165588
2025-10-31 15:33:23,407 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) minflt: 3596
2025-10-31 15:33:23,407 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) msgrcv: 0
2025-10-31 15:33:23,407 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) msgsnd: 0
2025-10-31 15:33:23,407 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) nivcsw: 1
2025-10-31 15:33:23,407 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) nsignals: 0
2025-10-31 15:33:23,407 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) nswap: 0
2025-10-31 15:33:23,408 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) nvcsw: 272
2025-10-31 15:33:23,408 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) oublock: 32
2025-10-31 15:33:23,408 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) stime: 0.018937
2025-10-31 15:33:23,408 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) utime: 0.056811
2025-10-31 15:33:23,408 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) wtime: 0.173
2025-10-31 15:33:23,408 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) output:

2025-10-31 15:33:23,408 - DEBUG - ya.test - _save_outputs: Command (pid 2754963) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/folder"
Path after adjusting: "/Root/folder"

2025-10-31 15:33:23,409 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 10bca183-4e27-4d13-993b-eb31c8914af2, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,409 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 10bca183-4e27-4d13-993b-eb31c8914af2, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:23,413 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 10bca183-4e27-4d13-993b-eb31c8914af2, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:23,413 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb
2025-10-31 15:33:23,414 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-10-31 15:33:23,415 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-10-31 15:33:23,415 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002e2f/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65481', '--database', '/Root', 'tools', 'restore', '--path', '/Root/restored_bulk_upsert_not_null', '--input', '/home/runner/.ya/build/build_root/stm2/002e2f/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk24/testing_out_stuff/TestSingleBackupRestore/test_single_table_with_data_backup_restore_bulk_upsert_not_null/backup_files_dir', '--bulk-upsert']' in 'None' (waiting)
2025-10-31 15:33:23,416 - DEBUG - ya.test - execute: Command pid: 2755118
2025-10-31 15:33:23,572 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) rc: 0
2025-10-31 15:33:23,573 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) elapsed time (sec): 0.15628981590270996
2025-10-31 15:33:23,573 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) idrss: 0
2025-10-31 15:33:23,573 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) inblock: 0
2025-10-31 15:33:23,574 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) isrss: 0
2025-10-31 15:33:23,574 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) ixrss: 0
2025-10-31 15:33:23,574 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) majflt: 0
2025-10-31 15:33:23,574 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) maxrss: 165588
2025-10-31 15:33:23,574 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) minflt: 4168
2025-10-31 15:33:23,575 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) msgrcv: 0
2025-10-31 15:33:23,575 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) msgsnd: 0
2025-10-31 15:33:23,575 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) nivcsw: 1
2025-10-31 15:33:23,577 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) nsignals: 0
2025-10-31 15:33:23,577 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) nswap: 0
2025-10-31 15:33:23,577 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) nvcsw: 597
2025-10-31 15:33:23,578 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) oublock: 8
2025-10-31 15:33:23,578 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) stime: 0.039882
2025-10-31 15:33:23,579 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) utime: 0.049852999999999995
2025-10-31 15:33:23,579 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) wtime: 0.157
2025-10-31 15:33:23,579 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) output:

2025-10-31 15:33:23,580 - DEBUG - ya.test - _save_outputs: Command (pid 2755118) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root/restored_bulk_upsert_not_null"
Path after adjusting: "/Root/restored_bulk_upsert_not_null"

2025-10-31 15:33:23,581 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 4bd12184-a553-4c4c-903c-d2085942cedf, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,581 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 4bd12184-a553-4c4c-903c-d2085942cedf, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:33:23,585 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 4bd12184-a553-4c4c-903c-d2085942cedf, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root" owner: "root" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } permissions { subject: "USERS" permission_names: "ydb.database.connect" } permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801850 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924799940 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924801890 tx_id: 281474976710660 } } children { name: "restored_bulk_upsert_not_null" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924803570 tx_id: 281474976710725 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:33:23,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 03fdb6bc-af11-4d47-8a38-bfc1083ca0ee, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,586 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 03fdb6bc-af11-4d47-8a38-bfc1083ca0ee, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_bulk_upsert_not_null" }
2025-10-31 15:33:23,588 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 03fdb6bc-af11-4d47-8a38-bfc1083ca0ee, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "restored_bulk_upsert_not_null" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924803570 tx_id: 281474976710725 } } children { name: "table" owner: "root@builtin" type: TABLE created_at { plan_step: 1761924803590 tx_id: 281474976710726 } } } } } }
2025-10-31 15:33:23,589 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, a12a2436-71a7-454b-b213-e724a44d6df3, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,590 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, a12a2436-71a7-454b-b213-e724a44d6df3, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" path: "/Root/folder/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,594 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, a12a2436-71a7-454b-b213-e724a44d6df3, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:23,594 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, 66b7019b-372e-4ec1-a6a1-f346a89cfeac, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,594 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, 66b7019b-372e-4ec1-a6a1-f346a89cfeac, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" path: "/Root/restored_bulk_upsert_not_null/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,597 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, 66b7019b-372e-4ec1-a6a1-f346a89cfeac, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924803590 tx_id: 281474976710726 } } columns { name: "id" type { optional_type { item { type_id: UINT32 } } } } columns { name: "number" type { optional_type { item { type_id: UINT64 } } } } columns { name: "string" type { optional_type { item { type_id: STRING } } } } columns { name: "fixed_point" type { optional_type { item { decimal_type { precision: 22 scale: 9 } } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-10-31 15:33:23,598 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, bac74b88-cf51-4d5d-b204-56419af3d37b, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,598 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, bac74b88-cf51-4d5d-b204-56419af3d37b, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder/table" }
2025-10-31 15:33:23,600 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, bac74b88-cf51-4d5d-b204-56419af3d37b, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924801910 tx_id: 281474976710661 } } } } } }
2025-10-31 15:33:23,601 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, d2ff3873-74a1-4c9b-90e2-b6d11c34c3f9, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,601 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, d2ff3873-74a1-4c9b-90e2-b6d11c34c3f9, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_bulk_upsert_not_null/table" }
2025-10-31 15:33:23,603 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, d2ff3873-74a1-4c9b-90e2-b6d11c34c3f9, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1761924803590 tx_id: 281474976710726 } } } } } }
2025-10-31 15:33:23,603 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, 9467e2f5-fe88-41d0-b635-a138797127ea, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,603 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, 9467e2f5-fe88-41d0-b635-a138797127ea, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" path: "/Root/folder/table" ordered: true }
2025-10-31 15:33:23,604 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, 9467e2f5-fe88-41d0-b635-a138797127ea, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:23,605 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, 8e8655c6-d362-4299-bc13-360cc8163887, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,605 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, 8e8655c6-d362-4299-bc13-360cc8163887, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" path: "/Root/restored_bulk_upsert_not_null/table" ordered: true }
2025-10-31 15:33:23,606 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, 8e8655c6-d362-4299-bc13-360cc8163887, ghrun-upzbhs6y6e.auto.internal:65481): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:33:23,613 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, dde8c9d7-0357-4ba6-a976-22fd81fecafa, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,614 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, dde8c9d7-0357-4ba6-a976-22fd81fecafa, ghrun-upzbhs6y6e.auto.internal:65481): request = { session_id: "ydb://session/3?node_id=1&id=NmQzZjBiMTUtNzAwYjcyYjktZjAxMTliOTktY2JjOTY0NWU=" path: "/Root/restored_bulk_upsert_not_null/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:23,623 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, dde8c9d7-0357-4ba6-a976-22fd81fecafa, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:23,623 - DEBUG - ydb.connection - _prepare_call: RpcState(RemoveDirectory, 36e874d6-f826-4732-8af4-860bfd477ace, ghrun-upzbhs6y6e.auto.internal:65481): creating call state
2025-10-31 15:33:23,624 - DEBUG - ydb.connection - _log_request: RpcState(RemoveDirectory, 36e874d6-f826-4732-8af4-860bfd477ace, ghrun-upzbhs6y6e.auto.internal:65481): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored_bulk_upsert_not_null" }
2025-10-31 15:33:23,632 - DEBUG - ydb.connection - _log_response: RpcState(RemoveDirectory, 36e874d6-f826-4732-8af4-860bfd477ace, ghrun-upzbhs6y6e.auto.internal:65481): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:23,633 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:33:45,635 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:65481/1
2025-10-31 15:33:45,637 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16977
2025-10-31 15:33:45,637 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2810
2025-10-31 15:33:45,637 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62668
2025-10-31 15:33:45,637 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/65481
2025-10-31 15:33:45,638 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23178
