2025-10-31 15:32:46,216 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:32:46,217 - INFO - ya.test - pytest_runtest_setup: test_dont_overwrite_on_already_existing
2025-10-31 15:32:46,217 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:32:46,217 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:32:46,271 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd
2025-10-31 15:32:46,272 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12756
2025-10-31 15:32:46,274 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/stm2/002f1e/r3tmp/kikimr_cluster_qn7mj3az
2025-10-31 15:32:46,308 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd
2025-10-31 15:32:46,309 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/65411
2025-10-31 15:32:46,309 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28432
2025-10-31 15:32:46,310 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28432
2025-10-31 15:32:46,310 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28433
2025-10-31 15:32:46,311 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19352
2025-10-31 15:32:46,312 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10347
2025-10-31 15:32:46,312 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/test_ydb_backup.py.TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting.test_dont_overwrite_on_already_existing/cluster/kikimr_configs"
2025-10-31 15:32:46,313 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/002f1e/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/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/test_ydb_backup.py.TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting.test_dont_overwrite_on_already_existing/cluster/node_1/logfile_zpvifci_.log --grpc-port=65411 --mon-port=28433 --ic-port=12756
2025-10-31 15:32:46,313 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:65411/1, disk_size 68719476736
2025-10-31 15:32:46,313 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/test_ydb_backup.py.TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting.test_dont_overwrite_on_already_existing/cluster/kikimr_configs"
2025-10-31 15:32:46,313 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/002f1e/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/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/test_ydb_backup.py.TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting.test_dont_overwrite_on_already_existing/cluster/node_1/logfile_zpvifci_.log --grpc-port=65411 --mon-port=28433 --ic-port=12756
2025-10-31 15:32:46,314 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/test_ydb_backup.py.TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting.test_dont_overwrite_on_already_existing/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/test_ydb_backup.py.TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting.test_dont_overwrite_on_already_existing/cluster/node_1/logfile_zpvifci_.log', '--grpc-port=65411', '--mon-port=28433', '--ic-port=12756')' in '/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/test_ydb_backup.py.TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting.test_dont_overwrite_on_already_existing/cluster/node_1' (no wait)
2025-10-31 15:32:46,316 - DEBUG - ya.test - execute: Command pid: 2726762
2025-10-31 15:32:46,316 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:65411/1
2025-10-31 15:32:46,318 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 15:32:46,318 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 15:32:46,320 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 15:32:46,321 - DEBUG - ya.test - execute: Command pid: 2726763
2025-10-31 15:32:46,736 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) rc: 0
2025-10-31 15:32:46,736 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) elapsed time (sec): 0.41422533988952637
2025-10-31 15:32:46,737 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) idrss: 0
2025-10-31 15:32:46,737 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) inblock: 0
2025-10-31 15:32:46,737 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) isrss: 0
2025-10-31 15:32:46,737 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) ixrss: 0
2025-10-31 15:32:46,737 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) majflt: 0
2025-10-31 15:32:46,737 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) maxrss: 538348
2025-10-31 15:32:46,737 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) minflt: 16132
2025-10-31 15:32:46,738 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) msgrcv: 0
2025-10-31 15:32:46,738 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) msgsnd: 0
2025-10-31 15:32:46,738 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) nivcsw: 2
2025-10-31 15:32:46,738 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) nsignals: 0
2025-10-31 15:32:46,738 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) nswap: 0
2025-10-31 15:32:46,738 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) nvcsw: 16
2025-10-31 15:32:46,738 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) oublock: 8
2025-10-31 15:32:46,739 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) stime: 0.257591
2025-10-31 15:32:46,739 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) utime: 0.10867099999999999
2025-10-31 15:32:46,739 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) wtime: 0.415
2025-10-31 15:32:46,739 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) 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:32:46,739 - DEBUG - ya.test - _save_outputs: Command (pid 2726763) errors:

2025-10-31 15:32:46,740 - 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:32:48,518 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd
2025-10-31 15:32:48,518 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65411', '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: 12756\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 15:32:48,519 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 15:32:48,519 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 15:32:48,520 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65411', '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: 12756\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 15:32:48,520 - DEBUG - ya.test - execute: Command pid: 2728286
2025-10-31 15:32:48,946 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) rc: 0
2025-10-31 15:32:48,946 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) elapsed time (sec): 0.425018310546875
2025-10-31 15:32:48,947 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) idrss: 0
2025-10-31 15:32:48,947 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) inblock: 0
2025-10-31 15:32:48,947 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) isrss: 0
2025-10-31 15:32:48,947 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) ixrss: 0
2025-10-31 15:32:48,947 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) majflt: 0
2025-10-31 15:32:48,947 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) maxrss: 546812
2025-10-31 15:32:48,947 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) minflt: 18274
2025-10-31 15:32:48,948 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) msgrcv: 0
2025-10-31 15:32:48,948 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) msgsnd: 0
2025-10-31 15:32:48,948 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) nivcsw: 3
2025-10-31 15:32:48,948 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) nsignals: 0
2025-10-31 15:32:48,948 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) nswap: 0
2025-10-31 15:32:48,948 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) nvcsw: 137
2025-10-31 15:32:48,949 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) oublock: 8
2025-10-31 15:32:48,949 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) stime: 0.286246
2025-10-31 15:32:48,949 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) utime: 0.095662
2025-10-31 15:32:48,949 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) wtime: 0.426
2025-10-31 15:32:48,949 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 15:32:48,949 - DEBUG - ya.test - _save_outputs: Command (pid 2728286) errors:

2025-10-31 15:32:48,950 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd
2025-10-31 15:32:48,951 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65411', '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:32:48,952 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 15:32:48,954 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 15:32:48,954 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65411', '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:32:48,959 - DEBUG - ya.test - execute: Command pid: 2728626
2025-10-31 15:32:49,385 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) rc: 0
2025-10-31 15:32:49,385 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) elapsed time (sec): 0.4245924949645996
2025-10-31 15:32:49,385 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) idrss: 0
2025-10-31 15:32:49,385 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) inblock: 0
2025-10-31 15:32:49,385 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) isrss: 0
2025-10-31 15:32:49,386 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) ixrss: 0
2025-10-31 15:32:49,386 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) majflt: 0
2025-10-31 15:32:49,386 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) maxrss: 546644
2025-10-31 15:32:49,386 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) minflt: 18387
2025-10-31 15:32:49,386 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) msgrcv: 0
2025-10-31 15:32:49,386 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) msgsnd: 0
2025-10-31 15:32:49,386 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) nivcsw: 1
2025-10-31 15:32:49,387 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) nsignals: 0
2025-10-31 15:32:49,387 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) nswap: 0
2025-10-31 15:32:49,387 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) nvcsw: 134
2025-10-31 15:32:49,387 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) oublock: 8
2025-10-31 15:32:49,387 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) stime: 0.260999
2025-10-31 15:32:49,387 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) utime: 0.120461
2025-10-31 15:32:49,387 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) wtime: 0.429
2025-10-31 15:32:49,388 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 15:32:49,388 - DEBUG - ya.test - _save_outputs: Command (pid 2728626) errors:

2025-10-31 15:32:49,388 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 15:32:49,401 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 15:32:49,410 - 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:32:49,412 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 15:32:49,413 - 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:32:49,421 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 15:32:49,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 13367b97-ffae-4c88-96b3-dffec845dfe6, localhost:65411): creating call state
2025-10-31 15:32:49,422 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 13367b97-ffae-4c88-96b3-dffec845dfe6, localhost:65411): request = { database: "/Root" }
2025-10-31 15:32:49,425 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 13367b97-ffae-4c88-96b3-dffec845dfe6, localhost:65411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-upzbhs6y6e.auto.internal" port: 65411 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:32:49,426 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-upzbhs6y6e.auto.internal:65411, location 1, ssl: False>]>
2025-10-31 15:32:49,426 - 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:32:49,434 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_backup.py::TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting, test_name: test_dont_overwrite_on_already_existing)
2025-10-31 15:32:49,435 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb
2025-10-31 15:32:49,435 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 15:32:49,436 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 15:32:49,436 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65411', '--database', '/Root', 'yql', '--script', "CREATE USER alice PASSWORD 'password'"]' in 'None' (waiting)
2025-10-31 15:32:49,437 - DEBUG - ya.test - execute: Command pid: 2728822
2025-10-31 15:32:49,553 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) rc: 0
2025-10-31 15:32:49,553 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) elapsed time (sec): 0.11566019058227539
2025-10-31 15:32:49,554 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) idrss: 0
2025-10-31 15:32:49,554 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) inblock: 0
2025-10-31 15:32:49,554 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) isrss: 0
2025-10-31 15:32:49,554 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) ixrss: 0
2025-10-31 15:32:49,554 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) majflt: 0
2025-10-31 15:32:49,554 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) maxrss: 163004
2025-10-31 15:32:49,555 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) minflt: 3424
2025-10-31 15:32:49,555 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) msgrcv: 0
2025-10-31 15:32:49,555 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) msgsnd: 0
2025-10-31 15:32:49,555 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) nivcsw: 0
2025-10-31 15:32:49,555 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) nsignals: 0
2025-10-31 15:32:49,555 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) nswap: 0
2025-10-31 15:32:49,555 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) nvcsw: 197
2025-10-31 15:32:49,556 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) oublock: 8
2025-10-31 15:32:49,556 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) stime: 0.014156
2025-10-31 15:32:49,556 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) utime: 0.047186
2025-10-31 15:32:49,556 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) wtime: 0.117
2025-10-31 15:32:49,556 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) output:

2025-10-31 15:32:49,556 - DEBUG - ya.test - _save_outputs: Command (pid 2728822) errors:
stdin is empty (EOF)
No authentication methods were found. Going without authentication

2025-10-31 15:32:49,557 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb
2025-10-31 15:32:49,557 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-31 15:32:49,558 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 15:32:49,558 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65411', '--database', '/Root', 'yql', '--script', "CREATE USER bob PASSWORD 'password'"]' in 'None' (waiting)
2025-10-31 15:32:49,559 - DEBUG - ya.test - execute: Command pid: 2728902
2025-10-31 15:32:49,675 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) rc: 0
2025-10-31 15:32:49,675 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) elapsed time (sec): 0.11569476127624512
2025-10-31 15:32:49,676 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) idrss: 0
2025-10-31 15:32:49,676 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) inblock: 0
2025-10-31 15:32:49,676 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) isrss: 0
2025-10-31 15:32:49,676 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) ixrss: 0
2025-10-31 15:32:49,676 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) majflt: 0
2025-10-31 15:32:49,676 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) maxrss: 163004
2025-10-31 15:32:49,677 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) minflt: 3427
2025-10-31 15:32:49,677 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) msgrcv: 0
2025-10-31 15:32:49,677 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) msgsnd: 0
2025-10-31 15:32:49,677 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) nivcsw: 0
2025-10-31 15:32:49,677 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) nsignals: 0
2025-10-31 15:32:49,677 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) nswap: 0
2025-10-31 15:32:49,677 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) nvcsw: 183
2025-10-31 15:32:49,678 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) oublock: 8
2025-10-31 15:32:49,678 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) stime: 0.024225
2025-10-31 15:32:49,678 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) utime: 0.036337999999999995
2025-10-31 15:32:49,678 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) wtime: 0.117
2025-10-31 15:32:49,678 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) output:

2025-10-31 15:32:49,678 - DEBUG - ya.test - _save_outputs: Command (pid 2728902) errors:
stdin is empty (EOF)
No authentication methods were found. Going without authentication

2025-10-31 15:32:49,679 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb
2025-10-31 15:32:49,680 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 15:32:49,680 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-10-31 15:32:49,680 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65411', '--database', '/Root', 'yql', '--script', "CREATE USER eve PASSWORD 'password'"]' in 'None' (waiting)
2025-10-31 15:32:49,681 - DEBUG - ya.test - execute: Command pid: 2729021
2025-10-31 15:32:49,795 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) rc: 0
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) elapsed time (sec): 0.11368775367736816
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) idrss: 0
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) inblock: 0
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) isrss: 0
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) ixrss: 0
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) majflt: 0
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) maxrss: 163004
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) minflt: 3435
2025-10-31 15:32:49,796 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) msgrcv: 0
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) msgsnd: 0
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) nivcsw: 1
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) nsignals: 0
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) nswap: 0
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) nvcsw: 205
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) oublock: 8
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) stime: 0.013949999999999999
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) utime: 0.0465
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) wtime: 0.115
2025-10-31 15:32:49,797 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) output:

2025-10-31 15:32:49,798 - DEBUG - ya.test - _save_outputs: Command (pid 2729021) errors:
stdin is empty (EOF)
No authentication methods were found. Going without authentication

2025-10-31 15:32:49,798 - DEBUG - ydb.connection - _prepare_call: RpcState(MakeDirectory, f023a490-936e-4969-8016-ccbdabee0d5a, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:49,798 - DEBUG - ydb.connection - _log_request: RpcState(MakeDirectory, f023a490-936e-4969-8016-ccbdabee0d5a, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" }
2025-10-31 15:32:49,803 - DEBUG - ydb.connection - _log_response: RpcState(MakeDirectory, f023a490-936e-4969-8016-ccbdabee0d5a, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:49,804 - DEBUG - ydb.connection - _prepare_call: RpcState(ModifyPermissions, c5dcb5ad-4819-45da-9d06-f7bed7ec5d99, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:49,804 - DEBUG - ydb.connection - _log_request: RpcState(ModifyPermissions, c5dcb5ad-4819-45da-9d06-f7bed7ec5d99, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" actions { grant { subject: "alice" permission_names: "ydb.generic.read" permission_names: "ydb.generic.write" } } actions { grant { subject: "bob" permission_names: "ydb.generic.read" } } actions { change_owner: "eve" } }
2025-10-31 15:32:49,807 - DEBUG - ydb.connection - _log_response: RpcState(ModifyPermissions, c5dcb5ad-4819-45da-9d06-f7bed7ec5d99, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:49,808 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 045e3ba3-37d7-4fe0-b7de-e6a1a4c8f55c, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:49,808 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 045e3ba3-37d7-4fe0-b7de-e6a1a4c8f55c, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:32:49,810 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 045e3ba3-37d7-4fe0-b7de-e6a1a4c8f55c, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" } } } }
2025-10-31 15:32:49,810 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 6b0f8691-8dab-41eb-9353-aeb3b8773c49, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:49,811 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 6b0f8691-8dab-41eb-9353-aeb3b8773c49, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" 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:32:49,834 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 6b0f8691-8dab-41eb-9353-aeb3b8773c49, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:49,835 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 92b8cd42-8f60-4a06-bb12-e3c18a6be220, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:49,836 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 92b8cd42-8f60-4a06-bb12-e3c18a6be220, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" 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:32:49,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 92b8cd42-8f60-4a06-bb12-e3c18a6be220, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:32:49,874 - DEBUG - ydb.connection - _prepare_call: RpcState(ModifyPermissions, 3308c329-3e37-427d-9eb7-ec0cd424ec29, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:49,875 - DEBUG - ydb.connection - _log_request: RpcState(ModifyPermissions, 3308c329-3e37-427d-9eb7-ec0cd424ec29, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder/table" actions { grant { subject: "alice" permission_names: "ydb.generic.read" permission_names: "ydb.generic.write" } } actions { grant { subject: "bob" permission_names: "ydb.generic.read" } } actions { change_owner: "eve" } }
2025-10-31 15:32:49,877 - DEBUG - ydb.connection - _log_response: RpcState(ModifyPermissions, 3308c329-3e37-427d-9eb7-ec0cd424ec29, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:49,877 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb
2025-10-31 15:32:49,878 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-10-31 15:32:49,878 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-10-31 15:32:49,879 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65411', '--database', '/Root', 'tools', 'dump', '--path', '/Root', '--output', '/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting/test_dont_overwrite_on_already_existing/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:32:49,879 - DEBUG - ya.test - execute: Command pid: 2729111
2025-10-31 15:32:50,082 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) rc: 0
2025-10-31 15:32:50,082 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) elapsed time (sec): 0.20183324813842773
2025-10-31 15:32:50,082 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) idrss: 0
2025-10-31 15:32:50,083 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) inblock: 0
2025-10-31 15:32:50,083 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) isrss: 0
2025-10-31 15:32:50,083 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) ixrss: 0
2025-10-31 15:32:50,083 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) majflt: 0
2025-10-31 15:32:50,083 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) maxrss: 163004
2025-10-31 15:32:50,083 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) minflt: 3635
2025-10-31 15:32:50,084 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) msgrcv: 0
2025-10-31 15:32:50,084 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) msgsnd: 0
2025-10-31 15:32:50,084 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) nivcsw: 1
2025-10-31 15:32:50,084 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) nsignals: 0
2025-10-31 15:32:50,084 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) nswap: 0
2025-10-31 15:32:50,084 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) nvcsw: 316
2025-10-31 15:32:50,084 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) oublock: 40
2025-10-31 15:32:50,085 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) stime: 0.035223
2025-10-31 15:32:50,085 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) utime: 0.043051
2025-10-31 15:32:50,085 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) wtime: 0.203
2025-10-31 15:32:50,085 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) output:

2025-10-31 15:32:50,085 - DEBUG - ya.test - _save_outputs: Command (pid 2729111) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root"
Path after adjusting: "/Root"

2025-10-31 15:32:50,086 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 2d446d81-8e8a-4939-a39f-30f0b7ec8472, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,086 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 2d446d81-8e8a-4939-a39f-30f0b7ec8472, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:32:50,089 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 2d446d81-8e8a-4939-a39f-30f0b7ec8472, ghrun-upzbhs6y6e.auto.internal:65411): 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: 1761924769440 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924767510 tx_id: 281474976710657 } } children { name: "folder" owner: "eve" type: DIRECTORY created_at { plan_step: 1761924769850 tx_id: 281474976710663 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:32:50,090 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 0ef559b1-15f7-4573-ac4b-ca9c456452da, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,090 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 0ef559b1-15f7-4573-ac4b-ca9c456452da, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" path: "/Root/folder/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:32:50,099 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 0ef559b1-15f7-4573-ac4b-ca9c456452da, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:50,100 - DEBUG - ydb.connection - _prepare_call: RpcState(RemoveDirectory, 43d8a39c-d471-4183-a528-78dc52436421, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,100 - DEBUG - ydb.connection - _log_request: RpcState(RemoveDirectory, 43d8a39c-d471-4183-a528-78dc52436421, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" }
2025-10-31 15:32:50,113 - DEBUG - ydb.connection - _log_response: RpcState(RemoveDirectory, 43d8a39c-d471-4183-a528-78dc52436421, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:50,113 - DEBUG - ydb.connection - _prepare_call: RpcState(MakeDirectory, 07d53fea-dd20-49e1-b6a9-3056119fb701, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,114 - DEBUG - ydb.connection - _log_request: RpcState(MakeDirectory, 07d53fea-dd20-49e1-b6a9-3056119fb701, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder" }
2025-10-31 15:32:50,122 - DEBUG - ydb.connection - _log_response: RpcState(MakeDirectory, 07d53fea-dd20-49e1-b6a9-3056119fb701, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:50,123 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, ea55ec16-40b0-4e43-8ec7-6fa74214671d, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,123 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, ea55ec16-40b0-4e43-8ec7-6fa74214671d, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" 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:32:50,143 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, ea55ec16-40b0-4e43-8ec7-6fa74214671d, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:32:50,144 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 3a58fa01-209e-4cc0-874e-42dcd1502f23, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,144 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 3a58fa01-209e-4cc0-874e-42dcd1502f23, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:32:50,147 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 3a58fa01-209e-4cc0-874e-42dcd1502f23, ghrun-upzbhs6y6e.auto.internal:65411): 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: 1761924769440 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924767510 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924770170 tx_id: 281474976710678 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:32:50,148 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb
2025-10-31 15:32:50,148 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-10-31 15:32:50,149 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 15:32:50,149 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65411', '--database', '/Root', 'tools', 'restore', '--path', '/Root', '--input', '/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting/test_dont_overwrite_on_already_existing/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:32:50,150 - DEBUG - ya.test - execute: Command pid: 2729299
2025-10-31 15:32:50,305 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) rc: 0
2025-10-31 15:32:50,305 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) elapsed time (sec): 0.154463529586792
2025-10-31 15:32:50,305 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) idrss: 0
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) inblock: 0
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) isrss: 0
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) ixrss: 0
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) majflt: 0
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) maxrss: 163004
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) minflt: 4181
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) msgrcv: 0
2025-10-31 15:32:50,306 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) msgsnd: 0
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) nivcsw: 1
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) nsignals: 0
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) nswap: 0
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) nvcsw: 595
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) oublock: 8
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) stime: 0.041249999999999995
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) utime: 0.049498
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) wtime: 0.155
2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) output:

2025-10-31 15:32:50,307 - DEBUG - ya.test - _save_outputs: Command (pid 2729299) errors:
No authentication methods were found. Going without authentication
Path before adjusting: "/Root"
Path after adjusting: "/Root"

2025-10-31 15:32:50,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, aa8a967f-a83c-4be9-8bd9-3558c1b1adb4, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,308 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, aa8a967f-a83c-4be9-8bd9-3558c1b1adb4, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:32:50,311 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, aa8a967f-a83c-4be9-8bd9-3558c1b1adb4, ghrun-upzbhs6y6e.auto.internal:65411): 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: 1761924769440 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924767510 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924770170 tx_id: 281474976710678 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:32:50,312 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb
2025-10-31 15:32:50,313 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-10-31 15:32:50,314 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 15:32:50,314 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002f1e/ydb/apps/ydb/ydb', '--verbose', '--endpoint', 'grpc://localhost:65411', '--database', '/Root', 'tools', 'restore', '--path', '/Root/restored', '--input', '/home/runner/.ya/build/build_root/stm2/002f1e/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_backup/chunk11/testing_out_stuff/TestPermissionsBackupRestoreDontOverwriteOnAlreadyExisting/test_dont_overwrite_on_already_existing/backup_files_dir']' in 'None' (waiting)
2025-10-31 15:32:50,315 - DEBUG - ya.test - execute: Command pid: 2729544
2025-10-31 15:32:50,491 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) rc: 0
2025-10-31 15:32:50,491 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) elapsed time (sec): 0.17480134963989258
2025-10-31 15:32:50,491 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) idrss: 0
2025-10-31 15:32:50,491 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) inblock: 0
2025-10-31 15:32:50,491 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) isrss: 0
2025-10-31 15:32:50,492 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) ixrss: 0
2025-10-31 15:32:50,492 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) majflt: 0
2025-10-31 15:32:50,492 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) maxrss: 163004
2025-10-31 15:32:50,492 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) minflt: 4187
2025-10-31 15:32:50,492 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) msgrcv: 0
2025-10-31 15:32:50,492 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) msgsnd: 0
2025-10-31 15:32:50,493 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) nivcsw: 3
2025-10-31 15:32:50,493 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) nsignals: 0
2025-10-31 15:32:50,493 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) nswap: 0
2025-10-31 15:32:50,493 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) nvcsw: 601
2025-10-31 15:32:50,493 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) oublock: 8
2025-10-31 15:32:50,493 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) stime: 0.033405
2025-10-31 15:32:50,493 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) utime: 0.057264999999999996
2025-10-31 15:32:50,494 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) wtime: 0.176
2025-10-31 15:32:50,494 - DEBUG - ya.test - _save_outputs: Command (pid 2729544) output:

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

2025-10-31 15:32:50,494 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, fe3bf331-2fcf-4ed9-aecf-7d6e164b0687, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,495 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, fe3bf331-2fcf-4ed9-aecf-7d6e164b0687, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root" }
2025-10-31 15:32:50,498 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, fe3bf331-2fcf-4ed9-aecf-7d6e164b0687, ghrun-upzbhs6y6e.auto.internal:65411): 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: 1761924769440 tx_id: 1 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1761924767510 tx_id: 281474976710657 } } children { name: "folder" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924770170 tx_id: 281474976710678 } } children { name: "restored" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1761924770470 tx_id: 281474976710682 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-31 15:32:50,499 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, f7fc9e80-2db3-4a39-874f-8604b7eb9076, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,500 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, f7fc9e80-2db3-4a39-874f-8604b7eb9076, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder/" }
2025-10-31 15:32:50,502 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, f7fc9e80-2db3-4a39-874f-8604b7eb9076, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "folder" 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: 1761924770170 tx_id: 281474976710678 } } } } } }
2025-10-31 15:32:50,503 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 43137c7d-baa4-4075-b2fb-362e6cdc0281, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,503 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 43137c7d-baa4-4075-b2fb-362e6cdc0281, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored/folder/" }
2025-10-31 15:32:50,506 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 43137c7d-baa4-4075-b2fb-362e6cdc0281, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "folder" owner: "eve" 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" } effective_permissions { subject: "alice" permission_names: "ydb.generic.read" } effective_permissions { subject: "alice" permission_names: "ydb.generic.write" } effective_permissions { subject: "bob" permission_names: "ydb.generic.read" } permissions { subject: "alice" permission_names: "ydb.generic.read" } permissions { subject: "alice" permission_names: "ydb.generic.write" } permissions { subject: "bob" permission_names: "ydb.generic.read" } created_at { plan_step: 1761924770480 tx_id: 281474976710683 } } } } } }
2025-10-31 15:32:50,506 - DEBUG - root - are_permissions_the_same: Permissions (are not the same)!
left object has owner# root@builtin permissions# {}
right object has owner# eve permissions# {alice:['ydb.generic.read'], alice:['ydb.generic.write'], bob:['ydb.generic.read']}
2025-10-31 15:32:50,506 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, 963ac309-d5f0-4bdd-89a8-747c1e8ba7c8, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,507 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, 963ac309-d5f0-4bdd-89a8-747c1e8ba7c8, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" path: "/Root/folder/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:32:50,509 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, 963ac309-d5f0-4bdd-89a8-747c1e8ba7c8, ghrun-upzbhs6y6e.auto.internal:65411): 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: 1761924770190 tx_id: 281474976710679 } } 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:32:50,510 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, 07f9ed6e-a6f8-4056-980c-1fa8b66142dc, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,511 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, 07f9ed6e-a6f8-4056-980c-1fa8b66142dc, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" path: "/Root/restored/folder/table" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:32:50,514 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, 07f9ed6e-a6f8-4056-980c-1fa8b66142dc, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table" owner: "eve" 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" } effective_permissions { subject: "alice" permission_names: "ydb.generic.read" } effective_permissions { subject: "alice" permission_names: "ydb.generic.write" } effective_permissions { subject: "bob" permission_names: "ydb.generic.read" } effective_permissions { subject: "alice" permission_names: "ydb.generic.read" } effective_permissions { subject: "alice" permission_names: "ydb.generic.write" } effective_permissions { subject: "bob" permission_names: "ydb.generic.read" } permissions { subject: "alice" permission_names: "ydb.generic.read" } permissions { subject: "alice" permission_names: "ydb.generic.write" } permissions { subject: "bob" permission_names: "ydb.generic.read" } created_at { plan_step: 1761924770500 tx_id: 281474976710685 } } 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:32:50,515 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, a54716e6-76d1-466e-8cc0-26dab252fd77, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,515 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, a54716e6-76d1-466e-8cc0-26dab252fd77, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" path: "/Root/folder/table" ordered: true }
2025-10-31 15:32:50,516 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, a54716e6-76d1-466e-8cc0-26dab252fd77, ghrun-upzbhs6y6e.auto.internal:65411): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:32:50,516 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamReadTable, 8485b978-bfca-4519-93cb-1601cd77474d, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,517 - DEBUG - ydb.connection - _log_request: RpcState(StreamReadTable, 8485b978-bfca-4519-93cb-1601cd77474d, ghrun-upzbhs6y6e.auto.internal:65411): request = { session_id: "ydb://session/3?node_id=1&id=MTM5ZDY4NjEtN2I1NDcyY2YtMmI1Y2IwODYtMmIzZWQ3Yjc=" path: "/Root/restored/folder/table" ordered: true }
2025-10-31 15:32:50,517 - DEBUG - ydb.connection - _log_response: RpcState(StreamReadTable, 8485b978-bfca-4519-93cb-1601cd77474d, ghrun-upzbhs6y6e.auto.internal:65411): response = { <_MultiThreadedRendezvous object> }
2025-10-31 15:32:50,523 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 81231247-3306-49a0-ae60-e376344eed5f, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,523 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 81231247-3306-49a0-ae60-e376344eed5f, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/folder/table" }
2025-10-31 15:32:50,526 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 81231247-3306-49a0-ae60-e376344eed5f, ghrun-upzbhs6y6e.auto.internal:65411): 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: 1761924770190 tx_id: 281474976710679 } } } } } }
2025-10-31 15:32:50,526 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, bb7e8189-523c-4a6b-b348-fe004af8d899, ghrun-upzbhs6y6e.auto.internal:65411): creating call state
2025-10-31 15:32:50,527 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, bb7e8189-523c-4a6b-b348-fe004af8d899, ghrun-upzbhs6y6e.auto.internal:65411): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/restored/folder/table" }
2025-10-31 15:32:50,529 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, bb7e8189-523c-4a6b-b348-fe004af8d899, ghrun-upzbhs6y6e.auto.internal:65411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "table" owner: "eve" 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" } effective_permissions { subject: "alice" permission_names: "ydb.generic.read" } effective_permissions { subject: "alice" permission_names: "ydb.generic.write" } effective_permissions { subject: "bob" permission_names: "ydb.generic.read" } effective_permissions { subject: "alice" permission_names: "ydb.generic.read" } effective_permissions { subject: "alice" permission_names: "ydb.generic.write" } effective_permissions { subject: "bob" permission_names: "ydb.generic.read" } permissions { subject: "alice" permission_names: "ydb.generic.read" } permissions { subject: "alice" permission_names: "ydb.generic.write" } permissions { subject: "bob" permission_names: "ydb.generic.read" } created_at { plan_step: 1761924770500 tx_id: 281474976710685 } } } } } }
2025-10-31 15:32:50,529 - DEBUG - root - are_permissions_the_same: Permissions (are not the same)!
left object has owner# root@builtin permissions# {}
right object has owner# eve permissions# {alice:['ydb.generic.read'], alice:['ydb.generic.write'], bob:['ydb.generic.read']}
2025-10-31 15:32:50,530 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:33:12,533 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:65411/1
2025-10-31 15:33:12,533 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10347
2025-10-31 15:33:12,534 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19352
2025-10-31 15:33:12,534 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28433
2025-10-31 15:33:12,534 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/65411
2025-10-31 15:33:12,534 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12756
