2025-12-08 23:29:17,043 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:29:17,082 - INFO - ya.test - pytest_runtest_setup: test_can_set_timeouts_on_query
2025-12-08 23:29:17,088 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:29:17,109 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:29:17,248 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd
2025-12-08 23:29:17,254 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18840
2025-12-08 23:29:17,256 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/cppz/000749/r3tmp/kikimr_cluster_hpl9319w
2025-12-08 23:29:17,302 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd
2025-12-08 23:29:17,303 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27710
2025-12-08 23:29:17,305 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26985
2025-12-08 23:29:17,309 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22468
2025-12-08 23:29:17,310 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13825
2025-12-08 23:29:17,324 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cppz/000749/ydb/tests/functional/api/test-results/py3test/testing_out_stuff/test_crud/testing_out_stuff/test_crud.py.TestClientTimeouts.test_can_set_timeouts_on_query/cluster/kikimr_configs"
2025-12-08 23:29:17,325 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cppz/000749/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/cppz/000749/ydb/tests/functional/api/test-results/py3test/testing_out_stuff/test_crud/testing_out_stuff/test_crud.py.TestClientTimeouts.test_can_set_timeouts_on_query/cluster/node_1/logfile_f2qhdg_g.log --grpc-port=27710 --mon-port=26985 --ic-port=18840
2025-12-08 23:29:17,325 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:27710/1, disk_size 68719476736
2025-12-08 23:29:17,326 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cppz/000749/ydb/tests/functional/api/test-results/py3test/testing_out_stuff/test_crud/testing_out_stuff/test_crud.py.TestClientTimeouts.test_can_set_timeouts_on_query/cluster/kikimr_configs"
2025-12-08 23:29:17,326 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cppz/000749/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/cppz/000749/ydb/tests/functional/api/test-results/py3test/testing_out_stuff/test_crud/testing_out_stuff/test_crud.py.TestClientTimeouts.test_can_set_timeouts_on_query/cluster/node_1/logfile_f2qhdg_g.log --grpc-port=27710 --mon-port=26985 --ic-port=18840
2025-12-08 23:29:17,338 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/cppz/000749/ydb/tests/functional/api/test-results/py3test/testing_out_stuff/test_crud/testing_out_stuff/test_crud.py.TestClientTimeouts.test_can_set_timeouts_on_query/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/cppz/000749/ydb/tests/functional/api/test-results/py3test/testing_out_stuff/test_crud/testing_out_stuff/test_crud.py.TestClientTimeouts.test_can_set_timeouts_on_query/cluster/node_1/logfile_f2qhdg_g.log', '--grpc-port=27710', '--mon-port=26985', '--ic-port=18840')' in '/home/runner/.ya/build/build_root/cppz/000749/ydb/tests/functional/api/test-results/py3test/testing_out_stuff/test_crud/testing_out_stuff/test_crud.py.TestClientTimeouts.test_can_set_timeouts_on_query/cluster/node_1' (no wait)
2025-12-08 23:29:17,343 - DEBUG - ya.test - execute: Command pid: 3744591
2025-12-08 23:29:17,344 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:27710/1
2025-12-08 23:29:17,345 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.8
2025-12-08 23:29:17,366 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.8
2025-12-08 23:29:17,381 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-08 23:29:17,391 - DEBUG - ya.test - execute: Command pid: 3744594
2025-12-08 23:29:18,373 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) rc: 0
2025-12-08 23:29:18,374 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) elapsed time (sec): 0.9719767570495605
2025-12-08 23:29:18,374 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) idrss: 0
2025-12-08 23:29:18,398 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) inblock: 0
2025-12-08 23:29:18,400 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) isrss: 0
2025-12-08 23:29:18,400 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) ixrss: 0
2025-12-08 23:29:18,400 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) majflt: 1
2025-12-08 23:29:18,401 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) maxrss: 543508
2025-12-08 23:29:18,401 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) minflt: 16226
2025-12-08 23:29:18,402 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) msgrcv: 0
2025-12-08 23:29:18,404 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) msgsnd: 0
2025-12-08 23:29:18,410 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) nivcsw: 128
2025-12-08 23:29:18,437 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) nsignals: 0
2025-12-08 23:29:18,437 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) nswap: 0
2025-12-08 23:29:18,438 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) nvcsw: 17
2025-12-08 23:29:18,438 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) oublock: 8
2025-12-08 23:29:18,438 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) stime: 0.34876199999999996
2025-12-08 23:29:18,438 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) utime: 0.138881
2025-12-08 23:29:18,438 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) wtime: 0.982
2025-12-08 23:29:18,439 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) output:
Git info:
    Commit: 3f19363c2392fb37492e884289909e0c09f878d3
    Branch: 
    Author: alexpaniman <43866303+alexpaniman@users.noreply.github.com>
    Summary: Merge 9c53ad9fbc3e56545eb3fb4caabe221e64e2f8db into ef9d00506fdcb12c8e921ea5f8b499532632efa6

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-sfoiezpm3a
    Host information: 
        Linux ghrun-sfoiezpm3a 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-12-08 23:29:18,439 - DEBUG - ya.test - _save_outputs: Command (pid 3744594) errors:

2025-12-08 23:29:18,440 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 3f19363c2392fb37492e884289909e0c09f878d3
    Branch: 
    Author: alexpaniman <43866303+alexpaniman@users.noreply.github.com>
    Summary: Merge 9c53ad9fbc3e56545eb3fb4caabe221e64e2f8db into ef9d00506fdcb12c8e921ea5f8b499532632efa6
2025-12-08 23:29:21,119 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd
2025-12-08 23:29:21,122 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:27710', '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: 18840\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-08 23:29:21,136 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.9
2025-12-08 23:29:21,177 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.9
2025-12-08 23:29:21,178 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:27710', '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: 18840\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-08 23:29:21,194 - DEBUG - ya.test - execute: Command pid: 3746962
2025-12-08 23:29:21,837 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) rc: 0
2025-12-08 23:29:21,837 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) elapsed time (sec): 0.641624927520752
2025-12-08 23:29:21,838 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) idrss: 0
2025-12-08 23:29:21,838 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) inblock: 0
2025-12-08 23:29:21,838 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) isrss: 0
2025-12-08 23:29:21,846 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) ixrss: 0
2025-12-08 23:29:21,847 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) majflt: 0
2025-12-08 23:29:21,847 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) maxrss: 551660
2025-12-08 23:29:21,863 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) minflt: 18493
2025-12-08 23:29:21,864 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) msgrcv: 0
2025-12-08 23:29:21,865 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) msgsnd: 0
2025-12-08 23:29:21,881 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) nivcsw: 165
2025-12-08 23:29:21,881 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) nsignals: 0
2025-12-08 23:29:21,894 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) nswap: 0
2025-12-08 23:29:21,895 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) nvcsw: 93
2025-12-08 23:29:21,895 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) oublock: 8
2025-12-08 23:29:21,895 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) stime: 0.296795
2025-12-08 23:29:21,895 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) utime: 0.138504
2025-12-08 23:29:21,895 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) wtime: 0.655
2025-12-08 23:29:21,898 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-08 23:29:21,898 - DEBUG - ya.test - _save_outputs: Command (pid 3746962) errors:

2025-12-08 23:29:21,944 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd
2025-12-08 23:29:21,952 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:27710', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-08 23:29:21,962 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.10
2025-12-08 23:29:21,962 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.10
2025-12-08 23:29:21,963 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cppz/000749/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:27710', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-08 23:29:21,965 - DEBUG - ya.test - execute: Command pid: 3747230
2025-12-08 23:29:23,026 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) rc: 0
2025-12-08 23:29:23,027 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) elapsed time (sec): 1.0479888916015625
2025-12-08 23:29:23,027 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) idrss: 0
2025-12-08 23:29:23,027 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) inblock: 0
2025-12-08 23:29:23,027 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) isrss: 0
2025-12-08 23:29:23,028 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) ixrss: 0
2025-12-08 23:29:23,028 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) majflt: 0
2025-12-08 23:29:23,035 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) maxrss: 551908
2025-12-08 23:29:23,035 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) minflt: 18368
2025-12-08 23:29:23,035 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) msgrcv: 0
2025-12-08 23:29:23,035 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) msgsnd: 0
2025-12-08 23:29:23,036 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) nivcsw: 198
2025-12-08 23:29:23,036 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) nsignals: 0
2025-12-08 23:29:23,036 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) nswap: 0
2025-12-08 23:29:23,036 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) nvcsw: 92
2025-12-08 23:29:23,065 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) oublock: 8
2025-12-08 23:29:23,065 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) stime: 0.366106
2025-12-08 23:29:23,069 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) utime: 0.16120099999999998
2025-12-08 23:29:23,105 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) wtime: 1.054
2025-12-08 23:29:23,121 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-08 23:29:23,122 - DEBUG - ya.test - _save_outputs: Command (pid 3747230) errors:

2025-12-08 23:29:23,122 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-08 23:29:23,208 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-08 23:29:23,227 - 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-12-08 23:29:23,239 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-08 23:29:23,242 - 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-12-08 23:29:23,251 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-12-08 23:29:23,251 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, eaab4c28-3e23-4082-a7b2-0d74f2cb673f, localhost:27710): creating call state
2025-12-08 23:29:23,263 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, eaab4c28-3e23-4082-a7b2-0d74f2cb673f, localhost:27710): request = { database: "/Root" }
2025-12-08 23:29:23,269 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, eaab4c28-3e23-4082-a7b2-0d74f2cb673f, localhost:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-sfoiezpm3a.auto.internal" port: 27710 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-12-08 23:29:23,278 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-sfoiezpm3a.auto.internal:27710, location 1, ssl: False>]>
2025-12-08 23:29:23,284 - 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-12-08 23:29:23,306 - INFO - ydb.connection - close: Closing channel for endpoint localhost:27710
2025-12-08 23:29:23,323 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_crud.py::TestClientTimeouts, test_name: test_can_set_timeouts_on_query)
2025-12-08 23:29:23,357 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:23,360 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfcefdf50 state=pending>
2025-12-08 23:29:23,365 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-08 23:29:23,366 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,368 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,370 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7316677d-b59d-4d84-a726-4737f6a0fac2, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,447 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7316677d-b59d-4d84-a726-4737f6a0fac2, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:23,459 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7316677d-b59d-4d84-a726-4737f6a0fac2, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,460 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7316677d-b59d-4d84-a726-4737f6a0fac2, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" } } } }
2025-12-08 23:29:23,461 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,502 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,509 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7316677d-b59d-4d84-a726-4737f6a0fac2, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:23,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b1e07f12-8218-4008-87db-d9cf2f6fbad0, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b1e07f12-8218-4008-87db-d9cf2f6fbad0, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:23,521 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:23,522 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbf9fa8560 state=pending>
2025-12-08 23:29:23,522 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 1 < 100). will create a new session.
2025-12-08 23:29:23,522 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:23,523 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:23,524 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f2ea1f9c-bf61-40f2-a97d-68d4f39a57ca, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,530 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f2ea1f9c-bf61-40f2-a97d-68d4f39a57ca, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:23,539 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, b1e07f12-8218-4008-87db-d9cf2f6fbad0, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,595 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b1e07f12-8218-4008-87db-d9cf2f6fbad0, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 19ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:23,596 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, b1e07f12-8218-4008-87db-d9cf2f6fbad0, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 19ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:23,596 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,615 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,618 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f2ea1f9c-bf61-40f2-a97d-68d4f39a57ca, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,626 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f2ea1f9c-bf61-40f2-a97d-68d4f39a57ca, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" } } } }
2025-12-08 23:29:23,616 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c3f88960-115c-4deb-a93d-59c5663fc07c, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,629 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:23,643 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f2ea1f9c-bf61-40f2-a97d-68d4f39a57ca, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:23,638 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c3f88960-115c-4deb-a93d-59c5663fc07c, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:23,654 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3b903c44-52e7-456e-a59a-14a2c5f655e8, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,658 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3b903c44-52e7-456e-a59a-14a2c5f655e8, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:23,667 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c3f88960-115c-4deb-a93d-59c5663fc07c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,672 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:23,685 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfaaf69a0 state=pending>
2025-12-08 23:29:23,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c3f88960-115c-4deb-a93d-59c5663fc07c, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:23,687 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 2 < 100). will create a new session.
2025-12-08 23:29:23,723 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:23,734 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:23,742 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0241b548-fa43-44f3-9877-3ed5f145e587, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,803 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0241b548-fa43-44f3-9877-3ed5f145e587, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:23,815 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,816 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,819 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c3f88960-115c-4deb-a93d-59c5663fc07c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:23,827 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 3b903c44-52e7-456e-a59a-14a2c5f655e8, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,824 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 14ce58ce-1610-457e-843d-eaaf4da54e74, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,832 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 14ce58ce-1610-457e-843d-eaaf4da54e74, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:23,829 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3b903c44-52e7-456e-a59a-14a2c5f655e8, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 23ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:23,848 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 3b903c44-52e7-456e-a59a-14a2c5f655e8, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 23ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:23,847 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:23,851 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfaaf7e90 state=pending>
2025-12-08 23:29:23,852 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 3 < 100). will create a new session.
2025-12-08 23:29:23,852 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:23,852 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:23,853 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d9e60a44-3f20-4a3e-bc98-d73201096d82, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,853 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d9e60a44-3f20-4a3e-bc98-d73201096d82, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:23,855 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:23,856 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:23,856 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0241b548-fa43-44f3-9877-3ed5f145e587, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,857 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0241b548-fa43-44f3-9877-3ed5f145e587, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" } } } }
2025-12-08 23:29:23,857 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:23,858 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0241b548-fa43-44f3-9877-3ed5f145e587, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:23,859 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d9e60a44-3f20-4a3e-bc98-d73201096d82, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 863fc535-5ad2-4093-9bd2-4fcd202580c1, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,876 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d9e60a44-3f20-4a3e-bc98-d73201096d82, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" } } } }
2025-12-08 23:29:23,899 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:23,922 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d9e60a44-3f20-4a3e-bc98-d73201096d82, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:23,898 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 863fc535-5ad2-4093-9bd2-4fcd202580c1, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:23,923 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 14ce58ce-1610-457e-843d-eaaf4da54e74, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,972 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 14ce58ce-1610-457e-843d-eaaf4da54e74, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:23,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5f2cedd7-5701-45ad-a990-75e23efd38ef, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:23,973 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:23,987 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 14ce58ce-1610-457e-843d-eaaf4da54e74, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:23,977 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5f2cedd7-5701-45ad-a990-75e23efd38ef, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:23,991 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 863fc535-5ad2-4093-9bd2-4fcd202580c1, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:23,998 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, af1bf43b-1de6-40d3-9b0e-a972891b8571, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,008 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, af1bf43b-1de6-40d3-9b0e-a972891b8571, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,010 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 000eb7c2-bfe3-4817-9104-8f0cdbab1e2a, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,005 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 863fc535-5ad2-4093-9bd2-4fcd202580c1, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,038 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 000eb7c2-bfe3-4817-9104-8f0cdbab1e2a, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,040 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 863fc535-5ad2-4093-9bd2-4fcd202580c1, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,042 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:24,044 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfce3e4f0 state=pending>
2025-12-08 23:29:24,064 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 4 < 100). will create a new session.
2025-12-08 23:29:24,064 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:24,065 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:24,065 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 1540ec10-c091-490d-bc24-59630a4f2946, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,066 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 1540ec10-c091-490d-bc24-59630a4f2946, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:24,069 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:24,070 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:24,071 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, af1bf43b-1de6-40d3-9b0e-a972891b8571, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,071 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, af1bf43b-1de6-40d3-9b0e-a972891b8571, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,072 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:24,072 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, af1bf43b-1de6-40d3-9b0e-a972891b8571, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,072 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5f2cedd7-5701-45ad-a990-75e23efd38ef, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,075 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5f2cedd7-5701-45ad-a990-75e23efd38ef, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request timeout exceeded." issue_code: 2021 severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04rr1q55sayznjy9bv06jm" } } } } }
2025-12-08 23:29:24,075 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5f2cedd7-5701-45ad-a990-75e23efd38ef, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request timeout exceeded." issue_code: 2021 severity: 1 (server_code: 400160)
2025-12-08 23:29:24,092 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:24,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 06cb45a9-7142-41e3-b812-387bcb36ffe4, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 06cb45a9-7142-41e3-b812-387bcb36ffe4, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,097 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f1d9dd6c-e1ac-49dd-93a2-37c7a15bb53b, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,097 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f1d9dd6c-e1ac-49dd-93a2-37c7a15bb53b, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,098 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 14838c92-f0ac-4834-85ec-454ed72e1efd, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,095 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 000eb7c2-bfe3-4817-9104-8f0cdbab1e2a, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,112 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 14838c92-f0ac-4834-85ec-454ed72e1efd, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,114 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 000eb7c2-bfe3-4817-9104-8f0cdbab1e2a, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,116 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:24,122 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfc9a7530 state=pending>
2025-12-08 23:29:24,124 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 5 < 100). will create a new session.
2025-12-08 23:29:24,124 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:24,124 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:24,125 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 32cf4622-8d48-4424-8a40-a0b8e2d4d120, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,150 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 32cf4622-8d48-4424-8a40-a0b8e2d4d120, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:24,151 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:24,151 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:24,152 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f368add8-007a-471b-bbbc-fa9c40679aca, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,152 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 000eb7c2-bfe3-4817-9104-8f0cdbab1e2a, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,158 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f368add8-007a-471b-bbbc-fa9c40679aca, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,160 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:24,161 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfc9a58c0 state=pending>
2025-12-08 23:29:24,161 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 6 < 100). will create a new session.
2025-12-08 23:29:24,161 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:24,162 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 1540ec10-c091-490d-bc24-59630a4f2946, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,162 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:24,163 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 1540ec10-c091-490d-bc24-59630a4f2946, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" } } } }
2025-12-08 23:29:24,164 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a37ad385-8579-4289-8b61-d05ae61d3b0a, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,165 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a37ad385-8579-4289-8b61-d05ae61d3b0a, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:24,167 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:24,170 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:24,170 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 96f4e315-8b35-4f7f-8a82-caee866099db, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,171 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 1540ec10-c091-490d-bc24-59630a4f2946, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,173 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 06cb45a9-7142-41e3-b812-387bcb36ffe4, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,172 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 96f4e315-8b35-4f7f-8a82-caee866099db, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,174 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 06cb45a9-7142-41e3-b812-387bcb36ffe4, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 22ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,185 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 06cb45a9-7142-41e3-b812-387bcb36ffe4, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 22ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,176 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:24,186 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfc9a49c0 state=pending>
2025-12-08 23:29:24,186 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 7 < 100). will create a new session.
2025-12-08 23:29:24,187 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:24,187 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:24,187 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6fe7c45f-c3a5-4225-aeec-7ca0a8567219, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,189 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6fe7c45f-c3a5-4225-aeec-7ca0a8567219, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:24,190 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:24,193 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:24,194 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a882ea39-dd26-4712-b4b5-dc5c6703d39e, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,201 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a882ea39-dd26-4712-b4b5-dc5c6703d39e, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,196 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 14838c92-f0ac-4834-85ec-454ed72e1efd, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,202 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:24,233 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfc9aecc0 state=pending>
2025-12-08 23:29:24,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 14838c92-f0ac-4834-85ec-454ed72e1efd, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,264 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 8 < 100). will create a new session.
2025-12-08 23:29:24,284 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:24,285 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:24,296 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5e628bbc-13ea-4c07-ba97-fff12ae4fba4, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,297 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5e628bbc-13ea-4c07-ba97-fff12ae4fba4, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:24,300 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:24,307 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:24,309 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 14838c92-f0ac-4834-85ec-454ed72e1efd, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d5c2fd4c-78f9-40af-9c15-ea10f19ca4b7, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,321 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d5c2fd4c-78f9-40af-9c15-ea10f19ca4b7, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,317 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f1d9dd6c-e1ac-49dd-93a2-37c7a15bb53b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,322 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:24,339 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfc9ad230 state=pending>
2025-12-08 23:29:24,340 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 9 < 100). will create a new session.
2025-12-08 23:29:24,340 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:24,340 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:24,335 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f1d9dd6c-e1ac-49dd-93a2-37c7a15bb53b, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request timeout exceeded." issue_code: 2021 severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04rr4x6pjjtgmdffwebywd" } } } } }
2025-12-08 23:29:24,340 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a0b4a73f-b055-461f-9ead-ac26f7da0d2d, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,374 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a0b4a73f-b055-461f-9ead-ac26f7da0d2d, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:24,342 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f1d9dd6c-e1ac-49dd-93a2-37c7a15bb53b, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request timeout exceeded." issue_code: 2021 severity: 1 (server_code: 400160)
2025-12-08 23:29:24,376 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:24,382 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:24,383 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 32cf4622-8d48-4424-8a40-a0b8e2d4d120, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,384 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 32cf4622-8d48-4424-8a40-a0b8e2d4d120, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" } } } }
2025-12-08 23:29:24,384 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:24,384 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 32cf4622-8d48-4424-8a40-a0b8e2d4d120, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,386 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a37ad385-8579-4289-8b61-d05ae61d3b0a, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,386 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a37ad385-8579-4289-8b61-d05ae61d3b0a, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" } } } }
2025-12-08 23:29:24,387 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:24,387 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a37ad385-8579-4289-8b61-d05ae61d3b0a, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,388 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 96f4e315-8b35-4f7f-8a82-caee866099db, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,393 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 96f4e315-8b35-4f7f-8a82-caee866099db, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,393 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:24,394 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 96f4e315-8b35-4f7f-8a82-caee866099db, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,395 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f368add8-007a-471b-bbbc-fa9c40679aca, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,395 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f368add8-007a-471b-bbbc-fa9c40679aca, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request timeout exceeded." issue_code: 2021 severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04rr6rdbw6w6z62dsj8jnd" } } } } }
2025-12-08 23:29:24,396 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f368add8-007a-471b-bbbc-fa9c40679aca, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request timeout exceeded." issue_code: 2021 severity: 1 (server_code: 400160)
2025-12-08 23:29:24,396 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:24,396 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6fe7c45f-c3a5-4225-aeec-7ca0a8567219, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,397 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6fe7c45f-c3a5-4225-aeec-7ca0a8567219, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" } } } }
2025-12-08 23:29:24,397 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:24,398 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6fe7c45f-c3a5-4225-aeec-7ca0a8567219, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,398 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a882ea39-dd26-4712-b4b5-dc5c6703d39e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,418 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a882ea39-dd26-4712-b4b5-dc5c6703d39e, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,426 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:24,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c67d3839-e560-4097-a76a-c82e0890826d, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,428 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a882ea39-dd26-4712-b4b5-dc5c6703d39e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,432 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c67d3839-e560-4097-a76a-c82e0890826d, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,433 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5e628bbc-13ea-4c07-ba97-fff12ae4fba4, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,474 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5e628bbc-13ea-4c07-ba97-fff12ae4fba4, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" } } } }
2025-12-08 23:29:24,476 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:24,473 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a02475a2-a3a5-453e-b1f7-f1a1e2e2a5dc, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,476 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5e628bbc-13ea-4c07-ba97-fff12ae4fba4, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,478 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, d5c2fd4c-78f9-40af-9c15-ea10f19ca4b7, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,479 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d5c2fd4c-78f9-40af-9c15-ea10f19ca4b7, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,479 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, d5c2fd4c-78f9-40af-9c15-ea10f19ca4b7, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,482 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:24,482 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a0b4a73f-b055-461f-9ead-ac26f7da0d2d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,483 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a0b4a73f-b055-461f-9ead-ac26f7da0d2d, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" } } } }
2025-12-08 23:29:24,484 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:24,477 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a02475a2-a3a5-453e-b1f7-f1a1e2e2a5dc, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,522 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 17057ba7-7465-47ea-a342-4ed363057e0f, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,523 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 17057ba7-7465-47ea-a342-4ed363057e0f, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,524 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4acf5b95-d731-4b09-b4f8-96e20e4e834a, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,502 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a0b4a73f-b055-461f-9ead-ac26f7da0d2d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,530 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c67d3839-e560-4097-a76a-c82e0890826d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,531 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c67d3839-e560-4097-a76a-c82e0890826d, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,532 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:24,532 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c67d3839-e560-4097-a76a-c82e0890826d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,529 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4acf5b95-d731-4b09-b4f8-96e20e4e834a, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,533 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9757ea36-f6e6-47c1-8d14-29e96d2e1d77, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,541 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9757ea36-f6e6-47c1-8d14-29e96d2e1d77, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,551 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f7f176da-65ae-4074-b624-864ce3d10022, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,551 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f7f176da-65ae-4074-b624-864ce3d10022, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,553 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 30581bfc-b5a0-40a1-b15d-027b17ef442d, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,562 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 30581bfc-b5a0-40a1-b15d-027b17ef442d, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7a5a015f-e61b-4f11-a913-9e3d2a6ad642, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7a5a015f-e61b-4f11-a913-9e3d2a6ad642, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,558 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 4acf5b95-d731-4b09-b4f8-96e20e4e834a, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4acf5b95-d731-4b09-b4f8-96e20e4e834a, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,574 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:24,565 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, bae65e24-f023-47d7-807e-4e116fe573fb, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,594 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, bae65e24-f023-47d7-807e-4e116fe573fb, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,595 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f32a19fd-5d75-4a0f-99f2-310bd5c384d0, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,596 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f32a19fd-5d75-4a0f-99f2-310bd5c384d0, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,597 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 80e2326a-7cf7-4602-95df-bcaf915f513b, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,597 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 80e2326a-7cf7-4602-95df-bcaf915f513b, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,592 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 4acf5b95-d731-4b09-b4f8-96e20e4e834a, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,610 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 37b82670-9f7b-4a2a-b0d2-e982bba76f72, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,646 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 37b82670-9f7b-4a2a-b0d2-e982bba76f72, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,647 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:24,648 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfa92ab80 state=pending>
2025-12-08 23:29:24,646 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 17057ba7-7465-47ea-a342-4ed363057e0f, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,662 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 17057ba7-7465-47ea-a342-4ed363057e0f, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request timeout exceeded." issue_code: 2021 severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04rrj880bpjsq53gxd5mhg" } } } } }
2025-12-08 23:29:24,663 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 17057ba7-7465-47ea-a342-4ed363057e0f, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request timeout exceeded." issue_code: 2021 severity: 1 (server_code: 400160)
2025-12-08 23:29:24,661 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 10 < 100). will create a new session.
2025-12-08 23:29:24,663 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:24,669 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:24,674 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, fa90763a-be77-45da-8b5d-f9eb7a159b36, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,675 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, fa90763a-be77-45da-8b5d-f9eb7a159b36, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:24,676 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:24,676 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:24,676 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a02475a2-a3a5-453e-b1f7-f1a1e2e2a5dc, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,678 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a02475a2-a3a5-453e-b1f7-f1a1e2e2a5dc, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request timeout exceeded." issue_code: 2021 severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04rrjabyme4hkajy4enxa4" } } } } }
2025-12-08 23:29:24,679 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a02475a2-a3a5-453e-b1f7-f1a1e2e2a5dc, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request timeout exceeded." issue_code: 2021 severity: 1 (server_code: 400160)
2025-12-08 23:29:24,679 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:24,680 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 9757ea36-f6e6-47c1-8d14-29e96d2e1d77, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,681 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9757ea36-f6e6-47c1-8d14-29e96d2e1d77, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 23ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,681 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 9757ea36-f6e6-47c1-8d14-29e96d2e1d77, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 23ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,682 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:24,682 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f7f176da-65ae-4074-b624-864ce3d10022, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,687 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f7f176da-65ae-4074-b624-864ce3d10022, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 24ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,698 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f7f176da-65ae-4074-b624-864ce3d10022, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 24ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,702 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:24,688 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5bd0001c-f697-4b3a-bb00-a57002bc6462, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,704 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5bd0001c-f697-4b3a-bb00-a57002bc6462, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,705 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 30581bfc-b5a0-40a1-b15d-027b17ef442d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,717 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 30581bfc-b5a0-40a1-b15d-027b17ef442d, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,718 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:24,725 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 30581bfc-b5a0-40a1-b15d-027b17ef442d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,726 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 7a5a015f-e61b-4f11-a913-9e3d2a6ad642, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,727 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7a5a015f-e61b-4f11-a913-9e3d2a6ad642, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,707 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 63d57b97-4d22-4eaf-9dd7-d26e8044e57a, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,729 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 63d57b97-4d22-4eaf-9dd7-d26e8044e57a, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,727 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:24,731 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 7a5a015f-e61b-4f11-a913-9e3d2a6ad642, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,731 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, bae65e24-f023-47d7-807e-4e116fe573fb, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,732 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, bae65e24-f023-47d7-807e-4e116fe573fb, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,733 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, bae65e24-f023-47d7-807e-4e116fe573fb, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,733 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:24,733 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f32a19fd-5d75-4a0f-99f2-310bd5c384d0, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,735 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f4f13f32-6b17-4147-845a-1e02a7963ba0, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,742 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f4f13f32-6b17-4147-845a-1e02a7963ba0, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,744 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 8b417b4d-a0fa-4c2a-a339-f69e40b65216, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f32a19fd-5d75-4a0f-99f2-310bd5c384d0, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,753 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f32a19fd-5d75-4a0f-99f2-310bd5c384d0, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,754 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:24,754 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 80e2326a-7cf7-4602-95df-bcaf915f513b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,755 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 80e2326a-7cf7-4602-95df-bcaf915f513b, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,752 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 8b417b4d-a0fa-4c2a-a339-f69e40b65216, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,757 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 8b0234df-28a2-4def-a671-c3e608e7e1fd, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,758 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 8b0234df-28a2-4def-a671-c3e608e7e1fd, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,759 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0d4e45d2-a4d4-4514-ba2c-898fe9f6911c, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,759 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0d4e45d2-a4d4-4514-ba2c-898fe9f6911c, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,756 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 80e2326a-7cf7-4602-95df-bcaf915f513b, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,776 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:24,776 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 37b82670-9f7b-4a2a-b0d2-e982bba76f72, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,777 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 37b82670-9f7b-4a2a-b0d2-e982bba76f72, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 18ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,775 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ca5b3cff-955e-4abf-9d4d-268d3e9efc95, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,796 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ca5b3cff-955e-4abf-9d4d-268d3e9efc95, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,798 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f60e6990-c073-4534-85a0-a3e6b2cc901e, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,799 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f60e6990-c073-4534-85a0-a3e6b2cc901e, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,795 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 37b82670-9f7b-4a2a-b0d2-e982bba76f72, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 18ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,804 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:24,805 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, fa90763a-be77-45da-8b5d-f9eb7a159b36, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,804 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 120ac377-795b-4309-b8df-cba9802db181, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,807 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 120ac377-795b-4309-b8df-cba9802db181, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,809 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e71ab671-1027-416c-a4b2-577341a487ad, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,806 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, fa90763a-be77-45da-8b5d-f9eb7a159b36, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZDBkMzMwNmItNGYwYzc2M2MtNDJkMmE5NmQtZjMxM2U5Y2Q=" } } } }
2025-12-08 23:29:24,849 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:24,850 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, fa90763a-be77-45da-8b5d-f9eb7a159b36, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,851 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5bd0001c-f697-4b3a-bb00-a57002bc6462, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,852 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5bd0001c-f697-4b3a-bb00-a57002bc6462, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,834 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e71ab671-1027-416c-a4b2-577341a487ad, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,853 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:24,908 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5bd0001c-f697-4b3a-bb00-a57002bc6462, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,909 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 63d57b97-4d22-4eaf-9dd7-d26e8044e57a, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,910 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 63d57b97-4d22-4eaf-9dd7-d26e8044e57a, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 21ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,917 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 63d57b97-4d22-4eaf-9dd7-d26e8044e57a, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 21ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,918 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:24,918 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f4f13f32-6b17-4147-845a-1e02a7963ba0, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f4f13f32-6b17-4147-845a-1e02a7963ba0, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,919 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:24,920 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f4f13f32-6b17-4147-845a-1e02a7963ba0, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:24,926 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 8b0234df-28a2-4def-a671-c3e608e7e1fd, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,927 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 8b0234df-28a2-4def-a671-c3e608e7e1fd, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,910 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e709c6d3-f501-413e-9c69-b904cb059c91, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,928 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 8b0234df-28a2-4def-a671-c3e608e7e1fd, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,937 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:24,938 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 0d4e45d2-a4d4-4514-ba2c-898fe9f6911c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,939 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0d4e45d2-a4d4-4514-ba2c-898fe9f6911c, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,939 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 0d4e45d2-a4d4-4514-ba2c-898fe9f6911c, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,940 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:24,940 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 8b417b4d-a0fa-4c2a-a339-f69e40b65216, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,941 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 8b417b4d-a0fa-4c2a-a339-f69e40b65216, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 20ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:24,942 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 8b417b4d-a0fa-4c2a-a339-f69e40b65216, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 20ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:24,931 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e709c6d3-f501-413e-9c69-b904cb059c91, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDBkMzMwNmItNGYwYzc2M2MtNDJkMmE5NmQtZjMxM2U5Y2Q=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,958 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:24,983 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7d4a9531-f009-4abc-aca2-4333f90149b8, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:24,984 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f60e6990-c073-4534-85a0-a3e6b2cc901e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:24,987 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7d4a9531-f009-4abc-aca2-4333f90149b8, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:24,990 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f60e6990-c073-4534-85a0-a3e6b2cc901e, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:24,997 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e3c8c8c3-0002-4dd0-a019-b36e601b1562, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,011 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:25,014 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f60e6990-c073-4534-85a0-a3e6b2cc901e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,012 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e3c8c8c3-0002-4dd0-a019-b36e601b1562, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,030 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ebb355f8-fcab-4f06-9aa8-03b8ffcdb536, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,026 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 120ac377-795b-4309-b8df-cba9802db181, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ebb355f8-fcab-4f06-9aa8-03b8ffcdb536, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,041 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, aa8db4ed-bbd6-4154-9bf2-b9cbb753ea16, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,032 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 120ac377-795b-4309-b8df-cba9802db181, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,042 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, aa8db4ed-bbd6-4154-9bf2-b9cbb753ea16, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,043 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:25,044 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 120ac377-795b-4309-b8df-cba9802db181, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,045 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ca5b3cff-955e-4abf-9d4d-268d3e9efc95, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,046 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e1fbe5e7-5071-4b4f-8fb4-f978200f2cc6, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,058 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ca5b3cff-955e-4abf-9d4d-268d3e9efc95, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,081 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:25,081 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ca5b3cff-955e-4abf-9d4d-268d3e9efc95, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e1fbe5e7-5071-4b4f-8fb4-f978200f2cc6, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,083 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 358c4097-c814-48c9-a3a8-581684449927, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,084 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 358c4097-c814-48c9-a3a8-581684449927, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,085 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 34871779-a1c8-4143-9cb3-2d2bccefa2bc, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,087 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 34871779-a1c8-4143-9cb3-2d2bccefa2bc, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,088 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2c3437a5-e930-4921-9fa5-d6b41a2def29, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,089 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2c3437a5-e930-4921-9fa5-d6b41a2def29, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,086 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e71ab671-1027-416c-a4b2-577341a487ad, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e71ab671-1027-416c-a4b2-577341a487ad, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 23ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:25,098 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e71ab671-1027-416c-a4b2-577341a487ad, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 23ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:25,103 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:25,104 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e709c6d3-f501-413e-9c69-b904cb059c91, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e709c6d3-f501-413e-9c69-b904cb059c91, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,113 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:25,113 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e709c6d3-f501-413e-9c69-b904cb059c91, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,114 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 7d4a9531-f009-4abc-aca2-4333f90149b8, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7d4a9531-f009-4abc-aca2-4333f90149b8, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,115 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:25,116 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 7d4a9531-f009-4abc-aca2-4333f90149b8, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,092 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 87d16658-562d-41bf-a564-03247a648270, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,120 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 87d16658-562d-41bf-a564-03247a648270, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,132 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5967f831-b092-44ec-be5d-68042f851467, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,116 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, aa8db4ed-bbd6-4154-9bf2-b9cbb753ea16, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,147 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, aa8db4ed-bbd6-4154-9bf2-b9cbb753ea16, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,148 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:25,148 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, aa8db4ed-bbd6-4154-9bf2-b9cbb753ea16, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,138 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5967f831-b092-44ec-be5d-68042f851467, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,150 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ebb355f8-fcab-4f06-9aa8-03b8ffcdb536, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ebb355f8-fcab-4f06-9aa8-03b8ffcdb536, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,151 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:25,152 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ebb355f8-fcab-4f06-9aa8-03b8ffcdb536, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,153 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 575affcc-d73b-4abc-8531-f185eee70f26, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,153 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 575affcc-d73b-4abc-8531-f185eee70f26, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDBkMzMwNmItNGYwYzc2M2MtNDJkMmE5NmQtZjMxM2U5Y2Q=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,154 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ea47f473-1046-4d2d-94aa-ef6d7f5f13f1, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ea47f473-1046-4d2d-94aa-ef6d7f5f13f1, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,155 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e3c8c8c3-0002-4dd0-a019-b36e601b1562, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,158 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e3c8c8c3-0002-4dd0-a019-b36e601b1562, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,161 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:25,161 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e3c8c8c3-0002-4dd0-a019-b36e601b1562, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,157 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 97624485-e7ef-4dcf-bbad-49ad64de7cf5, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,163 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 97624485-e7ef-4dcf-bbad-49ad64de7cf5, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,164 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 95221fd3-6b91-4c8e-9192-c15df7f93f82, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,165 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 95221fd3-6b91-4c8e-9192-c15df7f93f82, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,166 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c4a33978-e694-48c6-bc90-6422092c8ab1, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,166 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c4a33978-e694-48c6-bc90-6422092c8ab1, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,167 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:25,167 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbe59e50f0 state=pending>
2025-12-08 23:29:25,167 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 11 < 100). will create a new session.
2025-12-08 23:29:25,167 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbe59e4d30>
2025-12-08 23:29:25,167 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbe59e4d30>
2025-12-08 23:29:25,167 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4861d8de-8fe2-4da3-8cb6-79bbe63f0dfd, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,168 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4861d8de-8fe2-4da3-8cb6-79bbe63f0dfd, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:25,169 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 2c3437a5-e930-4921-9fa5-d6b41a2def29, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,170 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2c3437a5-e930-4921-9fa5-d6b41a2def29, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,170 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:25,171 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:25,171 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 2c3437a5-e930-4921-9fa5-d6b41a2def29, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,171 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 34871779-a1c8-4143-9cb3-2d2bccefa2bc, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,172 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 34871779-a1c8-4143-9cb3-2d2bccefa2bc, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,173 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:25,177 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 34871779-a1c8-4143-9cb3-2d2bccefa2bc, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,178 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e1fbe5e7-5071-4b4f-8fb4-f978200f2cc6, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,179 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e1fbe5e7-5071-4b4f-8fb4-f978200f2cc6, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request timeout exceeded." issue_code: 2021 severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04rs3m3tw3vnha1e4j89q1" } } } } }
2025-12-08 23:29:25,179 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, e1fbe5e7-5071-4b4f-8fb4-f978200f2cc6, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request timeout exceeded." issue_code: 2021 severity: 1 (server_code: 400160)
2025-12-08 23:29:25,180 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:25,180 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 358c4097-c814-48c9-a3a8-581684449927, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,181 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 358c4097-c814-48c9-a3a8-581684449927, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request timeout exceeded." issue_code: 2021 severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04rs3n9sjrzp9d5p2ejfax" } } } } }
2025-12-08 23:29:25,182 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 358c4097-c814-48c9-a3a8-581684449927, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request timeout exceeded." issue_code: 2021 severity: 1 (server_code: 400160)
2025-12-08 23:29:25,182 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:25,183 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 87d16658-562d-41bf-a564-03247a648270, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,183 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 87d16658-562d-41bf-a564-03247a648270, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, edf85ddb-43d4-4895-9584-d4b4a1069788, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,184 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:25,190 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 87d16658-562d-41bf-a564-03247a648270, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,192 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ea47f473-1046-4d2d-94aa-ef6d7f5f13f1, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,193 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ea47f473-1046-4d2d-94aa-ef6d7f5f13f1, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,187 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, edf85ddb-43d4-4895-9584-d4b4a1069788, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,193 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:25,194 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ea47f473-1046-4d2d-94aa-ef6d7f5f13f1, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,195 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 575affcc-d73b-4abc-8531-f185eee70f26, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,196 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 575affcc-d73b-4abc-8531-f185eee70f26, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,197 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5d99bfbb-d04c-4ef0-83bd-115aa6a66675, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,197 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5d99bfbb-d04c-4ef0-83bd-115aa6a66675, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,198 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 58fba52b-8276-42b4-a6d6-f5ebdf08a920, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,199 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 58fba52b-8276-42b4-a6d6-f5ebdf08a920, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,200 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:25,200 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 575affcc-d73b-4abc-8531-f185eee70f26, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,201 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 70d9496b-8818-4d56-b0f3-95e6b7432a51, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,202 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 70d9496b-8818-4d56-b0f3-95e6b7432a51, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,202 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c37bbd73-0f45-4c0f-b212-ef3a61b5b1fb, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,203 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c37bbd73-0f45-4c0f-b212-ef3a61b5b1fb, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,204 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5967f831-b092-44ec-be5d-68042f851467, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,205 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5967f831-b092-44ec-be5d-68042f851467, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,205 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:25,206 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5967f831-b092-44ec-be5d-68042f851467, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,206 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4861d8de-8fe2-4da3-8cb6-79bbe63f0dfd, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,207 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4861d8de-8fe2-4da3-8cb6-79bbe63f0dfd, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZmI1NGViNmUtMWI0NWM4M2EtZGJiMDRjNjYtYjJlMzljNA==" } } } }
2025-12-08 23:29:25,207 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbe59e4d30>
2025-12-08 23:29:25,208 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4861d8de-8fe2-4da3-8cb6-79bbe63f0dfd, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4542bd3d-a2e3-47b2-8bdf-42ac6d71e39e, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,209 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4542bd3d-a2e3-47b2-8bdf-42ac6d71e39e, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,210 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 616a6d53-dfd4-461c-bf70-40791892bc7b, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,211 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 616a6d53-dfd4-461c-bf70-40791892bc7b, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDBkMzMwNmItNGYwYzc2M2MtNDJkMmE5NmQtZjMxM2U5Y2Q=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,214 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c4a33978-e694-48c6-bc90-6422092c8ab1, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,214 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c4a33978-e694-48c6-bc90-6422092c8ab1, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,215 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:25,215 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c4a33978-e694-48c6-bc90-6422092c8ab1, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,216 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 95221fd3-6b91-4c8e-9192-c15df7f93f82, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,216 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 95221fd3-6b91-4c8e-9192-c15df7f93f82, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,218 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:25,219 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 95221fd3-6b91-4c8e-9192-c15df7f93f82, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,220 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0b4cf5cd-9f28-4159-acb7-a77d6947fdc5, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,221 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0b4cf5cd-9f28-4159-acb7-a77d6947fdc5, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,222 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c73cc9da-00f0-4140-aa29-b786d6f54c9c, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,223 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c73cc9da-00f0-4140-aa29-b786d6f54c9c, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmI1NGViNmUtMWI0NWM4M2EtZGJiMDRjNjYtYjJlMzljNA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,224 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, cf5e4759-7430-4212-b7bc-391b1c293e47, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,225 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, cf5e4759-7430-4212-b7bc-391b1c293e47, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,226 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 97624485-e7ef-4dcf-bbad-49ad64de7cf5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 97624485-e7ef-4dcf-bbad-49ad64de7cf5, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,228 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:25,229 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 97624485-e7ef-4dcf-bbad-49ad64de7cf5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,230 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 58fba52b-8276-42b4-a6d6-f5ebdf08a920, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,231 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 58fba52b-8276-42b4-a6d6-f5ebdf08a920, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,232 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:25,232 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 58fba52b-8276-42b4-a6d6-f5ebdf08a920, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,249 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a08382df-5917-4b89-8102-da4f534a77ef, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,250 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a08382df-5917-4b89-8102-da4f534a77ef, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6d79aa24-d110-4cc6-b9b6-59a08a501c12, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6d79aa24-d110-4cc6-b9b6-59a08a501c12, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,260 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 182217d3-28db-4a30-a125-d7d8088e14c5, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,260 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 182217d3-28db-4a30-a125-d7d8088e14c5, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,270 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:25,270 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfaa91fa0 state=pending>
2025-12-08 23:29:25,270 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 12 < 100). will create a new session.
2025-12-08 23:29:25,271 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfaa91d70>
2025-12-08 23:29:25,271 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfaa91d70>
2025-12-08 23:29:25,271 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 202fc34a-258c-4f12-96c1-f9ca23e3fa9b, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,272 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 202fc34a-258c-4f12-96c1-f9ca23e3fa9b, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:25,274 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 70d9496b-8818-4d56-b0f3-95e6b7432a51, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,275 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 70d9496b-8818-4d56-b0f3-95e6b7432a51, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,306 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:25,306 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:25,307 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 70d9496b-8818-4d56-b0f3-95e6b7432a51, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,307 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5d99bfbb-d04c-4ef0-83bd-115aa6a66675, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,308 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5d99bfbb-d04c-4ef0-83bd-115aa6a66675, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,309 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:25,313 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 5d99bfbb-d04c-4ef0-83bd-115aa6a66675, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,315 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, edf85ddb-43d4-4895-9584-d4b4a1069788, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,315 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, be2a8f7c-5652-4a6c-a2ef-fcb19c58e163, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,316 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, edf85ddb-43d4-4895-9584-d4b4a1069788, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,341 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:25,317 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, be2a8f7c-5652-4a6c-a2ef-fcb19c58e163, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,346 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, edf85ddb-43d4-4895-9584-d4b4a1069788, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,372 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 4542bd3d-a2e3-47b2-8bdf-42ac6d71e39e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,378 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4542bd3d-a2e3-47b2-8bdf-42ac6d71e39e, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,379 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:25,370 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 81be3617-9090-489a-9651-9a560746eaa7, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,381 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 81be3617-9090-489a-9651-9a560746eaa7, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,382 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6d13f4ce-9a19-4a6e-8cf9-e8b39cadce5c, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,379 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 4542bd3d-a2e3-47b2-8bdf-42ac6d71e39e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,383 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6d13f4ce-9a19-4a6e-8cf9-e8b39cadce5c, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,386 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c37bbd73-0f45-4c0f-b212-ef3a61b5b1fb, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,413 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c37bbd73-0f45-4c0f-b212-ef3a61b5b1fb, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,416 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:25,416 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c37bbd73-0f45-4c0f-b212-ef3a61b5b1fb, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,418 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 616a6d53-dfd4-461c-bf70-40791892bc7b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,418 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 616a6d53-dfd4-461c-bf70-40791892bc7b, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,423 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:25,424 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 616a6d53-dfd4-461c-bf70-40791892bc7b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,424 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 0b4cf5cd-9f28-4159-acb7-a77d6947fdc5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0b4cf5cd-9f28-4159-acb7-a77d6947fdc5, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,425 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:25,426 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 0b4cf5cd-9f28-4159-acb7-a77d6947fdc5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,426 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c73cc9da-00f0-4140-aa29-b786d6f54c9c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,411 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, deb07541-23c8-4fb7-b539-422e2fa4b07d, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,429 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, deb07541-23c8-4fb7-b539-422e2fa4b07d, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,428 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c73cc9da-00f0-4140-aa29-b786d6f54c9c, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,431 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbe59e4d30>
2025-12-08 23:29:25,432 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c73cc9da-00f0-4140-aa29-b786d6f54c9c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,432 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, cf5e4759-7430-4212-b7bc-391b1c293e47, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,433 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, cf5e4759-7430-4212-b7bc-391b1c293e47, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,433 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:25,434 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, cf5e4759-7430-4212-b7bc-391b1c293e47, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,434 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 182217d3-28db-4a30-a125-d7d8088e14c5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a05f5c0f-aaf5-4e01-add1-5d835d79c0e3, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,436 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 182217d3-28db-4a30-a125-d7d8088e14c5, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,437 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:25,438 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 182217d3-28db-4a30-a125-d7d8088e14c5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,438 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 6d79aa24-d110-4cc6-b9b6-59a08a501c12, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,439 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6d79aa24-d110-4cc6-b9b6-59a08a501c12, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,439 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:25,440 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 6d79aa24-d110-4cc6-b9b6-59a08a501c12, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,436 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a05f5c0f-aaf5-4e01-add1-5d835d79c0e3, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,441 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 202fc34a-258c-4f12-96c1-f9ca23e3fa9b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,443 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 202fc34a-258c-4f12-96c1-f9ca23e3fa9b, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTVlZTFhZDAtYzc4ZGNjZmEtOWQ4ZDdmNjEtYWU4MzQzMmU=" } } } }
2025-12-08 23:29:25,443 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaa91d70>
2025-12-08 23:29:25,443 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 202fc34a-258c-4f12-96c1-f9ca23e3fa9b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,444 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a08382df-5917-4b89-8102-da4f534a77ef, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a08382df-5917-4b89-8102-da4f534a77ef, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,445 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:25,446 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a08382df-5917-4b89-8102-da4f534a77ef, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,446 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, be2a8f7c-5652-4a6c-a2ef-fcb19c58e163, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,442 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 17d4886f-83b3-4add-b7cb-805365ac8f46, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,448 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 17d4886f-83b3-4add-b7cb-805365ac8f46, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDBkMzMwNmItNGYwYzc2M2MtNDJkMmE5NmQtZjMxM2U5Y2Q=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,447 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, be2a8f7c-5652-4a6c-a2ef-fcb19c58e163, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,458 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:25,459 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, be2a8f7c-5652-4a6c-a2ef-fcb19c58e163, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,459 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 81be3617-9090-489a-9651-9a560746eaa7, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,466 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 81be3617-9090-489a-9651-9a560746eaa7, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,467 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:25,468 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 81be3617-9090-489a-9651-9a560746eaa7, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0d95e8fc-6b17-4cc7-8f4f-869bed761c48, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,469 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0d95e8fc-6b17-4cc7-8f4f-869bed761c48, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,471 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b69ff6dd-5364-4154-9928-14000080cdc5, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,485 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b69ff6dd-5364-4154-9928-14000080cdc5, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmI1NGViNmUtMWI0NWM4M2EtZGJiMDRjNjYtYjJlMzljNA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 255d2cc1-df3f-4711-b3f1-868d1bf5698b, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 255d2cc1-df3f-4711-b3f1-868d1bf5698b, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,469 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 6d13f4ce-9a19-4a6e-8cf9-e8b39cadce5c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,504 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6d13f4ce-9a19-4a6e-8cf9-e8b39cadce5c, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 68eb825f-9552-4ab8-b81b-82a7ba8b2d71, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,509 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 68eb825f-9552-4ab8-b81b-82a7ba8b2d71, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,506 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:25,528 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 6d13f4ce-9a19-4a6e-8cf9-e8b39cadce5c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,529 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a05f5c0f-aaf5-4e01-add1-5d835d79c0e3, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a05f5c0f-aaf5-4e01-add1-5d835d79c0e3, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,530 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:25,531 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a05f5c0f-aaf5-4e01-add1-5d835d79c0e3, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,531 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, deb07541-23c8-4fb7-b539-422e2fa4b07d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,554 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ece1bb15-4813-424d-95ad-4f2b2adcd0f3, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,555 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ece1bb15-4813-424d-95ad-4f2b2adcd0f3, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,538 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, deb07541-23c8-4fb7-b539-422e2fa4b07d, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,572 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:25,572 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, deb07541-23c8-4fb7-b539-422e2fa4b07d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,574 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 17d4886f-83b3-4add-b7cb-805365ac8f46, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,575 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 17d4886f-83b3-4add-b7cb-805365ac8f46, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: CANCELLED issues { message: "Request canceled after 20ms" severity: 1 } issues { message: "Cancelling after 19ms during compilation" severity: 1 } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:29:25,575 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 17d4886f-83b3-4add-b7cb-805365ac8f46, ghrun-sfoiezpm3a.auto.internal:27710): received exception, message: "Request canceled after 20ms" severity: 1 ,message: "Cancelling after 19ms during compilation" severity: 1 (server_code: 400160)
2025-12-08 23:29:25,576 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:25,576 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 0d95e8fc-6b17-4cc7-8f4f-869bed761c48, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,570 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3716d941-e899-4dbd-b669-cf0d262e6889, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,578 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3716d941-e899-4dbd-b669-cf0d262e6889, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTVlZTFhZDAtYzc4ZGNjZmEtOWQ4ZDdmNjEtYWU4MzQzMmU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,583 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 09b8a5ac-27bd-473e-b5aa-f613ce61f389, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,584 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 09b8a5ac-27bd-473e-b5aa-f613ce61f389, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,585 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3e41c1ec-e71b-482f-b30b-846b13991cd7, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,586 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3e41c1ec-e71b-482f-b30b-846b13991cd7, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,587 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7ff67608-7a1a-4f04-b664-e071021e551d, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,577 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0d95e8fc-6b17-4cc7-8f4f-869bed761c48, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,615 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7ff67608-7a1a-4f04-b664-e071021e551d, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,616 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b2e512e6-7a22-4a79-89b4-2d9d717cea96, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,627 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b2e512e6-7a22-4a79-89b4-2d9d717cea96, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,617 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:25,629 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 0d95e8fc-6b17-4cc7-8f4f-869bed761c48, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,630 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 255d2cc1-df3f-4711-b3f1-868d1bf5698b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,631 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 255d2cc1-df3f-4711-b3f1-868d1bf5698b, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,632 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:25,632 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 255d2cc1-df3f-4711-b3f1-868d1bf5698b, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,632 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, b69ff6dd-5364-4154-9928-14000080cdc5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,633 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b69ff6dd-5364-4154-9928-14000080cdc5, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,634 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbe59e4d30>
2025-12-08 23:29:25,634 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, b69ff6dd-5364-4154-9928-14000080cdc5, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,634 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 68eb825f-9552-4ab8-b81b-82a7ba8b2d71, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,636 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 68eb825f-9552-4ab8-b81b-82a7ba8b2d71, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,636 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:25,637 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 68eb825f-9552-4ab8-b81b-82a7ba8b2d71, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,638 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 58abfbab-4845-4a16-ac24-967d943fc0ce, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,638 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 58abfbab-4845-4a16-ac24-967d943fc0ce, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c3428ab8-2376-4a4d-acd8-c17eb7d1eae4, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,640 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c3428ab8-2376-4a4d-acd8-c17eb7d1eae4, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,641 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f1189fd9-3aae-4053-8331-3f0af52abf65, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,658 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f1189fd9-3aae-4053-8331-3f0af52abf65, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDBkMzMwNmItNGYwYzc2M2MtNDJkMmE5NmQtZjMxM2U5Y2Q=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,659 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2396a4e2-e6a2-47ba-a117-8133bed87933, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,660 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2396a4e2-e6a2-47ba-a117-8133bed87933, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,661 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 883b53ca-dfb4-471c-867e-bbbdf6c596d4, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,666 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 883b53ca-dfb4-471c-867e-bbbdf6c596d4, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjNkYWM4MjMtNGY1NzI1OWQtMWYzOTQ0MWMtNTI0NTA1M2M=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,667 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 257985e1-0a4e-4ef0-a464-5991d4f977cf, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,667 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 257985e1-0a4e-4ef0-a464-5991d4f977cf, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmI1NGViNmUtMWI0NWM4M2EtZGJiMDRjNjYtYjJlMzljNA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f6bd8a8f-0cab-4873-9d40-e98b969bb948, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,655 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ece1bb15-4813-424d-95ad-4f2b2adcd0f3, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ece1bb15-4813-424d-95ad-4f2b2adcd0f3, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,690 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:25,691 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ece1bb15-4813-424d-95ad-4f2b2adcd0f3, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,691 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 09b8a5ac-27bd-473e-b5aa-f613ce61f389, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,692 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 09b8a5ac-27bd-473e-b5aa-f613ce61f389, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,692 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:25,693 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 09b8a5ac-27bd-473e-b5aa-f613ce61f389, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,687 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f6bd8a8f-0cab-4873-9d40-e98b969bb948, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YWI3ODE3YjQtNjUwY2I1N2MtNGI2NzE5MmItZDUxNDM2ZWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d0e2646d-0f83-46f6-bc3f-d271af9b656f, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,700 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d0e2646d-0f83-46f6-bc3f-d271af9b656f, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NGY2YTMtNTU3MTQ2MGEtYzg4NzJlNi04M2M1MjIwMw==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,701 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 30d2673b-4272-4f2b-a107-481f40c94c01, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,702 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 30d2673b-4272-4f2b-a107-481f40c94c01, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTM1ODNmYjYtNmVkNGJlZDYtNWY3OGUzNzYtM2UyZDViNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,703 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:25,704 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbfac746a0 state=pending>
2025-12-08 23:29:25,704 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 13 < 100). will create a new session.
2025-12-08 23:29:25,698 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 3716d941-e899-4dbd-b669-cf0d262e6889, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,719 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3716d941-e899-4dbd-b669-cf0d262e6889, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,719 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbfac74510>
2025-12-08 23:29:25,737 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbfac74510>
2025-12-08 23:29:25,738 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f0c993ee-5f14-45e3-b2b8-70c225e96e0e, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,738 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f0c993ee-5f14-45e3-b2b8-70c225e96e0e, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:25,742 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaa91d70>
2025-12-08 23:29:25,742 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfaa91d70>
2025-12-08 23:29:25,743 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 3716d941-e899-4dbd-b669-cf0d262e6889, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,743 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 3e41c1ec-e71b-482f-b30b-846b13991cd7, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,744 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3e41c1ec-e71b-482f-b30b-846b13991cd7, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,745 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:25,745 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 3e41c1ec-e71b-482f-b30b-846b13991cd7, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,746 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, b2e512e6-7a22-4a79-89b4-2d9d717cea96, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,746 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b2e512e6-7a22-4a79-89b4-2d9d717cea96, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,747 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:25,747 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, b2e512e6-7a22-4a79-89b4-2d9d717cea96, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,748 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 7ff67608-7a1a-4f04-b664-e071021e551d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,748 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a7367ae5-873a-435f-803e-ee957d2acd7c, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,758 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a7367ae5-873a-435f-803e-ee957d2acd7c, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NTVlZTFhZDAtYzc4ZGNjZmEtOWQ4ZDdmNjEtYWU4MzQzMmU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,760 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d75371c7-5599-4e3a-9a2d-f4208ba0178f, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,760 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d75371c7-5599-4e3a-9a2d-f4208ba0178f, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmQyZmRlMWQtZWRkYmM2MjQtODYzYzU1Y2EtZTZiYWM0MTg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,758 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7ff67608-7a1a-4f04-b664-e071021e551d, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,770 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:25,770 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 7ff67608-7a1a-4f04-b664-e071021e551d, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, cf47351d-c2ca-4f4d-b735-9abb8552e696, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,772 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, cf47351d-c2ca-4f4d-b735-9abb8552e696, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDcxYzQ5MTYtMTAwMGQzNmMtM2I3OTk0NGItZmY3ODkxNTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,773 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, eedbcc0d-76a0-4025-9733-700d51a57f66, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,774 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, eedbcc0d-76a0-4025-9733-700d51a57f66, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZjI2M2U3NGItNzA2OWNlYmItZmUzMzFjMDUtMjY2NTAxOWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,774 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:29:25,774 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fdbe0901000 state=pending>
2025-12-08 23:29:25,775 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 14 < 100). will create a new session.
2025-12-08 23:29:25,775 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fdbe0900c90>
2025-12-08 23:29:25,776 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fdbe0900c90>
2025-12-08 23:29:25,776 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0ea46951-2ed7-4a2d-951c-e2ef7a351f64, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,784 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0ea46951-2ed7-4a2d-951c-e2ef7a351f64, ghrun-sfoiezpm3a.auto.internal:27710): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:29:25,783 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c3428ab8-2376-4a4d-acd8-c17eb7d1eae4, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c3428ab8-2376-4a4d-acd8-c17eb7d1eae4, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,786 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:25,787 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:25,787 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, c3428ab8-2376-4a4d-acd8-c17eb7d1eae4, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,787 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 58abfbab-4845-4a16-ac24-967d943fc0ce, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 58abfbab-4845-4a16-ac24-967d943fc0ce, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,788 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:25,789 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 58abfbab-4845-4a16-ac24-967d943fc0ce, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,794 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 257985e1-0a4e-4ef0-a464-5991d4f977cf, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,799 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 257985e1-0a4e-4ef0-a464-5991d4f977cf, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,800 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbe59e4d30>
2025-12-08 23:29:25,795 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 071b52b7-7098-41b3-9d49-3cdfaf47d2fd, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,814 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 257985e1-0a4e-4ef0-a464-5991d4f977cf, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,817 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f1189fd9-3aae-4053-8331-3f0af52abf65, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,818 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f1189fd9-3aae-4053-8331-3f0af52abf65, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,818 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:25,818 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f1189fd9-3aae-4053-8331-3f0af52abf65, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,819 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 2396a4e2-e6a2-47ba-a117-8133bed87933, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,820 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2396a4e2-e6a2-47ba-a117-8133bed87933, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,828 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:25,833 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 2396a4e2-e6a2-47ba-a117-8133bed87933, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,834 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 883b53ca-dfb4-471c-867e-bbbdf6c596d4, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,835 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 883b53ca-dfb4-471c-867e-bbbdf6c596d4, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,835 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a7300>
2025-12-08 23:29:25,836 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 883b53ca-dfb4-471c-867e-bbbdf6c596d4, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,836 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 30d2673b-4272-4f2b-a107-481f40c94c01, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,838 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 30d2673b-4272-4f2b-a107-481f40c94c01, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,839 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfcefd370>
2025-12-08 23:29:25,839 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 30d2673b-4272-4f2b-a107-481f40c94c01, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,840 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, d0e2646d-0f83-46f6-bc3f-d271af9b656f, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,840 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d0e2646d-0f83-46f6-bc3f-d271af9b656f, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,841 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbf9faa310>
2025-12-08 23:29:25,816 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 071b52b7-7098-41b3-9d49-3cdfaf47d2fd, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=YTQ1YTFhNmYtOWFjNjc5NzktZjBmZGE0YTUtYmE1MmVmMjU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,851 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ff504052-c297-48ed-b760-bb21545c9c19, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,851 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ff504052-c297-48ed-b760-bb21545c9c19, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=NmQ1ZWY2NmUtNDU0MzdlOC0xYzRkMzhiZS0zOTAwOTdhYg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,852 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6756eb4f-6e76-4a48-9fd2-c2d5f1270402, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,846 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, d0e2646d-0f83-46f6-bc3f-d271af9b656f, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,854 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f6bd8a8f-0cab-4873-9d40-e98b969bb948, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,858 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f6bd8a8f-0cab-4873-9d40-e98b969bb948, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,858 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ae7c0>
2025-12-08 23:29:25,859 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, f6bd8a8f-0cab-4873-9d40-e98b969bb948, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,859 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f0c993ee-5f14-45e3-b2b8-70c225e96e0e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,860 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f0c993ee-5f14-45e3-b2b8-70c225e96e0e, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=Yzc0ZTU2MDItOTJlMDcwYmMtZDBjMzgxMTMtYzJjMzdmODY=" } } } }
2025-12-08 23:29:25,860 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfac74510>
2025-12-08 23:29:25,861 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f0c993ee-5f14-45e3-b2b8-70c225e96e0e, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,854 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6756eb4f-6e76-4a48-9fd2-c2d5f1270402, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZmI1NGViNmUtMWI0NWM4M2EtZGJiMDRjNjYtYjJlMzljNA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,863 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ea65083b-fd16-426f-a783-285167e1ec25, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,864 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ea65083b-fd16-426f-a783-285167e1ec25, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=ZDBkMzMwNmItNGYwYzc2M2MtNDJkMmE5NmQtZjMxM2U5Y2Q=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 82bd2d21-3e34-496b-a672-ae4880740d57, ghrun-sfoiezpm3a.auto.internal:27710): creating call state
2025-12-08 23:29:25,865 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 82bd2d21-3e34-496b-a672-ae4880740d57, ghrun-sfoiezpm3a.auto.internal:27710): request = { session_id: "ydb://session/3?node_id=1&id=OTgxNWI4MWMtMTZjOTViYmEtNmYyM2Y5NmItZTcxNjc5ZA==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "select 1" } operation_params { operation_timeout { nanos: 40000000 } cancel_after { nanos: 20000000 } } }
2025-12-08 23:29:25,866 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,866 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,867 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,861 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a7367ae5-873a-435f-803e-ee957d2acd7c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,892 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,910 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,911 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,911 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,911 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,911 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,894 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a7367ae5-873a-435f-803e-ee957d2acd7c, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,913 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaa91d70>
2025-12-08 23:29:25,914 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, a7367ae5-873a-435f-803e-ee957d2acd7c, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,915 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0ea46951-2ed7-4a2d-951c-e2ef7a351f64, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,915 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0ea46951-2ed7-4a2d-951c-e2ef7a351f64, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YWMyZDNiNjMtMTAwYTBkMTctMTVkMjI4ZjYtZDBkNWUwYWE=" } } } }
2025-12-08 23:29:25,916 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbe0900c90>
2025-12-08 23:29:25,916 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0ea46951-2ed7-4a2d-951c-e2ef7a351f64, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,917 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, cf47351d-c2ca-4f4d-b735-9abb8552e696, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,911 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,918 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,918 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,918 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,918 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,919 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,919 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,919 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,919 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,919 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,919 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,920 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,920 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,920 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,920 - INFO - __tests__.test_crud - test_can_set_timeouts_on_query: Received error on request CANCELLED
2025-12-08 23:29:25,917 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, cf47351d-c2ca-4f4d-b735-9abb8552e696, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,922 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf5910>
2025-12-08 23:29:25,922 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, cf47351d-c2ca-4f4d-b735-9abb8552e696, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,923 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, eedbcc0d-76a0-4025-9733-700d51a57f66, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,924 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, eedbcc0d-76a0-4025-9733-700d51a57f66, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,924 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9ace70>
2025-12-08 23:29:25,925 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, eedbcc0d-76a0-4025-9733-700d51a57f66, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,926 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, d75371c7-5599-4e3a-9a2d-f4208ba0178f, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,927 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d75371c7-5599-4e3a-9a2d-f4208ba0178f, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,929 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9affd0>
2025-12-08 23:29:25,930 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, d75371c7-5599-4e3a-9a2d-f4208ba0178f, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,931 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 6756eb4f-6e76-4a48-9fd2-c2d5f1270402, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,931 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6756eb4f-6e76-4a48-9fd2-c2d5f1270402, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,932 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbe59e4d30>
2025-12-08 23:29:25,932 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 6756eb4f-6e76-4a48-9fd2-c2d5f1270402, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,933 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ea65083b-fd16-426f-a783-285167e1ec25, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,933 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ea65083b-fd16-426f-a783-285167e1ec25, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,934 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfa92b2b0>
2025-12-08 23:29:25,934 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ea65083b-fd16-426f-a783-285167e1ec25, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,935 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 82bd2d21-3e34-496b-a672-ae4880740d57, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,936 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 82bd2d21-3e34-496b-a672-ae4880740d57, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,937 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfce3cb50>
2025-12-08 23:29:25,937 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 82bd2d21-3e34-496b-a672-ae4880740d57, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,938 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ff504052-c297-48ed-b760-bb21545c9c19, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,938 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ff504052-c297-48ed-b760-bb21545c9c19, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,939 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfaaf6d10>
2025-12-08 23:29:25,939 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, ff504052-c297-48ed-b760-bb21545c9c19, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,939 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 071b52b7-7098-41b3-9d49-3cdfaf47d2fd, ghrun-sfoiezpm3a.auto.internal:27710): on response callback started
2025-12-08 23:29:25,940 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 071b52b7-7098-41b3-9d49-3cdfaf47d2fd, ghrun-sfoiezpm3a.auto.internal:27710): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "column0" type { type_id: INT32 } } rows { items { int32_value: 1 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-08 23:29:25,941 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fdbfc9a6130>
2025-12-08 23:29:25,979 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteDataQuery, 071b52b7-7098-41b3-9d49-3cdfaf47d2fd, ghrun-sfoiezpm3a.auto.internal:27710): on response callback success
2025-12-08 23:29:25,955 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-12-08 23:29:48,067 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:27710/1
2025-12-08 23:29:48,068 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13825
2025-12-08 23:29:48,069 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22468
2025-12-08 23:29:48,069 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26985
2025-12-08 23:29:48,069 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27710
2025-12-08 23:29:48,069 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18840
2025-12-08 23:29:48,070 - DEBUG - ydb.topic - close: Close topic client
2025-12-08 23:29:48,070 - DEBUG - ydb.topic - close: Topic client was closed
2025-12-08 23:29:48,081 - INFO - ydb.connection - close: Closing channel for endpoint localhost:27710
2025-12-08 23:29:48,087 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-sfoiezpm3a.auto.internal:27710
2025-12-08 23:29:48,088 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
