2025-11-11T14:16:25.012389Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:369: Grpc request proxy started, nodeid# 1, serve as static node
2025-11-11T14:16:25.012489Z :GRPC_SERVER CRIT: grpc_request_proxy.cpp:429: Failed to deliver subscription request to config dispatcher
2025-11-11T14:16:25.012501Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:369: Grpc request proxy started, nodeid# 1, serve as static node
2025-11-11T14:16:25.012521Z :GRPC_SERVER CRIT: grpc_request_proxy.cpp:429: Failed to deliver subscription request to config dispatcher
2025-11-11T14:16:25.012567Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-11-11T14:16:25.012573Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-11-11T14:16:25.012613Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:10455" Database: "local" TablePrefix: "RateLimiter_4e673ac9-7f3e-43a7-b319-aa27ffb719df" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-11-11T14:16:25.012731Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:188: Bootstrap
2025-11-11T14:16:25.012734Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:191: Create sdk ydb connection
2025-11-11T14:16:25.012909Z :STREAMS_STORAGE_SERVICE TRACE: storage_proxy.cpp:213: Initialize
2025-11-11T14:16:25.012966Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:534: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7571471511420030237:2063], tenant /compute
2025-11-11T14:16:25.013017Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:209: Successfully bootstrapped TStorageProxy [1:7571471511420030236:2062] with connection to localhost:10455:local
2025-11-11T14:16:25.013060Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: a1009b68-450c10ec-3e29c0a4-b2717c1a
2025-11-11T14:16:25.013062Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-11-11T14:16:25.013072Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7571471511420030242:2068]
2025-11-11T14:16:25.013153Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136751, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:25.013161Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-11-11T14:16:25.013208Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:267: Coordinator: Successfully bootstrapped coordinator, id [1:7571471511420030261:2082], NodesManagerId [0:4705493345362661702:78]
2025-11-11T14:16:25.013219Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7571471511420030262:2083] Successfully bootstrapped, local coordinator id [1:7571471511420030261:2082], tenant id compute, local mode 0, coordination node path local/row_dispatcher/compute, endpoint localhost:10455
2025-11-11T14:16:25.013742Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-11-11T14:16:25.013827Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-11-11T14:16:25.014192Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:627: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7571471511420030261:2082]
2025-11-11T14:16:25.018411Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/row_dispatcher/compute" actor
2025-11-11T14:16:25.018421Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/row_dispatcher/compute"
2025-11-11T14:16:25.018424Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/row_dispatcher/compute"
2025-11-11T14:16:25.026150Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:16:25.026178Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471511420030256:2077][/Root] Set up state: owner# [1:7571471511420030216:2052], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:16:25.026189Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471511420030257:2078][/compute] Set up state: owner# [1:7571471511420030216:2052], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:16:25.026212Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471511420030259:2080][/compute] Set up state: owner# [1:7571471511420030217:2053], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:16:25.026223Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /Root Strong=0
2025-11-11T14:16:25.026225Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /compute Strong=0
2025-11-11T14:16:25.026227Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /compute Strong=0
2025-11-11T14:16:25.026241Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471511420030258:2079][/Root] Set up state: owner# [1:7571471511420030217:2053], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:16:25.026254Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /Root Strong=0
2025-11-11T14:16:25.042238Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:499: Checkpoint storage and state storage were successfully inited
2025-11-11T14:16:25.043225Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/row_dispatcher/compute"
2025-11-11T14:16:25.043243Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/row_dispatcher/compute": { <main>: Error: Check failed: path: '/local/row_dispatcher/compute', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 29], type: EPathTypeKesus, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_kesus.cpp:339 }
2025-11-11T14:16:25.043262Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7571471511420030262:2083] Coordination node successfully created
2025-11-11T14:16:25.043265Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7571471511420030262:2083] Start session
2025-11-11T14:16:25.045825Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7571471511420030262:2083] Session successfully created
2025-11-11T14:16:25.048572Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7571471511420030262:2083] Semaphore successfully created
2025-11-11T14:16:25.048584Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7571471511420030262:2083] Try to acquire semaphore
2025-11-11T14:16:25.048673Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7571471511420030262:2083] Describe semaphore
2025-11-11T14:16:25.051957Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7571471511420030262:2083] Semaphore successfully acquired
2025-11-11T14:16:25.052009Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7571471511420030262:2083] Semaphore successfully described: coordinator id [1:7571471511420030261:2082] generation 4
2025-11-11T14:16:25.052014Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7571471511420030262:2083] Send TEvCoordinatorChanged to [1:7571471511420030237:2063], new coordinator id [1:7571471511420030261:2082], previous coordinator id [0:0:0]
2025-11-11T14:16:25.052029Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:559: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7571471511420030261:2082] generation 4
2025-11-11T14:16:25.052040Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:25.052043Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:25.052053Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:386: Coordinator: New leader [1:7571471511420030261:2082], SelfId [1:7571471511420030261:2082]
2025-11-11T14:16:25.052073Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:25.090785Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:25.090800Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:25.100999Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-11-11T14:16:26.011560Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136752, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:26.011561Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:16:26.011586Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:16:26.011598Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 1
2025-11-11T14:16:26.017681Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:26.017692Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:26.648459Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 2
2025-11-11T14:16:27.011972Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:27.011992Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:27.012015Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136753, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:27.012027Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:27.012030Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:27.012204Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:27.016183Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:27.016194Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:27.800445Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 3
2025-11-11T14:16:27.800474Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 2
2025-11-11T14:16:28.012455Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136754, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:28.016088Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:28.016107Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:28.962719Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 3
2025-11-11T14:16:29.012924Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:29.012939Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:29.012945Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136755, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:29.012953Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:29.012956Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:29.012962Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:29.113282Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:29.113300Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:29.282237Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 2
2025-11-11T14:16:30.013372Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136756, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:30.016915Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:30.016927Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:30.025623Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:16:30.033329Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 2 to DynamicNameserver
2025-11-11T14:16:30.060502Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:230: IC Peers[2]: [2, 3]
2025-11-11T14:16:30.144409Z :INTERCONNECT WARN: interconnect_handshake.cpp:543: Handshake [1:7571471532894866934:2059] [node 2] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-11-11T14:16:30.244822Z :INTERCONNECT WARN: interconnect_handshake.cpp:543: Handshake [1:7571471532894866942:2061] [node 3] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-11-11T14:16:30.486738Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:451: Coordinator: TEvCoordinatorRequest from [2:7571471531092799315:2151], test_filter_use_unsupported_predicate_input, partIds: 0
2025-11-11T14:16:30.486771Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:506: Coordinator: Send TEvCoordinatorResult to [2:7571471531092799315:2151]
2025-11-11T14:16:30.486790Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:350: Coordinator: EvNodeConnected 2
2025-11-11T14:16:30.487757Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:843: RowDispatcher: Received TEvStartSession from [2:7571471531092799315:2151], read group ptcudslmqd5q4gapo0dk, topicPath test_filter_use_unsupported_predicate_input part id 0 query id ptqudslmqcrk9dobk1fp cookie 1
2025-11-11T14:16:30.487794Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:880: RowDispatcher: Create new session: read group ptcudslmqd5q4gapo0dk topic test_filter_use_unsupported_predicate_input part id 0
2025-11-11T14:16:30.487871Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:425: TopicSession [1:7571471532894866948:2147] Bootstrap test_filter_use_unsupported_predicate_input/0, Timeout 5.000000s sec
2025-11-11T14:16:30.487888Z :FQ_ROW_DISPATCHER INFO: topic_session.cpp:777: TopicSession [1:7571471532894866948:2147] New client: read actor id [2:7571471531092799315:2151], predicate: StartsWith(`event`, "event2"), watermark expr: , offset: (empty maybe)
2025-11-11T14:16:30.487932Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:440: TTopicFormatHandler [json_each_row]: Add client with id [2:7571471531092799315:2151]
2025-11-11T14:16:30.488487Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1039: RowDispatcher: TEvTryConnect to node id 2
2025-11-11T14:16:30.488549Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:577: RowDispatcher: EvNodeConnected, node id 2
2025-11-11T14:16:30.525283Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:559: TTopicFormatHandler [json_each_row]: UpdateParser to new schema with size 2
2025-11-11T14:16:30.526204Z :FQ_ROW_DISPATCHER INFO: json_parser.cpp:363: TJsonParser: Simdjson active implementation icelake (Intel/AMD AVX512), error skip mode: 0
2025-11-11T14:16:30.526253Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:570: TTopicFormatHandler [json_each_row]: Parser was created on schema with 2 columns
2025-11-11T14:16:30.526271Z :FQ_ROW_DISPATCHER DEBUG: purecalc_filter.cpp:461: GenerateSql: Generated sql:

            PRAGMA config.flags("LLVM", "OFF");
            SELECT COALESCE(StartsWith(`event`, "event2"), FALSE) AS _filter, _offset FROM Input;
        
2025-11-11T14:16:30.526299Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:116: TTopicFilters: Create program with client id [2:7571471531092799315:2151]
2025-11-11T14:16:30.526307Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:123: TTopicFilters: Create purecalc program for query '
            PRAGMA config.flags("LLVM", "OFF");
            SELECT COALESCE(StartsWith(`event`, "event2"), FALSE) AS _filter, _offset FROM Input;
        ' (client id: [2:7571471531092799315:2151])
2025-11-11T14:16:30.526316Z :FQ_ROW_DISPATCHER TRACE: purecalc_filter.cpp:341: TProgramCompileHandler: Send compile request with id 1
2025-11-11T14:16:30.526329Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:930: TopicSession [1:7571471532894866948:2147] SendStatistics
2025-11-11T14:16:30.526347Z :FQ_ROW_DISPATCHER TRACE: compile_service.cpp:131: TPurecalcCompileService: Add to compile queue request with id 1 from [1:7571471532894866949:2147]
2025-11-11T14:16:30.526370Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1221: RowDispatcher: TEvSessionStatistic from [1:7571471532894866948:2147]
2025-11-11T14:16:30.897712Z :FQ_ROW_DISPATCHER TRACE: compile_service.cpp:52: TPurecalcCompileActor [1:7571471532894866949:2147] [id 1]: Started compile request
2025-11-11T14:16:30.952018Z :FQ_ROW_DISPATCHER TRACE: compile_service.cpp:70: TPurecalcCompileActor [1:7571471532894866949:2147] [id 1]: Compilation completed for request
2025-11-11T14:16:30.952070Z :FQ_ROW_DISPATCHER TRACE: compile_service.cpp:151: TPurecalcCompileService: Compile finished for request with id 1 from [1:7571471532894866949:2147]
2025-11-11T14:16:30.952069Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:57: TTopicFilters: Got compile response for request with id 1
2025-11-11T14:16:30.952078Z :FQ_ROW_DISPATCHER TRACE: purecalc_filter.cpp:370: TProgramCompileHandler: Program compilation finished
2025-11-11T14:16:30.952091Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:110: TTopicFilters: Start program with client id [2:7571471531092799315:2151]
2025-11-11T14:16:31.013779Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:31.013792Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:31.013802Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136757, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:31.013818Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:31.013822Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:31.013831Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:31.017555Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:31.017566Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:31.279082Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471519956666639:2063]
2025-11-11T14:16:31.279103Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471519956666639:2063]
2025-11-11T14:16:31.279112Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:350: Coordinator: EvNodeConnected 3
2025-11-11T14:16:32.014259Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136758, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:32.017973Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:32.017985Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:32.087167Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471518207897204:2063]
2025-11-11T14:16:32.087180Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471518207897204:2063]
2025-11-11T14:16:32.488342Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:930: TopicSession [1:7571471532894866948:2147] SendStatistics
2025-11-11T14:16:32.488378Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1221: RowDispatcher: TEvSessionStatistic from [1:7571471532894866948:2147]
2025-11-11T14:16:32.488708Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:949: RowDispatcher: Received TEvHeartbeat from [2:7571471531092799315:2151], part id 0 query id ptqudslmqcrk9dobk1fp
2025-11-11T14:16:33.014687Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:33.014697Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c136759, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:33.014704Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:33.014714Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:33.014718Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:33.014733Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:33.018169Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:33.018181Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:33.279942Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471519956666639:2063]
2025-11-11T14:16:33.279958Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471519956666639:2063]
2025-11-11T14:16:34.015179Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367510, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:34.018723Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:34.018740Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:34.088119Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471518207897204:2063]
2025-11-11T14:16:34.088131Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471518207897204:2063]
2025-11-11T14:16:34.489245Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:930: TopicSession [1:7571471532894866948:2147] SendStatistics
2025-11-11T14:16:34.489281Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1221: RowDispatcher: TEvSessionStatistic from [1:7571471532894866948:2147]
2025-11-11T14:16:34.489610Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:949: RowDispatcher: Received TEvHeartbeat from [2:7571471531092799315:2151], part id 0 query id ptqudslmqcrk9dobk1fp
2025-11-11T14:16:35.015997Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:35.016013Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:35.016270Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367511, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:35.016291Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:35.016295Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:35.016567Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:35.026373Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:16:35.033598Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:35.033611Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:35.041650Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 2 to DynamicNameserver
2025-11-11T14:16:35.280836Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471519956666639:2063]
2025-11-11T14:16:35.280856Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471519956666639:2063]
2025-11-11T14:16:35.952564Z :FQ_ROW_DISPATCHER INFO: topic_session.cpp:497: TopicSession [1:7571471532894866948:2147] Create topic session, Path test_filter_use_unsupported_predicate_input/0, StartingMessageTimestamp 2025-11-11T14:16:29.463000Z, BufferSize 16000000, GetConsumerMode Without
2025-11-11T14:16:35.952613Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:35.952609Z :INFO: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Starting read session
2025-11-11T14:16:35.952618Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.952616Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Starting single session
2025-11-11T14:16:35.952655Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.952653Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] In Reconnect, ReadSizeBudget = 8000000, ReadSizeServerDelta = 0
2025-11-11T14:16:35.952662Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.952661Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] New values: ReadSizeBudget = 8000000, ReadSizeServerDelta = 0
2025-11-11T14:16:35.952666Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.952665Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Reconnecting session to cluster  in 0.000000s
2025-11-11T14:16:35.952860Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:447: TopicSession [1:7571471532894866948:2147] SubscribeOnNextEvent
2025-11-11T14:16:35.952906Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.952889Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Successfully connected. Initializing session
2025-11-11T14:16:35.954332Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:35.954327Z :INFO: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Got InitResponse. ReadSessionId: _1_4_15864546173152563732_v1
2025-11-11T14:16:35.954344Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.954342Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] In ContinueReadingDataImpl, ReadSizeBudget = 8000000, ReadSizeServerDelta = 0
2025-11-11T14:16:35.954390Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.954387Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 8000000
2025-11-11T14:16:35.955804Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:540: TopicSession [1:7571471532894866948:2147] TEvPqEventsReady
2025-11-11T14:16:35.955821Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:655: TopicSession [1:7571471532894866948:2147] StartPartitionSessionEvent received
2025-11-11T14:16:35.955823Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:663: TopicSession [1:7571471532894866948:2147] Confirm StartPartitionSession with offset (NULL)
2025-11-11T14:16:35.955833Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:35.955830Z :INFO: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Confirm partition stream create. Partition stream id: 1. Cluster: "-". Topic: "test_filter_use_unsupported_predicate_input". Partition: 0. Read offset: (NULL)
2025-11-11T14:16:35.955898Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:447: TopicSession [1:7571471532894866948:2147] SubscribeOnNextEvent
2025-11-11T14:16:35.956467Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956463Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Got ReadResponse, serverBytesSize = 96, now ReadSizeBudget = 0, ReadSizeServerDelta = 7999904
2025-11-11T14:16:35.956490Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956488Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] In ContinueReadingDataImpl, ReadSizeBudget = 0, ReadSizeServerDelta = 7999904
2025-11-11T14:16:35.956521Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956519Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 1 (0-0)
2025-11-11T14:16:35.956531Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956529Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Returning serverBytesSize = 96 to budget
2025-11-11T14:16:35.956535Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956534Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] In ContinueReadingDataImpl, ReadSizeBudget = 96, ReadSizeServerDelta = 7999904
2025-11-11T14:16:35.956563Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956562Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 8000000
2025-11-11T14:16:35.956583Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:540: TopicSession [1:7571471532894866948:2147] TEvPqEventsReady
2025-11-11T14:16:35.956604Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956602Z :DEBUG: [local] Take Data. Partition 0. Read: {0, 0} (0-0)
2025-11-11T14:16:35.956617Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956615Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] The application data is transferred to the client. Number of messages 1, size 50 bytes
2025-11-11T14:16:35.956624Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:35.956622Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Returning serverBytesSize = 0 to budget
2025-11-11T14:16:35.956640Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:632: TopicSession [1:7571471532894866948:2147] Data received: Message { Data: "{"time": 102, "data": "hello2", "event": "event2"}" Information: { Offset: 0 ProducerId: "" SeqNo: 0 CreateTime: 2025-11-11T14:16:32.157000Z WriteTime: 2025-11-11T14:16:32.157000Z MessageGroupId: "" Meta: { } MessageMeta: { } } Partition session id: 1 Topic: "test_filter_use_unsupported_predicate_input" Partition: 0 }
2025-11-11T14:16:35.956642Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:685: TopicSession [1:7571471532894866948:2147] SendToParsing, messages: 1
2025-11-11T14:16:35.956646Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:417: TTopicFormatHandler [json_each_row]: Send 1 messages to parser
2025-11-11T14:16:35.956650Z :FQ_ROW_DISPATCHER TRACE: json_parser.cpp:396: TJsonParser: Add 1 messages to parse
2025-11-11T14:16:35.956727Z :FQ_ROW_DISPATCHER TRACE: json_parser.cpp:462: TJsonParser: Do parsing, first offset: 0, values:
{"time": 102, "data": "hello2", "event": "event2"}                                                                
2025-11-11T14:16:35.956734Z :FQ_ROW_DISPATCHER TRACE: json_parser.cpp:598: TJsonParser: Init parser, skipped 0, outputRowId 0 size 114
2025-11-11T14:16:35.956756Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:77: TTopicFormatHandler [json_each_row]: TParserHandler: Got parsed data, number rows: 1
2025-11-11T14:16:35.956763Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:614: TTopicFormatHandler [json_each_row]: Send 1 messages to programs, first offset: 0, last offset: 0
2025-11-11T14:16:35.956767Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:34: TTopicFilters: ProcessData for 1 clients, number rows: 1
2025-11-11T14:16:35.956772Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:223: TTopicFilters: Pass 1 rows to purecalc filter (client id: [2:7571471531092799315:2151])
2025-11-11T14:16:35.956775Z :FQ_ROW_DISPATCHER TRACE: purecalc_filter.cpp:408: TProgramRunHandler: ProcessData for 1 rows
2025-11-11T14:16:35.956811Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:292: TTopicFormatHandler [json_each_row]: TClientHandler [2:7571471531092799315:2151]: OnBatchFinish, offset: 0, number rows: 1, row size: 26, watermark: (empty maybe)
2025-11-11T14:16:35.956818Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:187: TopicSession [1:7571471532894866948:2147] AddDataToClient to [2:7571471531092799315:2151], offset: 0, number rows: 1, row size: 26, watermark: (empty maybe)
2025-11-11T14:16:35.956820Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:914: TopicSession [1:7571471532894866948:2147] Send TEvNewDataArrived to [2:7571471531092799315:2151]
2025-11-11T14:16:35.956839Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:622: TTopicFormatHandler [json_each_row]: Commit client [2:7571471531092799315:2151] offset 0
2025-11-11T14:16:35.956846Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:199: TopicSession [1:7571471532894866948:2147] UpdateClientOffset for [2:7571471531092799315:2151], new offset: 0
2025-11-11T14:16:35.956851Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:447: TopicSession [1:7571471532894866948:2147] SubscribeOnNextEvent
2025-11-11T14:16:35.956855Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1068: RowDispatcher: Forward TEvNewDataArrived from [1:7571471532894866948:2147] to [2:7571471531092799315:2151] query id ptqudslmqcrk9dobk1fp
2025-11-11T14:16:35.957249Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:927: RowDispatcher: Received TEvGetNextBatch from [2:7571471531092799315:2151] part id 0 query id ptqudslmqcrk9dobk1fp
2025-11-11T14:16:35.957274Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:569: TopicSession [1:7571471532894866948:2147] TEvGetNextBatch from [2:7571471531092799315:2151]
2025-11-11T14:16:35.957280Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:324: TTopicFormatHandler [json_each_row]: TClientHandler [2:7571471531092799315:2151]: FinishPacking, batch size: 26, number rows: 1
2025-11-11T14:16:35.957289Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:174: TTopicFormatHandler [json_each_row]: TClientHandler [2:7571471531092799315:2151]: ExtractClientData, number batches: 1
2025-11-11T14:16:35.957297Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:742: TopicSession [1:7571471532894866948:2147] SendData to [2:7571471531092799315:2151], batch size 1
2025-11-11T14:16:35.957306Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:438: TopicSession [1:7571471532894866948:2147] Skip SubscribeOnNextEvent, has ReadSession: true, IsWaitingEvents: 1
2025-11-11T14:16:35.957316Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1087: RowDispatcher: Forward TEvMessageBatch from [1:7571471532894866948:2147] to [2:7571471531092799315:2151] query id ptqudslmqcrk9dobk1fp
2025-11-11T14:16:36.017070Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367512, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:36.020684Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:36.020700Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:36.089022Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471518207897204:2063]
2025-11-11T14:16:36.089032Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471518207897204:2063]
2025-11-11T14:16:36.374133Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:988: RowDispatcher: Received TEvStopSession from [2:7571471531092799315:2151] topic test_filter_use_unsupported_predicate_input query id ptqudslmqcrk9dobk1fp
2025-11-11T14:16:36.374159Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:1003: RowDispatcher: DeleteConsumer, readActorId [2:7571471531092799315:2151] query id ptqudslmqcrk9dobk1fp, partitions size 1
2025-11-11T14:16:36.374179Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:1023: RowDispatcher: Session is not used, sent TEvPoisonPill to [1:7571471532894866948:2147]
2025-11-11T14:16:36.374206Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:809: TopicSession [1:7571471532894866948:2147] TEvStopSession from [2:7571471531092799315:2151] topicPath test_filter_use_unsupported_predicate_input clients count 1
2025-11-11T14:16:36.374214Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:477: TTopicFormatHandler [json_each_row]: Remove client with id [2:7571471531092799315:2151]
2025-11-11T14:16:36.374217Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:94: TTopicFilters: Remove program with client id [2:7571471531092799315:2151]
2025-11-11T14:16:36.374403Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:559: TTopicFormatHandler [json_each_row]: UpdateParser to new schema with size 0
2025-11-11T14:16:36.374412Z :FQ_ROW_DISPATCHER INFO: format_handler.cpp:584: TTopicFormatHandler [json_each_row]: No columns to parse, reset parser
2025-11-11T14:16:36.374461Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:902: TopicSession [1:7571471532894866948:2147] Close read session
2025-11-11T14:16:36.374476Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:36.374472Z :INFO: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Closing read session. Close timeout: 0.000000s
2025-11-11T14:16:36.374488Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:36.374486Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test_filter_use_unsupported_predicate_input:0:1:0:0
2025-11-11T14:16:36.374496Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:36.374494Z :INFO: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Counters: { Errors: 0 CurrentSessionLifetimeMs: 421 BytesRead: 50 MessagesRead: 1 BytesReadCompressed: 50 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-11-11T14:16:36.374511Z :YDB_SDK NOTICE: actor.cpp:37: 2025-11-11T14:16:36.374509Z :NOTICE: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Close with zero timeout " }
2025-11-11T14:16:36.374523Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:16:36.374522Z :DEBUG: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] [] Abort session to cluster
2025-11-11T14:16:36.374679Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:36.374677Z :INFO: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Closing read session. Close timeout: 0.000000s
2025-11-11T14:16:36.374689Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:36.374687Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test_filter_use_unsupported_predicate_input:0:1:0:0
2025-11-11T14:16:36.374692Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:16:36.374691Z :INFO: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Counters: { Errors: 0 CurrentSessionLifetimeMs: 422 BytesRead: 50 MessagesRead: 1 BytesReadCompressed: 50 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-11-11T14:16:36.374701Z :YDB_SDK NOTICE: actor.cpp:37: 2025-11-11T14:16:36.374699Z :NOTICE: [local] [local] [a65aa761-d0a47ab9-1766c68e-c0e0c182] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
2025-11-11T14:16:36.374730Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:438: TopicSession [1:7571471532894866948:2147] Skip SubscribeOnNextEvent, has ReadSession: false, IsWaitingEvents: 1
2025-11-11T14:16:36.374741Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:430: TopicSession [1:7571471532894866948:2147] PassAway
2025-11-11T14:16:36.491156Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1046: RowDispatcher: No consumer with EventQueueId = 0
2025-11-11T14:16:37.016483Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:37.016510Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:37.016519Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:37.016523Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:37.016552Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:37.017507Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367513, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:37.025668Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:37.025695Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:37.281738Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471519956666639:2063]
2025-11-11T14:16:37.281752Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471519956666639:2063]
2025-11-11T14:16:38.017979Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367514, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:38.022971Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:38.022994Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:38.090127Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471518207897204:2063]
2025-11-11T14:16:38.090142Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471518207897204:2063]
2025-11-11T14:16:39.018239Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:39.018264Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:39.018468Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:39.018480Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:39.018491Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:39.018635Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367515, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:39.025948Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:39.025963Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:39.282657Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471519956666639:2063]
2025-11-11T14:16:39.282675Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471519956666639:2063]
2025-11-11T14:16:40.019065Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367516, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:40.025770Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:40.025792Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:40.027019Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:16:40.036509Z :YQL_NODES_MANAGER ERROR: nodes_manager.cpp:395: ydb/core/fq/libs/actors/nodes_manager.cpp:353: TRANSPORT_UNAVAILABLE
<main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:40.091184Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471518207897204:2063]
2025-11-11T14:16:40.091197Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471518207897204:2063]
2025-11-11T14:16:41.018297Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:41.018320Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:41.018336Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:41.018339Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:41.018345Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:41.019305Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367517, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:41.024409Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:41.024430Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:41.286392Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471519956666639:2063]
2025-11-11T14:16:41.286410Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471519956666639:2063]
2025-11-11T14:16:42.019768Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367518, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:42.026624Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:42.026644Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:42.091705Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471518207897204:2063]
2025-11-11T14:16:42.091723Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471518207897204:2063]
2025-11-11T14:16:43.019151Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471511420030261:2082]
2025-11-11T14:16:43.019176Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:43.019183Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471511420030237:2063]
2025-11-11T14:16:43.019187Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471511420030237:2063]
2025-11-11T14:16:43.019199Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:43.020200Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3aa28280-a7078e54-c38306e2-c9c1367519, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:16:43.025146Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:43.025169Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:20526: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20526
2025-11-11T14:16:43.286510Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471519956666639:2063]
2025-11-11T14:16:43.286537Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471519956666639:2063]
2025-11-11T14:16:43.845817Z :INTERCONNECT_NETWORK NOTICE: interconnect_tcp_input_session.cpp:715: [1 <-> 3] connection closed by peer
2025-11-11T14:16:43.846291Z :INTERCONNECT_NETWORK NOTICE: interconnect_handshake.cpp:1581: [1 <-> 3] network-related error occured on handshake: outgoing handshake Peer# ghrun-vb3yoermsa(127.0.1.1:17517) Socket error# Connection reset by peer state# SendExRequestHeader processed# 0 remain# 8
2025-11-11T14:16:43.846660Z :INTERCONNECT_NETWORK NOTICE: interconnect_handshake.cpp:1581: [1 <-> 3] network-related error occured on handshake: outgoing handshake Peer# ghrun-vb3yoermsa(127.0.1.1:17517) Couldn't connect to any resolved address
2025-11-11T14:16:43.846812Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:356: Coordinator: TEvNodeDisconnected, node id 3
2025-11-11T14:16:43.855471Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:587: RowDispatcher: TEvNodeDisconnected, node id 2
2025-11-11T14:16:43.855487Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:356: Coordinator: TEvNodeDisconnected, node id 2
2025-11-11T14:16:43.855802Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1039: RowDispatcher: TEvTryConnect to node id 2
2025-11-11T14:16:43.855852Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:587: RowDispatcher: TEvNodeDisconnected, node id 2
