2025-11-11T14:17:15.131469Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:369: Grpc request proxy started, nodeid# 1, serve as static node
2025-11-11T14:17:15.131524Z :GRPC_SERVER CRIT: grpc_request_proxy.cpp:429: Failed to deliver subscription request to config dispatcher
2025-11-11T14:17:15.131529Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:369: Grpc request proxy started, nodeid# 1, serve as static node
2025-11-11T14:17:15.131543Z :GRPC_SERVER CRIT: grpc_request_proxy.cpp:429: Failed to deliver subscription request to config dispatcher
2025-11-11T14:17:15.131722Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-11-11T14:17:15.131727Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-11-11T14:17:15.131754Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:188: Bootstrap
2025-11-11T14:17:15.131758Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:191: Create sdk ydb connection
2025-11-11T14:17:15.131769Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:20405" Database: "local" TablePrefix: "RateLimiter_b62fa318-adc1-42c5-98e8-a389e76eac93" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-11-11T14:17:15.132118Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:534: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7571471728352381249:2063], tenant /compute
2025-11-11T14:17:15.132172Z :STREAMS_STORAGE_SERVICE TRACE: storage_proxy.cpp:213: Initialize
2025-11-11T14:17:15.132212Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: cfaad67f-41ee6372-7bc0c3b5-95b7adf1
2025-11-11T14:17:15.132214Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-11-11T14:17:15.132229Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7571471728352381254:2068]
2025-11-11T14:17:15.132296Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:209: Successfully bootstrapped TStorageProxy [1:7571471728352381248:2062] with connection to localhost:20405:local
2025-11-11T14:17:15.133818Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf1, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:15.133826Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-11-11T14:17:15.133864Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:267: Coordinator: Successfully bootstrapped coordinator, id [1:7571471728352381289:2082], NodesManagerId [0:4705493345362661702:78]
2025-11-11T14:17:15.133874Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7571471728352381290:2083] Successfully bootstrapped, local coordinator id [1:7571471728352381289:2082], tenant id compute, local mode 0, coordination node path local/row_dispatcher/compute, endpoint localhost:20405
2025-11-11T14:17:15.134135Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-11-11T14:17:15.134503Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:627: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7571471728352381289:2082]
2025-11-11T14:17:15.134738Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:17:15.134748Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-11-11T14:17:15.135566Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/row_dispatcher/compute" actor
2025-11-11T14:17:15.135575Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/row_dispatcher/compute"
2025-11-11T14:17:15.135578Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/row_dispatcher/compute"
2025-11-11T14:17:15.135968Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471728352381268:2077][/Root] Set up state: owner# [1:7571471728352381228:2052], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:17:15.135970Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471728352381270:2079][/Root] Set up state: owner# [1:7571471728352381229:2053], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:17:15.135970Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471728352381269:2078][/compute] Set up state: owner# [1:7571471728352381228:2052], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:17:15.135986Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /Root Strong=0
2025-11-11T14:17:15.135987Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /compute Strong=0
2025-11-11T14:17:15.135989Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /Root Strong=0
2025-11-11T14:17:15.136000Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571471728352381271:2080][/compute] Set up state: owner# [1:7571471728352381229:2053], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-11T14:17:15.136010Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /compute Strong=0
2025-11-11T14:17:15.140546Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/row_dispatcher/compute"
2025-11-11T14:17:15.140565Z :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:17:15.140579Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7571471728352381290:2083] Coordination node successfully created
2025-11-11T14:17:15.140581Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7571471728352381290:2083] Start session
2025-11-11T14:17:15.142886Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7571471728352381290:2083] Session successfully created
2025-11-11T14:17:15.145099Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7571471728352381290:2083] Semaphore successfully created
2025-11-11T14:17:15.145106Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7571471728352381290:2083] Try to acquire semaphore
2025-11-11T14:17:15.145260Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7571471728352381290:2083] Describe semaphore
2025-11-11T14:17:15.146095Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:499: Checkpoint storage and state storage were successfully inited
2025-11-11T14:17:15.146893Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7571471728352381290:2083] Semaphore successfully acquired
2025-11-11T14:17:15.147006Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7571471728352381290:2083] Semaphore successfully described: coordinator id [1:7571471728352381289:2082] generation 4
2025-11-11T14:17:15.147019Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7571471728352381290:2083] Send TEvCoordinatorChanged to [1:7571471728352381249:2063], new coordinator id [1:7571471728352381289:2082], previous coordinator id [0:0:0]
2025-11-11T14:17:15.147038Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:559: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7571471728352381289:2082] generation 4
2025-11-11T14:17:15.147055Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:15.147070Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:15.147083Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:386: Coordinator: New leader [1:7571471728352381289:2082], SelfId [1:7571471728352381289:2082]
2025-11-11T14:17:15.147087Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:15.191633Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:15.191651Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:15.210256Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-11-11T14:17:16.131386Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf2, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:16.131390Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:17:16.131399Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:17:16.131409Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 1
2025-11-11T14:17:16.135537Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:16.135549Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:16.863572Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 2
2025-11-11T14:17:17.131818Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:17.131825Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf3, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:17.131832Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:17.131846Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:17.131850Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:17.131866Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:17.135492Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:17.135506Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:17.306929Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 2
2025-11-11T14:17:17.306947Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 3
2025-11-11T14:17:17.752365Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 3
2025-11-11T14:17:18.132293Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf4, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:18.136082Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:18.136098Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:18.219311Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 2
2025-11-11T14:17:18.915639Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 3
2025-11-11T14:17:19.132718Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:19.132723Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf5, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:19.132734Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:19.132746Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:19.132749Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:19.132755Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:19.136379Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:19.136393Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:19.422499Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:61:  ICN06 Unknown nodeId: 2
2025-11-11T14:17:20.131136Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:17:20.133142Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf6, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:20.135960Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:20.135971Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:20.138623Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 2 to DynamicNameserver
2025-11-11T14:17:20.198471Z :INTERCONNECT WARN: interconnect_handshake.cpp:543: Handshake [1:7571471749827217941:2060] [node 3] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-11-11T14:17:20.424590Z :INTERCONNECT WARN: interconnect_handshake.cpp:543: Handshake [1:7571471749827217955:2062] [node 2] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-11-11T14:17:20.425640Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:451: Coordinator: TEvCoordinatorRequest from [2:7571471734250156757:2151], test_nested_types_without_predicate_input, partIds: 0
2025-11-11T14:17:20.425672Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:506: Coordinator: Send TEvCoordinatorResult to [2:7571471734250156757:2151]
2025-11-11T14:17:20.425685Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:350: Coordinator: EvNodeConnected 2
2025-11-11T14:17:20.426730Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:843: RowDispatcher: Received TEvStartSession from [2:7571471734250156757:2151], read group ptcudslmouehme3t8b9f, topicPath test_nested_types_without_predicate_input part id 0 query id ptqudslmou3cu6fl01mh cookie 1
2025-11-11T14:17:20.426764Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:880: RowDispatcher: Create new session: read group ptcudslmouehme3t8b9f topic test_nested_types_without_predicate_input part id 0
2025-11-11T14:17:20.426830Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:425: TopicSession [1:7571471749827217961:2149] Bootstrap test_nested_types_without_predicate_input/0, Timeout 5.000000s sec
2025-11-11T14:17:20.426840Z :FQ_ROW_DISPATCHER INFO: topic_session.cpp:777: TopicSession [1:7571471749827217961:2149] New client: read actor id [2:7571471734250156757:2151], predicate: , watermark expr: , offset: (empty maybe)
2025-11-11T14:17:20.426881Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:440: TTopicFormatHandler [json_each_row]: Add client with id [2:7571471734250156757:2151]
2025-11-11T14:17:20.427065Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1039: RowDispatcher: TEvTryConnect to node id 2
2025-11-11T14:17:20.427103Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:577: RowDispatcher: EvNodeConnected, node id 2
2025-11-11T14:17:20.471371Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:559: TTopicFormatHandler [json_each_row]: UpdateParser to new schema with size 1
2025-11-11T14:17:20.472463Z :FQ_ROW_DISPATCHER INFO: json_parser.cpp:363: TJsonParser: Simdjson active implementation icelake (Intel/AMD AVX512), error skip mode: 0
2025-11-11T14:17:20.472518Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:570: TTopicFormatHandler [json_each_row]: Parser was created on schema with 1 columns
2025-11-11T14:17:20.472530Z :FQ_ROW_DISPATCHER TRACE: purecalc_filter.cpp:439: GenerateSql: No sql was generated
2025-11-11T14:17:20.472536Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:116: TTopicFilters: Create program with client id [2:7571471734250156757:2151]
2025-11-11T14:17:20.472546Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:110: TTopicFilters: Start program with client id [2:7571471734250156757:2151]
2025-11-11T14:17:20.472554Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:930: TopicSession [1:7571471749827217961:2149] SendStatistics
2025-11-11T14:17:20.472593Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1221: RowDispatcher: TEvSessionStatistic from [1:7571471749827217961:2149]
2025-11-11T14:17:20.584401Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471729955189253:2063]
2025-11-11T14:17:20.584419Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471729955189253:2063]
2025-11-11T14:17:21.088350Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471735474982228:2063]
2025-11-11T14:17:21.088369Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471735474982228:2063]
2025-11-11T14:17:21.088380Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:350: Coordinator: EvNodeConnected 3
2025-11-11T14:17:21.133628Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf7, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:21.133628Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:21.133637Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:21.133667Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:21.133673Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:21.133690Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:230: IC Peers[2]: [2, 3]
2025-11-11T14:17:21.133699Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:21.137192Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:21.137204Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:22.134067Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf8, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:22.137717Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:22.137736Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:22.426959Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:930: TopicSession [1:7571471749827217961:2149] SendStatistics
2025-11-11T14:17:22.427002Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1221: RowDispatcher: TEvSessionStatistic from [1:7571471749827217961:2149]
2025-11-11T14:17:22.427588Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:949: RowDispatcher: Received TEvHeartbeat from [2:7571471734250156757:2151], part id 0 query id ptqudslmou3cu6fl01mh
2025-11-11T14:17:22.585341Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471729955189253:2063]
2025-11-11T14:17:22.585353Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471729955189253:2063]
2025-11-11T14:17:23.089220Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471735474982228:2063]
2025-11-11T14:17:23.089234Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471735474982228:2063]
2025-11-11T14:17:23.134535Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:23.134536Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf9, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:23.134557Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:23.134592Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:23.134595Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:23.134601Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:23.138846Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:23.138860Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:24.134972Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf10, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:24.138731Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:24.138745Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:24.427793Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:930: TopicSession [1:7571471749827217961:2149] SendStatistics
2025-11-11T14:17:24.427831Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1221: RowDispatcher: TEvSessionStatistic from [1:7571471749827217961:2149]
2025-11-11T14:17:24.428526Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:949: RowDispatcher: Received TEvHeartbeat from [2:7571471734250156757:2151], part id 0 query id ptqudslmou3cu6fl01mh
2025-11-11T14:17:24.586179Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471729955189253:2063]
2025-11-11T14:17:24.586204Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471729955189253:2063]
2025-11-11T14:17:25.090124Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471735474982228:2063]
2025-11-11T14:17:25.090136Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471735474982228:2063]
2025-11-11T14:17:25.131335Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:17:25.135402Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:25.135409Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf11, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:25.135421Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:25.135446Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:25.135453Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:25.135474Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:25.138371Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:25.138383Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:25.139417Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 2 to DynamicNameserver
2025-11-11T14:17:25.472320Z :FQ_ROW_DISPATCHER INFO: topic_session.cpp:497: TopicSession [1:7571471749827217961:2149] Create topic session, Path test_nested_types_without_predicate_input/0, StartingMessageTimestamp 2025-11-11T14:17:17.940000Z, BufferSize 16000000, GetConsumerMode Without
2025-11-11T14:17:25.472367Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.472363Z :INFO: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Starting read session
2025-11-11T14:17:25.472385Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.472382Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Starting single session
2025-11-11T14:17:25.472434Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.472431Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] In Reconnect, ReadSizeBudget = 8000000, ReadSizeServerDelta = 0
2025-11-11T14:17:25.472442Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.472441Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] New values: ReadSizeBudget = 8000000, ReadSizeServerDelta = 0
2025-11-11T14:17:25.472448Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.472445Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Reconnecting session to cluster  in 0.000000s
2025-11-11T14:17:25.472685Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:447: TopicSession [1:7571471749827217961:2149] SubscribeOnNextEvent
2025-11-11T14:17:25.472786Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.472769Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Successfully connected. Initializing session
2025-11-11T14:17:25.474454Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.474445Z :INFO: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Got InitResponse. ReadSessionId: _1_4_7166874642871742880_v1
2025-11-11T14:17:25.474466Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.474463Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] In ContinueReadingDataImpl, ReadSizeBudget = 8000000, ReadSizeServerDelta = 0
2025-11-11T14:17:25.474548Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.474545Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 8000000
2025-11-11T14:17:25.475989Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:540: TopicSession [1:7571471749827217961:2149] TEvPqEventsReady
2025-11-11T14:17:25.476011Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:655: TopicSession [1:7571471749827217961:2149] StartPartitionSessionEvent received
2025-11-11T14:17:25.476015Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:663: TopicSession [1:7571471749827217961:2149] Confirm StartPartitionSession with offset (NULL)
2025-11-11T14:17:25.476027Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.476023Z :INFO: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Confirm partition stream create. Partition stream id: 1. Cluster: "-". Topic: "test_nested_types_without_predicate_input". Partition: 0. Read offset: (NULL)
2025-11-11T14:17:25.476102Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:447: TopicSession [1:7571471749827217961:2149] SubscribeOnNextEvent
2025-11-11T14:17:25.476702Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476695Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Got ReadResponse, serverBytesSize = 206, now ReadSizeBudget = 0, ReadSizeServerDelta = 7999794
2025-11-11T14:17:25.476730Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476728Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] In ContinueReadingDataImpl, ReadSizeBudget = 0, ReadSizeServerDelta = 7999794
2025-11-11T14:17:25.476786Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476783Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 1 (0-1)
2025-11-11T14:17:25.476797Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476795Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Returning serverBytesSize = 206 to budget
2025-11-11T14:17:25.476801Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476799Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] In ContinueReadingDataImpl, ReadSizeBudget = 206, ReadSizeServerDelta = 7999794
2025-11-11T14:17:25.476841Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476839Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 8000000
2025-11-11T14:17:25.476874Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:540: TopicSession [1:7571471749827217961:2149] TEvPqEventsReady
2025-11-11T14:17:25.476897Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476894Z :DEBUG: [local] Take Data. Partition 0. Read: {0, 0} (0-0)
2025-11-11T14:17:25.476908Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476907Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 0} (1-1)
2025-11-11T14:17:25.476919Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476917Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] The application data is transferred to the client. Number of messages 2, size 116 bytes
2025-11-11T14:17:25.476929Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.476928Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Returning serverBytesSize = 0 to budget
2025-11-11T14:17:25.476948Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:632: TopicSession [1:7571471749827217961:2149] Data received: Message { Data: "{"time": 101, "data": {"key": "value"}, "event": "event1"}" Information: { Offset: 0 ProducerId: "" SeqNo: 0 CreateTime: 2025-11-11T14:17:21.770000Z WriteTime: 2025-11-11T14:17:21.769000Z MessageGroupId: "" Meta: { } MessageMeta: { } } Partition session id: 1 Topic: "test_nested_types_without_predicate_input" Partition: 0 }
2025-11-11T14:17:25.476953Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:632: TopicSession [1:7571471749827217961:2149] Data received: Message { Data: "{"time": 102, "data": ["key1", "key2"], "event": "event2"}" Information: { Offset: 1 ProducerId: "" SeqNo: 0 CreateTime: 2025-11-11T14:17:21.770000Z WriteTime: 2025-11-11T14:17:21.774000Z MessageGroupId: "" Meta: { } MessageMeta: { } } Partition session id: 1 Topic: "test_nested_types_without_predicate_input" Partition: 0 }
2025-11-11T14:17:25.476955Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:685: TopicSession [1:7571471749827217961:2149] SendToParsing, messages: 2
2025-11-11T14:17:25.476958Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:417: TTopicFormatHandler [json_each_row]: Send 2 messages to parser
2025-11-11T14:17:25.476961Z :FQ_ROW_DISPATCHER TRACE: json_parser.cpp:396: TJsonParser: Add 2 messages to parse
2025-11-11T14:17:25.476969Z :FQ_ROW_DISPATCHER TRACE: json_parser.cpp:462: TJsonParser: Do parsing, first offset: 0, values:
{"time": 101, "data": {"key": "value"}, "event": "event1"}{"time": 102, "data": ["key1", "key2"], "event": "event2"}                                                                
2025-11-11T14:17:25.476972Z :FQ_ROW_DISPATCHER TRACE: json_parser.cpp:598: TJsonParser: Init parser, skipped 0, outputRowId 0 size 180
2025-11-11T14:17:25.477014Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:77: TTopicFormatHandler [json_each_row]: TParserHandler: Got parsed data, number rows: 2
2025-11-11T14:17:25.477021Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:614: TTopicFormatHandler [json_each_row]: Send 2 messages to programs, first offset: 0, last offset: 1
2025-11-11T14:17:25.477025Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:34: TTopicFilters: ProcessData for 1 clients, number rows: 2
2025-11-11T14:17:25.477030Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:223: TTopicFilters: Pass 2 rows to purecalc filter (client id: [2:7571471734250156757:2151])
2025-11-11T14:17:25.477032Z :FQ_ROW_DISPATCHER TRACE: purecalc_filter.cpp:408: TProgramRunHandler: ProcessData for 2 rows
2025-11-11T14:17:25.477034Z :FQ_ROW_DISPATCHER TRACE: purecalc_filter.cpp:411: TProgramRunHandler: Add 2 rows to client [2:7571471734250156757:2151] without processing
2025-11-11T14:17:25.477045Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:292: TTopicFormatHandler [json_each_row]: TClientHandler [2:7571471734250156757:2151]: OnBatchFinish, offset: 0, number rows: 1, row size: 28, watermark: (empty maybe)
2025-11-11T14:17:25.477047Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:187: TopicSession [1:7571471749827217961:2149] AddDataToClient to [2:7571471734250156757:2151], offset: 0, number rows: 1, row size: 28, watermark: (empty maybe)
2025-11-11T14:17:25.477049Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:914: TopicSession [1:7571471749827217961:2149] Send TEvNewDataArrived to [2:7571471734250156757:2151]
2025-11-11T14:17:25.477059Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:292: TTopicFormatHandler [json_each_row]: TClientHandler [2:7571471734250156757:2151]: OnBatchFinish, offset: 1, number rows: 1, row size: 20, watermark: (empty maybe)
2025-11-11T14:17:25.477061Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:187: TopicSession [1:7571471749827217961:2149] AddDataToClient to [2:7571471734250156757:2151], offset: 1, number rows: 1, row size: 20, watermark: (empty maybe)
2025-11-11T14:17:25.477064Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:622: TTopicFormatHandler [json_each_row]: Commit client [2:7571471734250156757:2151] offset 1
2025-11-11T14:17:25.477067Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:199: TopicSession [1:7571471749827217961:2149] UpdateClientOffset for [2:7571471734250156757:2151], new offset: 1
2025-11-11T14:17:25.477074Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:447: TopicSession [1:7571471749827217961:2149] SubscribeOnNextEvent
2025-11-11T14:17:25.477080Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1068: RowDispatcher: Forward TEvNewDataArrived from [1:7571471749827217961:2149] to [2:7571471734250156757:2151] query id ptqudslmou3cu6fl01mh
2025-11-11T14:17:25.477535Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:927: RowDispatcher: Received TEvGetNextBatch from [2:7571471734250156757:2151] part id 0 query id ptqudslmou3cu6fl01mh
2025-11-11T14:17:25.477559Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:569: TopicSession [1:7571471749827217961:2149] TEvGetNextBatch from [2:7571471734250156757:2151]
2025-11-11T14:17:25.477566Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:324: TTopicFormatHandler [json_each_row]: TClientHandler [2:7571471734250156757:2151]: FinishPacking, batch size: 48, number rows: 2
2025-11-11T14:17:25.477574Z :FQ_ROW_DISPATCHER TRACE: format_handler.cpp:174: TTopicFormatHandler [json_each_row]: TClientHandler [2:7571471734250156757:2151]: ExtractClientData, number batches: 1
2025-11-11T14:17:25.477581Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:742: TopicSession [1:7571471749827217961:2149] SendData to [2:7571471734250156757:2151], batch size 1
2025-11-11T14:17:25.477590Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:438: TopicSession [1:7571471749827217961:2149] Skip SubscribeOnNextEvent, has ReadSession: true, IsWaitingEvents: 1
2025-11-11T14:17:25.477597Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1087: RowDispatcher: Forward TEvMessageBatch from [1:7571471749827217961:2149] to [2:7571471734250156757:2151] query id ptqudslmou3cu6fl01mh
2025-11-11T14:17:25.924228Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:988: RowDispatcher: Received TEvStopSession from [2:7571471734250156757:2151] topic test_nested_types_without_predicate_input query id ptqudslmou3cu6fl01mh
2025-11-11T14:17:25.924250Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:1003: RowDispatcher: DeleteConsumer, readActorId [2:7571471734250156757:2151] query id ptqudslmou3cu6fl01mh, partitions size 1
2025-11-11T14:17:25.924275Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:1023: RowDispatcher: Session is not used, sent TEvPoisonPill to [1:7571471749827217961:2149]
2025-11-11T14:17:25.924290Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:809: TopicSession [1:7571471749827217961:2149] TEvStopSession from [2:7571471734250156757:2151] topicPath test_nested_types_without_predicate_input clients count 1
2025-11-11T14:17:25.924305Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:477: TTopicFormatHandler [json_each_row]: Remove client with id [2:7571471734250156757:2151]
2025-11-11T14:17:25.924308Z :FQ_ROW_DISPATCHER TRACE: filters_set.cpp:94: TTopicFilters: Remove program with client id [2:7571471734250156757:2151]
2025-11-11T14:17:25.924328Z :FQ_ROW_DISPATCHER DEBUG: format_handler.cpp:559: TTopicFormatHandler [json_each_row]: UpdateParser to new schema with size 0
2025-11-11T14:17:25.924335Z :FQ_ROW_DISPATCHER INFO: format_handler.cpp:584: TTopicFormatHandler [json_each_row]: No columns to parse, reset parser
2025-11-11T14:17:25.924457Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:902: TopicSession [1:7571471749827217961:2149] Close read session
2025-11-11T14:17:25.924483Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.924479Z :INFO: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Closing read session. Close timeout: 0.000000s
2025-11-11T14:17:25.924501Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.924499Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test_nested_types_without_predicate_input:0:1:1:0
2025-11-11T14:17:25.924512Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.924511Z :INFO: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Counters: { Errors: 0 CurrentSessionLifetimeMs: 452 BytesRead: 116 MessagesRead: 2 BytesReadCompressed: 116 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-11-11T14:17:25.924529Z :YDB_SDK NOTICE: actor.cpp:37: 2025-11-11T14:17:25.924527Z :NOTICE: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Close with zero timeout " }
2025-11-11T14:17:25.924539Z :YDB_SDK DEBUG: actor.cpp:37: 2025-11-11T14:17:25.924537Z :DEBUG: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] [] Abort session to cluster
2025-11-11T14:17:25.924718Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.924715Z :INFO: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Closing read session. Close timeout: 0.000000s
2025-11-11T14:17:25.924726Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.924725Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test_nested_types_without_predicate_input:0:1:1:0
2025-11-11T14:17:25.924730Z :YDB_SDK INFO: actor.cpp:37: 2025-11-11T14:17:25.924729Z :INFO: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Counters: { Errors: 0 CurrentSessionLifetimeMs: 452 BytesRead: 116 MessagesRead: 2 BytesReadCompressed: 116 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-11-11T14:17:25.924739Z :YDB_SDK NOTICE: actor.cpp:37: 2025-11-11T14:17:25.924737Z :NOTICE: [local] [local] [53eca9f7-7fa9dc9d-fe340b1d-318ebd0f] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
2025-11-11T14:17:25.924782Z :FQ_ROW_DISPATCHER TRACE: topic_session.cpp:438: TopicSession [1:7571471749827217961:2149] Skip SubscribeOnNextEvent, has ReadSession: false, IsWaitingEvents: 1
2025-11-11T14:17:25.924800Z :FQ_ROW_DISPATCHER DEBUG: topic_session.cpp:430: TopicSession [1:7571471749827217961:2149] PassAway
2025-11-11T14:17:26.135844Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf12, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:26.139675Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:26.139689Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:17:26.429732Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1046: RowDispatcher: No consumer with EventQueueId = 0
2025-11-11T14:17:26.587092Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471729955189253:2063]
2025-11-11T14:17:26.587108Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471729955189253:2063]
2025-11-11T14:17:27.091049Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471735474982228:2063]
2025-11-11T14:17:27.091064Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471735474982228:2063]
2025-11-11T14:17:27.136319Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:27.136330Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:27.136374Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf13, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:27.136402Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:27.136406Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:27.136530Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:27.144179Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:27.144204Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:28.136769Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf14, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:28.141970Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:28.141995Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:28.587956Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471729955189253:2063]
2025-11-11T14:17:28.587971Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471729955189253:2063]
2025-11-11T14:17:29.091922Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471735474982228:2063]
2025-11-11T14:17:29.091937Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471735474982228:2063]
2025-11-11T14:17:29.137225Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:29.137230Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf15, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:29.137240Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:29.137257Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:29.137263Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:29.137479Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:29.142303Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:29.142322Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:30.131519Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:17:30.137631Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf16, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:30.138299Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:30.138317Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:30.141736Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:30.588874Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471729955189253:2063]
2025-11-11T14:17:30.588889Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471729955189253:2063]
2025-11-11T14:17:31.092834Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471735474982228:2063]
2025-11-11T14:17:31.092849Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471735474982228:2063]
2025-11-11T14:17:31.138146Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:31.138164Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:31.138176Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf17, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:31.138196Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:31.138201Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:31.139235Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:31.145751Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:31.145771Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:32.138549Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf18, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:32.145674Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:32.145692Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:32.589822Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [2:7571471729955189253:2063]
2025-11-11T14:17:32.589848Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [2:7571471729955189253:2063]
2025-11-11T14:17:33.093925Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [3:7571471735474982228:2063]
2025-11-11T14:17:33.093940Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [3:7571471735474982228:2063]
2025-11-11T14:17:33.139057Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471728352381289:2082]
2025-11-11T14:17:33.139059Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: d98beb5f-1e3ea8bc-304707b7-8a569baf19, Host: ghrun-vb3yoermsa, Tenant: /compute
2025-11-11T14:17:33.139066Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:17:33.139087Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471728352381249:2063]
2025-11-11T14:17:33.139092Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471728352381249:2063]
2025-11-11T14:17:33.139293Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:17:33.149545Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:17:33.149562Z :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:20078: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:20078
2025-11-11T14:17:33.414141Z :INTERCONNECT_NETWORK NOTICE: interconnect_tcp_input_session.cpp:715: [1 <-> 3] connection closed by peer
2025-11-11T14:17:33.414889Z :INTERCONNECT_NETWORK NOTICE: interconnect_handshake.cpp:1581: [1 <-> 3] network-related error occured on handshake: outgoing handshake Peer# ghrun-vb3yoermsa(127.0.1.1:9242) Couldn't connect to any resolved address
2025-11-11T14:17:33.415074Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:356: Coordinator: TEvNodeDisconnected, node id 3
2025-11-11T14:17:33.425142Z :INTERCONNECT_NETWORK NOTICE: interconnect_handshake.cpp:1581: [1 <-> 3] network-related error occured on handshake: outgoing handshake Peer# ghrun-vb3yoermsa(127.0.1.1:9242) Couldn't connect to any resolved address
2025-11-11T14:17:33.431711Z :INTERCONNECT_NETWORK NOTICE: interconnect_tcp_input_session.cpp:715: [1 <-> 2] connection closed by peer
2025-11-11T14:17:33.431962Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:587: RowDispatcher: TEvNodeDisconnected, node id 2
2025-11-11T14:17:33.431976Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:356: Coordinator: TEvNodeDisconnected, node id 2
2025-11-11T14:17:33.432868Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1039: RowDispatcher: TEvTryConnect to node id 2
2025-11-11T14:17:33.433628Z :INTERCONNECT_NETWORK NOTICE: interconnect_handshake.cpp:1581: [1 <-> 2] network-related error occured on handshake: outgoing handshake Peer# ghrun-vb3yoermsa(127.0.1.1:7400) Socket error# Connection reset by peer state# ReceiveResponse processed# 0 remain# 52
2025-11-11T14:17:33.433995Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:587: RowDispatcher: TEvNodeDisconnected, node id 2
2025-11-11T14:17:33.442488Z :INTERCONNECT_NETWORK NOTICE: interconnect_handshake.cpp:1581: [1 <-> 2] network-related error occured on handshake: outgoing handshake Peer# ghrun-vb3yoermsa(127.0.1.1:7400) Couldn't connect to any resolved address
2025-11-11T14:17:33.463047Z :INTERCONNECT_NETWORK NOTICE: interconnect_handshake.cpp:1581: [1 <-> 2] network-related error occured on handshake: outgoing handshake Peer# ghrun-vb3yoermsa(127.0.1.1:7400) Couldn't connect to any resolved address
2025-11-11T14:17:33.530146Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1039: RowDispatcher: TEvTryConnect to node id 2
2025-11-11T14:17:33.530195Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:587: RowDispatcher: TEvNodeDisconnected, node id 2
