2025-11-14 07:04:30,493 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 07:04:30,493 - INFO - ya.test - pytest_runtest_setup: test_query_create_user_by_domain_admin[domain_login_only--false-YDB]
2025-11-14 07:04:30,494 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 07:04:30,494 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 07:04:30,494 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_users_groups_with_acl
2025-11-14 07:04:37,496 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:32639/1
2025-11-14 07:04:37,496 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6201
2025-11-14 07:04:37,497 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9344
2025-11-14 07:04:37,497 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11768
2025-11-14 07:04:37,497 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23864
2025-11-14 07:04:37,497 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5881
2025-11-14 07:04:37,497 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12732
2025-11-14 07:04:37,497 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24883
2025-11-14 07:04:37,497 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5202
2025-11-14 07:04:37,498 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32639
2025-11-14 07:04:37,498 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20715
2025-11-14 07:04:37,545 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1905
2025-11-14 07:04:37,545 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_users_groups_with_acl
2025-11-14 07:04:37,546 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-11-14 07:04:37,546 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/rl7h/0030be/r3tmp/kikimr_cluster__1w4usx6
2025-11-14 07:04:37,573 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9106
2025-11-14 07:04:37,574 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2300
2025-11-14 07:04:37,574 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12397
2025-11-14 07:04:37,574 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2380
2025-11-14 07:04:37,575 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/kikimr_configs"
2025-11-14 07:04:37,575 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/rl7h/0030be/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/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/node_1/logfile_2gfus4cu.log --grpc-port=9106 --mon-port=2300 --ic-port=1905
2025-11-14 07:04:37,575 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:9106/1, disk_size 68719476736
2025-11-14 07:04:37,575 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/rl7h/0030be/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/node_1/logfile_2gfus4cu.log', '--grpc-port=9106', '--mon-port=2300', '--ic-port=1905')' in '/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/node_1' (no wait)
2025-11-14 07:04:37,577 - DEBUG - ya.test - execute: Command pid: 2312888
2025-11-14 07:04:37,577 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:9106/1
2025-11-14 07:04:39,142 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-14 07:04:39,341 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/rl7h/0030be/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9106', '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: 1905\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-14 07:04:39,342 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.5
2025-11-14 07:04:39,342 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.5
2025-11-14 07:04:39,343 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/rl7h/0030be/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9106', '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: 1905\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 07:04:39,343 - DEBUG - ya.test - execute: Command pid: 2317410
2025-11-14 07:04:39,747 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) rc: 0
2025-11-14 07:04:39,747 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) elapsed time (sec): 0.4031221866607666
2025-11-14 07:04:39,748 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) idrss: 0
2025-11-14 07:04:39,748 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) inblock: 0
2025-11-14 07:04:39,748 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) isrss: 0
2025-11-14 07:04:39,748 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) ixrss: 0
2025-11-14 07:04:39,748 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) majflt: 0
2025-11-14 07:04:39,748 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) maxrss: 525760
2025-11-14 07:04:39,748 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) minflt: 19682
2025-11-14 07:04:39,749 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) msgrcv: 0
2025-11-14 07:04:39,749 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) msgsnd: 0
2025-11-14 07:04:39,749 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) nivcsw: 7
2025-11-14 07:04:39,749 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) nsignals: 0
2025-11-14 07:04:39,749 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) nswap: 0
2025-11-14 07:04:39,749 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) nvcsw: 140
2025-11-14 07:04:39,749 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) oublock: 8
2025-11-14 07:04:39,750 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) stime: 0.229653
2025-11-14 07:04:39,750 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) utime: 0.12274499999999999
2025-11-14 07:04:39,750 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) wtime: 0.404
2025-11-14 07:04:39,750 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-14 07:04:39,750 - DEBUG - ya.test - _save_outputs: Command (pid 2317410) errors:

2025-11-14 07:04:39,751 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/rl7h/0030be/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9106', '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-14 07:04:39,752 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.6
2025-11-14 07:04:39,752 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.6
2025-11-14 07:04:39,752 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/rl7h/0030be/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9106', '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-14 07:04:39,753 - DEBUG - ya.test - execute: Command pid: 2318478
2025-11-14 07:04:40,149 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) rc: 0
2025-11-14 07:04:40,149 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) elapsed time (sec): 0.39451122283935547
2025-11-14 07:04:40,149 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) idrss: 0
2025-11-14 07:04:40,149 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) inblock: 0
2025-11-14 07:04:40,149 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) isrss: 0
2025-11-14 07:04:40,150 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) ixrss: 0
2025-11-14 07:04:40,150 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) majflt: 0
2025-11-14 07:04:40,150 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) maxrss: 525760
2025-11-14 07:04:40,150 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) minflt: 19680
2025-11-14 07:04:40,150 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) msgrcv: 0
2025-11-14 07:04:40,150 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) msgsnd: 0
2025-11-14 07:04:40,151 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) nivcsw: 5
2025-11-14 07:04:40,151 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) nsignals: 0
2025-11-14 07:04:40,151 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) nswap: 0
2025-11-14 07:04:40,151 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) nvcsw: 146
2025-11-14 07:04:40,151 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) oublock: 8
2025-11-14 07:04:40,151 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) stime: 0.246724
2025-11-14 07:04:40,152 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) utime: 0.09868199999999999
2025-11-14 07:04:40,152 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) wtime: 0.396
2025-11-14 07:04:40,152 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-14 07:04:40,152 - DEBUG - ya.test - _save_outputs: Command (pid 2318478) errors:

2025-11-14 07:04:40,153 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-14 07:04:40,168 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 07:04:40,183 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_users_groups_with_acl.py, test_name: test_query_create_user_by_domain_admin[domain_login_only--false-YDB])
2025-11-14 07:04:40,183 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-14 07:04:40,183 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-14 07:04:40,185 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/users/database\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/users/database\' doesn\'t exist"
}

2025-11-14 07:04:40,185 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-14 07:04:40,692 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-14 07:04:40,693 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-14 07:04:40,694 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-14 07:04:40,695 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/users/database: create path and declare internals
2025-11-14 07:04:41,206 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/users/database: start nodes and construct internals
2025-11-14 07:04:41,206 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12679
2025-11-14 07:04:41,206 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20235
2025-11-14 07:04:41,207 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17933
2025-11-14 07:04:41,207 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1570
2025-11-14 07:04:41,207 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62589
2025-11-14 07:04:41,208 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/kikimr_configs"
2025-11-14 07:04:41,208 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/rl7h/0030be/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:9106 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/slot_1/logfile_a3tx80v9.log --grpc-port=12679 --mon-port=20235 --ic-port=17933
2025-11-14 07:04:41,208 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/rl7h/0030be/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:9106', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/slot_1/logfile_a3tx80v9.log', '--grpc-port=12679', '--mon-port=20235', '--ic-port=17933')' in '/home/runner/.ya/build/build_root/rl7h/0030be/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk17/testing_out_stuff/test_users_groups_with_acl.py.test_query_create_user_by_domain_admin.domain_login_only--false-YDB/cluster/slot_1' (no wait)
2025-11-14 07:04:41,210 - DEBUG - ya.test - execute: Command pid: 2321877
2025-11-14 07:04:41,210 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:12679/1
2025-11-14 07:04:41,211 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/users/database: wait construction done
2025-11-14 07:04:43,217 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/users/database: database up
2025-11-14 07:04:43,218 - 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')]
2025-11-14 07:04:43,220 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 07:04:43,220 - 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')]
2025-11-14 07:04:43,222 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 1, will create new one.
2025-11-14 07:04:43,225 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-14 07:04:43,226 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 5630bb37-0686-4faa-b8d7-eea48266dd9e, localhost:9106): creating call state
2025-11-14 07:04:43,226 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 5630bb37-0686-4faa-b8d7-eea48266dd9e, localhost:9106): request = { database: "/Root" }
2025-11-14 07:04:43,234 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 5630bb37-0686-4faa-b8d7-eea48266dd9e, localhost:9106): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-iuibjpfjoa.auto.internal" port: 9106 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-14 07:04:43,234 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-iuibjpfjoa.auto.internal:9106, location 1, ssl: False>]>
2025-11-14 07:04:43,235 - 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')]
2025-11-14 07:04:43,239 - INFO - ydb.connection - close: Closing channel for endpoint localhost:9106
2025-11-14 07:04:44,114 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 1, will create new one.
2025-11-14 07:04:44,115 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a4b3d343-7003-4094-9944-e2f30cc52973, ghrun-iuibjpfjoa.auto.internal:9106): creating call state
2025-11-14 07:04:44,116 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a4b3d343-7003-4094-9944-e2f30cc52973, ghrun-iuibjpfjoa.auto.internal:9106): request = {  }
2025-11-14 07:04:44,124 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a4b3d343-7003-4094-9944-e2f30cc52973, ghrun-iuibjpfjoa.auto.internal:9106): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=" node_id: 1 }
2025-11-14 07:04:44,125 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, cc0e2541-d129-4288-8a01-4f1452e0984f, ghrun-iuibjpfjoa.auto.internal:9106): creating call state
2025-11-14 07:04:44,125 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, cc0e2541-d129-4288-8a01-4f1452e0984f, ghrun-iuibjpfjoa.auto.internal:9106): request = { session_id: "ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=" }
2025-11-14 07:04:44,126 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, cc0e2541-d129-4288-8a01-4f1452e0984f, ghrun-iuibjpfjoa.auto.internal:9106): response = { <_MultiThreadedRendezvous object> }
2025-11-14 07:04:44,152 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=
2025-11-14 07:04:44,153 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6bf0d65d-26bb-435a-9153-56c88de4a6cb, ghrun-iuibjpfjoa.auto.internal:9106): creating call state
2025-11-14 07:04:44,154 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6bf0d65d-26bb-435a-9153-56c88de4a6cb, ghrun-iuibjpfjoa.auto.internal:9106): request = { session_id: "ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "CREATE USER user;" } stats_mode: STATS_MODE_NONE }
2025-11-14 07:04:44,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6bf0d65d-26bb-435a-9153-56c88de4a6cb, ghrun-iuibjpfjoa.auto.internal:9106): response = { <_MultiThreadedRendezvous object> }
2025-11-14 07:04:44,166 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=
2025-11-14 07:04:44,166 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=
2025-11-14 07:04:44,166 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbec37fe-442c-423f-99d9-7e0d0fd115f3, ghrun-iuibjpfjoa.auto.internal:9106): creating call state
2025-11-14 07:04:44,167 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbec37fe-442c-423f-99d9-7e0d0fd115f3, ghrun-iuibjpfjoa.auto.internal:9106): request = { session_id: "ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "ALTER USER user WITH PASSWORD NULL;" } stats_mode: STATS_MODE_NONE }
2025-11-14 07:04:44,167 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbec37fe-442c-423f-99d9-7e0d0fd115f3, ghrun-iuibjpfjoa.auto.internal:9106): response = { <_MultiThreadedRendezvous object> }
2025-11-14 07:04:44,178 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=
2025-11-14 07:04:44,179 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=
2025-11-14 07:04:44,179 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a30de934-148f-49a4-9673-6ca9c33bdae2, ghrun-iuibjpfjoa.auto.internal:9106): creating call state
2025-11-14 07:04:44,179 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a30de934-148f-49a4-9673-6ca9c33bdae2, ghrun-iuibjpfjoa.auto.internal:9106): request = { session_id: "ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "DROP USER user;" } stats_mode: STATS_MODE_NONE }
2025-11-14 07:04:44,181 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a30de934-148f-49a4-9673-6ca9c33bdae2, ghrun-iuibjpfjoa.auto.internal:9106): response = { <_MultiThreadedRendezvous object> }
2025-11-14 07:04:44,188 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=
2025-11-14 07:04:44,188 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 46d844ba-906b-41d4-ac61-65002772368d, ghrun-iuibjpfjoa.auto.internal:9106): creating call state
2025-11-14 07:04:44,189 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 46d844ba-906b-41d4-ac61-65002772368d, ghrun-iuibjpfjoa.auto.internal:9106): request = { session_id: "ydb://session/3?node_id=1&id=ZDA3MjBmZGUtZWNlOTQ3MzYtZDgyNGNkYzgtZWNmNzVhYTA=" }
2025-11-14 07:04:44,191 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 46d844ba-906b-41d4-ac61-65002772368d, ghrun-iuibjpfjoa.auto.internal:9106): response = { status: SUCCESS }
2025-11-14 07:04:44,191 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-14 07:04:44,192 - INFO - ydb.connection - close: Closing channel for endpoint localhost:9106
2025-11-14 07:04:44,196 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-iuibjpfjoa.auto.internal:9106
2025-11-14 07:04:44,197 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-14 07:04:44,198 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/users/database: remove path and dismantle internals
2025-11-14 07:04:44,198 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-14 07:04:44,198 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-14 07:04:44,202 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1763103884199997&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-14 07:04:44,202 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-14 07:04:44,705 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-14 07:04:44,705 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-14 07:04:44,713 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-14 07:04:44,713 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/users/database: stop nodes
2025-11-14 07:04:45,714 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:12679/1
2025-11-14 07:04:45,715 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/users/database: database down
2025-11-14 07:04:45,716 - INFO - ya.test - pytest_runtest_teardown: Test teardown
