2025-10-26T19:25:23.092331Z :MEMORY_PROFILER NOTICE: Bootstrapped
2025-10-26T19:25:23.160396Z :BLOB_CACHE NOTICE: MaxCacheDataSize: 1048576000 InFlightDataSize: 0
2025-10-26T19:25:23.328837Z :GRPC_SERVER NOTICE: Grpc request proxy started, nodeid# 50000, serve as dynamic node
2025-10-26T19:25:23.331653Z :GRPC_SERVER DEBUG: Subscribe to /Root
2025-10-26T19:25:23.331721Z :GRPC_SERVER DEBUG: Subscribe to /Root/test_auditlog.py
2025-10-26T19:25:23.350171Z :BS_PROXY NOTICE: EnsureMonitoring Group# 0 IsLimitedKeyless# 0 fullIfPossible# 0 Marker# DSP58
2025-10-26T19:25:23.350970Z :BS_PROXY NOTICE: Group# 4294967295 HasInvalidGroupId# 1 Bootstrap -> StateEjected Marker# DSP42
2025-10-26T19:25:23.361407Z :INTERCONNECT ERROR: Proxy [50000:7565613769511300508:1] [node 1] ICP35 event processing took too much time PendingNodeInfo:261(42798750ns) {Configure:101(42779290ns) {ProcessConfigured:131(224230ns) {StartInitialHandshake:153(222790ns) {DropHandshakes:474(1958ns) {DropIncomingHandshake:446(200ns) DropOutgoingHandshake:461(460ns)} GenerateSessionVirtualId:431(449ns) PrepareNewSessionHandshake:481(2568ns) {SwitchToState:178(1224ns)}}}}}
2025-10-26T19:25:23.375771Z :TX_CONVEYOR NOTICE: fline=service.cpp:16;name=Isrt;action=conveyor_registered;config=DefaultFractionOfThreadsCount=0.2;QueueSizeLimit=262144;Enabled=1;;
2025-10-26T19:25:23.381416Z :GRPC_SERVER NOTICE: Grpc request proxy started, nodeid# 50000, serve as dynamic node
2025-10-26T19:25:23.387389Z :GRPC_SERVER DEBUG: Subscribe to /Root
2025-10-26T19:25:23.387443Z :GRPC_SERVER DEBUG: Subscribe to /Root/test_auditlog.py
2025-10-26T19:25:23.388044Z :PGWIRE ERROR: Failed to bind [::]:5432. Error: Address already in use
2025-10-26T19:25:23.390767Z :TX_CONVEYOR NOTICE: fline=service.cpp:16;name=Comp;action=conveyor_registered;config=DefaultFractionOfThreadsCount=0.33;QueueSizeLimit=262144;Enabled=1;;
2025-10-26T19:25:23.391637Z :TX_CONVEYOR NOTICE: fline=service.cpp:16;name=Scan;action=conveyor_registered;config=QueueSizeLimit=262144;Enabled=1;;
2025-10-26T19:25:23.415841Z :GRPC_SERVER DEBUG: [0x61a00004ce80] created request Name# Legacy/SchemeOperation
2025-10-26T19:25:23.416639Z :GRPC_SERVER DEBUG: [0x61a00004d480] created request Name# Legacy/SchemeOperationStatus
2025-10-26T19:25:23.416985Z :GRPC_SERVER DEBUG: [0x61a00004da80] created request Name# Legacy/SchemeDescribe
2025-10-26T19:25:23.417330Z :GRPC_SERVER DEBUG: [0x61a00004e080] created request Name# Legacy/ChooseProxy
2025-10-26T19:25:23.417676Z :GRPC_SERVER DEBUG: [0x61a00004e680] created request Name# Legacy/PersQueueRequest
2025-10-26T19:25:23.420588Z :INTERCONNECT WARN: Handshake [50000:7565613769511300697:1362] [node 1] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-10-26T19:25:23.421031Z :GRPC_SERVER DEBUG: [0x61a00004ec80] created request Name# Legacy/SchemeInitRoot
2025-10-26T19:25:23.450532Z :GRPC_SERVER DEBUG: Got proxy service configuration
2025-10-26T19:25:23.450572Z :GRPC_SERVER INFO: Subscribed for config changes
2025-10-26T19:25:23.450612Z :GRPC_SERVER DEBUG: Got proxy service configuration
2025-10-26T19:25:23.450634Z :GRPC_SERVER INFO: Subscribed for config changes
2025-10-26T19:25:23.451867Z :GRPC_SERVER DEBUG: [0x61a00004f280] created request Name# Legacy/ResolveNode
2025-10-26T19:25:23.452172Z :INTERCONNECT ERROR: Proxy [50000:7565613769511300508:1] [node 1] ICP35 event processing took too much time PendingConnection:278(29895235ns) {HandleHandshakeStatus:325(29884707ns) {DropIncomingHandshake:446(265ns) DropOutgoingHandshake:461(640ns) SwitchToState:178(468ns) ProcessPendingSessionEvents:491(25146542ns) {unordered ForwardSessionEventToSession:608 num# 14 sum# 25124287ns max# 9990676ns}}}
2025-10-26T19:25:23.466410Z :GRPC_SERVER DEBUG: [0x61a000010280] created request Name# Legacy/FillNode
2025-10-26T19:25:23.466817Z :GRPC_SERVER DEBUG: [0x61a000010880] created request Name# Legacy/DrainNode
2025-10-26T19:25:23.467258Z :GRPC_SERVER DEBUG: [0x61a000010e80] created request Name# Legacy/BlobStorageConfig
2025-10-26T19:25:23.467594Z :GRPC_SERVER DEBUG: [0x61a000011480] created request Name# Legacy/HiveCreateTablet
2025-10-26T19:25:23.467918Z :GRPC_SERVER DEBUG: [0x61a000011a80] created request Name# Legacy/TestShardControl
2025-10-26T19:25:23.468262Z :GRPC_SERVER DEBUG: [0x61a000012080] created request Name# Legacy/RegisterNode
2025-10-26T19:25:23.468589Z :GRPC_SERVER DEBUG: [0x61a000012680] created request Name# Legacy/CmsRequest
2025-10-26T19:25:23.468984Z :GRPC_SERVER DEBUG: [0x61a00009fc80] created request Name# Legacy/ConsoleRequest
2025-10-26T19:25:23.469347Z :GRPC_SERVER DEBUG: [0x61a0000a0280] created request Name# Legacy/InterconnectDebug
2025-10-26T19:25:23.471767Z :GRPC_SERVER DEBUG: [0x61a0000a0880] created request Name# Legacy/TabletStateRequest
2025-10-26T19:25:23.472179Z :GRPC_SERVER DEBUG: [0x61a0000a0e80] created request Name# Legacy/LocalSchemeTx
2025-10-26T19:25:23.472528Z :GRPC_SERVER DEBUG: [0x61a0000a1480] created request Name# Legacy/LocalEnumerateTablets
2025-10-26T19:25:23.472849Z :GRPC_SERVER DEBUG: [0x61a0000a1a80] created request Name# Legacy/TabletKillRequest
2025-10-26T19:25:23.473215Z :GRPC_SERVER DEBUG: [0x61a0000a2080] created request Name# CreateSession
2025-10-26T19:25:23.473457Z :GRPC_SERVER DEBUG: [0x61a0000a2680] created request Name# CreateSession
2025-10-26T19:25:23.474142Z :GRPC_SERVER DEBUG: [0x61a0000a2c80] created request Name# CreateSession
2025-10-26T19:25:23.474433Z :GRPC_SERVER DEBUG: [0x61a0000a3280] created request Name# CreateSession
2025-10-26T19:25:23.474624Z :GRPC_SERVER DEBUG: [0x61a0000a3880] created request Name# CreateSession
2025-10-26T19:25:23.475103Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300680:4148][/Root/test_auditlog.py] Set up state: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.475378Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300679:4147][/Root] Set up state: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.475424Z :GRPC_SERVER DEBUG: [0x61a0000a3e80] created request Name# CreateSession
2025-10-26T19:25:23.475649Z :GRPC_SERVER DEBUG: [0x61a0000a4480] created request Name# CreateSession
2025-10-26T19:25:23.481915Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:23.482005Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:23.482121Z :GRPC_SERVER DEBUG: [0x61a0000a4a80] created request Name# CreateSession
2025-10-26T19:25:23.482236Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300707:4151][/Root] Set up state: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.482371Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300708:4152][/Root/test_auditlog.py] Set up state: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.482422Z :GRPC_SERVER DEBUG: [0x61a0000a5080] created request Name# CreateSession
2025-10-26T19:25:23.482651Z :GRPC_SERVER DEBUG: [0x61a0000a5680] created request Name# CreateSession
2025-10-26T19:25:23.482894Z :GRPC_SERVER DEBUG: [0x61a0000a5c80] created request Name# CreateSession
2025-10-26T19:25:23.483101Z :GRPC_SERVER DEBUG: [0x61a0000a6280] created request Name# CreateSession
2025-10-26T19:25:23.483364Z :GRPC_SERVER DEBUG: [0x61a0000a6880] created request Name# CreateSession
2025-10-26T19:25:23.483545Z :GRPC_SERVER DEBUG: [0x61a0000a6e80] created request Name# CreateSession
2025-10-26T19:25:23.483714Z :GRPC_SERVER DEBUG: [0x61a0000a7480] created request Name# CreateSession
2025-10-26T19:25:23.483903Z :GRPC_SERVER DEBUG: [0x61a0000a7a80] created request Name# CreateSession
2025-10-26T19:25:23.484141Z :GRPC_SERVER DEBUG: [0x61a0000a8080] created request Name# CreateSession
2025-10-26T19:25:23.484370Z :GRPC_SERVER DEBUG: [0x61a0000a8680] created request Name# CreateSession
2025-10-26T19:25:23.484582Z :GRPC_SERVER DEBUG: [0x61a0000a8c80] created request Name# CreateSession
2025-10-26T19:25:23.484779Z :GRPC_SERVER DEBUG: [0x61a0000a9280] created request Name# CreateSession
2025-10-26T19:25:23.485180Z :GRPC_SERVER DEBUG: [0x61a0000a9880] created request Name# KeepAlive
2025-10-26T19:25:23.485409Z :GRPC_SERVER DEBUG: [0x61a0000a9e80] created request Name# KeepAlive
2025-10-26T19:25:23.485593Z :GRPC_SERVER DEBUG: [0x61a0000aa480] created request Name# KeepAlive
2025-10-26T19:25:23.487074Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root
2025-10-26T19:25:23.487162Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root - no SecurityState
2025-10-26T19:25:23.487191Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root
2025-10-26T19:25:23.487310Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300742:4169][/Root/test_auditlog.py] Set up state: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.487330Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root - no SecurityState
2025-10-26T19:25:23.487350Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:23.487425Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:23.488120Z :GRPC_SERVER DEBUG: [0x61a0000aaa80] created request Name# KeepAlive
2025-10-26T19:25:23.489927Z :GRPC_SERVER DEBUG: [0x61a0000ab080] created request Name# KeepAlive
2025-10-26T19:25:23.490231Z :GRPC_SERVER DEBUG: [0x61a0000ab680] created request Name# KeepAlive
2025-10-26T19:25:23.493013Z :GRPC_SERVER DEBUG: [0x61a0000abc80] created request Name# KeepAlive
2025-10-26T19:25:23.493284Z :GRPC_SERVER DEBUG: [0x61a0000ac280] created request Name# KeepAlive
2025-10-26T19:25:23.495541Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300750:4142][/Root/test_auditlog.py/.metadata/cs_index/external] Set up state: owner# [50000:7565613769511300664:4136], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.495650Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300751:4143][/Root/test_auditlog.py/.metadata/initialization/migrations] Set up state: owner# [50000:7565613769511300664:4136], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.496127Z :GRPC_SERVER DEBUG: [0x61a0000ac880] created request Name# KeepAlive
2025-10-26T19:25:23.496366Z :GRPC_SERVER DEBUG: [0x61a0000ace80] created request Name# KeepAlive
2025-10-26T19:25:23.496555Z :GRPC_SERVER DEBUG: [0x61a0000ad480] created request Name# KeepAlive
2025-10-26T19:25:23.498307Z :TENANT_POOL NOTICE: TDomainTenantPool(Root) started tenant /Root/test_auditlog.py
2025-10-26T19:25:23.499442Z :GRPC_SERVER DEBUG: [0x61a0000ada80] created request Name# KeepAlive
2025-10-26T19:25:23.499761Z :GRPC_SERVER DEBUG: [0x61a0000ae080] created request Name# KeepAlive
2025-10-26T19:25:23.500039Z :GRPC_SERVER DEBUG: [0x61a0000ae680] created request Name# KeepAlive
2025-10-26T19:25:23.500271Z :GRPC_SERVER DEBUG: [0x61a0000aec80] created request Name# KeepAlive
2025-10-26T19:25:23.500489Z :GRPC_SERVER DEBUG: [0x61a0000af280] created request Name# KeepAlive
2025-10-26T19:25:23.503537Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300759:38][/Root/test_auditlog.py] Set up state: owner# [50000:7565613769511300614:9], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:23.505028Z :GRPC_SERVER DEBUG: [0x61a0000afe80] created request Name# KeepAlive
2025-10-26T19:25:23.505365Z :GRPC_SERVER DEBUG: [0x61a0000b0480] created request Name# KeepAlive
2025-10-26T19:25:23.505583Z :GRPC_SERVER DEBUG: [0x61a0000b0a80] created request Name# KeepAlive
2025-10-26T19:25:23.507521Z :GRPC_SERVER DEBUG: [0x61a0000b1080] created request Name# KeepAlive
2025-10-26T19:25:23.508226Z :GRPC_SERVER DEBUG: [0x61a0000b1680] created request Name# AlterTable
2025-10-26T19:25:23.508599Z :GRPC_SERVER DEBUG: [0x61a0000b1c80] created request Name# AlterTable
2025-10-26T19:25:23.508827Z :GRPC_SERVER DEBUG: [0x61a0000b2280] created request Name# AlterTable
2025-10-26T19:25:23.509056Z :GRPC_SERVER DEBUG: [0x61a0000b2880] created request Name# AlterTable
2025-10-26T19:25:23.514411Z :GRPC_SERVER DEBUG: [0x61a0000b2e80] created request Name# AlterTable
2025-10-26T19:25:23.514786Z :GRPC_SERVER DEBUG: [0x61a0000b3480] created request Name# AlterTable
2025-10-26T19:25:23.515818Z :GRPC_SERVER DEBUG: [0x61a0000b3a80] created request Name# AlterTable
2025-10-26T19:25:23.516100Z :GRPC_SERVER DEBUG: [0x61a0000b4080] created request Name# AlterTable
2025-10-26T19:25:23.516313Z :GRPC_SERVER DEBUG: [0x61a0000b4680] created request Name# AlterTable
2025-10-26T19:25:23.516551Z :GRPC_SERVER DEBUG: [0x61a0000b4c80] created request Name# AlterTable
2025-10-26T19:25:23.516807Z :GRPC_SERVER DEBUG: [0x61a0000b5280] created request Name# AlterTable
2025-10-26T19:25:23.517006Z :GRPC_SERVER DEBUG: [0x61a0000b5880] created request Name# AlterTable
2025-10-26T19:25:23.517254Z :GRPC_SERVER DEBUG: [0x61a0000b5e80] created request Name# AlterTable
2025-10-26T19:25:23.517484Z :GRPC_SERVER DEBUG: [0x61a0000b6480] created request Name# AlterTable
2025-10-26T19:25:23.517726Z :GRPC_SERVER DEBUG: [0x61a0000b6a80] created request Name# AlterTable
2025-10-26T19:25:23.519247Z :GRPC_SERVER DEBUG: [0x61a0000b7080] created request Name# AlterTable
2025-10-26T19:25:23.523927Z :GRPC_SERVER DEBUG: [0x61a0000b7680] created request Name# AlterTable
2025-10-26T19:25:23.529646Z :GRPC_SERVER DEBUG: [0x61a0000b7c80] created request Name# AlterTable
2025-10-26T19:25:23.529956Z :GRPC_SERVER DEBUG: [0x61a0000b8280] created request Name# AlterTable
2025-10-26T19:25:23.530172Z :GRPC_SERVER DEBUG: [0x61a0000b8880] created request Name# AlterTable
2025-10-26T19:25:23.530756Z :GRPC_SERVER DEBUG: [0x61a0000b8e80] created request Name# CreateTable
2025-10-26T19:25:23.531028Z :GRPC_SERVER DEBUG: [0x61a0000b9480] created request Name# CreateTable
2025-10-26T19:25:23.531261Z :GRPC_SERVER DEBUG: [0x61a0000b9a80] created request Name# CreateTable
2025-10-26T19:25:23.532606Z :GRPC_SERVER DEBUG: [0x61a0000ba080] created request Name# CreateTable
2025-10-26T19:25:23.533053Z :GRPC_SERVER DEBUG: [0x61a0000ba680] created request Name# CreateTable
2025-10-26T19:25:23.533266Z :GRPC_SERVER DEBUG: [0x61a0000bac80] created request Name# CreateTable
2025-10-26T19:25:23.533486Z :GRPC_SERVER DEBUG: [0x61a0000bb280] created request Name# CreateTable
2025-10-26T19:25:23.533710Z :GRPC_SERVER DEBUG: [0x61a0000bb880] created request Name# CreateTable
2025-10-26T19:25:23.533923Z :GRPC_SERVER DEBUG: [0x61a0000bbe80] created request Name# CreateTable
2025-10-26T19:25:23.534155Z :GRPC_SERVER DEBUG: [0x61a0000bc480] created request Name# CreateTable
2025-10-26T19:25:23.535017Z :GRPC_SERVER DEBUG: [0x61a0000bca80] created request Name# CreateTable
2025-10-26T19:25:23.535328Z :GRPC_SERVER DEBUG: [0x61a0000bd080] created request Name# CreateTable
2025-10-26T19:25:23.535550Z :GRPC_SERVER DEBUG: [0x61a0000bd680] created request Name# CreateTable
2025-10-26T19:25:23.535987Z :GRPC_SERVER DEBUG: [0x61a0000bdc80] created request Name# CreateTable
2025-10-26T19:25:23.536236Z :GRPC_SERVER DEBUG: [0x61a0000be280] created request Name# CreateTable
2025-10-26T19:25:23.536559Z :GRPC_SERVER DEBUG: [0x61a0000be880] created request Name# CreateTable
2025-10-26T19:25:23.536856Z :GRPC_SERVER DEBUG: [0x61a0000bee80] created request Name# CreateTable
2025-10-26T19:25:23.537088Z :GRPC_SERVER DEBUG: [0x61a0000bf480] created request Name# CreateTable
2025-10-26T19:25:23.537725Z :GRPC_SERVER DEBUG: [0x61a0000c0080] created request Name# CreateTable
2025-10-26T19:25:23.537999Z :GRPC_SERVER DEBUG: [0x61a0000c0680] created request Name# CreateTable
2025-10-26T19:25:23.538608Z :GRPC_SERVER DEBUG: [0x61a0000c0c80] created request Name# DropTable
2025-10-26T19:25:23.540937Z :GRPC_SERVER DEBUG: [0x61a0000c1280] created request Name# DropTable
2025-10-26T19:25:23.541298Z :GRPC_SERVER DEBUG: [0x61a0000c1880] created request Name# DropTable
2025-10-26T19:25:23.541546Z :GRPC_SERVER DEBUG: [0x61a0000c1e80] created request Name# DropTable
2025-10-26T19:25:23.541908Z :GRPC_SERVER DEBUG: [0x61a0000c2480] created request Name# DropTable
2025-10-26T19:25:23.542294Z :GRPC_SERVER DEBUG: [0x61a0000c2a80] created request Name# DropTable
2025-10-26T19:25:23.543600Z :GRPC_SERVER DEBUG: [0x61a0000c3080] created request Name# DropTable
2025-10-26T19:25:23.543910Z :GRPC_SERVER DEBUG: [0x61a0000c3680] created request Name# DropTable
2025-10-26T19:25:23.544192Z :GRPC_SERVER DEBUG: [0x61a0000c3c80] created request Name# DropTable
2025-10-26T19:25:23.544425Z :GRPC_SERVER DEBUG: [0x61a0000c4280] created request Name# DropTable
2025-10-26T19:25:23.544663Z :GRPC_SERVER DEBUG: [0x61a0000c4880] created request Name# DropTable
2025-10-26T19:25:23.544928Z :GRPC_SERVER DEBUG: [0x61a0000c4e80] created request Name# DropTable
2025-10-26T19:25:23.545157Z :GRPC_SERVER DEBUG: [0x61a0000c5480] created request Name# DropTable
2025-10-26T19:25:23.545387Z :GRPC_SERVER DEBUG: [0x61a0000c5a80] created request Name# DropTable
2025-10-26T19:25:23.545753Z :GRPC_SERVER DEBUG: [0x61a0000c6080] created request Name# DropTable
2025-10-26T19:25:23.545964Z :GRPC_SERVER DEBUG: [0x61a0000c6680] created request Name# DropTable
2025-10-26T19:25:23.546193Z :GRPC_SERVER DEBUG: [0x61a0000c6c80] created request Name# DropTable
2025-10-26T19:25:23.546450Z :GRPC_SERVER DEBUG: [0x61a0000c7280] created request Name# DropTable
2025-10-26T19:25:23.547506Z :GRPC_SERVER DEBUG: [0x61a0000c7880] created request Name# DropTable
2025-10-26T19:25:23.548199Z :GRPC_SERVER DEBUG: [0x61a0000c7e80] created request Name# DropTable
2025-10-26T19:25:23.548784Z :GRPC_SERVER DEBUG: [0x61a0000c8480] created request Name# DescribeTable
2025-10-26T19:25:23.551713Z :BS_PROXY NOTICE: EnsureMonitoring Group# 2181038082 IsLimitedKeyless# 0 fullIfPossible# 0 Marker# DSP58
2025-10-26T19:25:23.551917Z :GRPC_SERVER DEBUG: [0x61a0000c8a80] created request Name# DescribeTable
2025-10-26T19:25:23.554042Z :GRPC_SERVER DEBUG: [0x61a0000c9080] created request Name# DescribeTable
2025-10-26T19:25:23.555498Z :GRPC_SERVER DEBUG: [0x61a0000c9680] created request Name# DescribeTable
2025-10-26T19:25:23.555923Z :GRPC_SERVER DEBUG: [0x61a0000c9c80] created request Name# DescribeTable
2025-10-26T19:25:23.556277Z :GRPC_SERVER DEBUG: [0x61a0000ca280] created request Name# DescribeTable
2025-10-26T19:25:23.557675Z :GRPC_SERVER DEBUG: [0x61a0000ca880] created request Name# DescribeTable
2025-10-26T19:25:23.558014Z :GRPC_SERVER DEBUG: [0x61a0000cae80] created request Name# DescribeTable
2025-10-26T19:25:23.558250Z :GRPC_SERVER DEBUG: [0x61a0000cb480] created request Name# DescribeTable
2025-10-26T19:25:23.558444Z :GRPC_SERVER DEBUG: [0x61a0000cba80] created request Name# DescribeTable
2025-10-26T19:25:23.558965Z :GRPC_SERVER DEBUG: [0x61a0000cc080] created request Name# DescribeTable
2025-10-26T19:25:23.560643Z :GRPC_SERVER DEBUG: [0x61a0000cc680] created request Name# DescribeTable
2025-10-26T19:25:23.562349Z :GRPC_SERVER DEBUG: [0x61a0000ccc80] created request Name# DescribeTable
2025-10-26T19:25:23.562651Z :GRPC_SERVER DEBUG: [0x61a0000cd280] created request Name# DescribeTable
2025-10-26T19:25:23.562927Z :GRPC_SERVER DEBUG: [0x61a0000cd880] created request Name# DescribeTable
2025-10-26T19:25:23.563186Z :GRPC_SERVER DEBUG: [0x61a0000cde80] created request Name# DescribeTable
2025-10-26T19:25:23.563506Z :GRPC_SERVER DEBUG: [0x61a0000ce480] created request Name# DescribeTable
2025-10-26T19:25:23.563859Z :GRPC_SERVER DEBUG: [0x61a0000cea80] created request Name# DescribeTable
2025-10-26T19:25:23.564153Z :GRPC_SERVER DEBUG: [0x61a0000cf080] created request Name# DescribeTable
2025-10-26T19:25:23.564397Z :GRPC_SERVER DEBUG: [0x61a0000cf680] created request Name# DescribeTable
2025-10-26T19:25:23.565513Z :BS_PROXY NOTICE: EnsureMonitoring Group# 2181038082 IsLimitedKeyless# 0 Marker# DSP57 initialize full monitoring
2025-10-26T19:25:23.565722Z :GRPC_SERVER DEBUG: [0x61a0000cfc80] created request Name# CopyTable
2025-10-26T19:25:23.566101Z :GRPC_SERVER DEBUG: [0x61a0000d0280] created request Name# CopyTable
2025-10-26T19:25:23.566412Z :GRPC_SERVER DEBUG: [0x61a0000d0880] created request Name# CopyTable
2025-10-26T19:25:23.567723Z :GRPC_SERVER DEBUG: [0x61a0000d0e80] created request Name# CopyTable
2025-10-26T19:25:23.567999Z :GRPC_SERVER DEBUG: [0x61a0000d1480] created request Name# CopyTable
2025-10-26T19:25:23.568223Z :GRPC_SERVER DEBUG: [0x61a0000d1a80] created request Name# CopyTable
2025-10-26T19:25:23.568702Z :GRPC_SERVER DEBUG: [0x61a0000d2080] created request Name# CopyTable
2025-10-26T19:25:23.569614Z :GRPC_SERVER DEBUG: [0x61a0000d2680] created request Name# CopyTable
2025-10-26T19:25:23.570061Z :GRPC_SERVER DEBUG: [0x61a0000d2c80] created request Name# CopyTable
2025-10-26T19:25:23.571421Z :GRPC_SERVER DEBUG: [0x61a0000d3280] created request Name# CopyTable
2025-10-26T19:25:23.571965Z :GRPC_SERVER DEBUG: [0x61a0000d3880] created request Name# CopyTable
2025-10-26T19:25:23.572921Z :GRPC_SERVER DEBUG: [0x61a0000d3e80] created request Name# CopyTable
2025-10-26T19:25:23.573134Z :GRPC_SERVER DEBUG: [0x61a0000d4480] created request Name# CopyTable
2025-10-26T19:25:23.574509Z :BS_PROXY_DISCOVER WARN: [f7ae1c7c18de08a9] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.574723Z :BS_PROXY_DISCOVER WARN: [3cb31976cb4cbe50] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.574881Z :BS_PROXY_DISCOVER WARN: [2928dacbd38cdbbd] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.575022Z :BS_PROXY_DISCOVER WARN: [b45ca5476f1823d1] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.575170Z :BS_PROXY_DISCOVER WARN: [54ea6ebf2617b350] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.575309Z :BS_PROXY_DISCOVER WARN: [5c99c3acec924703] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.578445Z :GRPC_SERVER DEBUG: [0x61a0000d4a80] created request Name# CopyTable
2025-10-26T19:25:23.579149Z :GRPC_SERVER DEBUG: [0x61a0000d5080] created request Name# CopyTable
2025-10-26T19:25:23.579434Z :GRPC_SERVER DEBUG: [0x61a0000d5680] created request Name# CopyTable
2025-10-26T19:25:23.586363Z :GRPC_SERVER DEBUG: [0x61a0000d5c80] created request Name# CopyTable
2025-10-26T19:25:23.586810Z :GRPC_SERVER DEBUG: [0x61a0000d6280] created request Name# CopyTable
2025-10-26T19:25:23.587324Z :GRPC_SERVER DEBUG: [0x61a0000d6880] created request Name# CopyTable
2025-10-26T19:25:23.587643Z :GRPC_SERVER DEBUG: [0x61a0000d6e80] created request Name# CopyTable
2025-10-26T19:25:23.588125Z :GRPC_SERVER DEBUG: [0x61a0000d7480] created request Name# CopyTables
2025-10-26T19:25:23.588387Z :GRPC_SERVER DEBUG: [0x61a0000d7a80] created request Name# CopyTables
2025-10-26T19:25:23.588917Z :GRPC_SERVER DEBUG: [0x61a0000d8080] created request Name# CopyTables
2025-10-26T19:25:23.589138Z :GRPC_SERVER DEBUG: [0x61a0000d8680] created request Name# CopyTables
2025-10-26T19:25:23.589984Z :GRPC_SERVER DEBUG: [0x61a0000d8c80] created request Name# CopyTables
2025-10-26T19:25:23.590229Z :GRPC_SERVER DEBUG: [0x61a0000d9280] created request Name# CopyTables
2025-10-26T19:25:23.590466Z :GRPC_SERVER DEBUG: [0x61a0000d9880] created request Name# CopyTables
2025-10-26T19:25:23.590688Z :GRPC_SERVER DEBUG: [0x61a0000d9e80] created request Name# CopyTables
2025-10-26T19:25:23.590861Z :GRPC_SERVER DEBUG: [0x61a0000da480] created request Name# CopyTables
2025-10-26T19:25:23.591040Z :GRPC_SERVER DEBUG: [0x61a0000daa80] created request Name# CopyTables
2025-10-26T19:25:23.591281Z :GRPC_SERVER DEBUG: [0x61a0000db080] created request Name# CopyTables
2025-10-26T19:25:23.591472Z :GRPC_SERVER DEBUG: [0x61a0000db680] created request Name# CopyTables
2025-10-26T19:25:23.591651Z :GRPC_SERVER DEBUG: [0x61a0000dbc80] created request Name# CopyTables
2025-10-26T19:25:23.591847Z :GRPC_SERVER DEBUG: [0x61a0000dc280] created request Name# CopyTables
2025-10-26T19:25:23.592035Z :GRPC_SERVER DEBUG: [0x61a0000dc880] created request Name# CopyTables
2025-10-26T19:25:23.592241Z :GRPC_SERVER DEBUG: [0x61a0000dce80] created request Name# CopyTables
2025-10-26T19:25:23.592394Z :GRPC_SERVER DEBUG: [0x61a0000dd480] created request Name# CopyTables
2025-10-26T19:25:23.592556Z :GRPC_SERVER DEBUG: [0x61a0000dda80] created request Name# CopyTables
2025-10-26T19:25:23.592769Z :GRPC_SERVER DEBUG: [0x61a0000de080] created request Name# CopyTables
2025-10-26T19:25:23.592959Z :GRPC_SERVER DEBUG: [0x61a0000de680] created request Name# CopyTables
2025-10-26T19:25:23.593330Z :GRPC_SERVER DEBUG: [0x61a0000dec80] created request Name# RenameTables
2025-10-26T19:25:23.593558Z :GRPC_SERVER DEBUG: [0x61a0000df280] created request Name# RenameTables
2025-10-26T19:25:23.594504Z :GRPC_SERVER DEBUG: [0x61a0000dfe80] created request Name# RenameTables
2025-10-26T19:25:23.594739Z :GRPC_SERVER DEBUG: [0x61a0000e0480] created request Name# RenameTables
2025-10-26T19:25:23.594976Z :GRPC_SERVER DEBUG: [0x61a0000e0a80] created request Name# RenameTables
2025-10-26T19:25:23.595578Z :GRPC_SERVER DEBUG: [0x61a0000e1080] created request Name# RenameTables
2025-10-26T19:25:23.595871Z :GRPC_SERVER DEBUG: [0x61a0000e1680] created request Name# RenameTables
2025-10-26T19:25:23.596738Z :GRPC_SERVER DEBUG: [0x61a0000e1c80] created request Name# RenameTables
2025-10-26T19:25:23.596964Z :GRPC_SERVER DEBUG: [0x61a0000e2280] created request Name# RenameTables
2025-10-26T19:25:23.597200Z :GRPC_SERVER DEBUG: [0x61a0000e2880] created request Name# RenameTables
2025-10-26T19:25:23.597409Z :GRPC_SERVER DEBUG: [0x61a0000e2e80] created request Name# RenameTables
2025-10-26T19:25:23.601662Z :GRPC_SERVER DEBUG: [0x61a0000e3480] created request Name# RenameTables
2025-10-26T19:25:23.603334Z :GRPC_SERVER DEBUG: [0x61a0000e3a80] created request Name# RenameTables
2025-10-26T19:25:23.603955Z :GRPC_SERVER DEBUG: [0x61a0000e4080] created request Name# RenameTables
2025-10-26T19:25:23.607289Z :GRPC_SERVER DEBUG: [0x61a0000e4680] created request Name# RenameTables
2025-10-26T19:25:23.613946Z :GRPC_SERVER DEBUG: [0x61a0000e4c80] created request Name# RenameTables
2025-10-26T19:25:23.614269Z :GRPC_SERVER DEBUG: [0x61a0000e5280] created request Name# RenameTables
2025-10-26T19:25:23.614495Z :GRPC_SERVER DEBUG: [0x61a0000e5880] created request Name# RenameTables
2025-10-26T19:25:23.614934Z :GRPC_SERVER DEBUG: [0x61a0000e5e80] created request Name# RenameTables
2025-10-26T19:25:23.615186Z :GRPC_SERVER DEBUG: [0x61a0000e6480] created request Name# RenameTables
2025-10-26T19:25:23.615990Z :GRPC_SERVER DEBUG: [0x61a0000e6a80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.616023Z :BS_PROXY_DISCOVER WARN: [8181a8643e834c03] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.616258Z :BS_PROXY_DISCOVER WARN: [ff6e7ec661ec3e54] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.616310Z :GRPC_SERVER DEBUG: [0x61a0000e7080] created request Name# ExplainDataQuery
2025-10-26T19:25:23.616435Z :BS_PROXY_DISCOVER WARN: [d35ecf8569276dac] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.616514Z :GRPC_SERVER DEBUG: [0x61a0000e7680] created request Name# ExplainDataQuery
2025-10-26T19:25:23.616713Z :GRPC_SERVER DEBUG: [0x61a0000e7c80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.617346Z :BS_PROXY_DISCOVER WARN: [2c84338ba0c50c75] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.618164Z :GRPC_SERVER DEBUG: [0x61a0000e8280] created request Name# ExplainDataQuery
2025-10-26T19:25:23.618831Z :GRPC_SERVER DEBUG: [0x61a0000e8880] created request Name# ExplainDataQuery
2025-10-26T19:25:23.619132Z :GRPC_SERVER DEBUG: [0x61a0000e8e80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.619445Z :GRPC_SERVER DEBUG: [0x61a0000e9480] created request Name# ExplainDataQuery
2025-10-26T19:25:23.619678Z :GRPC_SERVER DEBUG: [0x61a0000e9a80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.619917Z :GRPC_SERVER DEBUG: [0x61a0000ea080] created request Name# ExplainDataQuery
2025-10-26T19:25:23.620157Z :GRPC_SERVER DEBUG: [0x61a0000ea680] created request Name# ExplainDataQuery
2025-10-26T19:25:23.624128Z :GRPC_SERVER DEBUG: [0x61a0000eac80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.625851Z :GRPC_SERVER DEBUG: [0x61a0000eb280] created request Name# ExplainDataQuery
2025-10-26T19:25:23.626129Z :GRPC_SERVER DEBUG: [0x61a0000eb880] created request Name# ExplainDataQuery
2025-10-26T19:25:23.626373Z :GRPC_SERVER DEBUG: [0x61a0000ebe80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.626747Z :GRPC_SERVER DEBUG: [0x61a0000ec480] created request Name# ExplainDataQuery
2025-10-26T19:25:23.626959Z :GRPC_SERVER DEBUG: [0x61a0000eca80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.627184Z :GRPC_SERVER DEBUG: [0x61a0000ed080] created request Name# ExplainDataQuery
2025-10-26T19:25:23.627459Z :GRPC_SERVER DEBUG: [0x61a0000ed680] created request Name# ExplainDataQuery
2025-10-26T19:25:23.628460Z :GRPC_SERVER DEBUG: [0x61a0000edc80] created request Name# ExplainDataQuery
2025-10-26T19:25:23.631637Z :GRPC_SERVER DEBUG: [0x61a0000ee280] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.633588Z :GRPC_SERVER DEBUG: [0x61a0000ee880] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.633904Z :GRPC_SERVER DEBUG: [0x61a0000eee80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.634264Z :GRPC_SERVER DEBUG: [0x61a0000ef480] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.635241Z :GRPC_SERVER DEBUG: [0x61a0000ffc80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.635601Z :GRPC_SERVER DEBUG: [0x61a000100280] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.638852Z :GRPC_SERVER DEBUG: [0x61a000100880] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.639129Z :GRPC_SERVER DEBUG: [0x61a000100e80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.639378Z :GRPC_SERVER DEBUG: [0x61a000101480] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.639609Z :GRPC_SERVER DEBUG: [0x61a000101a80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.639881Z :GRPC_SERVER DEBUG: [0x61a000102080] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.640624Z :GRPC_SERVER DEBUG: [0x61a000102680] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.640905Z :GRPC_SERVER DEBUG: [0x61a000102c80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.641703Z :GRPC_SERVER DEBUG: [0x61a000103280] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.641997Z :GRPC_SERVER DEBUG: [0x61a000103880] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.642301Z :GRPC_SERVER DEBUG: [0x61a000103e80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.642634Z :GRPC_SERVER DEBUG: [0x61a000104480] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.642940Z :GRPC_SERVER DEBUG: [0x61a000104a80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.643200Z :GRPC_SERVER DEBUG: [0x61a000105080] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.644150Z :GRPC_SERVER DEBUG: [0x61a000105680] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:23.644817Z :GRPC_SERVER DEBUG: [0x61a000105c80] created request Name# BeginTransaction
2025-10-26T19:25:23.645133Z :GRPC_SERVER DEBUG: [0x61a000106280] created request Name# BeginTransaction
2025-10-26T19:25:23.646185Z :BS_PROXY_DISCOVER WARN: [26e7a767e6639337] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.646401Z :BS_PROXY_DISCOVER WARN: [ba19ad5a99f853ca] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.646554Z :BS_PROXY_DISCOVER WARN: [50c2eea919acb20b] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.646702Z :BS_PROXY_DISCOVER WARN: [97accef4d64929c6] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.647154Z :GRPC_SERVER DEBUG: [0x61a000106880] created request Name# BeginTransaction
2025-10-26T19:25:23.647488Z :GRPC_SERVER DEBUG: [0x61a000106e80] created request Name# BeginTransaction
2025-10-26T19:25:23.654071Z :GRPC_SERVER DEBUG: [0x61a000107480] created request Name# BeginTransaction
2025-10-26T19:25:23.662847Z :GRPC_SERVER DEBUG: [0x61a000107a80] created request Name# BeginTransaction
2025-10-26T19:25:23.663218Z :GRPC_SERVER DEBUG: [0x61a000108080] created request Name# BeginTransaction
2025-10-26T19:25:23.663472Z :GRPC_SERVER DEBUG: [0x61a000108680] created request Name# BeginTransaction
2025-10-26T19:25:23.663716Z :GRPC_SERVER DEBUG: [0x61a000108c80] created request Name# BeginTransaction
2025-10-26T19:25:23.664330Z :GRPC_SERVER DEBUG: [0x61a000109280] created request Name# BeginTransaction
2025-10-26T19:25:23.664913Z :GRPC_SERVER DEBUG: [0x61a000109880] created request Name# BeginTransaction
2025-10-26T19:25:23.665175Z :GRPC_SERVER DEBUG: [0x61a000109e80] created request Name# BeginTransaction
2025-10-26T19:25:23.665393Z :GRPC_SERVER DEBUG: [0x61a00010a480] created request Name# BeginTransaction
2025-10-26T19:25:23.665423Z :BS_PROXY_DISCOVER WARN: [ebc641ead2319259] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.665596Z :GRPC_SERVER DEBUG: [0x61a00010aa80] created request Name# BeginTransaction
2025-10-26T19:25:23.665603Z :BS_PROXY_DISCOVER WARN: [acec664f2dcdec19] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.665801Z :BS_PROXY_DISCOVER WARN: [e36e1fe42dae572b] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:23.665848Z :GRPC_SERVER DEBUG: [0x61a00010b080] created request Name# BeginTransaction
2025-10-26T19:25:23.666078Z :GRPC_SERVER DEBUG: [0x61a00010b680] created request Name# BeginTransaction
2025-10-26T19:25:23.666329Z :GRPC_SERVER DEBUG: [0x61a00010bc80] created request Name# BeginTransaction
2025-10-26T19:25:23.666584Z :GRPC_SERVER DEBUG: [0x61a00010c280] created request Name# BeginTransaction
2025-10-26T19:25:23.666877Z :BS_PROXY_DISCOVER WARN: [3c4708984e2009c8] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.667019Z :BS_PROXY_DISCOVER WARN: [403fe1488897ad73] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.667721Z :GRPC_SERVER DEBUG: [0x61a00010c880] created request Name# BeginTransaction
2025-10-26T19:25:23.669857Z :GRPC_SERVER DEBUG: [0x61a00010ce80] created request Name# BeginTransaction
2025-10-26T19:25:23.675102Z :GRPC_SERVER DEBUG: [0x61a00010d480] created request Name# DescribeTableOptions
2025-10-26T19:25:23.675647Z :GRPC_SERVER DEBUG: [0x61a00010da80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.675961Z :GRPC_SERVER DEBUG: [0x61a00010e080] created request Name# DescribeTableOptions
2025-10-26T19:25:23.676782Z :GRPC_SERVER DEBUG: [0x61a00010e680] created request Name# DescribeTableOptions
2025-10-26T19:25:23.677205Z :GRPC_SERVER DEBUG: [0x61a00010ec80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.677701Z :GRPC_SERVER DEBUG: [0x61a00010f280] created request Name# DescribeTableOptions
2025-10-26T19:25:23.678403Z :GRPC_SERVER DEBUG: [0x61a00010fe80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.678742Z :GRPC_SERVER DEBUG: [0x61a000110480] created request Name# DescribeTableOptions
2025-10-26T19:25:23.681506Z :GRPC_SERVER DEBUG: [0x61a000110a80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.682829Z :GRPC_SERVER DEBUG: [0x61a000111080] created request Name# DescribeTableOptions
2025-10-26T19:25:23.683071Z :GRPC_SERVER DEBUG: [0x61a000111680] created request Name# DescribeTableOptions
2025-10-26T19:25:23.683500Z :GRPC_SERVER DEBUG: [0x61a000111c80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.683753Z :GRPC_SERVER DEBUG: [0x61a000112280] created request Name# DescribeTableOptions
2025-10-26T19:25:23.683945Z :GRPC_SERVER DEBUG: [0x61a000112880] created request Name# DescribeTableOptions
2025-10-26T19:25:23.684367Z :GRPC_SERVER DEBUG: [0x61a000112e80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.684650Z :GRPC_SERVER DEBUG: [0x61a000113480] created request Name# DescribeTableOptions
2025-10-26T19:25:23.684909Z :GRPC_SERVER DEBUG: [0x61a000113a80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.685301Z :GRPC_SERVER DEBUG: [0x61a000114080] created request Name# DescribeTableOptions
2025-10-26T19:25:23.690157Z :GRPC_SERVER DEBUG: [0x61a000114680] created request Name# DescribeTableOptions
2025-10-26T19:25:23.692512Z :GRPC_SERVER DEBUG: [0x61a000114c80] created request Name# DescribeTableOptions
2025-10-26T19:25:23.693137Z :GRPC_SERVER DEBUG: [0x61a000115280] created request Name# DeleteSession
2025-10-26T19:25:23.693483Z :GRPC_SERVER DEBUG: [0x61a000115880] created request Name# DeleteSession
2025-10-26T19:25:23.693967Z :GRPC_SERVER DEBUG: [0x61a000115e80] created request Name# DeleteSession
2025-10-26T19:25:23.694250Z :GRPC_SERVER DEBUG: [0x61a000116480] created request Name# DeleteSession
2025-10-26T19:25:23.694541Z :GRPC_SERVER DEBUG: [0x61a000116a80] created request Name# DeleteSession
2025-10-26T19:25:23.694791Z :GRPC_SERVER DEBUG: [0x61a000117080] created request Name# DeleteSession
2025-10-26T19:25:23.695008Z :GRPC_SERVER DEBUG: [0x61a000117680] created request Name# DeleteSession
2025-10-26T19:25:23.695251Z :GRPC_SERVER DEBUG: [0x61a000117c80] created request Name# DeleteSession
2025-10-26T19:25:23.695545Z :GRPC_SERVER DEBUG: [0x61a000118280] created request Name# DeleteSession
2025-10-26T19:25:23.695832Z :GRPC_SERVER DEBUG: [0x61a000118880] created request Name# DeleteSession
2025-10-26T19:25:23.701726Z :GRPC_SERVER DEBUG: [0x61a000118e80] created request Name# DeleteSession
2025-10-26T19:25:23.701948Z :GRPC_SERVER DEBUG: [0x61a000119480] created request Name# DeleteSession
2025-10-26T19:25:23.702153Z :GRPC_SERVER DEBUG: [0x61a000119a80] created request Name# DeleteSession
2025-10-26T19:25:23.702330Z :GRPC_SERVER DEBUG: [0x61a00011a080] created request Name# DeleteSession
2025-10-26T19:25:23.702978Z :GRPC_SERVER DEBUG: [0x61a00011a680] created request Name# DeleteSession
2025-10-26T19:25:23.703287Z :GRPC_SERVER DEBUG: [0x61a00011ac80] created request Name# DeleteSession
2025-10-26T19:25:23.703538Z :GRPC_SERVER DEBUG: [0x61a00011b280] created request Name# DeleteSession
2025-10-26T19:25:23.703789Z :GRPC_SERVER DEBUG: [0x61a00011b880] created request Name# DeleteSession
2025-10-26T19:25:23.708851Z :GRPC_SERVER DEBUG: [0x61a00011be80] created request Name# DeleteSession
2025-10-26T19:25:23.714767Z :GRPC_SERVER DEBUG: [0x61a00011c480] created request Name# DeleteSession
2025-10-26T19:25:23.720488Z :GRPC_SERVER DEBUG: [0x61a00011ca80] created request Name# CommitTransaction
2025-10-26T19:25:23.722053Z :GRPC_SERVER DEBUG: [0x61a00011d080] created request Name# CommitTransaction
2025-10-26T19:25:23.722772Z :GRPC_SERVER DEBUG: [0x61a00011d680] created request Name# CommitTransaction
2025-10-26T19:25:23.723126Z :GRPC_SERVER DEBUG: [0x61a00011dc80] created request Name# CommitTransaction
2025-10-26T19:25:23.723495Z :GRPC_SERVER DEBUG: [0x61a00011e280] created request Name# CommitTransaction
2025-10-26T19:25:23.723761Z :GRPC_SERVER DEBUG: [0x61a00011e880] created request Name# CommitTransaction
2025-10-26T19:25:23.724172Z :GRPC_SERVER DEBUG: [0x61a00011ee80] created request Name# CommitTransaction
2025-10-26T19:25:23.725499Z :GRPC_SERVER DEBUG: [0x61a00011f480] created request Name# CommitTransaction
2025-10-26T19:25:23.728630Z :GRPC_SERVER DEBUG: [0x61a000120080] created request Name# CommitTransaction
2025-10-26T19:25:23.730115Z :GRPC_SERVER DEBUG: [0x61a000120680] created request Name# CommitTransaction
2025-10-26T19:25:23.730405Z :GRPC_SERVER DEBUG: [0x61a000120c80] created request Name# CommitTransaction
2025-10-26T19:25:23.730680Z :GRPC_SERVER DEBUG: [0x61a000121280] created request Name# CommitTransaction
2025-10-26T19:25:23.731900Z :GRPC_SERVER DEBUG: [0x61a000121880] created request Name# CommitTransaction
2025-10-26T19:25:23.732807Z :GRPC_SERVER DEBUG: [0x61a000121e80] created request Name# CommitTransaction
2025-10-26T19:25:23.733157Z :GRPC_SERVER DEBUG: [0x61a000122480] created request Name# CommitTransaction
2025-10-26T19:25:23.733444Z :GRPC_SERVER DEBUG: [0x61a000122a80] created request Name# CommitTransaction
2025-10-26T19:25:23.733643Z :GRPC_SERVER DEBUG: [0x61a000123080] created request Name# CommitTransaction
2025-10-26T19:25:23.733874Z :GRPC_SERVER DEBUG: [0x61a000123680] created request Name# CommitTransaction
2025-10-26T19:25:23.734165Z :GRPC_SERVER DEBUG: [0x61a000123c80] created request Name# CommitTransaction
2025-10-26T19:25:23.736602Z :GRPC_SERVER DEBUG: [0x61a000124280] created request Name# CommitTransaction
2025-10-26T19:25:23.737882Z :GRPC_SERVER DEBUG: [0x61a000124880] created request Name# RollbackTransaction
2025-10-26T19:25:23.738253Z :GRPC_SERVER DEBUG: [0x61a000124e80] created request Name# RollbackTransaction
2025-10-26T19:25:23.752745Z :GRPC_SERVER DEBUG: [0x61a000125480] created request Name# RollbackTransaction
2025-10-26T19:25:23.753653Z :GRPC_SERVER DEBUG: [0x61a000125a80] created request Name# RollbackTransaction
2025-10-26T19:25:23.753979Z :GRPC_SERVER DEBUG: [0x61a000126080] created request Name# RollbackTransaction
2025-10-26T19:25:23.754286Z :GRPC_SERVER DEBUG: [0x61a000126680] created request Name# RollbackTransaction
2025-10-26T19:25:23.754503Z :GRPC_SERVER DEBUG: [0x61a000126c80] created request Name# RollbackTransaction
2025-10-26T19:25:23.762731Z :GRPC_SERVER DEBUG: [0x61a000127280] created request Name# RollbackTransaction
2025-10-26T19:25:23.763130Z :GRPC_SERVER DEBUG: [0x61a000127880] created request Name# RollbackTransaction
2025-10-26T19:25:23.763436Z :GRPC_SERVER DEBUG: [0x61a000127e80] created request Name# RollbackTransaction
2025-10-26T19:25:23.763703Z :GRPC_SERVER DEBUG: [0x61a000128480] created request Name# RollbackTransaction
2025-10-26T19:25:23.763944Z :GRPC_SERVER DEBUG: [0x61a000128a80] created request Name# RollbackTransaction
2025-10-26T19:25:23.764824Z :GRPC_SERVER DEBUG: [0x61a000129080] created request Name# RollbackTransaction
2025-10-26T19:25:23.765117Z :GRPC_SERVER DEBUG: [0x61a000129680] created request Name# RollbackTransaction
2025-10-26T19:25:23.766916Z :GRPC_SERVER DEBUG: [0x61a000129c80] created request Name# RollbackTransaction
2025-10-26T19:25:23.767327Z :GRPC_SERVER DEBUG: [0x61a00012a280] created request Name# RollbackTransaction
2025-10-26T19:25:23.767523Z :GRPC_SERVER DEBUG: [0x61a00012a880] created request Name# RollbackTransaction
2025-10-26T19:25:23.767719Z :GRPC_SERVER DEBUG: [0x61a00012ae80] created request Name# RollbackTransaction
2025-10-26T19:25:23.767902Z :GRPC_SERVER DEBUG: [0x61a00012b480] created request Name# RollbackTransaction
2025-10-26T19:25:23.768088Z :GRPC_SERVER DEBUG: [0x61a00012ba80] created request Name# RollbackTransaction
2025-10-26T19:25:23.768478Z :GRPC_SERVER DEBUG: [0x61a00012c080] created request Name# PrepareDataQuery
2025-10-26T19:25:23.768726Z :GRPC_SERVER DEBUG: [0x61a00012c680] created request Name# PrepareDataQuery
2025-10-26T19:25:23.768883Z :GRPC_SERVER DEBUG: [0x61a00012cc80] created request Name# PrepareDataQuery
2025-10-26T19:25:23.769068Z :GRPC_SERVER DEBUG: [0x61a00012d280] created request Name# PrepareDataQuery
2025-10-26T19:25:23.769235Z :GRPC_SERVER DEBUG: [0x61a00012d880] created request Name# PrepareDataQuery
2025-10-26T19:25:23.769420Z :GRPC_SERVER DEBUG: [0x61a00012de80] created request Name# PrepareDataQuery
2025-10-26T19:25:23.769633Z :GRPC_SERVER DEBUG: [0x61a00012e480] created request Name# PrepareDataQuery
2025-10-26T19:25:23.769851Z :GRPC_SERVER DEBUG: [0x61a00012ea80] created request Name# PrepareDataQuery
2025-10-26T19:25:23.770034Z :GRPC_SERVER DEBUG: [0x61a00012f080] created request Name# PrepareDataQuery
2025-10-26T19:25:23.770241Z :GRPC_SERVER DEBUG: [0x61a00012f680] created request Name# PrepareDataQuery
2025-10-26T19:25:23.777029Z :GRPC_SERVER DEBUG: [0x61a00012fc80] created request Name# PrepareDataQuery
2025-10-26T19:25:23.777454Z :GRPC_SERVER DEBUG: [0x61a000130280] created request Name# PrepareDataQuery
2025-10-26T19:25:23.778896Z :GRPC_SERVER DEBUG: [0x61a000130880] created request Name# PrepareDataQuery
2025-10-26T19:25:23.782885Z :GRPC_SERVER DEBUG: [0x61a000130e80] created request Name# PrepareDataQuery
2025-10-26T19:25:23.783432Z :GRPC_SERVER DEBUG: [0x61a000131480] created request Name# PrepareDataQuery
2025-10-26T19:25:23.783703Z :GRPC_SERVER DEBUG: [0x61a000131a80] created request Name# PrepareDataQuery
2025-10-26T19:25:23.784112Z :GRPC_SERVER DEBUG: [0x61a000132080] created request Name# PrepareDataQuery
2025-10-26T19:25:23.785972Z :GRPC_SERVER DEBUG: [0x61a000132680] created request Name# PrepareDataQuery
2025-10-26T19:25:23.786329Z :GRPC_SERVER DEBUG: [0x61a000132c80] created request Name# PrepareDataQuery
2025-10-26T19:25:23.791805Z :GRPC_SERVER DEBUG: [0x61a000133280] created request Name# PrepareDataQuery
2025-10-26T19:25:23.792549Z :GRPC_SERVER DEBUG: [0x61a000133880] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.793492Z :GRPC_SERVER DEBUG: [0x61a000133e80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.793775Z :GRPC_SERVER DEBUG: [0x61a000134480] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.796082Z :GRPC_SERVER DEBUG: [0x61a000134a80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.799371Z :GRPC_SERVER DEBUG: [0x61a000135080] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.810208Z :GRPC_SERVER DEBUG: [0x61a000135680] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.830335Z :GRPC_SERVER DEBUG: [0x61a000135c80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.830757Z :GRPC_SERVER DEBUG: [0x61a000136280] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.833241Z :GRPC_SERVER DEBUG: [0x61a000136880] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.833871Z :GRPC_SERVER DEBUG: [0x61a000136e80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.834130Z :GRPC_SERVER DEBUG: [0x61a000137480] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.834373Z :GRPC_SERVER DEBUG: [0x61a000137a80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.838119Z :GRPC_SERVER DEBUG: [0x61a000138080] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.838460Z :GRPC_SERVER DEBUG: [0x61a000138680] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.838668Z :GRPC_SERVER DEBUG: [0x61a000138c80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.839063Z :GRPC_SERVER DEBUG: [0x61a000139280] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.840514Z :GRPC_SERVER DEBUG: [0x61a000139880] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.840909Z :GRPC_SERVER DEBUG: [0x61a000139e80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.841393Z :GRPC_SERVER DEBUG: [0x61a00013a480] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.846423Z :GRPC_SERVER DEBUG: [0x61a00013aa80] created request Name# ExecuteDataQuery
2025-10-26T19:25:23.852837Z :GRPC_SERVER DEBUG: [0x61a00013b080] created request Name# BulkUpsert
2025-10-26T19:25:23.853261Z :GRPC_SERVER DEBUG: [0x61a00013b680] created request Name# BulkUpsert
2025-10-26T19:25:23.853606Z :GRPC_SERVER DEBUG: [0x61a00013bc80] created request Name# BulkUpsert
2025-10-26T19:25:23.853869Z :GRPC_SERVER DEBUG: [0x61a00013c280] created request Name# BulkUpsert
2025-10-26T19:25:23.854648Z :GRPC_SERVER DEBUG: [0x61a00013c880] created request Name# BulkUpsert
2025-10-26T19:25:23.855774Z :GRPC_SERVER DEBUG: [0x61a00013ce80] created request Name# BulkUpsert
2025-10-26T19:25:23.857993Z :GRPC_SERVER DEBUG: [0x61a00013d480] created request Name# BulkUpsert
2025-10-26T19:25:23.862328Z :GRPC_SERVER DEBUG: [0x61a00013da80] created request Name# BulkUpsert
2025-10-26T19:25:23.862826Z :GRPC_SERVER DEBUG: [0x61a00013e080] created request Name# BulkUpsert
2025-10-26T19:25:23.863143Z :GRPC_SERVER DEBUG: [0x61a00013e680] created request Name# BulkUpsert
2025-10-26T19:25:23.863497Z :GRPC_SERVER DEBUG: [0x61a00013ec80] created request Name# BulkUpsert
2025-10-26T19:25:23.864046Z :GRPC_SERVER DEBUG: [0x61a00013f280] created request Name# BulkUpsert
2025-10-26T19:25:23.865819Z :GRPC_SERVER DEBUG: [0x61a00013fe80] created request Name# BulkUpsert
2025-10-26T19:25:23.872810Z :GRPC_SERVER DEBUG: [0x61a000140480] created request Name# BulkUpsert
2025-10-26T19:25:23.873171Z :GRPC_SERVER DEBUG: [0x61a000140a80] created request Name# BulkUpsert
2025-10-26T19:25:23.873421Z :GRPC_SERVER DEBUG: [0x61a000141080] created request Name# BulkUpsert
2025-10-26T19:25:23.874041Z :GRPC_SERVER DEBUG: [0x61a000141680] created request Name# BulkUpsert
2025-10-26T19:25:23.874466Z :GRPC_SERVER DEBUG: [0x61a000141c80] created request Name# BulkUpsert
2025-10-26T19:25:23.876293Z :GRPC_SERVER DEBUG: [0x61a000142280] created request Name# BulkUpsert
2025-10-26T19:25:23.876807Z :GRPC_SERVER DEBUG: [0x61a000142880] created request Name# BulkUpsert
2025-10-26T19:25:23.880511Z :GRPC_SERVER DEBUG: [0x61a000142e80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.881139Z :GRPC_SERVER DEBUG: [0x61a000143480] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.881407Z :GRPC_SERVER DEBUG: [0x61a000143a80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.881718Z :GRPC_SERVER DEBUG: [0x61a000144080] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.881973Z :GRPC_SERVER DEBUG: [0x61a000144680] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.882252Z :GRPC_SERVER DEBUG: [0x61a000144c80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.882480Z :GRPC_SERVER DEBUG: [0x61a000145280] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.882707Z :GRPC_SERVER DEBUG: [0x61a000145880] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.882985Z :GRPC_SERVER DEBUG: [0x61a000145e80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.883305Z :GRPC_SERVER DEBUG: [0x61a000146480] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.883713Z :GRPC_SERVER DEBUG: [0x61a000146a80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.884037Z :GRPC_SERVER DEBUG: [0x61a000147080] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.884294Z :GRPC_SERVER DEBUG: [0x61a000147680] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.884772Z :GRPC_SERVER DEBUG: [0x61a000147c80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.885029Z :GRPC_SERVER DEBUG: [0x61a000148280] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.885303Z :GRPC_SERVER DEBUG: [0x61a000148880] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.885579Z :GRPC_SERVER DEBUG: [0x61a000148e80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.885835Z :GRPC_SERVER DEBUG: [0x61a000149480] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.886098Z :GRPC_SERVER DEBUG: [0x61a000149a80] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.886386Z :GRPC_SERVER DEBUG: [0x61a00014a080] created streaming request Name# StreamExecuteScanQuery
2025-10-26T19:25:23.886953Z :GRPC_SERVER DEBUG: [0x61a00014a680] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.887456Z :GRPC_SERVER DEBUG: [0x61a00014ac80] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.887827Z :GRPC_SERVER DEBUG: [0x61a00014b280] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.888879Z :GRPC_SERVER DEBUG: [0x61a00014b880] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.889179Z :GRPC_SERVER DEBUG: [0x61a00014be80] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.889401Z :GRPC_SERVER DEBUG: [0x61a00014c480] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.889623Z :GRPC_SERVER DEBUG: [0x61a00014ca80] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.889894Z :GRPC_SERVER DEBUG: [0x61a00014d080] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.890192Z :GRPC_SERVER DEBUG: [0x61a00014d680] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.890911Z :GRPC_SERVER DEBUG: [0x61a00014dc80] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.891416Z :GRPC_SERVER INFO: Updated app config
2025-10-26T19:25:23.891793Z :GRPC_SERVER DEBUG: [0x61a00014e280] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.892119Z :GRPC_SERVER DEBUG: [0x61a00014e880] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.892326Z :GRPC_SERVER DEBUG: [0x61a00014ee80] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.892545Z :GRPC_SERVER DEBUG: [0x61a00014f480] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.893524Z :GRPC_SERVER DEBUG: [0x61a000150080] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.893799Z :GRPC_SERVER INFO: Updated app config
2025-10-26T19:25:23.893887Z :GRPC_SERVER DEBUG: [0x61a000150680] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.894169Z :GRPC_SERVER DEBUG: [0x61a000150c80] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.894772Z :GRPC_SERVER DEBUG: [0x61a000151280] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.895069Z :GRPC_SERVER DEBUG: [0x61a000151880] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.895382Z :GRPC_SERVER DEBUG: [0x61a000151e80] created streaming request Name# StreamReadTable
2025-10-26T19:25:23.896066Z :GRPC_SERVER DEBUG: [0x61a000152480] created request Name# ReadRows
2025-10-26T19:25:23.896445Z :GRPC_SERVER DEBUG: [0x61a000152a80] created request Name# ReadRows
2025-10-26T19:25:23.896692Z :GRPC_SERVER DEBUG: [0x61a000153080] created request Name# ReadRows
2025-10-26T19:25:23.896878Z :GRPC_SERVER DEBUG: [0x61a000153680] created request Name# ReadRows
2025-10-26T19:25:23.897101Z :GRPC_SERVER DEBUG: [0x61a000153c80] created request Name# ReadRows
2025-10-26T19:25:23.897332Z :GRPC_SERVER DEBUG: [0x61a000154280] created request Name# ReadRows
2025-10-26T19:25:23.897536Z :GRPC_SERVER DEBUG: [0x61a000154880] created request Name# ReadRows
2025-10-26T19:25:23.897842Z :GRPC_SERVER DEBUG: [0x61a000154e80] created request Name# ReadRows
2025-10-26T19:25:23.899075Z :GRPC_SERVER DEBUG: [0x61a000155480] created request Name# ReadRows
2025-10-26T19:25:23.899366Z :GRPC_SERVER DEBUG: [0x61a000155a80] created request Name# ReadRows
2025-10-26T19:25:23.900352Z :GRPC_SERVER DEBUG: [0x61a000156080] created request Name# ReadRows
2025-10-26T19:25:23.901895Z :GRPC_SERVER DEBUG: [0x61a000156680] created request Name# ReadRows
2025-10-26T19:25:23.902221Z :GRPC_SERVER DEBUG: [0x61a000156c80] created request Name# ReadRows
2025-10-26T19:25:23.902480Z :GRPC_SERVER DEBUG: [0x61a000157280] created request Name# ReadRows
2025-10-26T19:25:23.902707Z :GRPC_SERVER DEBUG: [0x61a000157880] created request Name# ReadRows
2025-10-26T19:25:23.902967Z :GRPC_SERVER DEBUG: [0x61a000157e80] created request Name# ReadRows
2025-10-26T19:25:23.903165Z :GRPC_SERVER DEBUG: [0x61a000158480] created request Name# ReadRows
2025-10-26T19:25:23.903653Z :GRPC_SERVER DEBUG: [0x61a000158a80] created request Name# ReadRows
2025-10-26T19:25:23.903916Z :GRPC_SERVER DEBUG: [0x61a000159080] created request Name# ReadRows
2025-10-26T19:25:23.904635Z :GRPC_SERVER DEBUG: [0x61a000159680] created request Name# ReadRows
2025-10-26T19:25:23.906338Z :GRPC_SERVER DEBUG: [0x61a000159c80] created request Name# Scan
2025-10-26T19:25:23.906953Z :GRPC_SERVER DEBUG: [0x61a00015a280] created request Name# GetShardLocations
2025-10-26T19:25:23.907747Z :GRPC_SERVER DEBUG: [0x61a00015a880] created request Name# DescribeTable
2025-10-26T19:25:23.908116Z :STATISTICS WARN: [72075186224037897] TTxInit::Complete. EnableColumnStatistics=false
2025-10-26T19:25:23.911846Z :GRPC_SERVER DEBUG: [0x61a00015ae80] created request Name# CreateSnapshot
2025-10-26T19:25:23.912414Z :GRPC_SERVER DEBUG: [0x61a00015b480] created request Name# RefreshSnapshot
2025-10-26T19:25:23.913411Z :GRPC_SERVER DEBUG: [0x61a00015ba80] created request Name# DiscardSnapshot
2025-10-26T19:25:23.914294Z :GRPC_SERVER DEBUG: [0x61a00015c080] created request Name# ExecuteYql
2025-10-26T19:25:23.914743Z :GRPC_SERVER DEBUG: [0x61a00015c680] created streaming request Name# StreamExecuteYql
2025-10-26T19:25:23.916789Z :GRPC_SERVER DEBUG: [0x61a00015cc80] created request Name# ExplainYql
2025-10-26T19:25:23.921966Z :BS_PROXY_DISCOVER WARN: [ad779891b3e9ced7] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:23.922330Z :FLAT_TX_SCHEMESHARD TRACE: StateInit, received event# 268828672, Sender [50000:7565613769511300767:4105], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTablet::TEvBoot
2025-10-26T19:25:23.923182Z :GRPC_SERVER DEBUG: [0x61a00015d280] created request Name# MakeDirectory
2025-10-26T19:25:23.923591Z :GRPC_SERVER DEBUG: [0x61a00015d880] created request Name# RemoveDirectory
2025-10-26T19:25:23.923939Z :GRPC_SERVER DEBUG: [0x61a00015de80] created request Name# ListDirectory
2025-10-26T19:25:23.924313Z :GRPC_SERVER DEBUG: [0x61a00015e480] created request Name# DescribePath
2025-10-26T19:25:23.924642Z :GRPC_SERVER DEBUG: [0x61a00015ea80] created request Name# ModifyPermissions
2025-10-26T19:25:23.924979Z :GRPC_SERVER DEBUG: [0x61a00015f080] created request Name# GetOperation
2025-10-26T19:25:23.925300Z :GRPC_SERVER DEBUG: [0x61a00015f680] created request Name# CancelOperation
2025-10-26T19:25:23.925627Z :GRPC_SERVER DEBUG: [0x61a00015fc80] created request Name# ForgetOperation
2025-10-26T19:25:23.926013Z :GRPC_SERVER DEBUG: [0x61a000160280] created request Name# ListOperations
2025-10-26T19:25:23.926353Z :GRPC_SERVER DEBUG: [0x61a000160880] created request Name# ExportToYt
2025-10-26T19:25:23.926701Z :GRPC_SERVER DEBUG: [0x61a000160e80] created request Name# ExportToS3
2025-10-26T19:25:23.927056Z :GRPC_SERVER DEBUG: [0x61a000161480] created request Name# ImportFromS3
2025-10-26T19:25:23.927454Z :GRPC_SERVER DEBUG: [0x61a000161a80] created request Name# ImportData
2025-10-26T19:25:23.927832Z :GRPC_SERVER DEBUG: [0x61a000162080] created request Name# Coordination/CreateNode
2025-10-26T19:25:23.929066Z :FLAT_TX_SCHEMESHARD TRACE: StateInit, received event# 268828673, Sender [50000:7565613769511300767:4105], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTablet::TEvRestored
2025-10-26T19:25:23.929405Z :FLAT_TX_SCHEMESHARD NOTICE: BackgroundCompactionQueue configured: Timeout# 600.000000s, compact single parted# no, Rate# 1, WakeupInterval# 60.000000s, RoundInterval# 172800.000000s, InflightLimit# 1, MinCompactionRepeatDelaySeconds# 600.000000s, MaxRate# 1
2025-10-26T19:25:23.929458Z :FLAT_TX_SCHEMESHARD NOTICE: BorrowedCompactionQueue configured: Timeout# 15.000000s, Rate# 0, WakeupInterval# 1.000000s, InflightLimit# 10
2025-10-26T19:25:23.929488Z :FLAT_TX_SCHEMESHARD NOTICE: StatsBatching config: StatsBatchTimeout# 0.100000s, StatsMaxBatchSize# 100, StatsMaxExecuteTime# 0.010000s
2025-10-26T19:25:23.929553Z :FLAT_TX_SCHEMESHARD NOTICE: OperationsProcessing config: using default configuration
2025-10-26T19:25:23.929609Z :FLAT_TX_SCHEMESHARD NOTICE: OperationsProcessing config: type TxMergeTablePartition, limit 10000
2025-10-26T19:25:23.929641Z :FLAT_TX_SCHEMESHARD NOTICE: OperationsProcessing config: type TxSplitTablePartition, limit 10000
2025-10-26T19:25:23.929672Z :FLAT_TX_SCHEMESHARD NOTICE: BackgroundCleaningQueue configured: Timeout# 15.000000s, Rate# 0, WakeupInterval# 1.000000s, InflightLimit# 10
2025-10-26T19:25:23.930141Z :GRPC_SERVER DEBUG: [0x61a000162680] created request Name# Coordination/AlterNode
2025-10-26T19:25:23.930554Z :GRPC_SERVER DEBUG: [0x61a000162c80] created request Name# Coordination/DropNode
2025-10-26T19:25:23.930960Z :GRPC_SERVER DEBUG: [0x61a000163280] created request Name# Coordination/DescribeNode
2025-10-26T19:25:23.933158Z :FLAT_TX_SCHEMESHARD DEBUG: TxInitSchema.Execute
2025-10-26T19:25:23.968383Z :NET_CLASSIFIER WARN: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/70hy/0010b1/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk2/testing_out_stuff/netData.tsv
2025-10-26T19:25:23.968573Z :NET_CLASSIFIER WARN: will try to initialize from file: /home/runner/.ya/build/build_root/70hy/0010b1/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk2/testing_out_stuff/netData.tsv
2025-10-26T19:25:23.968762Z :NET_CLASSIFIER WARN: successfully initialized from file: /home/runner/.ya/build/build_root/70hy/0010b1/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk2/testing_out_stuff/netData.tsv
2025-10-26T19:25:23.968998Z :NET_CLASSIFIER ERROR: got bad distributable configuration
2025-10-26T19:25:23.988886Z :GRPC_SERVER DEBUG: [0x61a000163e80] created request Name# PersQueueService/GetReadSessionsInfo
2025-10-26T19:25:23.989345Z :GRPC_SERVER DEBUG: [0x61a000164480] created request Name# PersQueueService/DropTopic
2025-10-26T19:25:23.990029Z :GRPC_SERVER DEBUG: [0x61a000164a80] created request Name# PersQueueService/CreateTopic
2025-10-26T19:25:23.990634Z :GRPC_SERVER DEBUG: [0x61a000165080] created request Name# PersQueueService/AlterTopic
2025-10-26T19:25:23.991130Z :GRPC_SERVER DEBUG: [0x61a000165680] created request Name# PersQueueService/DescribeTopic
2025-10-26T19:25:23.991560Z :GRPC_SERVER DEBUG: [0x61a000165c80] created request Name# PersQueueService/AddReadRule
2025-10-26T19:25:23.992039Z :GRPC_SERVER DEBUG: [0x61a000166280] created request Name# PersQueueService/RemoveReadRule
2025-10-26T19:25:23.994264Z :GRPC_SERVER DEBUG: [0x61a000166880] created request Name# TopicService/CommitOffset
2025-10-26T19:25:23.995141Z :GRPC_SERVER DEBUG: [0x61a000166e80] created request Name# TopicService/DropTopic
2025-10-26T19:25:23.995662Z :GRPC_SERVER DEBUG: [0x61a000167480] created request Name# TopicService/CreateTopic
2025-10-26T19:25:23.996172Z :GRPC_SERVER DEBUG: [0x61a000167a80] created request Name# TopicService/AlterTopic
2025-10-26T19:25:23.996656Z :GRPC_SERVER DEBUG: [0x61a000168080] created request Name# TopicService/DescribeTopic
2025-10-26T19:25:23.997774Z :GRPC_SERVER DEBUG: [0x61a000168680] created request Name# TopicService/DescribeConsumer
2025-10-26T19:25:23.998213Z :GRPC_SERVER DEBUG: [0x61a000168c80] created request Name# TopicService/DescribePartition
2025-10-26T19:25:23.998592Z :GRPC_SERVER DEBUG: [0x61a000169280] created request Name# UpdateOffsetsInTransaction
2025-10-26T19:25:23.999703Z :GRPC_SERVER DEBUG: [0x61a000169880] created request Name# DiscoverClusters
2025-10-26T19:25:24.000111Z :GRPC_SERVER DEBUG: [0x61a000169e80] created request Name# CreateDatabase
2025-10-26T19:25:24.000894Z :GRPC_SERVER DEBUG: [0x61a00016a480] created request Name# AlterDatabase
2025-10-26T19:25:24.001312Z :GRPC_SERVER DEBUG: [0x61a00016aa80] created request Name# GetDatabaseStatus
2025-10-26T19:25:24.001942Z :GRPC_SERVER DEBUG: [0x61a00016b080] created request Name# ListDatabases
2025-10-26T19:25:24.002712Z :GRPC_SERVER DEBUG: [0x61a00016b680] created request Name# RemoveDatabase
2025-10-26T19:25:24.003605Z :GRPC_SERVER DEBUG: [0x61a00016bc80] created request Name# DescribeDatabaseOptions
2025-10-26T19:25:24.005177Z :GRPC_SERVER DEBUG: [0x61a00016c280] created request Name# GetScaleRecommendation
2025-10-26T19:25:24.006543Z :FLAT_TX_SCHEMESHARD TRACE: StateInit, received event# 268828684, Sender [50000:7565613769511300767:4105], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-26T19:25:24.006910Z :FLAT_TX_SCHEMESHARD TRACE: StateInit, received event# 273481729, Sender [50000:7565613769511300586:4109], Recipient [50000:7565613769511300997:56]: NKikimr::NConsole::TEvConfigsDispatcher::TEvSetConfigSubscriptionResponse
2025-10-26T19:25:24.006927Z :FLAT_TX_SCHEMESHARD TRACE: StateInit, processing event NConsole::TEvConfigsDispatcher::TEvSetConfigSubscriptionResponse
2025-10-26T19:25:24.006938Z :FLAT_TX_SCHEMESHARD NOTICE: Subscription to Console has been set up, schemeshardId: 72075186224037889
2025-10-26T19:25:24.008837Z :GRPC_SERVER DEBUG: [0x61a00016c880] created request Name# SetConfig
2025-10-26T19:25:24.009989Z :GRPC_SERVER DEBUG: [0x61a00016ce80] created request Name# ReplaceConfig
2025-10-26T19:25:24.010458Z :GRPC_SERVER DEBUG: [0x61a00016d480] created request Name# DropConfig
2025-10-26T19:25:24.011036Z :GRPC_SERVER DEBUG: [0x61a00016da80] created request Name# AddVolatileConfig
2025-10-26T19:25:24.013257Z :GRPC_SERVER DEBUG: [0x61a00016e080] created request Name# RemoveVolatileConfig
2025-10-26T19:25:24.015189Z :GRPC_SERVER DEBUG: [0x61a00016e680] created request Name# GetConfig
2025-10-26T19:25:24.015643Z :GRPC_SERVER DEBUG: [0x61a00016ec80] created request Name# GetMetadata
2025-10-26T19:25:24.016146Z :GRPC_SERVER DEBUG: [0x61a00016f280] created request Name# GetNodeLabels
2025-10-26T19:25:24.016580Z :GRPC_SERVER DEBUG: [0x61a00016fe80] created request Name# ResolveConfig
2025-10-26T19:25:24.016962Z :GRPC_SERVER DEBUG: [0x61a000170480] created request Name# ResolveAllConfig
2025-10-26T19:25:24.017375Z :GRPC_SERVER DEBUG: [0x61a000170a80] created request Name# WhoAmI
2025-10-26T19:25:24.017774Z :GRPC_SERVER DEBUG: [0x61a000171080] created request Name# NodeRegistration
2025-10-26T19:25:24.018161Z :GRPC_SERVER DEBUG: [0x61a000171680] created request Name# ListEndpoints
2025-10-26T19:25:24.018585Z :GRPC_SERVER DEBUG: [0x61a000171c80] created request Name# RateLimiter/CreateResource
2025-10-26T19:25:24.018948Z :GRPC_SERVER DEBUG: [0x61a000172280] created request Name# RateLimiter/AlterResource
2025-10-26T19:25:24.019414Z :GRPC_SERVER DEBUG: [0x61a000172880] created request Name# RateLimiter/DropResource
2025-10-26T19:25:24.019801Z :GRPC_SERVER DEBUG: [0x61a000172e80] created request Name# RateLimiter/ListResources
2025-10-26T19:25:24.026466Z :GRPC_SERVER DEBUG: [0x61a000173480] created request Name# RateLimiter/DescribeResource
2025-10-26T19:25:24.026935Z :GRPC_SERVER DEBUG: [0x61a000173a80] created request Name# RateLimiter/AcquireResource
2025-10-26T19:25:24.027359Z :GRPC_SERVER DEBUG: [0x61a000174080] created request Name# SelfCheck
2025-10-26T19:25:24.027647Z :GRPC_SERVER DEBUG: [0x61a000174680] created request Name# NodeCheck
2025-10-26T19:25:24.027890Z :GRPC_SERVER DEBUG: [0x61a000174c80] created request Name# Login
2025-10-26T19:25:24.029002Z :GRPC_SERVER DEBUG: [0x61a000175280] created request Name# DescribeStream
2025-10-26T19:25:24.029316Z :GRPC_SERVER DEBUG: [0x61a000175880] created request Name# CreateStream
2025-10-26T19:25:24.029650Z :GRPC_SERVER DEBUG: [0x61a000175e80] created request Name# ListStreams
2025-10-26T19:25:24.029958Z :GRPC_SERVER DEBUG: [0x61a000176480] created request Name# DeleteStream
2025-10-26T19:25:24.030998Z :GRPC_SERVER DEBUG: [0x61a000176a80] created request Name# ListShards
2025-10-26T19:25:24.031436Z :GRPC_SERVER DEBUG: [0x61a000177080] created request Name# PutRecord
2025-10-26T19:25:24.031830Z :GRPC_SERVER DEBUG: [0x61a000177680] created request Name# PutRecords
2025-10-26T19:25:24.032447Z :FLAT_TX_SCHEMESHARD TRACE: StateInit, received event# 273285138, Sender [50000:7565613769511300586:4109], Recipient [50000:7565613769511300997:56]: NKikimr::NConsole::TEvConsole::TEvConfigNotificationRequest { Config { FeatureFlags { EnablePersistentQueryStats: true EnableMvccSnapshotReads: true EnableSchemeTransactionsAtSchemeShard: true EnablePublicApiExternalBlobs: false } TableProfilesConfig { TableProfiles { Name: "default" CompactionPolicy: "default" ExecutionPolicy: "default" PartitioningPolicy: "default" StoragePolicy: "default" ReplicationPolicy: "default" CachingPolicy: "default" } TableProfiles { Name: "log_lz4" CompactionPolicy: "default" ExecutionPolicy: "default" PartitioningPolicy: "default" StoragePolicy: "log_lz4" ReplicationPolicy: "default" CachingPolicy: "default" } CompactionPolicies { Name: "default" } ExecutionPolicies { Name: "default" } PartitioningPolicies { Name: "default" AutoSplit: true AutoMerge: false SizeToSplit: 2147483648 } StoragePolicies { Name: "log_lz4" ColumnFamilies { ColumnCodec: ColumnCodecLZ4 StorageConfig { SysLog { PreferredPoolKind: "hdd" } Log { PreferredPoolKind: "hdd" } Data { Prefe
2025-10-26T19:25:24.033474Z :GRPC_SERVER DEBUG: [0x61a000177c80] created request Name# GetRecords
2025-10-26T19:25:24.033718Z :FLAT_TX_SCHEMESHARD INFO: Got new config: FeatureFlags { EnablePersistentQueryStats: true EnableMvccSnapshotReads: true EnableSchemeTransactionsAtSchemeShard: true EnablePublicApiExternalBlobs: false } TableProfilesConfig { TableProfiles { Name: "default" CompactionPolicy: "default" ExecutionPolicy: "default" PartitioningPolicy: "default" StoragePolicy: "default" ReplicationPolicy: "default" CachingPolicy: "default" } TableProfiles { Name: "log_lz4" CompactionPolicy: "default" ExecutionPolicy: "default" PartitioningPolicy: "default" StoragePolicy: "log_lz4" ReplicationPolicy: "default" CachingPolicy: "default" } CompactionPolicies { Name: "default" } ExecutionPolicies { Name: "default" } PartitioningPolicies { Name: "default" AutoSplit: true AutoMerge: false SizeToSplit: 2147483648 } StoragePolicies { Name: "log_lz4" ColumnFamilies { ColumnCodec: ColumnCodecLZ4 StorageConfig { SysLog { PreferredPoolKind: "hdd" } Log { PreferredPoolKind: "hdd" } Data { PreferredPoolKind: "hdd" } } } } StoragePolicies { Name: "default" ColumnFamilies { StorageConfig { SysLog { PreferredPoolKind: "hdd" } Log { PreferredPoolKind: "hdd" } Data { PreferredPoolKind: "hdd" } } } } ReplicationPolicies { Name: "default" } CachingPolicies { Name: "default" } }
2025-10-26T19:25:24.033750Z :IMPORT NOTICE: Load table profiles
2025-10-26T19:25:24.033928Z :FLAT_TX_SCHEMESHARD TRACE: Send TEvConfigNotificationResponse: SubscriptionId: 0 ConfigId { }
2025-10-26T19:25:24.035448Z :FLAT_TX_SCHEMESHARD DEBUG: TxInitSchema.Complete
2025-10-26T19:25:24.035507Z :FLAT_TX_SCHEMESHARD DEBUG: TTxUpgradeSchema.Execute
2025-10-26T19:25:24.035570Z :GRPC_SERVER DEBUG: [0x61a000178280] created request Name# GetShardIterator
2025-10-26T19:25:24.036133Z :FLAT_TX_SCHEMESHARD NOTICE: UpgradeInitState as Uninitialized, schemeshardId: 72075186224037889
2025-10-26T19:25:24.042015Z :GRPC_SERVER DEBUG: [0x61a000178880] created streaming request Name# SubscribeToShard
2025-10-26T19:25:24.043253Z :FLAT_TX_SCHEMESHARD DEBUG: TTxUpgradeSchema.Complete
2025-10-26T19:25:24.043391Z :FLAT_TX_SCHEMESHARD INFO: Clear TempDirsState with owners number: 0
2025-10-26T19:25:24.043621Z :GRPC_SERVER DEBUG: [0x61a000178e80] created request Name# DescribeLimits
2025-10-26T19:25:24.044820Z :GRPC_SERVER DEBUG: [0x61a000179480] created request Name# DescribeStreamSummary
2025-10-26T19:25:24.046001Z :FLAT_TX_SCHEMESHARD NOTICE: TTxInit, SS hasn't been configured yet, state: 1, at schemeshard: 72075186224037889
2025-10-26T19:25:24.046157Z :GRPC_SERVER DEBUG: [0x61a000179a80] created request Name# DecreaseStreamRetentionPeriod
2025-10-26T19:25:24.046458Z :GRPC_SERVER DEBUG: [0x61a00017a080] created request Name# IncreaseStreamRetentionPeriod
2025-10-26T19:25:24.046631Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:24.051653Z :GRPC_SERVER DEBUG: [0x61a00017a680] created request Name# UpdateShardCount
2025-10-26T19:25:24.057965Z :GRPC_SERVER DEBUG: [0x61a00017ac80] created request Name# UpdateStreamMode
2025-10-26T19:25:24.063593Z :GRPC_SERVER DEBUG: [0x61a00017b280] created request Name# RegisterStreamConsumer
2025-10-26T19:25:24.064110Z :GRPC_SERVER DEBUG: [0x61a00017b880] created request Name# DeregisterStreamConsumer
2025-10-26T19:25:24.064859Z :GRPC_SERVER DEBUG: [0x61a00017be80] created request Name# DescribeStreamConsumer
2025-10-26T19:25:24.065298Z :GRPC_SERVER DEBUG: [0x61a00017c480] created request Name# ListStreamConsumers
2025-10-26T19:25:24.065679Z :GRPC_SERVER DEBUG: [0x61a00017ca80] created request Name# AddTagsToStream
2025-10-26T19:25:24.066103Z :GRPC_SERVER DEBUG: [0x61a00017d080] created request Name# DisableEnhancedMonitoring
2025-10-26T19:25:24.066477Z :GRPC_SERVER DEBUG: [0x61a00017d680] created request Name# EnableEnhancedMonitoring
2025-10-26T19:25:24.066918Z :GRPC_SERVER DEBUG: [0x61a00017dc80] created request Name# ListTagsForStream
2025-10-26T19:25:24.067306Z :GRPC_SERVER DEBUG: [0x61a00017e280] created request Name# MergeShards
2025-10-26T19:25:24.067755Z :GRPC_SERVER DEBUG: [0x61a00017e880] created request Name# RemoveTagsFromStream
2025-10-26T19:25:24.068173Z :GRPC_SERVER DEBUG: [0x61a00017ee80] created request Name# SplitShard
2025-10-26T19:25:24.068567Z :GRPC_SERVER DEBUG: [0x61a00017f480] created request Name# StartStreamEncryption
2025-10-26T19:25:24.077942Z :GRPC_SERVER DEBUG: [0x61a00018fc80] created request Name# StopStreamEncryption
2025-10-26T19:25:24.093207Z :GRPC_SERVER DEBUG: [0x61a000190280] created request Name# UpdateStream
2025-10-26T19:25:24.098750Z :GRPC_SERVER DEBUG: [0x61a000190880] created request Name# SetWriteQuota
2025-10-26T19:25:24.099313Z :GRPC_SERVER DEBUG: [0x61a000190e80] created request Name# CreateQuery
2025-10-26T19:25:24.114475Z :GRPC_SERVER DEBUG: [0x61a000191480] created request Name# ListQueries
2025-10-26T19:25:24.123128Z :GRPC_SERVER DEBUG: [0x61a000191a80] created request Name# DescribeQuery
2025-10-26T19:25:24.123568Z :GRPC_SERVER DEBUG: [0x61a000192080] created request Name# GetQueryStatus
2025-10-26T19:25:24.124021Z :GRPC_SERVER DEBUG: [0x61a000192680] created request Name# ModifyQuery
2025-10-26T19:25:24.124387Z :GRPC_SERVER DEBUG: [0x61a000192c80] created request Name# DeleteQuery
2025-10-26T19:25:24.124646Z :GRPC_SERVER DEBUG: [0x61a000193280] created request Name# ControlQuery
2025-10-26T19:25:24.124897Z :GRPC_SERVER DEBUG: [0x61a000193880] created request Name# GetResultData
2025-10-26T19:25:24.125195Z :GRPC_SERVER DEBUG: [0x61a000193e80] created request Name# ListJobs
2025-10-26T19:25:24.125453Z :GRPC_SERVER DEBUG: [0x61a000194480] created request Name# DescribeJob
2025-10-26T19:25:24.125757Z :GRPC_SERVER DEBUG: [0x61a000194a80] created request Name# CreateConnection
2025-10-26T19:25:24.126042Z :GRPC_SERVER DEBUG: [0x61a000195080] created request Name# ListConnections
2025-10-26T19:25:24.126290Z :GRPC_SERVER DEBUG: [0x61a000195680] created request Name# DescribeConnection
2025-10-26T19:25:24.126570Z :GRPC_SERVER DEBUG: [0x61a000195c80] created request Name# ModifyConnection
2025-10-26T19:25:24.126807Z :GRPC_SERVER DEBUG: [0x61a000196280] created request Name# DeleteConnection
2025-10-26T19:25:24.127050Z :GRPC_SERVER DEBUG: [0x61a000196880] created request Name# TestConnection
2025-10-26T19:25:24.127301Z :GRPC_SERVER DEBUG: [0x61a000196e80] created request Name# CreateBinding
2025-10-26T19:25:24.127532Z :GRPC_SERVER DEBUG: [0x61a000197480] created request Name# ListBindings
2025-10-26T19:25:24.127871Z :GRPC_SERVER DEBUG: [0x61a000197a80] created request Name# DescribeBinding
2025-10-26T19:25:24.128263Z :GRPC_SERVER DEBUG: [0x61a000198080] created request Name# ModifyBinding
2025-10-26T19:25:24.128576Z :GRPC_SERVER DEBUG: [0x61a000198680] created request Name# DeleteBinding
2025-10-26T19:25:24.128948Z :GRPC_SERVER DEBUG: [0x61a000198c80] created request Name# PingTask
2025-10-26T19:25:24.129240Z :GRPC_SERVER DEBUG: [0x61a000199280] created request Name# GetTask
2025-10-26T19:25:24.129497Z :GRPC_SERVER DEBUG: [0x61a000199880] created request Name# WriteTaskResult
2025-10-26T19:25:24.129914Z :GRPC_SERVER DEBUG: [0x61a000199e80] created request Name# NodesHealthCheck
2025-10-26T19:25:24.130293Z :GRPC_SERVER DEBUG: [0x61a00019a480] created request Name# CreateRateLimiterResource
2025-10-26T19:25:24.130598Z :GRPC_SERVER DEBUG: [0x61a00019aa80] created request Name# DeleteRateLimiterResource
2025-10-26T19:25:24.131043Z :GRPC_SERVER DEBUG: [0x61a00019b080] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.131314Z :GRPC_SERVER DEBUG: [0x61a00019b680] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.131566Z :GRPC_SERVER DEBUG: [0x61a00019bc80] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.131828Z :GRPC_SERVER DEBUG: [0x61a00019c280] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.132022Z :GRPC_SERVER DEBUG: [0x61a00019c880] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.132231Z :GRPC_SERVER DEBUG: [0x61a00019ce80] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.132400Z :GRPC_SERVER DEBUG: [0x61a00019d480] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.132612Z :GRPC_SERVER DEBUG: [0x61a00019da80] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.132800Z :GRPC_SERVER DEBUG: [0x61a00019e080] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.132960Z :GRPC_SERVER DEBUG: [0x61a00019e680] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.133158Z :GRPC_SERVER DEBUG: [0x61a00019ec80] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.133356Z :GRPC_SERVER DEBUG: [0x61a00019f280] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.133599Z :GRPC_SERVER DEBUG: [0x61a00019fe80] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.134461Z :GRPC_SERVER DEBUG: [0x61a0001a0480] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.134758Z :GRPC_SERVER DEBUG: [0x61a0001a0a80] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.135038Z :GRPC_SERVER DEBUG: [0x61a0001a1080] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.135313Z :GRPC_SERVER DEBUG: [0x61a0001a1680] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.135886Z :GRAPH WARN: SVC Error resolving database /Root/test_auditlog.py error RedirectLookupError
2025-10-26T19:25:24.137109Z :GRPC_SERVER DEBUG: [0x61a0001a1c80] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.137389Z :GRPC_SERVER DEBUG: [0x61a0001a2280] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.137596Z :GRPC_SERVER DEBUG: [0x61a0001a2880] created streaming request Name# ExecuteQuery
2025-10-26T19:25:24.147057Z :GRPC_SERVER DEBUG: [0x61a0001a2e80] created request Name# ExecuteScript
2025-10-26T19:25:24.147473Z :GRPC_SERVER DEBUG: [0x61a0001a3480] created request Name# ExecuteScript
2025-10-26T19:25:24.147712Z :GRPC_SERVER DEBUG: [0x61a0001a3a80] created request Name# ExecuteScript
2025-10-26T19:25:24.147957Z :GRPC_SERVER DEBUG: [0x61a0001a4080] created request Name# ExecuteScript
2025-10-26T19:25:24.148173Z :GRPC_SERVER DEBUG: [0x61a0001a4680] created request Name# ExecuteScript
2025-10-26T19:25:24.148336Z :GRPC_SERVER DEBUG: [0x61a0001a4c80] created request Name# ExecuteScript
2025-10-26T19:25:24.148468Z :GRPC_SERVER DEBUG: [0x61a0001a5280] created request Name# ExecuteScript
2025-10-26T19:25:24.148606Z :GRPC_SERVER DEBUG: [0x61a0001a5880] created request Name# ExecuteScript
2025-10-26T19:25:24.148817Z :GRPC_SERVER DEBUG: [0x61a0001a5e80] created request Name# ExecuteScript
2025-10-26T19:25:24.149008Z :GRPC_SERVER DEBUG: [0x61a0001a6480] created request Name# ExecuteScript
2025-10-26T19:25:24.149197Z :GRPC_SERVER DEBUG: [0x61a0001a6a80] created request Name# ExecuteScript
2025-10-26T19:25:24.149386Z :GRPC_SERVER DEBUG: [0x61a0001a7080] created request Name# ExecuteScript
2025-10-26T19:25:24.149571Z :GRPC_SERVER DEBUG: [0x61a0001a7680] created request Name# ExecuteScript
2025-10-26T19:25:24.149779Z :GRPC_SERVER DEBUG: [0x61a0001a7c80] created request Name# ExecuteScript
2025-10-26T19:25:24.149991Z :GRPC_SERVER DEBUG: [0x61a0001a8280] created request Name# ExecuteScript
2025-10-26T19:25:24.150189Z :GRPC_SERVER DEBUG: [0x61a0001a8880] created request Name# ExecuteScript
2025-10-26T19:25:24.150447Z :GRPC_SERVER DEBUG: [0x61a0001a8e80] created request Name# ExecuteScript
2025-10-26T19:25:24.150655Z :GRPC_SERVER DEBUG: [0x61a0001a9480] created request Name# ExecuteScript
2025-10-26T19:25:24.150858Z :GRPC_SERVER DEBUG: [0x61a0001a9a80] created request Name# ExecuteScript
2025-10-26T19:25:24.151044Z :GRPC_SERVER DEBUG: [0x61a0001aa080] created request Name# ExecuteScript
2025-10-26T19:25:24.151461Z :GRPC_SERVER DEBUG: [0x61a0001aa680] created request Name# FetchScriptResults
2025-10-26T19:25:24.151684Z :GRPC_SERVER DEBUG: [0x61a0001aac80] created request Name# FetchScriptResults
2025-10-26T19:25:24.151913Z :GRPC_SERVER DEBUG: [0x61a0001ab280] created request Name# FetchScriptResults
2025-10-26T19:25:24.152149Z :GRPC_SERVER DEBUG: [0x61a0001ab880] created request Name# FetchScriptResults
2025-10-26T19:25:24.152392Z :GRPC_SERVER DEBUG: [0x61a0001abe80] created request Name# FetchScriptResults
2025-10-26T19:25:24.152569Z :GRPC_SERVER DEBUG: [0x61a0001ac480] created request Name# FetchScriptResults
2025-10-26T19:25:24.152777Z :GRPC_SERVER DEBUG: [0x61a0001aca80] created request Name# FetchScriptResults
2025-10-26T19:25:24.152995Z :GRPC_SERVER DEBUG: [0x61a0001ad080] created request Name# FetchScriptResults
2025-10-26T19:25:24.153161Z :GRPC_SERVER DEBUG: [0x61a0001ad680] created request Name# FetchScriptResults
2025-10-26T19:25:24.153356Z :GRPC_SERVER DEBUG: [0x61a0001adc80] created request Name# FetchScriptResults
2025-10-26T19:25:24.153533Z :GRPC_SERVER DEBUG: [0x61a0001ae280] created request Name# FetchScriptResults
2025-10-26T19:25:24.155662Z :GRPC_SERVER DEBUG: [0x61a0001ae880] created request Name# FetchScriptResults
2025-10-26T19:25:24.155954Z :GRPC_SERVER DEBUG: [0x61a0001aee80] created request Name# FetchScriptResults
2025-10-26T19:25:24.156207Z :GRPC_SERVER DEBUG: [0x61a0001af480] created request Name# FetchScriptResults
2025-10-26T19:25:24.156707Z :GRPC_SERVER DEBUG: [0x61a0001b0080] created request Name# FetchScriptResults
2025-10-26T19:25:24.156971Z :GRPC_SERVER DEBUG: [0x61a0001b0680] created request Name# FetchScriptResults
2025-10-26T19:25:24.157248Z :GRPC_SERVER DEBUG: [0x61a0001b0c80] created request Name# FetchScriptResults
2025-10-26T19:25:24.157490Z :GRPC_SERVER DEBUG: [0x61a0001b1280] created request Name# FetchScriptResults
2025-10-26T19:25:24.157796Z :GRPC_SERVER DEBUG: [0x61a0001b1880] created request Name# FetchScriptResults
2025-10-26T19:25:24.158079Z :GRPC_SERVER DEBUG: [0x61a0001b1e80] created request Name# FetchScriptResults
2025-10-26T19:25:24.158470Z :GRPC_SERVER DEBUG: [0x61a0001b2480] created request Name# CreateSession
2025-10-26T19:25:24.158794Z :GRPC_SERVER DEBUG: [0x61a0001b2a80] created request Name# CreateSession
2025-10-26T19:25:24.159060Z :GRPC_SERVER DEBUG: [0x61a0001b3080] created request Name# CreateSession
2025-10-26T19:25:24.159307Z :GRPC_SERVER DEBUG: [0x61a0001b3680] created request Name# CreateSession
2025-10-26T19:25:24.159546Z :GRPC_SERVER DEBUG: [0x61a0001b3c80] created request Name# CreateSession
2025-10-26T19:25:24.159816Z :GRPC_SERVER DEBUG: [0x61a0001b4280] created request Name# CreateSession
2025-10-26T19:25:24.160059Z :GRPC_SERVER DEBUG: [0x61a0001b4880] created request Name# CreateSession
2025-10-26T19:25:24.160326Z :GRPC_SERVER DEBUG: [0x61a0001b4e80] created request Name# CreateSession
2025-10-26T19:25:24.160586Z :GRPC_SERVER DEBUG: [0x61a0001b5480] created request Name# CreateSession
2025-10-26T19:25:24.160817Z :GRPC_SERVER DEBUG: [0x61a0001b5a80] created request Name# CreateSession
2025-10-26T19:25:24.161066Z :GRPC_SERVER DEBUG: [0x61a0001b6080] created request Name# CreateSession
2025-10-26T19:25:24.161338Z :GRPC_SERVER DEBUG: [0x61a0001b6680] created request Name# CreateSession
2025-10-26T19:25:24.161613Z :GRPC_SERVER DEBUG: [0x61a0001b6c80] created request Name# CreateSession
2025-10-26T19:25:24.167029Z :GRPC_SERVER DEBUG: [0x61a0001b7280] created request Name# CreateSession
2025-10-26T19:25:24.167326Z :GRPC_SERVER DEBUG: [0x61a0001b7880] created request Name# CreateSession
2025-10-26T19:25:24.167532Z :GRPC_SERVER DEBUG: [0x61a0001b7e80] created request Name# CreateSession
2025-10-26T19:25:24.167715Z :GRPC_SERVER DEBUG: [0x61a0001b8480] created request Name# CreateSession
2025-10-26T19:25:24.167912Z :GRPC_SERVER DEBUG: [0x61a0001b8a80] created request Name# CreateSession
2025-10-26T19:25:24.168138Z :GRPC_SERVER DEBUG: [0x61a0001b9080] created request Name# CreateSession
2025-10-26T19:25:24.168397Z :GRPC_SERVER DEBUG: [0x61a0001b9680] created request Name# CreateSession
2025-10-26T19:25:24.168817Z :GRPC_SERVER DEBUG: [0x61a0001b9c80] created request Name# DeleteSession
2025-10-26T19:25:24.169046Z :GRPC_SERVER DEBUG: [0x61a0001ba280] created request Name# DeleteSession
2025-10-26T19:25:24.169252Z :GRPC_SERVER DEBUG: [0x61a0001ba880] created request Name# DeleteSession
2025-10-26T19:25:24.169451Z :GRPC_SERVER DEBUG: [0x61a0001bae80] created request Name# DeleteSession
2025-10-26T19:25:24.169625Z :GRPC_SERVER DEBUG: [0x61a0001bb480] created request Name# DeleteSession
2025-10-26T19:25:24.169860Z :GRPC_SERVER DEBUG: [0x61a0001bba80] created request Name# DeleteSession
2025-10-26T19:25:24.170103Z :GRPC_SERVER DEBUG: [0x61a0001bc080] created request Name# DeleteSession
2025-10-26T19:25:24.170306Z :GRPC_SERVER DEBUG: [0x61a0001bc680] created request Name# DeleteSession
2025-10-26T19:25:24.175280Z :GRPC_SERVER DEBUG: [0x61a0001bcc80] created request Name# DeleteSession
2025-10-26T19:25:24.175729Z :GRPC_SERVER DEBUG: [0x61a0001bd280] created request Name# DeleteSession
2025-10-26T19:25:24.175992Z :GRPC_SERVER DEBUG: [0x61a0001bd880] created request Name# DeleteSession
2025-10-26T19:25:24.176394Z :GRPC_SERVER DEBUG: [0x61a0001bde80] created request Name# DeleteSession
2025-10-26T19:25:24.176750Z :GRPC_SERVER DEBUG: [0x61a0001be480] created request Name# DeleteSession
2025-10-26T19:25:24.177004Z :GRPC_SERVER DEBUG: [0x61a0001bea80] created request Name# DeleteSession
2025-10-26T19:25:24.177254Z :GRPC_SERVER DEBUG: [0x61a0001bf080] created request Name# DeleteSession
2025-10-26T19:25:24.177548Z :GRPC_SERVER DEBUG: [0x61a0001bf680] created request Name# DeleteSession
2025-10-26T19:25:24.177886Z :GRPC_SERVER DEBUG: [0x61a0001bfc80] created request Name# DeleteSession
2025-10-26T19:25:24.178515Z :GRPC_SERVER DEBUG: [0x61a0001c0280] created request Name# DeleteSession
2025-10-26T19:25:24.178856Z :GRPC_SERVER DEBUG: [0x61a0001c0880] created request Name# DeleteSession
2025-10-26T19:25:24.179171Z :GRPC_SERVER DEBUG: [0x61a0001c0e80] created request Name# DeleteSession
2025-10-26T19:25:24.179636Z :GRPC_SERVER DEBUG: [0x61a0001c1480] created streaming request Name# AttachSession
2025-10-26T19:25:24.179976Z :GRPC_SERVER DEBUG: [0x61a0001c1a80] created streaming request Name# AttachSession
2025-10-26T19:25:24.180942Z :GRPC_SERVER DEBUG: [0x61a0001c2080] created streaming request Name# AttachSession
2025-10-26T19:25:24.181261Z :GRPC_SERVER DEBUG: [0x61a0001c2680] created streaming request Name# AttachSession
2025-10-26T19:25:24.181543Z :GRPC_SERVER DEBUG: [0x61a0001c2c80] created streaming request Name# AttachSession
2025-10-26T19:25:24.181829Z :GRPC_SERVER DEBUG: [0x61a0001c3280] created streaming request Name# AttachSession
2025-10-26T19:25:24.182113Z :GRPC_SERVER DEBUG: [0x61a0001c3880] created streaming request Name# AttachSession
2025-10-26T19:25:24.182403Z :GRPC_SERVER DEBUG: [0x61a0001c3e80] created streaming request Name# AttachSession
2025-10-26T19:25:24.182767Z :GRPC_SERVER DEBUG: [0x61a0001c4480] created streaming request Name# AttachSession
2025-10-26T19:25:24.183232Z :GRPC_SERVER DEBUG: [0x61a0001c4a80] created streaming request Name# AttachSession
2025-10-26T19:25:24.183535Z :GRPC_SERVER DEBUG: [0x61a0001c5080] created streaming request Name# AttachSession
2025-10-26T19:25:24.183800Z :GRPC_SERVER DEBUG: [0x61a0001c5680] created streaming request Name# AttachSession
2025-10-26T19:25:24.184163Z :GRPC_SERVER DEBUG: [0x61a0001c5c80] created streaming request Name# AttachSession
2025-10-26T19:25:24.189300Z :GRPC_SERVER DEBUG: [0x61a0001c6280] created streaming request Name# AttachSession
2025-10-26T19:25:24.189714Z :GRPC_SERVER DEBUG: [0x61a0001c6880] created streaming request Name# AttachSession
2025-10-26T19:25:24.190134Z :GRPC_SERVER DEBUG: [0x61a0001c6e80] created streaming request Name# AttachSession
2025-10-26T19:25:24.190461Z :GRPC_SERVER DEBUG: [0x61a0001c7480] created streaming request Name# AttachSession
2025-10-26T19:25:24.191003Z :GRPC_SERVER DEBUG: [0x61a0001c7a80] created streaming request Name# AttachSession
2025-10-26T19:25:24.191361Z :GRPC_SERVER DEBUG: [0x61a0001c8080] created streaming request Name# AttachSession
2025-10-26T19:25:24.191642Z :GRPC_SERVER DEBUG: [0x61a0001c8680] created streaming request Name# AttachSession
2025-10-26T19:25:24.192154Z :GRPC_SERVER DEBUG: [0x61a0001c8c80] created request Name# BeginTransaction
2025-10-26T19:25:24.192474Z :GRPC_SERVER DEBUG: [0x61a0001c9280] created request Name# BeginTransaction
2025-10-26T19:25:24.192774Z :GRPC_SERVER DEBUG: [0x61a0001c9880] created request Name# BeginTransaction
2025-10-26T19:25:24.193136Z :GRPC_SERVER DEBUG: [0x61a0001c9e80] created request Name# BeginTransaction
2025-10-26T19:25:24.194622Z :GRPC_SERVER DEBUG: [0x61a0001ca480] created request Name# BeginTransaction
2025-10-26T19:25:24.196428Z :GRPC_SERVER DEBUG: [0x61a0001caa80] created request Name# BeginTransaction
2025-10-26T19:25:24.196754Z :GRPC_SERVER DEBUG: [0x61a0001cb080] created request Name# BeginTransaction
2025-10-26T19:25:24.196978Z :GRPC_SERVER DEBUG: [0x61a0001cb680] created request Name# BeginTransaction
2025-10-26T19:25:24.197219Z :GRPC_SERVER DEBUG: [0x61a0001cbc80] created request Name# BeginTransaction
2025-10-26T19:25:24.197497Z :GRPC_SERVER DEBUG: [0x61a0001cc280] created request Name# BeginTransaction
2025-10-26T19:25:24.197765Z :GRPC_SERVER DEBUG: [0x61a0001cc880] created request Name# BeginTransaction
2025-10-26T19:25:24.197991Z :GRPC_SERVER DEBUG: [0x61a0001cce80] created request Name# BeginTransaction
2025-10-26T19:25:24.198243Z :GRPC_SERVER DEBUG: [0x61a0001cd480] created request Name# BeginTransaction
2025-10-26T19:25:24.198489Z :GRPC_SERVER DEBUG: [0x61a0001cda80] created request Name# BeginTransaction
2025-10-26T19:25:24.198974Z :GRPC_SERVER DEBUG: [0x61a0001ce080] created request Name# BeginTransaction
2025-10-26T19:25:24.199451Z :GRPC_SERVER DEBUG: [0x61a0001ce680] created request Name# BeginTransaction
2025-10-26T19:25:24.199700Z :GRPC_SERVER DEBUG: [0x61a0001cec80] created request Name# BeginTransaction
2025-10-26T19:25:24.199909Z :GRPC_SERVER DEBUG: [0x61a0001cf280] created request Name# BeginTransaction
2025-10-26T19:25:24.200149Z :GRPC_SERVER DEBUG: [0x61a0001cfe80] created request Name# BeginTransaction
2025-10-26T19:25:24.200353Z :GRPC_SERVER DEBUG: [0x61a0001d0480] created request Name# BeginTransaction
2025-10-26T19:25:24.200744Z :GRPC_SERVER DEBUG: [0x61a0001d0a80] created request Name# CommitTransaction
2025-10-26T19:25:24.201013Z :GRPC_SERVER DEBUG: [0x61a0001d1080] created request Name# CommitTransaction
2025-10-26T19:25:24.201229Z :GRPC_SERVER DEBUG: [0x61a0001d1680] created request Name# CommitTransaction
2025-10-26T19:25:24.201435Z :GRPC_SERVER DEBUG: [0x61a0001d1c80] created request Name# CommitTransaction
2025-10-26T19:25:24.201646Z :GRPC_SERVER DEBUG: [0x61a0001d2280] created request Name# CommitTransaction
2025-10-26T19:25:24.201881Z :GRPC_SERVER DEBUG: [0x61a0001d2880] created request Name# CommitTransaction
2025-10-26T19:25:24.202115Z :GRPC_SERVER DEBUG: [0x61a0001d2e80] created request Name# CommitTransaction
2025-10-26T19:25:24.202355Z :GRPC_SERVER DEBUG: [0x61a0001d3480] created request Name# CommitTransaction
2025-10-26T19:25:24.202581Z :GRPC_SERVER DEBUG: [0x61a0001d3a80] created request Name# CommitTransaction
2025-10-26T19:25:24.202875Z :GRPC_SERVER DEBUG: [0x61a0001d4080] created request Name# CommitTransaction
2025-10-26T19:25:24.203111Z :GRPC_SERVER DEBUG: [0x61a0001d4680] created request Name# CommitTransaction
2025-10-26T19:25:24.203434Z :GRPC_SERVER DEBUG: [0x61a0001d4c80] created request Name# CommitTransaction
2025-10-26T19:25:24.203872Z :GRPC_SERVER DEBUG: [0x61a0001d5280] created request Name# CommitTransaction
2025-10-26T19:25:24.204162Z :GRPC_SERVER DEBUG: [0x61a0001d5880] created request Name# CommitTransaction
2025-10-26T19:25:24.204388Z :GRPC_SERVER DEBUG: [0x61a0001d5e80] created request Name# CommitTransaction
2025-10-26T19:25:24.204623Z :GRPC_SERVER DEBUG: [0x61a0001d6480] created request Name# CommitTransaction
2025-10-26T19:25:24.204871Z :GRPC_SERVER DEBUG: [0x61a0001d6a80] created request Name# CommitTransaction
2025-10-26T19:25:24.205112Z :GRPC_SERVER DEBUG: [0x61a0001d7080] created request Name# CommitTransaction
2025-10-26T19:25:24.205309Z :GRPC_SERVER DEBUG: [0x61a0001d7680] created request Name# CommitTransaction
2025-10-26T19:25:24.205580Z :GRPC_SERVER DEBUG: [0x61a0001d7c80] created request Name# CommitTransaction
2025-10-26T19:25:24.206112Z :GRPC_SERVER DEBUG: [0x61a0001d8280] created request Name# RollbackTransaction
2025-10-26T19:25:24.206404Z :GRPC_SERVER DEBUG: [0x61a0001d8880] created request Name# RollbackTransaction
2025-10-26T19:25:24.206698Z :GRPC_SERVER DEBUG: [0x61a0001d8e80] created request Name# RollbackTransaction
2025-10-26T19:25:24.206947Z :GRPC_SERVER DEBUG: [0x61a0001d9480] created request Name# RollbackTransaction
2025-10-26T19:25:24.207191Z :GRPC_SERVER DEBUG: [0x61a0001d9a80] created request Name# RollbackTransaction
2025-10-26T19:25:24.207762Z :GRPC_SERVER DEBUG: [0x61a0001da080] created request Name# RollbackTransaction
2025-10-26T19:25:24.208042Z :GRPC_SERVER DEBUG: [0x61a0001da680] created request Name# RollbackTransaction
2025-10-26T19:25:24.208282Z :GRPC_SERVER DEBUG: [0x61a0001dac80] created request Name# RollbackTransaction
2025-10-26T19:25:24.208547Z :GRPC_SERVER DEBUG: [0x61a0001db280] created request Name# RollbackTransaction
2025-10-26T19:25:24.208794Z :GRPC_SERVER DEBUG: [0x61a0001db880] created request Name# RollbackTransaction
2025-10-26T19:25:24.209049Z :GRPC_SERVER DEBUG: [0x61a0001dbe80] created request Name# RollbackTransaction
2025-10-26T19:25:24.209317Z :GRPC_SERVER DEBUG: [0x61a0001dc480] created request Name# RollbackTransaction
2025-10-26T19:25:24.209600Z :GRPC_SERVER DEBUG: [0x61a0001dca80] created request Name# RollbackTransaction
2025-10-26T19:25:24.209897Z :GRPC_SERVER DEBUG: [0x61a0001dd080] created request Name# RollbackTransaction
2025-10-26T19:25:24.210117Z :GRPC_SERVER DEBUG: [0x61a0001dd680] created request Name# RollbackTransaction
2025-10-26T19:25:24.210383Z :GRPC_SERVER DEBUG: [0x61a0001ddc80] created request Name# RollbackTransaction
2025-10-26T19:25:24.210623Z :GRPC_SERVER DEBUG: [0x61a0001de280] created request Name# RollbackTransaction
2025-10-26T19:25:24.210772Z :GRPC_SERVER DEBUG: [0x61a0001de880] created request Name# RollbackTransaction
2025-10-26T19:25:24.210991Z :GRPC_SERVER DEBUG: [0x61a0001dee80] created request Name# RollbackTransaction
2025-10-26T19:25:24.211183Z :GRPC_SERVER DEBUG: [0x61a0001df480] created request Name# RollbackTransaction
2025-10-26T19:25:24.211697Z :GRPC_SERVER DEBUG: [0x61a0001e0080] created request Name# KeyValue/CreateVolume
2025-10-26T19:25:24.212171Z :GRPC_SERVER DEBUG: [0x61a0001e0680] created request Name# KeyValue/DropVolume
2025-10-26T19:25:24.212737Z :GRPC_SERVER DEBUG: [0x61a0001e0c80] created request Name# KeyValue/AlterVolume
2025-10-26T19:25:24.213229Z :GRPC_SERVER DEBUG: [0x61a0001e1280] created request Name# KeyValue/DescribeVolume
2025-10-26T19:25:24.213676Z :GRPC_SERVER DEBUG: [0x61a0001e1880] created request Name# KeyValue/ListLocalPartitions
2025-10-26T19:25:24.214156Z :GRPC_SERVER DEBUG: [0x61a0001e1e80] created request Name# KeyValue/AcquireLock
2025-10-26T19:25:24.214659Z :GRPC_SERVER DEBUG: [0x61a0001e2480] created request Name# KeyValue/ExecuteTransaction
2025-10-26T19:25:24.215088Z :GRPC_SERVER DEBUG: [0x61a0001e2a80] created request Name# KeyValue/Read
2025-10-26T19:25:24.215542Z :GRPC_SERVER DEBUG: [0x61a0001e3080] created request Name# KeyValue/ReadRange
2025-10-26T19:25:24.215967Z :GRPC_SERVER DEBUG: [0x61a0001e3680] created request Name# KeyValue/ListRange
2025-10-26T19:25:24.216386Z :GRPC_SERVER DEBUG: [0x61a0001e3c80] created request Name# KeyValue/GetStorageChannelStatus
2025-10-26T19:25:25.052610Z :TX_MEDIATOR NOTICE: tablet# 72075186224037894 actor# [50000:7565613769511300844:10234] HANDLE TEvMediatorConfiguration Version# 2
2025-10-26T19:25:25.196942Z :TX_COORDINATOR NOTICE: tablet# 72075186224037891 HANDLE TEvConfigure Version# 2
2025-10-26T19:25:25.202923Z :TX_COORDINATOR NOTICE: tablet# 72075186224037891 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037894
2025-10-26T19:25:25.243378Z :TX_COORDINATOR NOTICE: tablet# 72075186224037890 HANDLE TEvConfigure Version# 2
2025-10-26T19:25:25.249274Z :TX_COORDINATOR NOTICE: tablet# 72075186224037890 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037894
2025-10-26T19:25:25.450946Z :TX_MEDIATOR NOTICE: tablet# 72075186224037893 actor# [50000:7565613769511300855:8195] HANDLE TEvMediatorConfiguration Version# 2
2025-10-26T19:25:25.456886Z :TX_COORDINATOR NOTICE: tablet# 72075186224037891 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037893
2025-10-26T19:25:25.457414Z :TX_COORDINATOR NOTICE: tablet# 72075186224037890 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037893
2025-10-26T19:25:25.469390Z :TX_MEDIATOR NOTICE: tablet# 72075186224037895 actor# [50000:7565613769511300845:10235] HANDLE TEvMediatorConfiguration Version# 2
2025-10-26T19:25:25.478604Z :TX_COORDINATOR NOTICE: tablet# 72075186224037891 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037895
2025-10-26T19:25:25.478650Z :TX_COORDINATOR NOTICE: tablet# 72075186224037890 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037895
2025-10-26T19:25:25.572587Z :TX_COORDINATOR NOTICE: tablet# 72075186224037892 HANDLE TEvConfigure Version# 2
2025-10-26T19:25:25.577733Z :TX_COORDINATOR NOTICE: tablet# 72075186224037892 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037893
2025-10-26T19:25:25.577758Z :TX_COORDINATOR NOTICE: tablet# 72075186224037892 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037894
2025-10-26T19:25:25.577769Z :TX_COORDINATOR NOTICE: tablet# 72075186224037892 HANDLE EvMediatorQueueRestart MediatorId# 72075186224037895
2025-10-26T19:25:25.591938Z :FLAT_TX_SCHEMESHARD TRACE: StateConfigure, received event# 269877761, Sender [50000:7565613778101235753:71], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:25.592027Z :FLAT_TX_SCHEMESHARD TRACE: StateConfigure, processing event TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:25.592054Z :FLAT_TX_SCHEMESHARD TRACE: Pipe server connected, at tablet: 72075186224037889
2025-10-26T19:25:25.592107Z :FLAT_TX_SCHEMESHARD TRACE: StateConfigure, received event# 271124999, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:25.592127Z :FLAT_TX_SCHEMESHARD TRACE: StateConfigure, processing event TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:25.597309Z :FLAT_TX_SCHEMESHARD TRACE: StateConfigure, received event# 271125001, Sender [1:7565613740781861204:70], Recipient [50000:7565613778101235753:71]
2025-10-26T19:25:25.597358Z :FLAT_TX_SCHEMESHARD TRACE: StateConfigure, processing event TEvSchemeShard::TEvInitTenantSchemeShard
2025-10-26T19:25:25.606570Z :FLAT_TX_SCHEMESHARD NOTICE: TEvInitTenantSchemeShard DoExecute, message: DomainSchemeShard: 72075186232723360 DomainPathId: 2 RootPath: "/Root/test_auditlog.py" Owner: "root@builtin" EffectiveACL: "\n\021\010\001\020\200\200\002\032\005USERS \003(\001\n\033\010\001\020\210\004\032\020METADATA-READERS \003(\001\n\026\010\001\020\001\032\014DATA-READERS \003(\001\n\026\010\001\020\006\032\014DATA-WRITERS \003(\001\n\025\010\001\020\360\n\032\nDDL-ADMINS \003(\001\n\030\010\001\020\200@\032\rACCESS-ADMINS \003(\001\n\032\010\001\020\2000\032\017DATABASE-ADMINS \003(\001" EffectiveACLVersion: 0 ProcessingParams { Version: 2 PlanResolution: 10 Coordinators: 72075186224037890 Coordinators: 72075186224037891 Coordinators: 72075186224037892 TimeCastBucketsPerMediator: 2 Mediators: 72075186224037893 Mediators: 72075186224037894 Mediators: 72075186224037895 SchemeShard: 72075186224037889 Hive: 72075186224037888 SysViewProcessor: 72075186224037896 StatisticsAggregator: 72075186224037897 } StoragePools { Name: "/Root/test_auditlog.py:hdd" Kind: "hdd" } UserAttributesVersion: 1 SchemeLimits { MaxDepth: 32 MaxPaths: 10000 MaxChildrenInDir: 100000 MaxAclBytesSize: 10240 MaxTableColumns: 200 MaxTableColumnNameLength: 255 MaxTableKeyColumns: 20 MaxTableIndices: 20 MaxShards: 200000 MaxShardsInPath: 35000 MaxConsistentCopyTargets: 10000 MaxPathElementLength: 255 ExtraPathSymbolsAllowed: "!\"#$%&\'()*+,-.:;<=>?@[\\]^_`{|}~" MaxPQPartitions: 1000000 MaxTableCdcStreams: 5 MaxExports: 10 MaxImports: 10 MaxColumnTableColumns: 10000 } ResourcesDomainOwnerId: 72075186232723360 ResourcesDomainPathId: 2, at schemeshard: 72075186224037889
2025-10-26T19:25:25.606925Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 0
2025-10-26T19:25:25.607095Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 1
2025-10-26T19:25:25.607136Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 2
2025-10-26T19:25:25.607160Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 3
2025-10-26T19:25:25.607185Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 4
2025-10-26T19:25:25.607279Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 5
2025-10-26T19:25:25.607336Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 6
2025-10-26T19:25:25.607372Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 7
2025-10-26T19:25:25.608531Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 8
2025-10-26T19:25:25.608608Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 9
2025-10-26T19:25:25.612188Z :FLAT_TX_SCHEMESHARD NOTICE: TEvInitTenantSchemeShard DoComplete, status: StatusSuccess, at schemeshard: 72075186224037889
2025-10-26T19:25:25.612356Z :FLAT_TX_SCHEMESHARD DEBUG: Send TEvSyncTenantSchemeShard, to parent: [OwnerId: 72075186232723360, LocalPathId: 2], from: 72075186224037889
2025-10-26T19:25:25.612992Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 2146435079, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvPrivate::TEvServerlessStorageBilling
2025-10-26T19:25:25.613056Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvPrivate::TEvServerlessStorageBilling
2025-10-26T19:25:25.613214Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoExecute, at schemeshard: 72075186224037889
2025-10-26T19:25:25.613243Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard DescribePath, at schemeshard: 72075186224037889, txId: 0, path id: [OwnerId: 72075186224037889, LocalPathId: 1]
2025-10-26T19:25:25.613406Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoComplete, at schemeshard: 72075186224037889
2025-10-26T19:25:25.613444Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard Send, to populator: [50000:7565613778101235759:4168], at schemeshard: 72075186224037889, txId: 0, path id: 1
2025-10-26T19:25:25.613489Z :FLAT_TX_SCHEMESHARD DEBUG: TTxServerlessStorageBilling.Execute
2025-10-26T19:25:25.613492Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Update description: owner# 72075186224037889, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], cookie# 0, is deletion# false, version: 4
2025-10-26T19:25:25.613514Z :FLAT_TX_SCHEMESHARD NOTICE: TTxServerlessStorageBilling: unable to make a bill, domain is not a serverless db, schemeshardId: 72075186224037889, domainId: [OwnerId: 72075186232723360, LocalPathId: 2]
2025-10-26T19:25:25.613528Z :FLAT_TX_SCHEMESHARD DEBUG: TTxServerlessStorageBilling.Complete
2025-10-26T19:25:25.613541Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:25.613636Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 274399233, Sender [50000:7565613769511301019:56], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTxAllocatorClient::TEvAllocateResult
2025-10-26T19:25:25.613665Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTxAllocatorClient::TEvAllocateResult
2025-10-26T19:25:25.613720Z :FLAT_TX_SCHEMESHARD INFO: Handle: TEvAllocateResult: Cookie# 0, at schemeshard: 72075186224037889
2025-10-26T19:25:25.613798Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877760, Sender [50000:7565613778101235757:4141], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:25.613832Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:25.613852Z :FLAT_TX_SCHEMESHARD TRACE: Handle TEvClientConnected, tabletId: 72075186224037897, status: OK, at schemeshard: 72075186224037889
2025-10-26T19:25:25.614090Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877760, Sender [50000:7565613778101235756:4178], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:25.614124Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:25.614139Z :FLAT_TX_SCHEMESHARD TRACE: Handle TEvClientConnected, tabletId: 72075186232723360, status: OK, at schemeshard: 72075186224037889
2025-10-26T19:25:25.615198Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235760:67] Successful handshake: replica# [1:7565613736486893353:4110]
2025-10-26T19:25:25.615246Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235760:67] Start full sync: replica# [1:7565613736486893353:4110]
2025-10-26T19:25:25.618201Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300759:38][/Root/test_auditlog.py] Path was updated as a replacement from TSS, GSS->TSS: owner# [50000:7565613769511300614:9], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:25.618633Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300742:4169][/Root/test_auditlog.py] Path was updated as a replacement from TSS, GSS->TSS: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:25.618885Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300680:4148][/Root/test_auditlog.py] Path was updated as a replacement from TSS, GSS->TSS: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:25.619017Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300708:4152][/Root/test_auditlog.py] Path was updated as a replacement from TSS, GSS->TSS: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:25.619471Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:25.619610Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:25.619628Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:25.619714Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:25.619751Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Ack update: ack to# [50000:7565613769511300997:56], cookie# 0, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], version# 4
2025-10-26T19:25:25.622561Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 274137603, Sender [50000:7565613778101235759:4168], Recipient [50000:7565613769511300997:56]: NKikimrSchemeBoard.TEvUpdateAck { Owner: 72075186224037889 Generation: 1 PathId: [OwnerId: 72075186224037889, LocalPathId: 1] Version: 4 }
2025-10-26T19:25:25.622597Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event NSchemeBoard::NSchemeshardEvents::TEvUpdateAck
2025-10-26T19:25:25.624108Z :FLAT_TX_SCHEMESHARD INFO: Handle TEvUpdateAck, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 1 Version: 4 PathOwnerId: 72075186224037889, cookie: 0
2025-10-26T19:25:25.626138Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613778101235765:4200][[OwnerId: 72075186232723360, LocalPathId: 2]] Set up state: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:25.733094Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613778101235765:4200][[OwnerId: 72075186232723360, LocalPathId: 2]] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:26.592499Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271125000, Sender [0:0:0], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:26.592534Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:26.592600Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271124999, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:26.592618Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:27.592857Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271125000, Sender [0:0:0], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:27.592887Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:27.592978Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271124999, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:27.592993Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:27.900114Z :GRPC_SERVER DEBUG: [0x61a0000a2080] received request Name# CreateSession ok# true data# operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:27.900198Z :GRPC_SERVER DEBUG: [0x61a00002f480] created request Name# CreateSession
2025-10-26T19:25:27.900521Z :GRPC_SERVER DEBUG: [0x61a0000a2080] received request without user token Name# CreateSession data# operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } }  peer# ipv4:127.0.0.1:60616 database# /Root/test_auditlog.py
2025-10-26T19:25:27.900846Z :GRPC_SERVER DEBUG: Got grpc request# CreateSessionRequest, traceId# 01k8gzq5ywakta6cajt2jtt5kt, sdkBuildInfo# ydb-python-sdk/3.12.3, state# AS_NOT_PERFORMED, database# /Root/test_auditlog.py, peer# ipv4:127.0.0.1:60616, grpcInfo# python-library grpc-python/1.54.2 grpc-c/31.0.0 (linux; chttp2), timeout# 2.995874s
2025-10-26T19:25:28.023397Z :KQP_PROXY DEBUG: Updated YQL logs priority: 3
2025-10-26T19:25:28.023799Z :KQP_PROXY INFO: Cannot start publishing usage, tenants: /Root/test_auditlog.py, empty
2025-10-26T19:25:28.030207Z :KQP_PROXY DEBUG: Created new session, sessionId: ydb://session/3?node_id=50000&id=YmU0YjczNjEtMmVlNzI4ZGMtMmVjZDcyMGYtOTY5NjY0NzY=, workerId: [50000:7565613790986137690:4212], database: , longSession: 0, local sessions count: 1
2025-10-26T19:25:28.030267Z :KQP_PROXY INFO: Cannot start publishing usage, tenants: /Root/test_auditlog.py, empty
2025-10-26T19:25:28.030431Z :KQP_PROXY DEBUG: Ctx: { TraceId: , Database: , DatabaseId: , SessionId: ydb://session/3?node_id=50000&id=YmU0YjczNjEtMmVlNzI4ZGMtMmVjZDcyMGYtOTY5NjY0NzY=, CurrentExecutionId: , CustomerSuppliedId: , PoolId: }. TEvQueryRequest, set timer for: 300.000000s timeout: 0.000000s cancelAfter: 0.000000s. Send request to target, requestId: 2, targetId: [50000:7565613790986137690:4212]
2025-10-26T19:25:28.030508Z :KQP_PROXY DEBUG: Scheduled timeout timer for requestId: 2 timeout: 300.000000s actor id: [50000:7565613790986137691:44]
2025-10-26T19:25:28.030624Z :KQP_PROXY DEBUG: TraceId: "01k8gzq5ywakta6cajt2jtt5kt", Request has 2.866085s seconds to be completed
2025-10-26T19:25:28.032426Z :KQP_PROXY DEBUG: TraceId: "01k8gzq5ywakta6cajt2jtt5kt", Created new session, sessionId: ydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=, workerId: [50000:7565613790986137692:4166], database: /Root/test_auditlog.py, longSession: 1, local sessions count: 2
2025-10-26T19:25:28.032469Z :KQP_PROXY INFO: Cannot start publishing usage, tenants: /Root/test_auditlog.py, empty
2025-10-26T19:25:28.032563Z :KQP_PROXY DEBUG: Received create session request, trace_id: 01k8gzq5ywakta6cajt2jtt5kt
2025-10-26T19:25:28.032663Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3439098644
2025-10-26T19:25:28.032695Z :KQP_PROXY INFO: Cannot start publishing usage, tenants: /Root/test_auditlog.py, empty
2025-10-26T19:25:28.032758Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3439098644
2025-10-26T19:25:28.032775Z :KQP_PROXY INFO: Cannot start publishing usage, tenants: /Root/test_auditlog.py, empty
2025-10-26T19:25:28.032851Z :KQP_PROXY DEBUG: Subscribed for config changes.
2025-10-26T19:25:28.032887Z :KQP_PROXY DEBUG: Updated table service config.
2025-10-26T19:25:28.032948Z :KQP_PROXY DEBUG: Updated YQL logs priority: 3
2025-10-26T19:25:28.033457Z :GRPC_SERVER DEBUG: [0x61a0000a2080] issuing response Name# CreateSession data# operation { ready: true status: SUCCESS result { type_url: "type.googleapis.com/Ydb.Table.CreateSessionResult" value: "\nQydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=" } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:28.034435Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3439098644
2025-10-26T19:25:28.034494Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3439098644
2025-10-26T19:25:28.034525Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3439098644
2025-10-26T19:25:28.034565Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3439098644
2025-10-26T19:25:28.034593Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3439098644
2025-10-26T19:25:28.044699Z :GRPC_SERVER DEBUG: [0x61a0000a2080] finished request Name# CreateSession ok# true peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:28.055999Z :GRPC_SERVER DEBUG: [0x61a0000ee280] received request Name# ExecuteSchemeQuery ok# true data# session_id: "ydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=" yql_text: "\n            create table `/Root/test_auditlog.py/test-table` (\n                id int64,\n                value int64,\n                primary key (id)\n            );\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:28.056091Z :GRPC_SERVER DEBUG: [0x61a00002ee80] created request Name# ExecuteSchemeQuery
2025-10-26T19:25:28.056455Z :GRPC_SERVER DEBUG: [0x61a0000ee280] received request without user token Name# ExecuteSchemeQuery data# session_id: "ydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=" yql_text: "\n            create table `/Root/test_auditlog.py/test-table` (\n                id int64,\n                value int64,\n                primary key (id)\n            );\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } }  peer# ipv4:127.0.0.1:60616 database# /Root/test_auditlog.py
2025-10-26T19:25:28.056652Z :GRPC_SERVER DEBUG: Got grpc request# ExecuteSchemeQueryRequest, traceId# 01k8gzq63r5vf8r6gmk6t2rrf3, sdkBuildInfo# ydb-python-sdk/3.12.3, state# AS_NOT_PERFORMED, database# /Root/test_auditlog.py, peer# ipv4:127.0.0.1:60616, grpcInfo# python-library grpc-python/1.54.2 grpc-c/31.0.0 (linux; chttp2), timeout# 600.998071s
2025-10-26T19:25:28.056967Z :KQP_PROXY DEBUG: Ctx: { TraceId: 01k8gzq63r5vf8r6gmk6t2rrf3, Database: /Root/test_auditlog.py, DatabaseId: , SessionId: ydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=, CurrentExecutionId: , CustomerSuppliedId: , PoolId: }. TEvQueryRequest, set timer for: 600.000000s timeout: 0.000000s cancelAfter: 0.000000s. Send request to target, requestId: 4, targetId: [50000:7565613790986137692:4166]
2025-10-26T19:25:28.057004Z :KQP_PROXY DEBUG: Scheduled timeout timer for requestId: 4 timeout: 600.000000s actor id: [50000:7565613790986137697:90]
2025-10-26T19:25:28.191044Z :KQP_GATEWAY DEBUG: Load table metadata from cache by path, request Path: /Root/test_auditlog.py/test-table
2025-10-26T19:25:28.195730Z :KQP_GATEWAY DEBUG: Load table metadata from cache by path, request Path: 
2025-10-26T19:25:28.196169Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137700:4144][/Root/test_auditlog.py/test-table] Set up state: owner# [50000:7565613769511300664:4136], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.210678Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137709:4155][/Root] Set up state: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.213344Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877761, Sender [50000:7565613790986137716:152], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.213373Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.213393Z :FLAT_TX_SCHEMESHARD TRACE: Pipe server connected, at tablet: 72075186224037889
2025-10-26T19:25:28.213545Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271122432, Sender [50000:7565613790986137708:4158], Recipient [50000:7565613769511300997:56]: {TEvModifySchemeTransaction txid# 281474976720657 TabletId# 72075186224037889}
2025-10-26T19:25:28.213565Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvModifySchemeTransaction
2025-10-26T19:25:28.218889Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationPropose Execute, message: Transaction { WorkingDir: "/Root/test_auditlog.py" OperationType: ESchemeOpCreateTable CreateTable { Name: "test-table" Columns { Name: "id" Type: "Int64" NotNull: false } Columns { Name: "value" Type: "Int64" NotNull: false } KeyColumnNames: "id" PartitionConfig { PartitioningPolicy { SizeToSplit: 2147483648 } ColumnFamilies { StorageConfig { SysLog { PreferredPoolKind: "hdd" } Log { PreferredPoolKind: "hdd" } Data { PreferredPoolKind: "hdd" } } } } Temporary: false } } TxId: 281474976720657 TabletId: 72075186224037889 PeerName: "" , at schemeshard: 72075186224037889
2025-10-26T19:25:28.219337Z :FLAT_TX_SCHEMESHARD NOTICE: TCreateTable Propose, path: /Root/test_auditlog.py/test-table, opId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.219494Z :FLAT_TX_SCHEMESHARD DEBUG: TCreateTable Propose, path: /Root/test_auditlog.py/test-table, opId: 281474976720657:0, schema: Name: "test-table" Columns { Name: "id" Type: "Int64" NotNull: false } Columns { Name: "value" Type: "Int64" NotNull: false } KeyColumnNames: "id" PartitionConfig { PartitioningPolicy { SizeToSplit: 2147483648 } ColumnFamilies { StorageConfig { SysLog { PreferredPoolKind: "hdd" } Log { PreferredPoolKind: "hdd" } Data { PreferredPoolKind: "hdd" } } } } Temporary: false, at schemeshard: 72075186224037889
2025-10-26T19:25:28.220012Z :FLAT_TX_SCHEMESHARD DEBUG: AttachChild: child attached as only one child to the parent, parent id: [OwnerId: 72075186224037889, LocalPathId: 1], parent name: Root/test_auditlog.py, child name: test-table, child id: [OwnerId: 72075186224037889, LocalPathId: 2], at schemeshard: 72075186224037889
2025-10-26T19:25:28.220048Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason transaction target path for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 0
2025-10-26T19:25:28.220081Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new shard created for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 1
2025-10-26T19:25:28.220199Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason new path created for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 2
2025-10-26T19:25:28.220225Z :FLAT_TX_SCHEMESHARD INFO: Change state for txid 281474976720657:0 1 -> 2
2025-10-26T19:25:28.220829Z :FLAT_TX_SCHEMESHARD NOTICE: IgniteOperation, opId: 281474976720657:0, propose status:StatusAccepted, reason: , at schemeshard: 72075186224037889
2025-10-26T19:25:28.220853Z :FLAT_TX_SCHEMESHARD WARN: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720657:0, at schemeshard:  72075186224037889
2025-10-26T19:25:28.220921Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.220985Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason publish path for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 11
2025-10-26T19:25:28.221013Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason publish path for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 3
2025-10-26T19:25:28.224894Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationPropose Complete, txId: 281474976720657, response: Status: StatusAccepted TxId: 281474976720657 SchemeshardId: 72075186224037889 PathId: 2, at schemeshard: 72075186224037889
2025-10-26T19:25:28.225157Z :FLAT_TX_SCHEMESHARD NOTICE: AUDIT: txId: 281474976720657, database: /Root/test_auditlog.py, subject: , status: StatusAccepted, operation: CREATE TABLE, path: /Root/test_auditlog.py/test-table
2025-10-26T19:25:28.225220Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.225245Z :FLAT_TX_SCHEMESHARD TRACE: Activate send for 281474976720657:0
2025-10-26T19:25:28.225487Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 2146435072, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.225518Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.225565Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoExecute, at schemeshard: 72075186224037889
2025-10-26T19:25:28.225587Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard DescribePath, at schemeshard: 72075186224037889, txId: 281474976720657, path id: [OwnerId: 72075186224037889, LocalPathId: 1]
2025-10-26T19:25:28.225737Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard DescribePath, at schemeshard: 72075186224037889, txId: 281474976720657, path id: [OwnerId: 72075186224037889, LocalPathId: 2]
2025-10-26T19:25:28.225820Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoComplete, at schemeshard: 72075186224037889
2025-10-26T19:25:28.225844Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard Send, to populator: [50000:7565613778101235759:4168], at schemeshard: 72075186224037889, txId: 281474976720657, path id: 1
2025-10-26T19:25:28.225861Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard Send, to populator: [50000:7565613778101235759:4168], at schemeshard: 72075186224037889, txId: 281474976720657, path id: 2
2025-10-26T19:25:28.225903Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationProgress Execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.225941Z :FLAT_TX_SCHEMESHARD INFO: TCreateParts opId# 281474976720657:0 ProgressState, operation type: TxCreateTable, at tablet72075186224037889
2025-10-26T19:25:28.226248Z :FLAT_TX_SCHEMESHARD DEBUG: TCreateParts opId# 281474976720657:0 CreateRequest Event to Hive: 72075186224037888 msg:  Owner: 72075186224037889
OwnerIdx: 11
TabletType: DataShard
ObjectDomain {
  SchemeShard: 72075186232723360
  PathId: 2
}
ObjectId: 2
BindedChannels {
  StoragePoolName: "/Root/test_auditlog.py:hdd"
}
BindedChannels {
  StoragePoolName: "/Root/test_auditlog.py:hdd"
}
AllowedDomains {
  SchemeShard: 72075186232723360
  PathId: 2
}
2025-10-26T19:25:28.226292Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.226637Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Update description: owner# 72075186224037889, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], cookie# 281474976720657, is deletion# false, version: 5
2025-10-26T19:25:28.226737Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Update description: owner# 72075186224037889, pathId# [OwnerId: 72075186224037889, LocalPathId: 2], cookie# 281474976720657, is deletion# false, version: 1
2025-10-26T19:25:28.226917Z :KQP_GATEWAY DEBUG: Received TEvProposeTransactionStatus for scheme request, TxId: 281474976720657, status: ExecInProgress, scheme shard status: 1
2025-10-26T19:25:28.226948Z :KQP_GATEWAY DEBUG: Sent TEvNotifyTxCompletion request, TxId: 281474976720657
2025-10-26T19:25:28.227171Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877764, Sender [50000:7565613790986137716:152], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerDisconnected
2025-10-26T19:25:28.227222Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvServerDisconnected
2025-10-26T19:25:28.227239Z :FLAT_TX_SCHEMESHARD TRACE: Server pipe is reset, at schemeshard: 72075186224037889
2025-10-26T19:25:28.227794Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877761, Sender [50000:7565613790986137722:162], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.227810Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.227822Z :FLAT_TX_SCHEMESHARD TRACE: Pipe server connected, at tablet: 72075186224037889
2025-10-26T19:25:28.228927Z :KQP_COMPILE_ACTOR ERROR: Compilation failed, self: [50000:7565613790986137693:4145], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:3:16: Error: At function: KiReadTable!
        <main>:3:16: Error: Cannot find table 'db.[]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-10-26T19:25:28.229579Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300759:38][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300614:9], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.230535Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300742:4169][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.230660Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300680:4148][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.230764Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300708:4152][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.230856Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137700:4144][/Root/test_auditlog.py/test-table] Update to non-empty state: owner# [50000:7565613769511300664:4136], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 2], Version: 1) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.230879Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271124996, Sender [50000:7565613790986137707:4157], Recipient [50000:7565613769511300997:56]: NKikimrScheme.TEvNotifyTxCompletion TxId: 281474976720657
2025-10-26T19:25:28.230903Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvNotifyTxCompletion
2025-10-26T19:25:28.230948Z :FLAT_TX_SCHEMESHARD DEBUG: NotifyTxCompletion operation in-flight, txId: 281474976720657, at schemeshard: 72075186224037889
2025-10-26T19:25:28.230967Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation IsReadyToNotify, TxId: 281474976720657, ready parts: 0/1, is published: false
2025-10-26T19:25:28.230968Z :KQP_PROXY DEBUG: Forwarded response to sender actor, requestId: 2, sender: [50000:7565613769511300546:4101], selfId: [50000:7565613769511300576:4115], source: [50000:7565613790986137690:4212]
2025-10-26T19:25:28.230986Z :FLAT_TX_SCHEMESHARD INFO: NotifyTxCompletion transaction is registered, txId: 281474976720657, at schemeshard: 72075186224037889
2025-10-26T19:25:28.231021Z :KQP_PROXY DEBUG: Session closed, sessionId: ydb://session/3?node_id=50000&id=YmU0YjczNjEtMmVlNzI4ZGMtMmVjZDcyMGYtOTY5NjY0NzY=, workerId: [50000:7565613790986137690:4212], local sessions count: 1
2025-10-26T19:25:28.231047Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.231078Z :FLAT_TX_SCHEMESHARD DEBUG: Send tablet strongly msg  operationId: 281474976720657:0 from tablet: 72075186224037889 to tablet: 72075186224037888 cookie: 72075186224037889:11 msg type: 268697601
2025-10-26T19:25:28.231189Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation RegisterRelationByTabletId, TxId: 281474976720657, partId: 0, tablet: 72075186224037888
2025-10-26T19:25:28.231259Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:28.231294Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation RegisterRelationByShardIdx, TxId: 281474976720657, shardIdx: 72075186224037889:11, partId: 0
2025-10-26T19:25:28.231353Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:28.231382Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Ack update: ack to# [50000:7565613769511300997:56], cookie# 281474976720657, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], version# 5
2025-10-26T19:25:28.231403Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Ack update: ack to# [50000:7565613769511300997:56], cookie# 281474976720657, pathId# [OwnerId: 72075186224037889, LocalPathId: 2], version# 1
2025-10-26T19:25:28.231415Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:28.231451Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 274137603, Sender [50000:7565613778101235759:4168], Recipient [50000:7565613769511300997:56]: NKikimrSchemeBoard.TEvUpdateAck { Owner: 72075186224037889 Generation: 1 PathId: [OwnerId: 72075186224037889, LocalPathId: 1] Version: 5 }
2025-10-26T19:25:28.231468Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event NSchemeBoard::NSchemeshardEvents::TEvUpdateAck
2025-10-26T19:25:28.231473Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:28.231517Z :FLAT_TX_SCHEMESHARD INFO: Handle TEvUpdateAck, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 1 Version: 5 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.231587Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Execute, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 1 Version: 5 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.231599Z :FLAT_TX_SCHEMESHARD INFO: Operation in-flight, at schemeshard: 72075186224037889, txId: 281474976720657
2025-10-26T19:25:28.231617Z :FLAT_TX_SCHEMESHARD INFO: AckPublish, at schemeshard: 72075186224037889, txId: 281474976720657, pathId: [OwnerId: 72075186224037889, LocalPathId: 1], version: 5
2025-10-26T19:25:28.231637Z :FLAT_TX_SCHEMESHARD DEBUG: DecrementPathDbRefCount reason remove publishing for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 12
2025-10-26T19:25:28.231711Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.231892Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 274137603, Sender [50000:7565613778101235759:4168], Recipient [50000:7565613769511300997:56]: NKikimrSchemeBoard.TEvUpdateAck { Owner: 72075186224037889 Generation: 1 PathId: [OwnerId: 72075186224037889, LocalPathId: 2] Version: 1 }
2025-10-26T19:25:28.231907Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event NSchemeBoard::NSchemeshardEvents::TEvUpdateAck
2025-10-26T19:25:28.231949Z :FLAT_TX_SCHEMESHARD INFO: Handle TEvUpdateAck, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 2 Version: 1 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.232016Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Execute, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 2 Version: 1 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.232026Z :FLAT_TX_SCHEMESHARD INFO: Operation in-flight, at schemeshard: 72075186224037889, txId: 281474976720657
2025-10-26T19:25:28.232038Z :FLAT_TX_SCHEMESHARD INFO: AckPublish, at schemeshard: 72075186224037889, txId: 281474976720657, pathId: [OwnerId: 72075186224037889, LocalPathId: 2], version: 1
2025-10-26T19:25:28.232051Z :FLAT_TX_SCHEMESHARD DEBUG: DecrementPathDbRefCount reason remove publishing for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 4
2025-10-26T19:25:28.232100Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation IsReadyToNotify, TxId: 281474976720657, ready parts: 0/1, is published: true
2025-10-26T19:25:28.232108Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.234370Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877760, Sender [50000:7565613790986137724:4159], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:28.234395Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:28.234420Z :FLAT_TX_SCHEMESHARD TRACE: Handle TEvClientConnected, tabletId: 72075186224037888, status: OK, at schemeshard: 72075186224037889
2025-10-26T19:25:28.235707Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Complete, at schemeshard: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.235722Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.235775Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Complete, at schemeshard: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.235781Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.249348Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 268698113, Sender [50000:7565613769511300827:4154], Recipient [50000:7565613769511300997:56]: {EvCreateTabletReply Status: OK Owner: 72075186224037889 OwnerIdx: 11 TabletID: 72075186224037898 Origin: 72075186224037888}
2025-10-26T19:25:28.249383Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvHive::TEvCreateTabletReply
2025-10-26T19:25:28.249451Z :FLAT_TX_SCHEMESHARD DEBUG: Handle TEvCreateTabletReply at schemeshard: 72075186224037889 message: Status: OK Owner: 72075186224037889 OwnerIdx: 11 TabletID: 72075186224037898 Origin: 72075186224037888
2025-10-26T19:25:28.249474Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation FindRelatedPartByShardIdx, TxId: 281474976720657, shardIdx: 72075186224037889:11, partId: 0
2025-10-26T19:25:28.249595Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvHive::TEvCreateTabletReply> execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889, message: Status: OK Owner: 72075186224037889 OwnerIdx: 11 TabletID: 72075186224037898 Origin: 72075186224037888
2025-10-26T19:25:28.249638Z :FLAT_TX_SCHEMESHARD INFO: TCreateParts opId# 281474976720657:0 HandleReply TEvCreateTabletReply, at tabletId: 72075186224037889
2025-10-26T19:25:28.249703Z :FLAT_TX_SCHEMESHARD DEBUG: TCreateParts opId# 281474976720657:0 HandleReply TEvCreateTabletReply, message: Status: OK Owner: 72075186224037889 OwnerIdx: 11 TabletID: 72075186224037898 Origin: 72075186224037888
2025-10-26T19:25:28.249794Z :FLAT_TX_SCHEMESHARD INFO: Change state for txid 281474976720657:0 2 -> 3
2025-10-26T19:25:28.249861Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.249891Z :FLAT_TX_SCHEMESHARD TRACE: Ack tablet strongly msg opId: 281474976720657:0 from tablet: 72075186224037889 to tablet: 72075186224037888 cookie: 72075186224037889:11
2025-10-26T19:25:28.253416Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvHive::TEvCreateTabletReply> complete, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.253438Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.253467Z :FLAT_TX_SCHEMESHARD TRACE: Activate send for 281474976720657:0
2025-10-26T19:25:28.253596Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 2146435072, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.253623Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.253661Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationProgress Execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.253707Z :FLAT_TX_SCHEMESHARD INFO: TCreateTable TConfigureParts operationId#281474976720657:0 ProgressState at tabletId# 72075186224037889
2025-10-26T19:25:28.253772Z :FLAT_TX_SCHEMESHARD DEBUG: TCreateTable TConfigureParts operationId#281474976720657:0 ProgressState Propose modify scheme on datashard datashardId: 72075186224037898 seqNo: 1:1
2025-10-26T19:25:28.270038Z :BS_PROXY_DISCOVER WARN: [2082a1ed03a7b441] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-10-26T19:25:28.275163Z :FLAT_TX_SCHEMESHARD DEBUG: TCreateTable TConfigureParts operationId#281474976720657:0 ProgressState Propose modify scheme on datashard datashardId: 72075186224037898 message: TxKind: TX_KIND_SCHEME SourceDeprecated { RawX1: 7565613769511300997 RawX2: 4718347992170552 } TxBody: "\n\364\004\n\ntest-table\020\002\032\014\n\002id\030\003 \001(\000@\000\032\017\n\005value\030\003 \002(\000@\000(\001:\374\003\022\253\003\010\200\200\200\002\020\254\002\030\364\003 \200\200\200\010(\0000\200\200\200 8\200\200\200\010@\2008H\000RX\010\000\020\000\030\010 \010(\200\200\200@0\377\377\377\377\0178\001B$\010e\020d\031\000\000\000\000\000\000\360?*\025background_compactionJ\017compaction_gen1P\nX\200\200\001`nh\000p\000Rb\010\001\020\200\200\200\024\030\005 \020(\200\200\200\200\0020\377\377\377\377\0178\000B$\010e\020d\031\000\000\000\000\000\000\360?*\025background_compactionJ\017compaction_gen2P\nX\200\200\001`nh\200\200\200\004p\200\200\200\004Rc\010\002\020\200\200\200\310\001\030\005 \020(\200\200\200\200@0\377\377\377\377\0178\000B$\010e\020d\031\000\000\000\000\000\000\360?*\025background_compactionJ\017compaction_gen3P\nX\200\200\001`nh\200\200\200(p\200\200\200(X\001`\005j$\010e\020d\031\000\000\000\000\000\000\360?*\025background_compactionr\017compaction_gen0z\017compaction_gen0\202\001\004scan\210\001\200\200\200\010\220\001\364\003\230\0012\270\001\2008\300\001\006R\010\010\200\200\200\200\010\020\001b\031\010\000J\025\n\005\n\003hdd\022\005\n\003hdd\032\005\n\003hdd\212\001&\010\000\022\004\010\001\020\000\022\004\010\002\020\001\022\004\010\003\020\001\032\004\010\001\020\000\032\004\010\002\020\001\032\004\010\003\020\001J!/Root/test_auditlog.py/test-table\242\001\006\001\000\000\000\000\200\252\001\000\260\001\001\270\001\000\210\002\001\222\002\013\t\001\000\001\000\000\020\000\001\020\002:\004\010\001\020\001" TxId: 281474976720657 ExecLevel: 0 Flags: 0 SchemeShardId: 72075186224037889 ProcessingParams { Version: 2 PlanResolution: 10 Coordinators: 72075186224037890 Coordinators: 72075186224037891 Coordinators: 72075186224037892 TimeCastBucketsPerMediator: 2 Mediators: 72075186224037893 Mediators: 72075186224037894 Mediators: 72075186224037895 SchemeShard: 72075186224037889 Hive: 72075186224037888 SysViewProcessor: 72075186224037896 StatisticsAggregator: 72075186224037897 } SubDomainPathId: 1
2025-10-26T19:25:28.275315Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.280314Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.280362Z :FLAT_TX_SCHEMESHARD DEBUG: Send tablet strongly msg  operationId: 281474976720657:0 from tablet: 72075186224037889 to tablet: 72075186224037898 cookie: 72075186224037889:11 msg type: 269549568
2025-10-26T19:25:28.280489Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation RegisterRelationByTabletId, TxId: 281474976720657, partId: 0, tablet: 72075186224037898
2025-10-26T19:25:28.298119Z :BS_PROXY_DISCOVER WARN: [616bc457589d9706] Die. Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD05
2025-10-26T19:25:28.338417Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 268698119, Sender [50000:7565613769511300827:4154], Recipient [50000:7565613769511300997:56]: {EvTabletCreationResult Status: OK TabletID: 72075186224037898}
2025-10-26T19:25:28.342269Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877760, Sender [50000:7565613790986137748:4215], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:28.342304Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:28.342327Z :FLAT_TX_SCHEMESHARD TRACE: Handle TEvClientConnected, tabletId: 72075186224037898, status: OK, at schemeshard: 72075186224037889
2025-10-26T19:25:28.354236Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137776:4161][[OwnerId: 72075186224037889, LocalPathId: 1]] Set up state: owner# [50000:7565613790986137775:4160], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.357401Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269550080, Sender [50000:7565613790986137751:68], Recipient [50000:7565613769511300997:56]: NKikimrTxDataShard.TEvProposeTransactionResult TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: PREPARED TxId: 281474976720657 MinStep: 1 MaxStep: 18446744073709551615 PrepareArriveTime: 1761506728342289 ExecLatency: 0 ProposeLatency: 12 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 396 } }
2025-10-26T19:25:28.357436Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvDataShard::TEvProposeTransactionResult
2025-10-26T19:25:28.357530Z :FLAT_TX_SCHEMESHARD DEBUG: Handle TEvProposeTransactionResult, at schemeshard: 72075186224037889, message: TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: PREPARED TxId: 281474976720657 MinStep: 1 MaxStep: 18446744073709551615 PrepareArriveTime: 1761506728342289 ExecLatency: 0 ProposeLatency: 12 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 396 } }
2025-10-26T19:25:28.357554Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation FindRelatedPartByTabletId, TxId: 281474976720657, tablet: 72075186224037898, partId: 0
2025-10-26T19:25:28.358159Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvDataShard::TEvProposeTransactionResult> execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889, message: TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: PREPARED TxId: 281474976720657 MinStep: 1 MaxStep: 18446744073709551615 PrepareArriveTime: 1761506728342289 ExecLatency: 0 ProposeLatency: 12 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 396 } }
2025-10-26T19:25:28.358200Z :FLAT_TX_SCHEMESHARD INFO: TCreateTable TConfigureParts operationId#281474976720657:0 HandleReply TEvProposeTransactionResult at tabletId# 72075186224037889
2025-10-26T19:25:28.358291Z :FLAT_TX_SCHEMESHARD DEBUG: TCreateTable TConfigureParts operationId#281474976720657:0 HandleReply TEvProposeTransactionResult message: TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: PREPARED TxId: 281474976720657 MinStep: 1 MaxStep: 18446744073709551615 PrepareArriveTime: 1761506728342289 ExecLatency: 0 ProposeLatency: 12 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 396 } }
2025-10-26T19:25:28.358309Z :FLAT_TX_SCHEMESHARD INFO: TEvProposeTransactionResult at tablet: 72075186224037889
2025-10-26T19:25:28.358394Z :FLAT_TX_SCHEMESHARD DEBUG: CollectProposeTransactionResults accept TEvProposeTransactionResult, shard: 72075186224037898, shardIdx: 72075186224037889:11, operationId: 281474976720657:0, left await: 0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.358412Z :FLAT_TX_SCHEMESHARD INFO: Change state for txid 281474976720657:0 3 -> 128
2025-10-26T19:25:28.358469Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.358496Z :FLAT_TX_SCHEMESHARD TRACE: Ack tablet strongly msg opId: 281474976720657:0 from tablet: 72075186224037889 to tablet: 72075186224037898 cookie: 72075186224037889:11
2025-10-26T19:25:28.366368Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvDataShard::TEvProposeTransactionResult> complete, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.366408Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.366427Z :FLAT_TX_SCHEMESHARD TRACE: Activate send for 281474976720657:0
2025-10-26T19:25:28.366563Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 2146435072, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.366583Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.366620Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationProgress Execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.366665Z :FLAT_TX_SCHEMESHARD INFO: TCreateTable TPropose operationId#281474976720657:0 HandleReply ProgressState at tablet: 72075186224037889
2025-10-26T19:25:28.366694Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.366738Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation IsReadyToPropose , TxId: 281474976720657 ready parts: 1/1
2025-10-26T19:25:28.370797Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation DoPropose send propose to coordinator: 72075186224037890 message:Transaction { AffectedSet { TabletId: 72075186224037889 Flags: 2 } AffectedSet { TabletId: 72075186224037898 Flags: 2 } ExecLevel: 0 TxId: 281474976720657 MinStep: 1 MaxStep: 18446744073709551615 IgnoreLowDiskSpace: true } CoordinatorID: 72075186224037890
2025-10-26T19:25:28.376681Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.376714Z :FLAT_TX_SCHEMESHARD DEBUG: Send tablet strongly msg  operationId: 281474976720657:4294967295 from tablet: 72075186224037889 to tablet: 72075186224037890 cookie: 0:281474976720657 msg type: 269090816
2025-10-26T19:25:28.376827Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation RegisterRelationByTabletId, TxId: 281474976720657, partId: 4294967295, tablet: 72075186224037890
2025-10-26T19:25:28.377275Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877760, Sender [50000:7565613790986137785:4180], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:28.377297Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvClientConnected
2025-10-26T19:25:28.377321Z :FLAT_TX_SCHEMESHARD TRACE: Handle TEvClientConnected, tabletId: 72075186224037890, status: OK, at schemeshard: 72075186224037889
2025-10-26T19:25:28.377602Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269091328, Sender [50000:7565613769511300829:8193], Recipient [50000:7565613769511300997:56]: NKikimrTx.TEvProposeTransactionStatus Status: 16 StepId: 1761506728010 TxId: 281474976720657
2025-10-26T19:25:28.382125Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269091328, Sender [50000:7565613769511300829:8193], Recipient [50000:7565613769511300997:56]: NKikimrTx.TEvProposeTransactionStatus Status: 17 StepId: 1761506728420 TxId: 281474976720657
2025-10-26T19:25:28.385496Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877761, Sender [50000:7565613790986137799:182], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.385526Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.385540Z :FLAT_TX_SCHEMESHARD TRACE: Pipe server connected, at tablet: 72075186224037889
2025-10-26T19:25:28.385584Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269287424, Sender [50000:7565613778101235722:4156], Recipient [50000:7565613769511300997:56]: {TEvPlanStep step# 1761506728420 MediatorId# 72075186224037893 TabletID 72075186224037889}
2025-10-26T19:25:28.385598Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTxProcessing::TEvPlanStep
2025-10-26T19:25:28.385637Z :FLAT_TX_SCHEMESHARD NOTICE: TTxOperationPlanStep Execute, stepId: 1761506728420, transactions count in step: 1, at schemeshard: 72075186224037889
2025-10-26T19:25:28.385746Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationPlanStep Execute, message: Transactions { TxId: 281474976720657 AckTo { RawX1: 7565613778101235693 RawX2: 214748364800063 } } Step: 1761506728420 MediatorID: 72075186224037893 TabletID: 72075186224037889, at schemeshard: 72075186224037889
2025-10-26T19:25:28.385790Z :FLAT_TX_SCHEMESHARD INFO: TCreateTable TPropose operationId#281474976720657:0 HandleReply TEvOperationPlan at tablet: 72075186224037889, stepId: 1761506728420
2025-10-26T19:25:28.385914Z :FLAT_TX_SCHEMESHARD INFO: Change state for txid 281474976720657:0 128 -> 129
2025-10-26T19:25:28.386002Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.386045Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason publish path for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 11
2025-10-26T19:25:28.386074Z :FLAT_TX_SCHEMESHARD DEBUG: IncrementPathDbRefCount reason publish path for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 3
2025-10-26T19:25:28.386108Z :FLAT_TX_SCHEMESHARD TRACE: Ack tablet strongly msg opId: 281474976720657:4294967295 from tablet: 72075186224037889 to tablet: 0 cookie: 0:281474976720657
2025-10-26T19:25:28.389845Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269550080, Sender [50000:7565613790986137751:68], Recipient [50000:7565613769511300997:56]: NKikimrTxDataShard.TEvProposeTransactionResult TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: COMPLETE TxId: 281474976720657 Step: 1761506728420 OrderId: 281474976720657 ExecLatency: 0 ProposeLatency: 6 DomainCoordinators: 72075186224037890 DomainCoordinators: 72075186224037891 DomainCoordinators: 72075186224037892 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 1562 } }
2025-10-26T19:25:28.389869Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvDataShard::TEvProposeTransactionResult
2025-10-26T19:25:28.389952Z :FLAT_TX_SCHEMESHARD DEBUG: Handle TEvProposeTransactionResult, at schemeshard: 72075186224037889, message: TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: COMPLETE TxId: 281474976720657 Step: 1761506728420 OrderId: 281474976720657 ExecLatency: 0 ProposeLatency: 6 DomainCoordinators: 72075186224037890 DomainCoordinators: 72075186224037891 DomainCoordinators: 72075186224037892 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 1562 } }
2025-10-26T19:25:28.389971Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation FindRelatedPartByTabletId, TxId: 281474976720657, tablet: 72075186224037898, partId: 0
2025-10-26T19:25:28.390139Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvDataShard::TEvProposeTransactionResult> execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889, message: TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: COMPLETE TxId: 281474976720657 Step: 1761506728420 OrderId: 281474976720657 ExecLatency: 0 ProposeLatency: 6 DomainCoordinators: 72075186224037890 DomainCoordinators: 72075186224037891 DomainCoordinators: 72075186224037892 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 1562 } }
2025-10-26T19:25:28.390250Z :FLAT_TX_SCHEMESHARD INFO: Unexpected message: tablet# 72075186224037889, ev# TxKind: TX_KIND_SCHEME Origin: 72075186224037898 Status: COMPLETE TxId: 281474976720657 Step: 1761506728420 OrderId: 281474976720657 ExecLatency: 0 ProposeLatency: 6 DomainCoordinators: 72075186224037890 DomainCoordinators: 72075186224037891 DomainCoordinators: 72075186224037892 TxStats { PerShardStats { ShardId: 72075186224037898 CpuTimeUsec: 1562 } }
2025-10-26T19:25:28.390264Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.390512Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.390541Z :FLAT_TX_SCHEMESHARD TRACE: Ack coordinator stepId#1761506728420 first txId#281474976720657 countTxs#1
2025-10-26T19:25:28.390565Z :FLAT_TX_SCHEMESHARD TRACE: Ack mediator stepId#1761506728420
2025-10-26T19:25:28.390583Z :FLAT_TX_SCHEMESHARD TRACE: Activate send for 281474976720657:0
2025-10-26T19:25:28.390767Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 2146435072, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.390803Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.390851Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoExecute, at schemeshard: 72075186224037889
2025-10-26T19:25:28.390869Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard DescribePath, at schemeshard: 72075186224037889, txId: 281474976720657, path id: [OwnerId: 72075186224037889, LocalPathId: 1]
2025-10-26T19:25:28.391003Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard DescribePath, at schemeshard: 72075186224037889, txId: 281474976720657, path id: [OwnerId: 72075186224037889, LocalPathId: 2]
2025-10-26T19:25:28.391270Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationProgress Execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.391315Z :FLAT_TX_SCHEMESHARD INFO: NTableState::TProposedWaitParts operationId# 281474976720657:0 ProgressState at tablet: 72075186224037889
2025-10-26T19:25:28.391389Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.391902Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877761, Sender [50000:7565613790986137827:92], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.391930Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-26T19:25:28.391953Z :FLAT_TX_SCHEMESHARD TRACE: Pipe server connected, at tablet: 72075186224037889
2025-10-26T19:25:28.392086Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269551620, Sender [50000:7565613790986137751:68], Recipient [50000:7565613769511300997:56]: NKikimrTxDataShard.TEvSchemaChanged Source { RawX1: 7565613790986137751 RawX2: 4718347992170564 } Origin: 72075186224037898 State: 2 TxId: 281474976720657 Step: 0 Generation: 1
2025-10-26T19:25:28.392116Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvDataShard::TEvSchemaChanged
2025-10-26T19:25:28.392181Z :FLAT_TX_SCHEMESHARD DEBUG: Handle TEvSchemaChanged, tabletId: 72075186224037889, at schemeshard: 72075186224037889, message: Source { RawX1: 7565613790986137751 RawX2: 4718347992170564 } Origin: 72075186224037898 State: 2 TxId: 281474976720657 Step: 0 Generation: 1
2025-10-26T19:25:28.392208Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation FindRelatedPartByTabletId, TxId: 281474976720657, tablet: 72075186224037898, partId: 0
2025-10-26T19:25:28.392395Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvDataShard::TEvSchemaChanged> execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889, message: Source { RawX1: 7565613790986137751 RawX2: 4718347992170564 } Origin: 72075186224037898 State: 2 TxId: 281474976720657 Step: 0 Generation: 1
2025-10-26T19:25:28.392428Z :FLAT_TX_SCHEMESHARD INFO: NTableState::TProposedWaitParts operationId# 281474976720657:0 HandleReply TEvSchemaChanged at tablet: 72075186224037889
2025-10-26T19:25:28.392505Z :FLAT_TX_SCHEMESHARD DEBUG: NTableState::TProposedWaitParts operationId# 281474976720657:0 HandleReply TEvSchemaChanged at tablet: 72075186224037889 message: Source { RawX1: 7565613790986137751 RawX2: 4718347992170564 } Origin: 72075186224037898 State: 2 TxId: 281474976720657 Step: 0 Generation: 1
2025-10-26T19:25:28.392546Z :FLAT_TX_SCHEMESHARD DEBUG: CollectSchemaChanged accept TEvDataShard::TEvSchemaChanged, operationId: 281474976720657:0, shardIdx: 72075186224037889:11, datashard: 72075186224037898, left await: 0, txState.State: ProposedWaitParts, txState.ReadyForNotifications: 1, at schemeshard: 72075186224037889
2025-10-26T19:25:28.392567Z :FLAT_TX_SCHEMESHARD INFO: all shard schema changes has been received, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.392584Z :FLAT_TX_SCHEMESHARD DEBUG: send schema changes ack message, operation: 281474976720657:0, datashard: 72075186224037898, at schemeshard: 72075186224037889
2025-10-26T19:25:28.392622Z :FLAT_TX_SCHEMESHARD INFO: Change state for txid 281474976720657:0 129 -> 240
2025-10-26T19:25:28.392759Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.395009Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvDataShard::TEvProposeTransactionResult> complete, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.395032Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.395062Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoComplete, at schemeshard: 72075186224037889
2025-10-26T19:25:28.395082Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard Send, to populator: [50000:7565613778101235759:4168], at schemeshard: 72075186224037889, txId: 281474976720657, path id: 1
2025-10-26T19:25:28.395104Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard Send, to populator: [50000:7565613778101235759:4168], at schemeshard: 72075186224037889, txId: 281474976720657, path id: 2
2025-10-26T19:25:28.395179Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.395242Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Update description: owner# 72075186224037889, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], cookie# 281474976720657, is deletion# false, version: 6
2025-10-26T19:25:28.395362Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Update description: owner# 72075186224037889, pathId# [OwnerId: 72075186224037889, LocalPathId: 2], cookie# 281474976720657, is deletion# false, version: 3
2025-10-26T19:25:28.396243Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationReply<NKikimr::TEvDataShard::TEvSchemaChanged> complete, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.396267Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.396288Z :FLAT_TX_SCHEMESHARD TRACE: Activate send for 281474976720657:0
2025-10-26T19:25:28.396375Z :FLAT_TX_SCHEMESHARD TRACE: Send  to actor: [50000:7565613790986137751:68] msg type: 269552132 msg: NKikimrTxDataShard.TEvSchemaChangedResult TxId: 281474976720657 at schemeshard: 72075186224037889
2025-10-26T19:25:28.396489Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 2146435072, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.396509Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvPrivate::TEvProgressOperation
2025-10-26T19:25:28.396542Z :FLAT_TX_SCHEMESHARD DEBUG: TTxOperationProgress Execute, operationId: 281474976720657:0, at schemeshard: 72075186224037889
2025-10-26T19:25:28.396575Z :FLAT_TX_SCHEMESHARD INFO: [72075186224037889] TDone opId# 281474976720657:0ProgressState
2025-10-26T19:25:28.396663Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.396680Z :FLAT_TX_SCHEMESHARD INFO: Part operation is done id#281474976720657:0 progress is 1/1
2025-10-26T19:25:28.396695Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation IsReadyToDone  TxId: 281474976720657 ready parts: 1/1
2025-10-26T19:25:28.396719Z :FLAT_TX_SCHEMESHARD INFO: Part operation is done id#281474976720657:0 progress is 1/1
2025-10-26T19:25:28.396732Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation IsReadyToDone  TxId: 281474976720657 ready parts: 1/1
2025-10-26T19:25:28.396751Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation IsReadyToNotify, TxId: 281474976720657, ready parts: 1/1, is published: false
2025-10-26T19:25:28.396779Z :FLAT_TX_SCHEMESHARD DEBUG: TOperation IsReadyToDone  TxId: 281474976720657 ready parts: 1/1
2025-10-26T19:25:28.396800Z :FLAT_TX_SCHEMESHARD NOTICE: Operation and all the parts is done, operation id: 281474976720657:0
2025-10-26T19:25:28.396810Z :FLAT_TX_SCHEMESHARD DEBUG: RemoveTx for txid 281474976720657:0
2025-10-26T19:25:28.396919Z :FLAT_TX_SCHEMESHARD DEBUG: DecrementPathDbRefCount reason remove txstate target path for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 4
2025-10-26T19:25:28.396957Z :FLAT_TX_SCHEMESHARD NOTICE: Publication still in progress, tx: 281474976720657, publications: 2, subscribers: 1
2025-10-26T19:25:28.396972Z :FLAT_TX_SCHEMESHARD DEBUG: Publication details:  tx: 281474976720657, [OwnerId: 72075186224037889, LocalPathId: 1], 6
2025-10-26T19:25:28.396981Z :FLAT_TX_SCHEMESHARD DEBUG: Publication details:  tx: 281474976720657, [OwnerId: 72075186224037889, LocalPathId: 2], 3
2025-10-26T19:25:28.398044Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300742:4169][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.398079Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300680:4148][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.398208Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300759:38][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300614:9], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.398243Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300708:4152][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.398255Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137776:4161][[OwnerId: 72075186224037889, LocalPathId: 1]] Path was updated to new version: owner# [50000:7565613790986137775:4160], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.398376Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137700:4144][/Root/test_auditlog.py/test-table] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 2], Version: 1) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 2], Version: 3) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:28.398528Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:28.398544Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:28.398644Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:28.398673Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:28.398706Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Ack update: ack to# [50000:7565613769511300997:56], cookie# 281474976720657, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], version# 6
2025-10-26T19:25:28.398726Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Ack update: ack to# [50000:7565613769511300997:56], cookie# 281474976720657, pathId# [OwnerId: 72075186224037889, LocalPathId: 2], version# 3
2025-10-26T19:25:28.398851Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 274137603, Sender [50000:7565613778101235759:4168], Recipient [50000:7565613769511300997:56]: NKikimrSchemeBoard.TEvUpdateAck { Owner: 72075186224037889 Generation: 1 PathId: [OwnerId: 72075186224037889, LocalPathId: 1] Version: 6 }
2025-10-26T19:25:28.398868Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event NSchemeBoard::NSchemeshardEvents::TEvUpdateAck
2025-10-26T19:25:28.398926Z :FLAT_TX_SCHEMESHARD INFO: Handle TEvUpdateAck, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 1 Version: 6 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.398979Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Execute, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 1 Version: 6 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.398995Z :FLAT_TX_SCHEMESHARD INFO: Publication in-flight, count: 2, at schemeshard: 72075186224037889, txId: 281474976720657
2025-10-26T19:25:28.399012Z :FLAT_TX_SCHEMESHARD INFO: AckPublish, at schemeshard: 72075186224037889, txId: 281474976720657, pathId: [OwnerId: 72075186224037889, LocalPathId: 1], version: 6
2025-10-26T19:25:28.399033Z :FLAT_TX_SCHEMESHARD DEBUG: DecrementPathDbRefCount reason remove publishing for pathId [OwnerId: 72075186224037889, LocalPathId: 1] was 12
2025-10-26T19:25:28.399091Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.399282Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 274137603, Sender [50000:7565613778101235759:4168], Recipient [50000:7565613769511300997:56]: NKikimrSchemeBoard.TEvUpdateAck { Owner: 72075186224037889 Generation: 1 PathId: [OwnerId: 72075186224037889, LocalPathId: 2] Version: 3 }
2025-10-26T19:25:28.399297Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event NSchemeBoard::NSchemeshardEvents::TEvUpdateAck
2025-10-26T19:25:28.399359Z :FLAT_TX_SCHEMESHARD INFO: Handle TEvUpdateAck, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 2 Version: 3 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.399410Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Execute, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 2 Version: 3 PathOwnerId: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.399421Z :FLAT_TX_SCHEMESHARD INFO: Publication in-flight, count: 1, at schemeshard: 72075186224037889, txId: 281474976720657
2025-10-26T19:25:28.399432Z :FLAT_TX_SCHEMESHARD INFO: AckPublish, at schemeshard: 72075186224037889, txId: 281474976720657, pathId: [OwnerId: 72075186224037889, LocalPathId: 2], version: 3
2025-10-26T19:25:28.399444Z :FLAT_TX_SCHEMESHARD DEBUG: DecrementPathDbRefCount reason remove publishing for pathId [OwnerId: 72075186224037889, LocalPathId: 2] was 3
2025-10-26T19:25:28.399485Z :FLAT_TX_SCHEMESHARD NOTICE: Publication complete, notify & remove, at schemeshard: 72075186224037889, txId: 281474976720657, subscribers: 1
2025-10-26T19:25:28.399505Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Notify send TEvNotifyTxCompletionResult, at schemeshard: 72075186224037889, to actorId: [50000:7565613790986137707:4157]
2025-10-26T19:25:28.399525Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:28.400977Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.401879Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Complete, at schemeshard: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.401898Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.402101Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877764, Sender [50000:7565613790986137827:92], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerDisconnected
2025-10-26T19:25:28.402139Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvServerDisconnected
2025-10-26T19:25:28.402158Z :FLAT_TX_SCHEMESHARD TRACE: Server pipe is reset, at schemeshard: 72075186224037889
2025-10-26T19:25:28.402845Z :FLAT_TX_SCHEMESHARD DEBUG: TTxAckPublishToSchemeBoard Complete, at schemeshard: 72075186224037889, cookie: 281474976720657
2025-10-26T19:25:28.402863Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:28.402953Z :FLAT_TX_SCHEMESHARD TRACE: Send  to actor: [50000:7565613790986137707:4157] msg type: 271124998 msg: NKikimrScheme.TEvNotifyTxCompletionResult TxId: 281474976720657 at schemeshard: 72075186224037889
2025-10-26T19:25:28.403002Z :KQP_GATEWAY DEBUG: Received TEvNotifyTxCompletionResult for scheme request, TxId: 281474976720657
2025-10-26T19:25:28.403022Z :KQP_GATEWAY DEBUG: Successful completion of scheme request, TxId: 281474976720657
2025-10-26T19:25:28.403307Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 269877764, Sender [50000:7565613790986137722:162], Recipient [50000:7565613769511300997:56]: NKikimr::TEvTabletPipe::TEvServerDisconnected
2025-10-26T19:25:28.403331Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvTabletPipe::TEvServerDisconnected
2025-10-26T19:25:28.403345Z :FLAT_TX_SCHEMESHARD TRACE: Server pipe is reset, at schemeshard: 72075186224037889
2025-10-26T19:25:28.405861Z :KQP_PROXY DEBUG: TraceId: "01k8gzq63r5vf8r6gmk6t2rrf3", Forwarded response to sender actor, requestId: 4, sender: [50000:7565613790986137695:4163], selfId: [50000:7565613769511300576:4115], source: [50000:7565613790986137692:4166]
2025-10-26T19:25:28.406001Z :GRPC_SERVER DEBUG: [0x61a0000ee280] issuing response Name# ExecuteSchemeQuery data# operation { ready: true status: SUCCESS }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:28.407042Z :GRPC_SERVER DEBUG: [0x61a0000ee280] finished request Name# ExecuteSchemeQuery ok# true peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:28.415390Z :GRPC_SERVER DEBUG: [0x61a000133880] received request Name# ExecuteDataQuery ok# true data# session_id: "ydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: "\n            insert into `/Root/test_auditlog.py/test-table` (id, value) values (1, 1), (2, 2)\n        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:28.415463Z :GRPC_SERVER DEBUG: [0x61a00002e880] created request Name# ExecuteDataQuery
2025-10-26T19:25:28.415807Z :GRPC_SERVER DEBUG: [0x61a000133880] received request without user token Name# ExecuteDataQuery data# session_id: "ydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: "\n            insert into `/Root/test_auditlog.py/test-table` (id, value) values (1, 1), (2, 2)\n        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } }  peer# ipv4:127.0.0.1:60616 database# /Root/test_auditlog.py
2025-10-26T19:25:28.415985Z :GRPC_SERVER DEBUG: Got grpc request# ExecuteDataQueryRequest, traceId# 01k8gzq6ezawct6rsawmb7rvbn, sdkBuildInfo# ydb-python-sdk/3.12.3, state# AS_NOT_PERFORMED, database# /Root/test_auditlog.py, peer# ipv4:127.0.0.1:60616, grpcInfo# python-library grpc-python/1.54.2 grpc-c/31.0.0 (linux; chttp2), timeout# 600.997721s
2025-10-26T19:25:28.416358Z :KQP_PROXY DEBUG: Ctx: { TraceId: 01k8gzq6ezawct6rsawmb7rvbn, Database: /Root/test_auditlog.py, DatabaseId: , SessionId: ydb://session/3?node_id=50000&id=ZTkzNWE2YWItNzIxYzY3ZjMtNGFlNDk3ZjItZTE5Yzk1MWU=, CurrentExecutionId: , CustomerSuppliedId: , PoolId: }. TEvQueryRequest, set timer for: 300.000000s timeout: 600.000000s cancelAfter: 600.000000s. Send request to target, requestId: 5, targetId: [50000:7565613790986137692:4166]
2025-10-26T19:25:28.416432Z :KQP_PROXY DEBUG: Scheduled timeout timer for requestId: 5 timeout: 300.000000s actor id: [50000:7565613790986137841:110]
2025-10-26T19:25:28.428719Z :KQP_GATEWAY DEBUG: Load table metadata from cache by path, request Path: /Root/test_auditlog.py/test-table
2025-10-26T19:25:28.593294Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271125000, Sender [0:0:0], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:28.593333Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:28.593406Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271124999, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:28.593423Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:28.693238Z :KQP_PROXY DEBUG: TraceId: "01k8gzq6ezawct6rsawmb7rvbn", Forwarded response to sender actor, requestId: 5, sender: [50000:7565613790986137839:4170], selfId: [50000:7565613769511300576:4115], source: [50000:7565613790986137692:4166]
2025-10-26T19:25:28.693459Z :GRPC_SERVER DEBUG: [0x61a000133880] issuing response Name# ExecuteDataQuery data# operation { ready: true status: SUCCESS result { type_url: "type.googleapis.com/Ydb.Table.ExecuteQueryResult" value: "\022\034\n\03201k8gzq6ps7emhy5r37tx0esx3" } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:28.697836Z :GRPC_SERVER DEBUG: [0x61a000133880] finished request Name# ExecuteDataQuery ok# true peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.033212Z :KQP_PROXY DEBUG: Received node white board pool stats: 0.3548539103
2025-10-26T19:25:29.233254Z :KQP_PROXY DEBUG: Created new session, sessionId: ydb://session/3?node_id=50000&id=MTNhZmJlMmQtMzY5NDc4MTQtMTNjMjc1YmMtNGVmMDMxNWI=, workerId: [50000:7565613795281105200:4145], database: , longSession: 0, local sessions count: 2
2025-10-26T19:25:29.233391Z :KQP_PROXY DEBUG: Ctx: { TraceId: , Database: , DatabaseId: , SessionId: ydb://session/3?node_id=50000&id=MTNhZmJlMmQtMzY5NDc4MTQtMTNjMjc1YmMtNGVmMDMxNWI=, CurrentExecutionId: , CustomerSuppliedId: , PoolId: }. TEvQueryRequest, set timer for: 300.000000s timeout: 0.000000s cancelAfter: 0.000000s. Send request to target, requestId: 6, targetId: [50000:7565613795281105200:4145]
2025-10-26T19:25:29.233415Z :KQP_PROXY DEBUG: Scheduled timeout timer for requestId: 6 timeout: 300.000000s actor id: [50000:7565613795281105201:185]
2025-10-26T19:25:29.248316Z :KQP_GATEWAY DEBUG: Load table metadata from cache by path, request Path: 
2025-10-26T19:25:29.251363Z :KQP_COMPILE_ACTOR ERROR: Compilation failed, self: [50000:7565613795281105202:4174], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:3:16: Error: At function: KiReadTable!
        <main>:3:16: Error: Cannot find table 'db.[]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-10-26T19:25:29.252069Z :KQP_PROXY DEBUG: Forwarded response to sender actor, requestId: 6, sender: [50000:7565613769511300546:4101], selfId: [50000:7565613769511300576:4115], source: [50000:7565613795281105200:4145]
2025-10-26T19:25:29.252124Z :KQP_PROXY DEBUG: Session closed, sessionId: ydb://session/3?node_id=50000&id=MTNhZmJlMmQtMzY5NDc4MTQtMTNjMjc1YmMtNGVmMDMxNWI=, workerId: [50000:7565613795281105200:4145], local sessions count: 1
2025-10-26T19:25:29.400615Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271125004, Sender [1:7565613740781861204:70], Recipient [50000:7565613769511300997:56]
2025-10-26T19:25:29.400656Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvUpdateTenantSchemeShard
2025-10-26T19:25:29.400801Z :FLAT_TX_SCHEMESHARD INFO: Handle TEvUpdateTenantSchemeShard, at schemeshard: 72075186224037889, msg: TabletId: 72075186232723360 Generation: 2 StoragePools { Name: "/Root/test_auditlog.py:hdd" Kind: "hdd" } SubdomainVersion: 3 AuditSettings { EnableDmlAudit: true }
2025-10-26T19:25:29.400934Z :FLAT_TX_SCHEMESHARD DEBUG: TTxUpdateTenant DoExecute, msg: TabletId: 72075186232723360 Generation: 2 StoragePools { Name: "/Root/test_auditlog.py:hdd" Kind: "hdd" } SubdomainVersion: 3 AuditSettings { EnableDmlAudit: true }, at schemeshard: 72075186224037889
2025-10-26T19:25:29.401107Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnExecute at tablet# 72075186224037889
2025-10-26T19:25:29.401130Z :FLAT_TX_SCHEMESHARD DEBUG: Cannot publish paths for unknown operation id#0
2025-10-26T19:25:29.401407Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613778101235765:4200][[OwnerId: 72075186232723360, LocalPathId: 2]] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 4) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:29.404592Z :FLAT_TX_SCHEMESHARD TRACE: TSideEffects ApplyOnComplete at tablet# 72075186224037889
2025-10-26T19:25:29.404798Z :FLAT_TX_SCHEMESHARD TRACE: Send  to actor: [1:7565613740781861204:70] msg type: 271125003 msg: NKikimrScheme.TEvSyncTenantSchemeShard DomainSchemeShard: 72075186232723360 DomainPathId: 2 TabletID: 72075186224037889 Generation: 1 EffectiveACLVersion: 0 SubdomainVersion: 3 UserAttributesVersion: 1 TenantHive: 72075186224037888 TenantSysViewProcessor: 72075186224037896 TenantRootACL: "" TenantStatisticsAggregator: 72075186224037897 TenantGraphShard: 18446744073709551615 at schemeshard: 72075186224037889
2025-10-26T19:25:29.404926Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoExecute, at schemeshard: 72075186224037889
2025-10-26T19:25:29.404995Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard DescribePath, at schemeshard: 72075186224037889, txId: 0, path id: [OwnerId: 72075186224037889, LocalPathId: 1]
2025-10-26T19:25:29.405145Z :FLAT_TX_SCHEMESHARD INFO: TTxPublishToSchemeBoard DoComplete, at schemeshard: 72075186224037889
2025-10-26T19:25:29.405170Z :FLAT_TX_SCHEMESHARD DEBUG: TTxPublishToSchemeBoard Send, to populator: [50000:7565613778101235759:4168], at schemeshard: 72075186224037889, txId: 0, path id: 1
2025-10-26T19:25:29.405263Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Update description: owner# 72075186224037889, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], cookie# 0, is deletion# false, version: 7
2025-10-26T19:25:29.407805Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300680:4148][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:29.407806Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300742:4169][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:29.407809Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300759:38][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300614:9], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:29.407974Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137776:4161][[OwnerId: 72075186224037889, LocalPathId: 1]] Path was updated to new version: owner# [50000:7565613790986137775:4160], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:29.407987Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300708:4152][/Root/test_auditlog.py] Path was updated to new version: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:29.408105Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:29.408113Z :SCHEME_BOARD_POPULATOR NOTICE: [50000:7565613778101235759:4168] Ack update: ack to# [50000:7565613769511300997:56], cookie# 0, pathId# [OwnerId: 72075186224037889, LocalPathId: 1], version# 7
2025-10-26T19:25:29.408222Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:29.408242Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 274137603, Sender [50000:7565613778101235759:4168], Recipient [50000:7565613769511300997:56]: NKikimrSchemeBoard.TEvUpdateAck { Owner: 72075186224037889 Generation: 1 PathId: [OwnerId: 72075186224037889, LocalPathId: 1] Version: 7 }
2025-10-26T19:25:29.408246Z :GRPC_SERVER DEBUG: SchemeBoardUpdate /Root/test_auditlog.py
2025-10-26T19:25:29.408261Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event NSchemeBoard::NSchemeshardEvents::TEvUpdateAck
2025-10-26T19:25:29.408316Z :FLAT_TX_SCHEMESHARD INFO: Handle TEvUpdateAck, at schemeshard: 72075186224037889, msg: Owner: 72075186224037889 Generation: 1 LocalPathId: 1 Version: 7 PathOwnerId: 72075186224037889, cookie: 0
2025-10-26T19:25:29.408334Z :GRPC_SERVER DEBUG: Can't update SecurityState for /Root/test_auditlog.py - no SecurityState
2025-10-26T19:25:29.593710Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271125000, Sender [0:0:0], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:29.593745Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvWakeupToMeasureSelfResponseTime
2025-10-26T19:25:29.593783Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, received event# 271124999, Sender [50000:7565613769511300997:56], Recipient [50000:7565613769511300997:56]: NKikimr::NSchemeShard::TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:29.593797Z :FLAT_TX_SCHEMESHARD TRACE: StateWork, processing event TEvSchemeShard::TEvMeasureSelfResponseTime
2025-10-26T19:25:29.676446Z :GRPC_SERVER DEBUG: [0x61a0000a2c80] received request Name# CreateSession ok# true data# operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.676509Z :GRPC_SERVER DEBUG: [0x61a00002e280] created request Name# CreateSession
2025-10-26T19:25:29.676845Z :GRPC_SERVER DEBUG: Got grpc request# CreateSessionRequest, traceId# 01k8gzq7pcbwe5gv3n3q7vyjdg, sdkBuildInfo# ydb-python-sdk/3.12.3, state# AS_NOT_PERFORMED, database# /Root/test_auditlog.py, peer# ipv4:127.0.0.1:60616, grpcInfo# python-library grpc-python/1.54.2 grpc-c/31.0.0 (linux; chttp2), timeout# 3.008862s
2025-10-26T19:25:29.677378Z :KQP_PROXY DEBUG: TraceId: "01k8gzq7pcbwe5gv3n3q7vyjdg", Request has 3.008325s seconds to be completed
2025-10-26T19:25:29.678807Z :KQP_PROXY DEBUG: TraceId: "01k8gzq7pcbwe5gv3n3q7vyjdg", Created new session, sessionId: ydb://session/3?node_id=50000&id=ZDk0OTZjNzAtNDAyM2U2YjAtYmVhYWExYjYtYmZjMzliZjE=, workerId: [50000:7565613795281105221:4157], database: /Root/test_auditlog.py, longSession: 1, local sessions count: 2
2025-10-26T19:25:29.678889Z :KQP_PROXY DEBUG: Received create session request, trace_id: 01k8gzq7pcbwe5gv3n3q7vyjdg
2025-10-26T19:25:29.679049Z :GRPC_SERVER DEBUG: [0x61a0000a2c80] issuing response Name# CreateSession data# operation { ready: true status: SUCCESS result { type_url: "type.googleapis.com/Ydb.Table.CreateSessionResult" value: "\nQydb://session/3?node_id=50000&id=ZDk0OTZjNzAtNDAyM2U2YjAtYmVhYWExYjYtYmZjMzliZjE=" } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.679509Z :GRPC_SERVER DEBUG: [0x61a0000a2c80] finished request Name# CreateSession ok# true peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.690965Z :GRPC_SERVER DEBUG: [0x61a00016c880] received request Name# SetConfig ok# true data# operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } config: "\n---\n123metadata:\n  kind: MainConfig\n  cluster: \"\"\n  version: %s\nconfig:\n  yaml_config_enabled: true\nallowed_labels:\n  node_id:\n    type: string\n  host:\n    type: string\n  tenant:\n    type: string\nselector_config: []\n    "  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.691057Z :GRPC_SERVER DEBUG: [0x61a00002dc80] created request Name# SetConfig
2025-10-26T19:25:29.691420Z :GRPC_SERVER DEBUG: Got grpc request# SetConfigRequest, traceId# 01k8gzq7pv7929g9n8hamp1eyn, sdkBuildInfo# ydb-python-sdk/3.12.3, state# AS_NOT_PERFORMED, database# /Root/test_auditlog.py, peer# ipv4:127.0.0.1:60616, grpcInfo# python-library grpc-python/1.54.2 grpc-c/31.0.0 (linux; chttp2), timeout# 600.997293s
2025-10-26T19:25:29.698353Z :GRPC_SERVER DEBUG: [0x61a00016c880] issuing response Name# SetConfig data# operation { ready: true status: BAD_REQUEST issues { message: "ydb/library/fyamlcpp/fyamlcpp.cpp:1053: \n6:12 plain scalar cannot start with \'%\'" severity: 1 } }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.698838Z :GRPC_SERVER DEBUG: [0x61a00016c880] finished request Name# SetConfig ok# true peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.823994Z :GRPC_SERVER DEBUG: [0x61a00002dc80] received request Name# SetConfig ok# true data# operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } config: "\n---\nmetadata:\n  kind: MainConfig\n  cluster: \"\"\n  version: 0\nconfig:\n  yaml_config_enabled: true\nallowed_labels:\n  node_id:\n    type: string\n  host:\n    type: string\n  tenant:\n    type: string\nselector_config: []\n    "  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.824084Z :GRPC_SERVER DEBUG: [0x61a00002d680] created request Name# SetConfig
2025-10-26T19:25:29.824367Z :GRPC_SERVER DEBUG: Got grpc request# SetConfigRequest, traceId# 01k8gzq7v0aamwpw1qkepskevj, sdkBuildInfo# ydb-python-sdk/3.12.3, state# AS_NOT_PERFORMED, database# /Root/test_auditlog.py, peer# ipv4:127.0.0.1:60616, grpcInfo# python-library grpc-python/1.54.2 grpc-c/31.0.0 (linux; chttp2), timeout# 600.998347s
2025-10-26T19:25:29.833217Z :GRPC_SERVER DEBUG: [0x61a00002dc80] issuing response Name# SetConfig data# operation { ready: true status: SUCCESS }  peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.833639Z :GRPC_SERVER DEBUG: [0x61a00002dc80] finished request Name# SetConfig ok# true peer# ipv4:127.0.0.1:60616
2025-10-26T19:25:29.913756Z :KQP_PROXY DEBUG: Updated table service config.
2025-10-26T19:25:29.913792Z :KQP_PROXY DEBUG: Updated YQL logs priority to current level: 3
2025-10-26T19:25:29.918063Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component TX_DATASHARD has been changed from ERROR to NOTICE
2025-10-26T19:25:29.918090Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component FLAT_TX_SCHEMESHARD has been changed from TRACE to NOTICE
2025-10-26T19:25:29.918101Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component HIVE has been changed from ERROR to NOTICE
2025-10-26T19:25:29.918116Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component TX_PROXY has been changed from CRIT to NOTICE
2025-10-26T19:25:29.918128Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component TX_PROXY_SCHEME_CACHE has been changed from CRIT to NOTICE
2025-10-26T19:25:29.918138Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component GRPC_SERVER has been changed from DEBUG to NOTICE
2025-10-26T19:25:29.918147Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component GRPC_PROXY has been changed from DEBUG to NOTICE
2025-10-26T19:25:29.918171Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component CMS_TENANTS has been changed from ERROR to NOTICE
2025-10-26T19:25:29.918185Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component KQP_PROXY has been changed from DEBUG to NOTICE
2025-10-26T19:25:29.918195Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component KQP_WORKER has been changed from ERROR to NOTICE
2025-10-26T19:25:29.918206Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component KQP_GATEWAY has been changed from DEBUG to NOTICE
2025-10-26T19:25:29.918216Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component KQP_YQL has been changed from ERROR to NOTICE
2025-10-26T19:25:29.918232Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component KQP_COMPILE_ACTOR has been changed from ERROR to NOTICE
2025-10-26T19:25:29.918241Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component KQP_SESSION has been changed from ERROR to NOTICE
2025-10-26T19:25:29.918257Z :CMS_CONFIGS NOTICE: TLogSettingsConfigurator: Priority for the component PERSQUEUE_CLUSTER_TRACKER has been changed from CRIT to NOTICE
2025-10-26T19:25:30.006426Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300679:4147][/Root] Path was updated to new version: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.006452Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137709:4155][/Root] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.006518Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300707:4151][/Root] Path was updated to new version: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022340Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137709:4155][/Root] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022340Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300707:4151][/Root] Path was updated to new version: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022359Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300679:4147][/Root] Path was updated to new version: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022551Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613790986137776:4161][[OwnerId: 72075186224037889, LocalPathId: 1]] Path was updated to new version: owner# [50000:7565613790986137775:4160], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 1 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 18446744073709551615) DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022577Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613778101235765:4200][[OwnerId: 72075186232723360, LocalPathId: 2]] Path was updated to new version: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 5) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 1 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 18446744073709551615) DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022633Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300742:4169][/Root/test_auditlog.py] Path was deleted: owner# [50000:7565613769511300664:4136], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 1 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 18446744073709551615) DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022706Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300680:4148][/Root/test_auditlog.py] Path was deleted: owner# [50000:7565613769511300553:27], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 1 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 18446744073709551615) DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022711Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300708:4152][/Root/test_auditlog.py] Path was deleted: owner# [50000:7565613769511300554:28], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 1 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 18446744073709551615) DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.022902Z :GRPC_SERVER WARN: SchemeBoardDelete /Root/test_auditlog.py Strong=1
2025-10-26T19:25:30.022909Z :GRPC_SERVER WARN: SchemeBoardDelete /Root/test_auditlog.py Strong=1
2025-10-26T19:25:30.022943Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613769511300759:38][/Root/test_auditlog.py] Path was deleted: owner# [50000:7565613769511300614:9], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72075186232723360, LocalPathId: 2] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 1 Strong: 1 Version: (PathId: [OwnerId: 72075186232723360, LocalPathId: 2], Version: 18446744073709551615) DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.026651Z :SCHEME_BOARD_SUBSCRIBER NOTICE: [main][50000:7565613799576072543:4166][[OwnerId: 72075186224037889, LocalPathId: 1]] Set up state: owner# [50000:7565613769511300664:4136], state# { Deleted: 1 Strong: 1 Version: (PathId: [OwnerId: 72075186224037889, LocalPathId: 1], Version: 18446744073709551615) DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-26T19:25:30.030253Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,7) wasn't found - using supplied 72075186224037894
2025-10-26T19:25:30.030304Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037894) to Hive 72057594037968897
2025-10-26T19:25:30.030431Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,4) wasn't found - using supplied 72075186224037891
2025-10-26T19:25:30.030462Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037891) to Hive 72057594037968897
2025-10-26T19:25:30.030519Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,10) wasn't found - using supplied 72075186224037897
2025-10-26T19:25:30.030535Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037897) to Hive 72057594037968897
2025-10-26T19:25:30.030572Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,1) wasn't found - using supplied 72075186224037888
2025-10-26T19:25:30.030583Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037888) to Hive 72057594037968897
2025-10-26T19:25:30.030618Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,9) wasn't found - using supplied 72075186224037896
2025-10-26T19:25:30.030630Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037896) to Hive 72057594037968897
2025-10-26T19:25:30.030664Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,6) wasn't found - using supplied 72075186224037893
2025-10-26T19:25:30.030674Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037893) to Hive 72057594037968897
2025-10-26T19:25:30.030707Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,3) wasn't found - using supplied 72075186224037890
2025-10-26T19:25:30.030716Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037890) to Hive 72057594037968897
2025-10-26T19:25:30.030749Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,2) wasn't found - using supplied 72075186224037889
2025-10-26T19:25:30.030760Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037889) to Hive 72057594037968897
2025-10-26T19:25:30.030793Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,5) wasn't found - using supplied 72075186224037892
2025-10-26T19:25:30.030804Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037892) to Hive 72057594037968897
2025-10-26T19:25:30.030841Z :HIVE WARN: HIVE#72075186224037888 THive::TTxDeleteTablet tablet (72075186232723360,8) wasn't found - using supplied 72075186224037895
2025-10-26T19:25:30.030851Z :HIVE NOTICE: HIVE#72075186224037888 Forwarding TabletRequest(TabletID 72075186224037895) to Hive 72057594037968897
2025-10-26T19:25:30.039775Z :TABLET_MAIN NOTICE: Tablet: 72075186224037897  Type: StatisticsAggregator, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.039949Z :TABLET_MAIN NOTICE: Tablet: 72075186224037896  Type: SysViewProcessor, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.040034Z :TABLET_MAIN NOTICE: Tablet: 72075186224037889  Type: SchemeShard, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.040103Z :TABLET_MAIN NOTICE: Tablet: 72075186224037894  Type: Mediator, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.040364Z :TABLET_MAIN NOTICE: Tablet: 72075186224037891  Type: Coordinator, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.040418Z :TABLET_MAIN NOTICE: Tablet: 72075186224037888  Type: Hive, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.040464Z :TABLET_MAIN NOTICE: Tablet: 72075186224037893  Type: Mediator, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.040502Z :TABLET_MAIN NOTICE: Tablet: 72075186224037890  Type: Coordinator, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.041071Z :TABLET_MAIN NOTICE: Tablet: 72075186224037892  Type: Coordinator, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.041182Z :TABLET_MAIN NOTICE: Tablet: 72075186224037895  Type: Mediator, EReason: ReasonPill, SuggestedGeneration: 1, KnownGeneration: 1 Marker# TSYS31
2025-10-26T19:25:30.155714Z :BS_PROXY NOTICE: Group# 2181038082 HasInvalidGroupId# 0 Bootstrap -> StateEjected Marker# DSP42
2025-10-26T19:25:30.155768Z :BS_PROXY NOTICE: EnsureMonitoring Group# 2181038082 IsLimitedKeyless# 0 fullIfPossible# 0 Marker# DSP58
2025-10-26T19:25:30.158750Z :TABLET_MAIN ERROR: Tablet: 72075186224037898  Type: DataShard, EReason: ReasonBSError, SuggestedGeneration: 1, KnownGeneration: 1, Details: Created as unconfigured in error state (DSPE7). It happens when group doesn't present in BSC Marker# TSYS31
2025-10-26T19:25:30.275326Z :KQP_YQL NOTICE: TraceId: 01k8gzq88e394sgadp7rwvyqjz, SessionId: CompileActor 2025-10-26 19:25:30.275 NOTE  ydbd(pid=1862315, tid=0x00007F430AD93640) [common provider] yql_provider_gateway.cpp:21: <main>: Error: Type annotation, code: 1030
2025-10-26T19:25:30.275388Z :KQP_YQL NOTICE: TraceId: 01k8gzq88e394sgadp7rwvyqjz, SessionId: CompileActor 2025-10-26 19:25:30.275 NOTE  ydbd(pid=1862315, tid=0x00007F430AD93640) [common provider] yql_provider_gateway.cpp:21: <main>:3:16: Error: At function: KiReadTable!
2025-10-26T19:25:30.275427Z :KQP_YQL NOTICE: TraceId: 01k8gzq88e394sgadp7rwvyqjz, SessionId: CompileActor 2025-10-26 19:25:30.275 NOTE  ydbd(pid=1862315, tid=0x00007F430AD93640) [common provider] yql_provider_gateway.cpp:21: <main>:3:16: Error: Cannot find table 'db.[]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-10-26T19:25:30.276916Z :KQP_COMPILE_ACTOR ERROR: Compilation failed, self: [50000:7565613799576072722:4148], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:3:16: Error: At function: KiReadTable!
        <main>:3:16: Error: Cannot find table 'db.[]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-10-26T19:25:30.277186Z :KQP_SESSION WARN: SessionId: ydb://session/3?node_id=50000&id=ZGE0MGMyNGUtNDM4ZjFiOTQtMzY4ZmQxMC0xNTg3ODk=, ActorId: [50000:7565613799576072720:4227], ActorState: ExecuteState, TraceId: 01k8gzq88e394sgadp7rwvyqjz, ReplyQueryCompileError, status SCHEME_ERROR remove tx with tx_id: 
2025-10-26T19:25:30.277850Z :PERSQUEUE_CLUSTER_TRACKER ERROR: failed to list clusters: { Response { QueryIssues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } } TxMeta { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
2025-10-26T19:25:30.524445Z :KQP_PROXY NOTICE: KQP proxy shutdown requested.
2025-10-26T19:25:30.524489Z :GRPC_SERVER NOTICE: Stop publish endpoints for database: /Root/test_auditlog.py
2025-10-26T19:25:30.524672Z :TENANT_POOL NOTICE: Forward drain node to local.
2025-10-26T19:25:30.524760Z :LOCAL NOTICE: Forward drain node to local, hive id: 72057594037968897
2025-10-26T19:25:30.524797Z :LOCAL NOTICE: Send drain node to hive: 72057594037968897. Online tablets: 0
2025-10-26T19:25:30.533298Z :LOCAL NOTICE: Drain node result received. Online tablets: 0
2025-10-26T19:25:30.625124Z :INTERCONNECT NOTICE: Proxy [50000:7565613769511300508:1] [node 1] ICP32 transit to hold-by-error state Explanation# terminated
2025-10-26T19:25:30.625925Z :BS_NODE ERROR: {NW42@node_warden_pipe.cpp:55} Handle(TEvTabletPipe::TEvClientDestroyed) ClientId# [50000:7565613769511300673:23] ServerId# [1:7565613770846632919:176] TabletId# 72057594037932033 PipeClientId# [50000:7565613769511300673:23]
2025-10-26T19:25:30.625715Z :TX_PROXY WARN: actor# [50000:7565613769511300544:1363] HANDLE TEvClientDestroyed from tablet# 72057594046447617
2025-10-26T19:25:30.627610Z :INTERCONNECT_NETWORK NOTICE: [50000 <-> 1] connection closed by peer
