2025-11-01 19:58:19,825 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 19:58:19,825 - INFO - ya.test - pytest_runtest_setup: test_tenant_auth_groups_access[clusteradmin-True]
2025-11-01 19:58:19,825 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 19:58:19,826 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 19:58:19,917 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd
2025-11-01 19:58:19,918 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28804
2025-11-01 19:58:19,920 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_auth_system_views
2025-11-01 19:58:19,920 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-11-01 19:58:19,921 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/vv82/003047/r3tmp/kikimr_cluster_3z1zkman
2025-11-01 19:58:19,972 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd
2025-11-01 19:58:19,973 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23363
2025-11-01 19:58:19,974 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28758
2025-11-01 19:58:19,974 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15921
2025-11-01 19:58:19,974 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8413
2025-11-01 19:58:19,975 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/kikimr_configs"
2025-11-01 19:58:19,975 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/003047/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/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/node_1/logfile_cl9hxqrl.log --grpc-port=23363 --mon-port=28758 --ic-port=28804
2025-11-01 19:58:19,981 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:23363/1, disk_size 68719476736
2025-11-01 19:58:19,982 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/kikimr_configs"
2025-11-01 19:58:19,982 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/003047/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/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/node_1/logfile_cl9hxqrl.log --grpc-port=23363 --mon-port=28758 --ic-port=28804
2025-11-01 19:58:19,983 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/node_1/logfile_cl9hxqrl.log', '--grpc-port=23363', '--mon-port=28758', '--ic-port=28804')' in '/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/node_1' (no wait)
2025-11-01 19:58:19,992 - DEBUG - ya.test - execute: Command pid: 3230898
2025-11-01 19:58:19,993 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:23363/1
2025-11-01 19:58:19,994 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 19:58:19,994 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 19:58:19,995 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 19:58:20,005 - DEBUG - ya.test - execute: Command pid: 3230899
2025-11-01 19:58:20,876 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) rc: 0
2025-11-01 19:58:20,876 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) elapsed time (sec): 0.8701009750366211
2025-11-01 19:58:20,877 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) idrss: 0
2025-11-01 19:58:20,877 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) inblock: 0
2025-11-01 19:58:20,877 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) isrss: 0
2025-11-01 19:58:20,877 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) ixrss: 0
2025-11-01 19:58:20,877 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) majflt: 0
2025-11-01 19:58:20,877 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) maxrss: 538576
2025-11-01 19:58:20,878 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) minflt: 16129
2025-11-01 19:58:20,878 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) msgrcv: 0
2025-11-01 19:58:20,878 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) msgsnd: 0
2025-11-01 19:58:20,878 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) nivcsw: 129
2025-11-01 19:58:20,878 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) nsignals: 0
2025-11-01 19:58:20,879 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) nswap: 0
2025-11-01 19:58:20,879 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) nvcsw: 13
2025-11-01 19:58:20,879 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) oublock: 8
2025-11-01 19:58:20,879 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) stime: 0.24647999999999998
2025-11-01 19:58:20,879 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) utime: 0.159487
2025-11-01 19:58:20,884 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) wtime: 0.881
2025-11-01 19:58:20,885 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mjpjznvske
    Host information: 
        Linux ghrun-mjpjznvske 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-11-01 19:58:20,885 - DEBUG - ya.test - _save_outputs: Command (pid 3230899) errors:

2025-11-01 19:58:20,886 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 19:58:23,218 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd
2025-11-01 19:58:23,219 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:23363', '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: 28804\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 19:58:23,219 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 19:58:23,220 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 19:58:23,220 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:23363', '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: 28804\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 19:58:23,221 - DEBUG - ya.test - execute: Command pid: 3232154
2025-11-01 19:58:23,924 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) rc: 0
2025-11-01 19:58:23,924 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) elapsed time (sec): 0.7019059658050537
2025-11-01 19:58:23,925 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) idrss: 0
2025-11-01 19:58:23,925 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) inblock: 0
2025-11-01 19:58:23,925 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) isrss: 0
2025-11-01 19:58:23,925 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) ixrss: 0
2025-11-01 19:58:23,926 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) majflt: 0
2025-11-01 19:58:23,926 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) maxrss: 546964
2025-11-01 19:58:23,926 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) minflt: 18271
2025-11-01 19:58:23,926 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) msgrcv: 0
2025-11-01 19:58:23,926 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) msgsnd: 0
2025-11-01 19:58:23,926 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) nivcsw: 146
2025-11-01 19:58:23,927 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) nsignals: 0
2025-11-01 19:58:23,927 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) nswap: 0
2025-11-01 19:58:23,927 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) nvcsw: 90
2025-11-01 19:58:23,927 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) oublock: 8
2025-11-01 19:58:23,927 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) stime: 0.331573
2025-11-01 19:58:23,928 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) utime: 0.137108
2025-11-01 19:58:23,928 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) wtime: 0.703
2025-11-01 19:58:23,928 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 19:58:23,928 - DEBUG - ya.test - _save_outputs: Command (pid 3232154) errors:

2025-11-01 19:58:23,929 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd
2025-11-01 19:58:23,930 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:23363', '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-11-01 19:58:23,931 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 19:58:23,931 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 19:58:23,934 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:23363', '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-11-01 19:58:23,952 - DEBUG - ya.test - execute: Command pid: 3232441
2025-11-01 19:58:24,688 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) rc: 0
2025-11-01 19:58:24,688 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) elapsed time (sec): 0.7354950904846191
2025-11-01 19:58:24,689 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) idrss: 0
2025-11-01 19:58:24,689 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) inblock: 0
2025-11-01 19:58:24,689 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) isrss: 0
2025-11-01 19:58:24,689 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) ixrss: 0
2025-11-01 19:58:24,689 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) majflt: 0
2025-11-01 19:58:24,690 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) maxrss: 546964
2025-11-01 19:58:24,690 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) minflt: 18272
2025-11-01 19:58:24,690 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) msgrcv: 0
2025-11-01 19:58:24,690 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) msgsnd: 0
2025-11-01 19:58:24,690 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) nivcsw: 132
2025-11-01 19:58:24,690 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) nsignals: 0
2025-11-01 19:58:24,691 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) nswap: 0
2025-11-01 19:58:24,691 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) nvcsw: 90
2025-11-01 19:58:24,691 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) oublock: 8
2025-11-01 19:58:24,691 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) stime: 0.285223
2025-11-01 19:58:24,696 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) utime: 0.148472
2025-11-01 19:58:24,696 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) wtime: 0.753
2025-11-01 19:58:24,696 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 19:58:24,697 - DEBUG - ya.test - _save_outputs: Command (pid 3232441) errors:

2025-11-01 19:58:24,697 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 19:58:24,758 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 19:58:24,776 - 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-11-01 19:58:24,780 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 19:58:24,791 - 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-11-01 19:58:24,813 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:58:24,814 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f314d96e450 state=pending>
2025-11-01 19:58:24,814 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 19:58:24,814 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f314d96f710>
2025-11-01 19:58:24,815 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f314d96f710>
2025-11-01 19:58:24,815 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 19:58:24,828 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 19:58:24,828 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, e64d2abb-dd2f-4514-960c-3937e0d93668, localhost:23363): creating call state
2025-11-01 19:58:24,829 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, e64d2abb-dd2f-4514-960c-3937e0d93668, localhost:23363): request = { database: "/Root" }
2025-11-01 19:58:24,833 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, e64d2abb-dd2f-4514-960c-3937e0d93668, localhost:23363): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 23363 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-11-01 19:58:24,834 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:23363, location 1, ssl: False>]>
2025-11-01 19:58:24,834 - 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-11-01 19:58:24,865 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:23363
2025-11-01 19:58:24,872 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f314d96f710>
2025-11-01 19:58:24,880 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a707c57d-077c-4195-85f4-18d7e100b827, ghrun-mjpjznvske.auto.internal:23363): creating call state
2025-11-01 19:58:24,881 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a707c57d-077c-4195-85f4-18d7e100b827, ghrun-mjpjznvske.auto.internal:23363): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:58:24,890 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a707c57d-077c-4195-85f4-18d7e100b827, ghrun-mjpjznvske.auto.internal:23363): on response callback started
2025-11-01 19:58:24,891 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a707c57d-077c-4195-85f4-18d7e100b827, ghrun-mjpjznvske.auto.internal:23363): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NzRlZmJhMzEtMjBjMDNhMWItN2IyOGVmZTgtMjMwOTFiOWY=" } } } }
2025-11-01 19:58:24,892 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a707c57d-077c-4195-85f4-18d7e100b827, ghrun-mjpjznvske.auto.internal:23363): on response callback success
2025-11-01 19:58:24,892 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f314d96f710>
2025-11-01 19:58:24,892 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f314d96f710>
2025-11-01 19:58:24,893 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, c0ce28a3-037d-4f69-954d-fa39934d03c5, ghrun-mjpjznvske.auto.internal:23363): creating call state
2025-11-01 19:58:24,894 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, c0ce28a3-037d-4f69-954d-fa39934d03c5, ghrun-mjpjznvske.auto.internal:23363): request = { session_id: "ydb://session/3?node_id=1&id=NzRlZmJhMzEtMjBjMDNhMWItN2IyOGVmZTgtMjMwOTFiOWY=" yql_text: "create user clusteradmin password \'1234\'" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:58:24,908 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, c0ce28a3-037d-4f69-954d-fa39934d03c5, ghrun-mjpjznvske.auto.internal:23363): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:58:24,909 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, afafe377-9438-4fa9-8068-0a5c2f1a4336, ghrun-mjpjznvske.auto.internal:23363): creating call state
2025-11-01 19:58:24,910 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, afafe377-9438-4fa9-8068-0a5c2f1a4336, ghrun-mjpjznvske.auto.internal:23363): request = { session_id: "ydb://session/3?node_id=1&id=NzRlZmJhMzEtMjBjMDNhMWItN2IyOGVmZTgtMjMwOTFiOWY=" yql_text: "create user clusteruser password \'1234\'" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:58:24,926 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, afafe377-9438-4fa9-8068-0a5c2f1a4336, ghrun-mjpjznvske.auto.internal:23363): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:58:24,927 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f314d96f710>
2025-11-01 19:58:24,927 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 19:58:24,927 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 19:58:24,928 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:23363
2025-11-01 19:58:25,027 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:23363
2025-11-01 19:58:25,068 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 19:58:25,071 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_auth_system_views
2025-11-01 19:58:25,071 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_auth_system_views: send console operation, token root@builtin
2025-11-01 19:58:25,082 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_auth_system_views: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/test_auth_system_views\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/test_auth_system_views\' doesn\'t exist"
}

2025-11-01 19:58:25,083 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_auth_system_views: wait console operation done
2025-11-01 19:58:25,587 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_auth_system_views: console operation done
2025-11-01 19:58:25,588 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_auth_system_views: wait tenant gone
2025-11-01 19:58:25,593 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_auth_system_views: tenant gone
2025-11-01 19:58:25,598 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_auth_system_views: create path and declare internals
2025-11-01 19:58:26,123 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_auth_system_views: start nodes and construct internals
2025-11-01 19:58:26,123 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd
2025-11-01 19:58:26,124 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24586
2025-11-01 19:58:26,124 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23048
2025-11-01 19:58:26,125 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13528
2025-11-01 19:58:26,125 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32752
2025-11-01 19:58:26,128 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61866
2025-11-01 19:58:26,129 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/kikimr_configs"
2025-11-01 19:58:26,129 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:23363 --tenant=/Root/test_auth_system_views --log-file-name=/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/slot_1/logfile_nhczzf9j.log --grpc-port=24586 --mon-port=23048 --ic-port=13528
2025-11-01 19:58:26,129 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/kikimr_configs"
2025-11-01 19:58:26,129 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:23363 --tenant=/Root/test_auth_system_views --log-file-name=/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/slot_1/logfile_nhczzf9j.log --grpc-port=24586 --mon-port=23048 --ic-port=13528
2025-11-01 19:58:26,130 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:23363', '--tenant=/Root/test_auth_system_views', '--log-file-name=/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/slot_1/logfile_nhczzf9j.log', '--grpc-port=24586', '--mon-port=23048', '--ic-port=13528')' in '/home/runner/.ya/build/build_root/vv82/003047/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_auth_system_views.py.test_tenant_auth_groups_access.clusteradmin-True/cluster/slot_1' (no wait)
2025-11-01 19:58:26,144 - DEBUG - ya.test - execute: Command pid: 3233728
2025-11-01 19:58:26,145 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:24586/1
2025-11-01 19:58:26,152 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 19:58:26,152 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 19:58:26,153 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/003047/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 19:58:26,164 - DEBUG - ya.test - execute: Command pid: 3233731
2025-11-01 19:58:27,171 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) rc: 0
2025-11-01 19:58:27,171 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) elapsed time (sec): 1.0062615871429443
2025-11-01 19:58:27,172 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) idrss: 0
2025-11-01 19:58:27,172 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) inblock: 0
2025-11-01 19:58:27,172 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) isrss: 0
2025-11-01 19:58:27,172 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) ixrss: 0
2025-11-01 19:58:27,172 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) majflt: 0
2025-11-01 19:58:27,173 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) maxrss: 538576
2025-11-01 19:58:27,173 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) minflt: 16254
2025-11-01 19:58:27,173 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) msgrcv: 0
2025-11-01 19:58:27,173 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) msgsnd: 0
2025-11-01 19:58:27,173 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) nivcsw: 118
2025-11-01 19:58:27,173 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) nsignals: 0
2025-11-01 19:58:27,174 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) nswap: 0
2025-11-01 19:58:27,174 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) nvcsw: 16
2025-11-01 19:58:27,174 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) oublock: 8
2025-11-01 19:58:27,174 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) stime: 0.294961
2025-11-01 19:58:27,174 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) utime: 0.13175299999999998
2025-11-01 19:58:27,175 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) wtime: 1.018
2025-11-01 19:58:27,175 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mjpjznvske
    Host information: 
        Linux ghrun-mjpjznvske 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-11-01 19:58:27,175 - DEBUG - ya.test - _save_outputs: Command (pid 3233731) errors:

2025-11-01 19:58:27,184 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 19:58:27,184 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_auth_system_views: wait construction done
2025-11-01 20:01:03,473 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_auth_system_views: database up
2025-11-01 20:01:03,478 - 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-11-01 20:01:03,507 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:03,507 - 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-11-01 20:01:03,519 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 20:01:03,520 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f314d9686f0 state=pending>
2025-11-01 20:01:03,520 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 20:01:03,520 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f314d968970>
2025-11-01 20:01:03,520 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f314d968970>
2025-11-01 20:01:03,521 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 20:01:03,551 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_auth_system_views
2025-11-01 20:01:03,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 8bfb0f56-9e41-434b-af4a-49c2ca626241, localhost:23363): creating call state
2025-11-01 20:01:03,552 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 8bfb0f56-9e41-434b-af4a-49c2ca626241, localhost:23363): request = { database: "/Root/test_auth_system_views" }
2025-11-01 20:01:03,561 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 8bfb0f56-9e41-434b-af4a-49c2ca626241, localhost:23363): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 24586 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" node_id: 50000 } self_location: "1" } } } }
2025-11-01 20:01:03,564 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/test_auth_system_views: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:24586, location , ssl: False>]>
2025-11-01 20:01:03,565 - 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-11-01 20:01:03,581 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:23363
2025-11-01 20:01:03,582 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f314d968970>
2025-11-01 20:01:03,604 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d6a03c9a-e9b1-4e64-887d-30bf99375935, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,632 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d6a03c9a-e9b1-4e64-887d-30bf99375935, ghrun-mjpjznvske.auto.internal:24586): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:01:03,636 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d6a03c9a-e9b1-4e64-887d-30bf99375935, ghrun-mjpjznvske.auto.internal:24586): on response callback started
2025-11-01 20:01:03,658 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d6a03c9a-e9b1-4e64-887d-30bf99375935, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" } } } }
2025-11-01 20:01:03,678 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f314d968970>
2025-11-01 20:01:03,679 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f314d968970>
2025-11-01 20:01:03,679 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 52f9fbf5-9a71-4ea4-abe4-b6c749cbf2c1, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,681 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d6a03c9a-e9b1-4e64-887d-30bf99375935, ghrun-mjpjznvske.auto.internal:24586): on response callback success
2025-11-01 20:01:03,681 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 52f9fbf5-9a71-4ea4-abe4-b6c749cbf2c1, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "create user dbadmin password \'1234\'" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:03,755 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 52f9fbf5-9a71-4ea4-abe4-b6c749cbf2c1, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:03,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 5efdd620-6926-4859-9080-272e66b075f5, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 5efdd620-6926-4859-9080-272e66b075f5, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "create user ordinaryuser password \'1234\'" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:03,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 5efdd620-6926-4859-9080-272e66b075f5, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:03,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 1c08f5ac-1b86-4fc6-bca2-823a3c61f296, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,783 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 1c08f5ac-1b86-4fc6-bca2-823a3c61f296, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "create group dbadmins" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:03,797 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 1c08f5ac-1b86-4fc6-bca2-823a3c61f296, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:03,798 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 17c4f211-1c0d-4302-9d85-3223f4ebd686, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,798 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 17c4f211-1c0d-4302-9d85-3223f4ebd686, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "alter group dbadmins add user dbadmin" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:03,818 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 17c4f211-1c0d-4302-9d85-3223f4ebd686, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:03,819 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, fd2cf065-24fd-49ec-b902-f9af41970097, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,883 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, fd2cf065-24fd-49ec-b902-f9af41970097, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "grant \"ydb.generic.use\" on `/Root/test_auth_system_views` to clusteradmin" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:03,896 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, fd2cf065-24fd-49ec-b902-f9af41970097, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:03,897 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 306b77d3-0f48-43af-9496-8f3fa827e380, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,910 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 306b77d3-0f48-43af-9496-8f3fa827e380, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "grant \"ydb.generic.use\" on `/Root/test_auth_system_views` to clusteruser" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:03,928 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 306b77d3-0f48-43af-9496-8f3fa827e380, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:03,983 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, f52c114f-a5f0-4e74-bfc4-2c98d6ce6b2f, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:03,984 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, f52c114f-a5f0-4e74-bfc4-2c98d6ce6b2f, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "grant \"ydb.generic.use\" on `/Root/test_auth_system_views` to dbadmin" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:04,007 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, f52c114f-a5f0-4e74-bfc4-2c98d6ce6b2f, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:04,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, ebdd680c-f0fe-4ae3-b456-150c7c917ac6, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:04,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, ebdd680c-f0fe-4ae3-b456-150c7c917ac6, ghrun-mjpjznvske.auto.internal:24586): request = { session_id: "ydb://session/3?node_id=50000&id=YWRkYzY3OTAtMmQxOWMwYy1hYjdlNDZiLWY4Y2IyNjQ1" yql_text: "grant \"ydb.generic.use\" on `/Root/test_auth_system_views` to ordinaryuser" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:04,018 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, ebdd680c-f0fe-4ae3-b456-150c7c917ac6, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:04,019 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f314d968970>
2025-11-01 20:01:04,019 - DEBUG - ydb.connection - _prepare_call: RpcState(ModifyPermissions, 71c5e10e-d619-4a36-bc81-c69dba4fccb5, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:04,057 - DEBUG - ydb.connection - _log_request: RpcState(ModifyPermissions, 71c5e10e-d619-4a36-bc81-c69dba4fccb5, ghrun-mjpjznvske.auto.internal:24586): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/test_auth_system_views" actions { change_owner: "dbadmin" } }
2025-11-01 20:01:04,064 - DEBUG - ydb.connection - _log_response: RpcState(ModifyPermissions, 71c5e10e-d619-4a36-bc81-c69dba4fccb5, ghrun-mjpjznvske.auto.internal:24586): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:04,080 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 20:01:04,081 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 20:01:04,081 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:23363
2025-11-01 20:01:04,084 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:24586
2025-11-01 20:01:04,097 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 20:01:04,108 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_auth_system_views.py, test_name: test_tenant_auth_groups_access[clusteradmin-True])
2025-11-01 20:01:04,109 - 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-11-01 20:01:04,124 - DEBUG - ydb.connection - _prepare_call: RpcState(Login, 3cdf3135-b10b-467b-bbe9-b4810bee02ca, localhost:23363): creating call state
2025-11-01 20:01:04,131 - DEBUG - ydb.connection - _log_request: RpcState(Login, 3cdf3135-b10b-467b-bbe9-b4810bee02ca, localhost:23363): request = { operation_params { operation_timeout { seconds: 10 } cancel_after { seconds: 10 } } user: "clusteradmin" password: "1234" }
2025-11-01 20:01:04,160 - DEBUG - ydb.connection - _log_response: RpcState(Login, 3cdf3135-b10b-467b-bbe9-b4810bee02ca, localhost:23363): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Auth.LoginResult] { token: "eyJhbGciOiJQUzI1NiIsImtpZCI6IjEifQ.eyJhdWQiOlsiXC9Sb290Il0sImV4cCI6MTc2MjA3MDQ2NCwiaWF0IjoxNzYyMDI3MjY0LCJzdWIiOiJjbHVzdGVyYWRtaW4ifQ.pujRDeql7Mjk1fsiTuqeEgCXqsh7D_4ZJ9hM0lvevm0UzWhIeFWfrRmN4jWHH-tD1JAxk8YXTvNavVc1OQy2g4hB6WDXz2vhv5rgc4mC7PWAh-8zXEkBl7KFVWLQALLerbYXT1oGmW4iidOscKY27-300lD0L-KBRvHxGiroGQtugIajmfw0p5SeZCxmoIXnQrnH4KJKmbzYW5lu-FCyNSTcvVq0nI8XHYmJ5T23hwwEHBav9BYnrUqod-W2ZTeLPS-WeEJGub8dJnX3rnPfKSskchY-Dhng-BX_OLKwqavWla2mvLVgqp3MTCGAFKBNxuut4LOilmS33OxSSe2bUg" } } } }
2025-11-01 20:01:04,161 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:23363
2025-11-01 20:01:04,336 - 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-11-01 20:01:04,348 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:04,348 - 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-11-01 20:01:04,380 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_auth_system_views
2025-11-01 20:01:04,406 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 9a38d518-84bf-4da7-9ffd-be36c51ee2ee, localhost:23363): creating call state
2025-11-01 20:01:04,407 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 9a38d518-84bf-4da7-9ffd-be36c51ee2ee, localhost:23363): request = { database: "/Root/test_auth_system_views" }
2025-11-01 20:01:04,430 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 9a38d518-84bf-4da7-9ffd-be36c51ee2ee, localhost:23363): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 24586 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" node_id: 50000 } self_location: "1" } } } }
2025-11-01 20:01:04,430 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/test_auth_system_views: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:24586, location , ssl: False>]>
2025-11-01 20:01:04,431 - 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-11-01 20:01:04,441 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:23363
2025-11-01 20:01:04,444 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 610195e1-c23f-47af-a52d-59f121aefbcf, ghrun-mjpjznvske.auto.internal:24586): creating call state
2025-11-01 20:01:04,452 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 610195e1-c23f-47af-a52d-59f121aefbcf, ghrun-mjpjznvske.auto.internal:24586): request = { query { yql_text: "SELECT * FROM `/Root/test_auth_system_views/.sys/auth_groups`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 20:01:04,460 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 610195e1-c23f-47af-a52d-59f121aefbcf, ghrun-mjpjznvske.auto.internal:24586): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:01:04,488 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 20:01:04,488 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 20:01:04,491 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:23363
2025-11-01 20:01:04,585 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:24586
2025-11-01 20:01:04,644 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 20:01:04,748 - INFO - ya.test - pytest_runtest_teardown: Test teardown
