2025-10-16T09:58:02.143131Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7561756711525373367:2070]
2025-10-16T09:58:02.159782Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:367: Grpc request proxy started, nodeid# 1, serve as static node
2025-10-16T09:58:02.160184Z :GRPC_SERVER CRIT: grpc_request_proxy.cpp:427: Failed to deliver subscription request to config dispatcher
2025-10-16T09:58:02.160227Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:367: Grpc request proxy started, nodeid# 1, serve as static node
2025-10-16T09:58:02.160331Z :GRPC_SERVER CRIT: grpc_request_proxy.cpp:427: Failed to deliver subscription request to config dispatcher
2025-10-16T09:58:02.161161Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:5177" Database: "local" TablePrefix: "RateLimiter_15047324-d2c4-42f2-a58a-f40b7e857bb0" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-10-16T09:58:02.258077Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-10-16T09:58:02.258135Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-10-16T09:58:02.258179Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-10-16T09:58:02.258327Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:158: Bootstrap
2025-10-16T09:58:02.278665Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-10-16T09:58:02.409116Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:537: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7561756711525373362:2065], tenant /alpha
2025-10-16T09:58:02.409181Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:178: Initialize
2025-10-16T09:58:02.439427Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: 23a02716-4f6f23fc-4db13ea6-6ebaf5c6
2025-10-16T09:58:02.439466Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-10-16T09:58:02.449591Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c1, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:02.526791Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-10-16T09:58:02.526939Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:267: Coordinator: Successfully bootstrapped coordinator, id [1:7561756711525373431:2115], NodesManagerId [0:4705493345362661702:78]
2025-10-16T09:58:02.527087Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7561756711525373432:2116] Successfully bootstrapped, local coordinator id [1:7561756711525373431:2115], tenant id alpha, local mode 0, coordination node path local/row_dispatcher/alpha
2025-10-16T09:58:02.528110Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-10-16T09:58:02.529127Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:630: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7561756711525373431:2115]
2025-10-16T09:58:02.545396Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/row_dispatcher/alpha" actor
2025-10-16T09:58:02.545424Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/row_dispatcher/alpha"
2025-10-16T09:58:02.545433Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/row_dispatcher/alpha"
2025-10-16T09:58:02.545705Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7561756711525373396:2092][/Root] Set up state: owner# [1:7561756711525373339:2052], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-16T09:58:02.545806Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7561756711525373398:2094][/Root] Set up state: owner# [1:7561756711525373340:2053], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-16T09:58:02.545874Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7561756711525373399:2095][/alpha] Set up state: owner# [1:7561756711525373340:2053], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-16T09:58:02.546158Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:529: SchemeBoardDelete /Root Strong=0
2025-10-16T09:58:02.546169Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:529: SchemeBoardDelete /Root Strong=0
2025-10-16T09:58:02.546174Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:529: SchemeBoardDelete /alpha Strong=0
2025-10-16T09:58:02.569781Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7561756711525373397:2093][/alpha] Set up state: owner# [1:7561756711525373339:2052], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-10-16T09:58:02.569838Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:529: SchemeBoardDelete /alpha Strong=0
2025-10-16T09:58:03.526749Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-10-16T09:58:03.526818Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-10-16T09:58:03.526845Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-10-16T09:58:04.003094Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/row_dispatcher/alpha"
2025-10-16T09:58:04.003132Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/row_dispatcher/alpha": 
2025-10-16T09:58:04.003201Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7561756711525373432:2116] Coordination node successfully created
2025-10-16T09:58:04.003221Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7561756711525373432:2116] Start session
2025-10-16T09:58:04.019403Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:04.019423Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:04.063075Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7561756711525373432:2116] Session successfully created
2025-10-16T09:58:04.088518Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7561756711525373432:2116] Semaphore successfully created
2025-10-16T09:58:04.088561Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7561756711525373432:2116] Try to acquire semaphore
2025-10-16T09:58:04.088876Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7561756711525373432:2116] Describe semaphore
2025-10-16T09:58:04.113146Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7561756711525373432:2116] Semaphore successfully acquired
2025-10-16T09:58:04.113207Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7561756711525373432:2116] Semaphore successfully described: coordinator id [1:7561756711525373431:2115] generation 1
2025-10-16T09:58:04.113225Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7561756711525373432:2116] Send TEvCoordinatorChanged to [1:7561756711525373362:2065], new coordinator id [1:7561756711525373431:2115], previous coordinator id [0:0:0]
2025-10-16T09:58:04.113305Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:562: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7561756711525373431:2115] generation 1
2025-10-16T09:58:04.113373Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:04.113390Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:04.113452Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:386: Coordinator: New leader [1:7561756711525373431:2115], SelfId [1:7561756711525373431:2115]
2025-10-16T09:58:04.113478Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:04.142289Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:171: Successfully bootstrapped TStorageProxy [1:7561756711525373361:2064] with connection to localhost:5177:local
2025-10-16T09:58:04.368628Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-10-16T09:58:04.408401Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:04.408423Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:04.408445Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:04.408456Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:04.408467Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:04.450846Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c2, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:04.497836Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:04.497861Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:04.526854Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-10-16T09:58:04.526902Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-10-16T09:58:04.526938Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 1
2025-10-16T09:58:05.455086Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c3, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:05.515042Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:05.515079Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:06.410980Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:06.411007Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:06.411044Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:06.411056Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:06.411075Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:06.454990Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c4, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:06.526796Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:06.526821Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:07.459386Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:07.529251Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-10-16T09:58:07.926823Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-10-16T09:58:08.414105Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:08.414147Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:08.414181Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:08.414195Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:08.414234Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:09.727354Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:09.727378Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-10-16T09:58:09.727759Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://a_folder@a_cloud Id: ptqudunhcf12i8iu0jo0 UserId: root@builtin AuthToken: **** (B6C6F477)
2025-10-16T09:58:09.728489Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c6, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:09.728612Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudunhcf12i8iu0jo0 Start run actor. Compute state: STARTING
2025-10-16T09:58:09.728620Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudunhcf12i8iu0jo0 FillConnections
2025-10-16T09:58:09.728752Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2332: QueryId: ptqudunhcf12i8iu0jo0 Run actors params: { QueryId: ptqudunhcf12i8iu0jo0 CloudId: a_cloud UserId: root@builtin Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 PreviousQueryRevision: 1 Connections: 0 Bindings: 0 AccountIdSignatures: 0 QueryType: STREAMING ExecuteMode: RUN ResultId: ptrudunhc7bli2gmsneo StateLoadMode: EMPTY StreamingDisposition: { fresh { } } Status: STARTING DqGraphs: 0 DqGraphIndex: 0 Resource.TopicConsumers: 0 }
2025-10-16T09:58:09.728818Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:571: QueryId: ptqudunhcf12i8iu0jo0 Start rate limiter resource creator
2025-10-16T09:58:09.730765Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Start Pinger
2025-10-16T09:58:09.730974Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { started_at { seconds: 1760608689 nanos: 728000000 } }
2025-10-16T09:58:09.731001Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:09.731467Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { status: RUNNING resources { rate_limiter: PREPARE compilation: PREPARE } }
2025-10-16T09:58:09.800566Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:09.800587Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:10.386567Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1476: QueryId: ptqudunhcf12i8iu0jo0 Rate limiter resource creation finished. Success: 1
2025-10-16T09:58:10.386623Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1973: QueryId: ptqudunhcf12i8iu0jo0 Compiling query ...
2025-10-16T09:58:10.390936Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { status: RUNNING resources { rate_limiter: READY compilation: PREPARE rate_limiter_path: "local/RateLimiter_15047324-d2c4-42f2-a58a-f40b7e857bb0/rate_limiter" } }
2025-10-16T09:58:10.417475Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:10.417515Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:10.417548Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:10.417560Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:10.417575Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:10.467313Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c7, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:10.557381Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:10.557427Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:11.076001Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.075 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_facade.cpp:855: Execution of [ParseSql] took 673ms
2025-10-16T09:58:11.088357Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.088 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_facade.cpp:898: Execution of [Compile] took 12.2ms
2025-10-16T09:58:11.089267Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.089 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] yql_dq_provider.cpp:109: OpenSession ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud
2025-10-16T09:58:11.089367Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.089 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] yql_dq_provider.cpp:112: OpenSession ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud complete in 0
2025-10-16T09:58:11.090075Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.089 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_optimize.cpp:671: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ExpandApply] took 201us
2025-10-16T09:58:11.095891Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.095 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core eval] yql_eval_expr.cpp:410: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} EvaluateExpression - start
2025-10-16T09:58:11.097441Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.097 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core eval] yql_eval_expr.cpp:1181: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} EvaluateExpression - finish
2025-10-16T09:58:11.103719Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.103 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [PQ] yql_pq_helpers.cpp:100: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Ids to resolve: 0
2025-10-16T09:58:11.104740Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.104 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [RESULT] yql_result_provider.cpp:1418: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} RewriteIO
2025-10-16T09:58:11.119992Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.119 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core] type_ann_expr.cpp:159: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Repeated callable stats:
RemovePrefixMembers=1
TypeOf=1
PersistableRepr=1
OrderedSqlProject=1
SqlProjectItem=1
ResWrite!=1

2025-10-16T09:58:11.120167Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.120 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 15.2ms
2025-10-16T09:58:11.120722Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.120 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 247us
2025-10-16T09:58:11.120812Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.120 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 8us
2025-10-16T09:58:11.121277Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.121 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 293us
2025-10-16T09:58:11.121507Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.121 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-10-16T09:58:11.121583Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.121 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-16T09:58:11.121820Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.121 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 116us
2025-10-16T09:58:11.122027Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.121 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [UpdateCompletness] took 67us
2025-10-16T09:58:11.122395Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.122 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [EliminateCommonSubExpressions] took 244us
2025-10-16T09:58:11.122936Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.122 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedSqlProject (AsList (AsStruct)) '((SqlProjectItem (ListType (StructType)) '"column0" (lambda '($5) (Int32 '"101")) '())))) $3))
(return (Commit! $4 $2))
)

2025-10-16T09:58:11.126411Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.126 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_co_simple1.cpp:6750: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Expand OrderedSqlProject
2025-10-16T09:58:11.126926Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.126 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-10-16T09:58:11.127535Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.127 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 472us
2025-10-16T09:58:11.127721Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.127 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 90us
2025-10-16T09:58:11.127939Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.127 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-10-16T09:58:11.128034Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.127 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-10-16T09:58:11.128286Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.128 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 143us
2025-10-16T09:58:11.128436Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.128 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [UpdateCompletness] took 50us
2025-10-16T09:58:11.128718Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.128 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [EliminateCommonSubExpressions] took 192us
2025-10-16T09:58:11.129150Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.129 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedFlatMap (AsList (AsStruct)) (lambda '($5) (AsList (FlattenMembers '('"" (AsStruct '('"column0" (Int32 '"101"))))))))) $3))
(return (Commit! $4 $2))
)

2025-10-16T09:58:11.129466Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.129 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_opt_utils.cpp:880: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Enumerate struct literal for FlattenMembers
2025-10-16T09:58:11.129780Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.129 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-10-16T09:58:11.130159Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.130 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 272us
2025-10-16T09:58:11.130290Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.130 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 53us
2025-10-16T09:58:11.130478Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.130 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-10-16T09:58:11.130573Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.130 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 4us
2025-10-16T09:58:11.132379Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.132 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 99us
2025-10-16T09:58:11.132524Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.132 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [UpdateCompletness] took 39us
2025-10-16T09:58:11.132840Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.132 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [EliminateCommonSubExpressions] took 190us
2025-10-16T09:58:11.133174Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.133 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedFlatMap (AsList (AsStruct)) (lambda '($5) (AsList (AsStruct '('"column0" (Int32 '"101"))))))) $3))
(return (Commit! $4 $2))
)

2025-10-16T09:58:11.133609Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.133 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_co_simple1.cpp:2115: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Fuse OrderedFlatMap over AsList
2025-10-16T09:58:11.133925Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.133 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-10-16T09:58:11.134189Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.134 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 162us
2025-10-16T09:58:11.134334Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.134 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 69us
2025-10-16T09:58:11.134463Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.134 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [UpdateCompletness] took 36us
2025-10-16T09:58:11.134726Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.134 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [EliminateCommonSubExpressions] took 125us
2025-10-16T09:58:11.135038Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.134 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (AsList (AsStruct '('"column0" (Int32 '"101"))))) $3))
(return (Commit! $4 $2))
)

2025-10-16T09:58:11.135310Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.135 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_co_simple1.cpp:6467: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Drop Unordered over AsList
2025-10-16T09:58:11.136140Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.136 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-10-16T09:58:11.136447Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.136 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 192us
2025-10-16T09:58:11.136586Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.136 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 46us
2025-10-16T09:58:11.136749Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.136 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [UpdateCompletness] took 27us
2025-10-16T09:58:11.136968Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.136 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [EliminateCommonSubExpressions] took 135us
2025-10-16T09:58:11.137285Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.137 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (AsList (AsStruct '('"column0" (Int32 '"101")))) $3))
(return (Commit! $4 $2))
)

2025-10-16T09:58:11.141702Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.141 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} DqReplicateStageMultiOutput: start traverse node #199947, Commit!
2025-10-16T09:58:11.142346Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.142 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [RESULT] yql_result_provider.cpp:802: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} ResFill
2025-10-16T09:58:11.142671Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.142 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-10-16T09:58:11.142971Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.142 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 180us
2025-10-16T09:58:11.143181Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.143 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [ConstraintTransformer::DoTransform] took 61us
2025-10-16T09:58:11.143342Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.143 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [UpdateCompletness] took 57us
2025-10-16T09:58:11.143586Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.143 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution of [EliminateCommonSubExpressions] took 138us
2025-10-16T09:58:11.143974Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.143 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct '('"column0" (Int32 '"101")))) $3 '"dq"))
(return (Commit! $4 $2))
)

2025-10-16T09:58:11.145434Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.145 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} DqReplicateStageMultiOutput: start traverse node #199951, Commit!
2025-10-16T09:58:11.152317Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.152 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:60: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Begin, root #199951
2025-10-16T09:58:11.152517Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.152 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:73: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Collect unused nodes for root #199951, status: Ok
2025-10-16T09:58:11.152655Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.152 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:410: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} {0}, callable #199951 <Commit!>
2025-10-16T09:58:11.152795Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.152 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:410: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} {1}, callable #199950 <ResFill!>
2025-10-16T09:58:11.153041Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.152 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:410: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} {2}, callable #199812 <Configure!>
2025-10-16T09:58:11.153180Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.153 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:410: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} {2}, callable #199812 <Configure!>
2025-10-16T09:58:11.153312Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.153 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:629: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Node #199812<Configure!> finished execution
2025-10-16T09:58:11.153484Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.153 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:646: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Node #199812<Configure!> created 0 trackable nodes: 
2025-10-16T09:58:11.153618Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.153 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:410: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} {1}, callable #199950 <ResFill!>
2025-10-16T09:58:11.154034Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.153 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] yql_dq_exectransformer.cpp:884: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Executing Result (UniqueId=199960)
2025-10-16T09:58:11.155183Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.155 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [core] type_ann_expr.cpp:159: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Repeated callable stats:
ToStream=1

2025-10-16T09:58:11.155406Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.155 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [TypeAnnotationTransformer::DoTransform] took 300us
2025-10-16T09:58:11.155532Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.155 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [TypeAnnotationTransformer::DoTransform] took 15us
2025-10-16T09:58:11.155689Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.155 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [ConstraintTransformer::DoTransform] took 38us
2025-10-16T09:58:11.157364Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.155 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [UpdateCompletness] took 20us
2025-10-16T09:58:11.157640Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.157 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [EliminateCommonSubExpressionsForSubGraph] took 98us
2025-10-16T09:58:11.157936Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.157 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core peephole] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} PeepHoleOpt:
(
(return (Iterator (AsList (AsStruct '('"column0" (Int32 '"101"))))))
)

2025-10-16T09:58:11.464992Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.464 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} DqReplicateStageMultiOutput: start traverse node #199962, Iterator
2025-10-16T09:58:11.465382Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.465 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core dq] dq_opt_build.cpp:235: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} [DQ/Build/TransformConsumers] (
(return (Iterator (AsList (AsStruct '('"column0" (Int32 '"101"))))))
)

2025-10-16T09:58:11.465722Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.465 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core dq] dq_opt_build.cpp:325: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} [DQ/Build/TransformPhysical] (
(return (Iterator (AsList (AsStruct '('"column0" (Int32 '"101"))))))
)

2025-10-16T09:58:11.468539Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.468 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] type_ann_expr.cpp:48: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [TypeAnnotationTransformer::DoTransform] took 21us
2025-10-16T09:58:11.468736Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.468 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_constraint.cpp:3307: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [ConstraintTransformer::DoTransform] took 18us
2025-10-16T09:58:11.468858Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.468 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:638: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [UpdateCompletness] took 17us
2025-10-16T09:58:11.469049Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.468 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [perf] yql_expr_csee.cpp:651: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} Execution of [EliminateCommonSubExpressionsForSubGraph] took 61us
2025-10-16T09:58:11.469217Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.469 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core peephole] yql_out_transformers.cpp:62: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} PeepHoleOpt:
(
(return (Iterator (AsList (AsStruct '('"column0" (Int32 '"101"))))))
)

2025-10-16T09:58:11.469980Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.469 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] yql_dq_exectransformer.cpp:2098: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud/#199950/#199960} After PeepHole
(
(return (Iterator (AsList (AsStruct '('"column0" (Int32 '"101"))))))
)

2025-10-16T09:58:11.475324Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c8, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:11.481076Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.480 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:518: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Register async execution for node #199950
2025-10-16T09:58:11.481160Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:928: QueryId: ptqudunhcf12i8iu0jo0 Graph (execution) with tasks: 1
2025-10-16T09:58:11.481358Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.481 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:88: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Finish, output #199951, status: Async
2025-10-16T09:58:11.481693Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.481 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:134: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Completed async execution for node #199950
2025-10-16T09:58:11.481855Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.481 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] yql_dq_exectransformer.cpp:1050: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud WrapFutureCallback
2025-10-16T09:58:11.482021Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.481 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] yql_dq_exectransformer.cpp:1053: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Execution Result complete, duration: 0.327820s
2025-10-16T09:58:11.498769Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.498 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:154: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} State is ExecutionComplete after apply async changes for node #199950
2025-10-16T09:58:11.499003Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.498 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:60: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Begin, root #199951
2025-10-16T09:58:11.499171Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.499 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:73: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Collect unused nodes for root #199951, status: Ok
2025-10-16T09:58:11.499370Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.499 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:410: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} {0}, callable #199951 <Commit!>
2025-10-16T09:58:11.499612Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.499 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:629: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Node #199951<Commit!> finished execution
2025-10-16T09:58:11.499791Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.499 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:646: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Node #199951<Commit!> created 0 trackable nodes: 
2025-10-16T09:58:11.499957Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.499 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:88: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Finish, output #199951, status: Ok
2025-10-16T09:58:11.500148Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:11.500 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [core exec] yql_execution.cpp:94: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Creating finalizing transformer, output #199951
2025-10-16T09:58:11.502333Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudunhcf12i8iu0jo0 Overall dq tasks: 1
2025-10-16T09:58:11.502904Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:758: QueryId: ptqudunhcf12i8iu0jo0 Graph 0
2025-10-16T09:58:11.503157Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { ast: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"column0\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"column0\" (Int32 \'\"101\")))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" plan: "{\"Detailed\":{\"Operations\":[{\"Id\":2,\"Name\":\"ResFill!\"}],\"OperationRoot\":2,\"Providers\":[],\"OperationStats\":{\"ResFill!\":1}},\"Basic\":{\"nodes\":[{\"id\":2,\"level\":1,\"name\":\"ResFill!\",\"type\":\"op\"}],\"links\":[]}}" }
2025-10-16T09:58:11.503334Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { result_set_count: 1 result_set_meta { column { name: "column0" type { type_id: INT32 } } } dq_graph: "\022\311\002\010\001\022\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\000\032\004\010\240\215\00628\n4\010\001\030\002 \001*\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\0002\024\n\022\t\001\000\000\000\000\000\000\000\021\000\000\000\000\001\000\000\000X\001R\000:s\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022E\010\001\022A\022(4588e168564fe8ba46df0295340cc1685559a0c2\032\023dq_vanilla_job.lite \002H\001RClocal/RateLimiter_15047324-d2c4-42f2-a58a-f40b7e857bb0/rate_limiterZ-a_cloud/a_folder_a_cloud/ptqudunhcf12i8iu0jo0`\200\200\200\200 \"\017\037\000\205\002\203\002\034column0/*\007column02uptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud:\024\n\017_LiteralTimeout\022\0010:\022\n\r_TableTimeout\022\0010:\037\n\025_AllResultsBytesLimit\022\006100000:\032\n\022_RowsLimitPerWrite\022\0041000:\027\n\022EnableComputeActor\022\0011:\016\n\014WorkerFilter:\031\n\020ComputeActorType\022\005async:\031\n\016WatermarksMode\022\007disable:\037\n\027WatermarksGranularityMs\022\0041000:$\n\034WatermarksLateArrivalDelayMs\022\0045000:\027\n\022EnableChannelStats\022\0011:\020\n\013ExportStats\022\0011bf\010\001\022b\037\000\005\205\006\213\000\205\000\204\205\002\203\002\034column0\030Inputs(Parameters\034Program\013\213\000)\251\000?\002\014Arg\000\004)\211\002?\010\206?\006 Iterator\000\006\002?\024\005?\006\003?\004)\003\001\001\001\002\001/" }
2025-10-16T09:58:11.546792Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:11.546821Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:12.416864Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:12.416919Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:12.416979Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:12.416993Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:12.417007Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:12.479593Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c9, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:12.529241Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-10-16T09:58:12.540142Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:12.540176Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:12.644468Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-10-16T09:58:13.480439Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c10, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:13.544832Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:13.544854Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:13.698320Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608712 nanos: 752000000 } }
2025-10-16T09:58:13.698393Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:13.698485Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudunhcf12i8iu0jo0 Forward ping response. Success: 1. Cookie: 2
2025-10-16T09:58:13.865079Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608713 nanos: 774000000 } }
2025-10-16T09:58:13.865140Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:13.865229Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudunhcf12i8iu0jo0 Forward ping response. Success: 1. Cookie: 0
2025-10-16T09:58:14.018711Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608713 nanos: 943000000 } }
2025-10-16T09:58:14.018772Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:14.018865Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudunhcf12i8iu0jo0 Forward ping response. Success: 1. Cookie: 0
2025-10-16T09:58:14.232075Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608714 nanos: 151000000 } }
2025-10-16T09:58:14.232133Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:14.232248Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudunhcf12i8iu0jo0 Forward ping response. Success: 1. Cookie: 0
2025-10-16T09:58:14.380561Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608714 nanos: 324000000 } }
2025-10-16T09:58:14.380654Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudunhcf12i8iu0jo0 Forward ping response. Success: 1. Cookie: 1
2025-10-16T09:58:14.380717Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudunhcf12i8iu0jo0 Overall dq tasks: 1
2025-10-16T09:58:14.382673Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.382 DEBUG ydbd(pid=611120, tid=0x00007BDBC6F77640) [DQ] task_controller_impl.h:83: _AllResultsBytesLimit = 100000
2025-10-16T09:58:14.382862Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.382 DEBUG ydbd(pid=611120, tid=0x00007BDBC6F77640) [DQ] task_controller_impl.h:86: _RowsLimitPerWrite = 1000
2025-10-16T09:58:14.383195Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1659: QueryId: ptqudunhcf12i8iu0jo0 Executer: [1:7561756763064981023:2142], Controller: [1:7561756763064981026:2145], ResultIdActor: [1:7561756763064981024:2143]
2025-10-16T09:58:14.383488Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { owner_id: "4aa0e678-a11b997a-c9d862bb-4d929a2c5" query_id { value: "ptqudunhcf12i8iu0jo0" } status: RUNNING result_set_count: 1 result_set_meta { column { name: "column0" type { type_id: INT32 } } } resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_15047324-d2c4-42f2-a58a-f40b7e857bb0/rate_limiter" } scope: "yandexcloud://a_folder@a_cloud" }
2025-10-16T09:58:14.383502Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:14.385174Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.385 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] executer_actor.cpp:140: {ptqudunhcf12i8iu0jo0} TDqExecuter::OnGraph
2025-10-16T09:58:14.386740Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.385 INFO  ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] executer_actor.cpp:157: {ptqudunhcf12i8iu0jo0} Trying to allocate 1 workers [EnableComputeActor=1]
2025-10-16T09:58:14.387444Z :FQ_RESULT_WRITER INFO: result_writer.cpp:62: TraceId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud Bootstrap
2025-10-16T09:58:14.387694Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.387 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] executer_actor.cpp:247: {ptqudunhcf12i8iu0jo0} Dq timeouts are set to: 0.000000s (global), 600.000000s (workers allocation fail), 30.000000s (workers allocation warn) 
2025-10-16T09:58:14.395516Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:125: TEvAllocateWorkersResponse Nodes {
  ResourceId: 4294967297
  Worker {
    NodeId: 1
    Guid: "23a02716-4f6f23fc-4db13ea6-6ebaf5c6,ghrun-anqctpgcma"
  }
}
2025-10-16T09:58:14.395883Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.395 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:141: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} TEvAllocateWorkersResponse 1
2025-10-16T09:58:14.396034Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.395 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:106: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} RequestActorIdsFromNodes 1
2025-10-16T09:58:14.396228Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.396 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:115: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} RequestActorIdsFromNodes 1 4294967297
2025-10-16T09:58:14.396354Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.396 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:119: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} RequestedNode: 1
2025-10-16T09:58:14.396631Z :YQL_PROXY WARN: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.396 WARN  ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:259: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} Send TEvAllocateWorkersRequest to Id:23a02716-4f6f23fc-4db13ea6-6ebaf5c6,ghrun-anqctpgcma,NodeId:1,
2025-10-16T09:58:14.396881Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.396 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] local_worker_manager.cpp:316: {ptqudunhcf12i8iu0jo0} TLocalWorkerManager::TEvAllocateWorkersRequest 281479271677953
2025-10-16T09:58:14.397041Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.396 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] local_worker_manager.cpp:376: {ptqudunhcf12i8iu0jo0} Create compute actor: async
2025-10-16T09:58:14.397960Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.397 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:141: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} TEvAllocateWorkersResponse 1
2025-10-16T09:58:14.398053Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:134: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Start compute actor [1:7561756763064981028:2147], task: 1
2025-10-16T09:58:14.398205Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:113: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_15047324-d2c4-42f2-a58a-f40b7e857bb0/rate_limiter", "a_cloud/a_folder_a_cloud/ptqudunhcf12i8iu0jo0" }
2025-10-16T09:58:14.399859Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.399 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] executer_actor.cpp:358: {ptqudunhcf12i8iu0jo0} TDqExecuter::TEvAllocateWorkersResponse
2025-10-16T09:58:14.400263Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.399 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] executer_actor.cpp:384: {ptqudunhcf12i8iu0jo0} Allocated resource 4294967297
2025-10-16T09:58:14.400543Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.400 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] executer_actor.cpp:401: {ptqudunhcf12i8iu0jo0} WorkerInfo: Id:23a02716-4f6f23fc-4db13ea6-6ebaf5c6,ghrun-anqctpgcma,NodeId:1,StageId:1
2025-10-16T09:58:14.400724Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.400 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] executer_actor.cpp:402: {ptqudunhcf12i8iu0jo0} TaskInfo: 0/1
2025-10-16T09:58:14.400859Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.400 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] executer_actor.cpp:404: {ptqudunhcf12i8iu0jo0}  ObjectId: 4588e168564fe8ba46df0295340cc1685559a0c2, dq_vanilla_job.lite, 
2025-10-16T09:58:14.411317Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.411 INFO  ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] executer_actor.cpp:415: {ptqudunhcf12i8iu0jo0} 1 workers allocated
2025-10-16T09:58:14.414477Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.414 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:271: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud}  [1:7561756763064981026:2145] ExportStats Summary
2025-10-16T09:58:14.414865Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.414 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:526: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Ready State: [1:7561756763064981026:2145]
2025-10-16T09:58:14.415039Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.414 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:537: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Forward: [1:7561756763064981026:2145] to [1:7561756763064981025:2144]
2025-10-16T09:58:14.415213Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:51: [ptqudunhcf12i8iu0jo0-0.1] TEvReadyState, streaming disposition { fresh { } }, state load mode EMPTY
2025-10-16T09:58:14.415308Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:85: [ptqudunhcf12i8iu0jo0-0.1] AllActors count: 1, ActorsToTrigger count: 0, ActorsToNotify count: 0, ActorsToWaitFor count: 0
2025-10-16T09:58:14.415321Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:88: [ptqudunhcf12i8iu0jo0-0.1] No ingress tasks, coordinator was disabled
2025-10-16T09:58:14.415333Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:423: [ptqudunhcf12i8iu0jo0-0.1] Send TEvRun to all actors
2025-10-16T09:58:14.418180Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.398 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1
2025-10-16T09:58:14.418717Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.418 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} task: 1, program size: 10, llvm: ``.
2025-10-16T09:58:14.421807Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:14.421832Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:14.421885Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:14.421896Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:14.421912Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:14.471814Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.471 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Build task: 1 takes 53431 us
2025-10-16T09:58:14.471975Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.471 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Prepare task: 1
2025-10-16T09:58:14.472222Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.472 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Prepare task: 1, takes 82 us
2025-10-16T09:58:14.472442Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1169: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Got TEvRun from actor [1:7561756763064981025:2144]
2025-10-16T09:58:14.472474Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1486: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Poll inputs
2025-10-16T09:58:14.472483Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1501: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Poll sources
2025-10-16T09:58:14.472494Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. DoExecuteImpl: 0
2025-10-16T09:58:14.472631Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.472 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} Run task: 1
2025-10-16T09:58:14.472995Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.472 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} task1, execution finished, finish consumers
2025-10-16T09:58:14.473173Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.473 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] tasks_runner_local.cpp:267: {TODO/1} channelId: 1. Finish request
2025-10-16T09:58:14.474924Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.474 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:160: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} [1:7561756763064981026:2145] EvState TaskId: 1 State: 2 PingCookie: 0 StatusCode: UNSPECIFIED
2025-10-16T09:58:14.475127Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.474 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:201: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud}  [1:7561756763064981026:2145] Executing TaskId: 1
2025-10-16T09:58:14.475324Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.475 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:561: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} Update channels
2025-10-16T09:58:14.482464Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.475 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:577: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} 1 Update { Id: 1 SrcTaskId: 2 DstTaskId: 1 SrcEndpoint { ActorId { RawX1: 0 RawX2: 0 } } DstEndpoint { ActorId { RawX1: 7561756763064981024 RawX2: 4503603922339935 } } SrcStageId: 1 }
2025-10-16T09:58:14.483150Z :KQP_COMPUTE TRACE: dq_compute_memory_quota.h:97: TxId: ptqudunhcf12i8iu0jo0, task: 1. Peak memory usage: 196608
2025-10-16T09:58:14.483243Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c11, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:14.483401Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1074: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Received channels info: Update { Id: 1 SrcTaskId: 2 DstTaskId: 1 SrcEndpoint { ActorId { RawX1: 0 RawX2: 0 } } DstEndpoint { ActorId { RawX1: 7561756763064981024 RawX2: 4503603922339935 } } SrcStageId: 1 }
2025-10-16T09:58:14.483413Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1093: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Update output channelId: 1, peer: [1:7561756763064981024:2143]
2025-10-16T09:58:14.483452Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1486: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Poll inputs
2025-10-16T09:58:14.483464Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1501: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Poll sources
2025-10-16T09:58:14.483478Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. DoExecuteImpl: 0
2025-10-16T09:58:14.483511Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. update task runner stats
2025-10-16T09:58:14.483606Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Resume execution, run status: Finished checkpoint: 0 watermark injected: (empty maybe)
2025-10-16T09:58:14.483617Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-10-16T09:58:14.483648Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. About to drain channelId: 1, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:0;inFlightCount:0;)
2025-10-16T09:58:14.483663Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1105: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Ask CPU quota: 74ms
2025-10-16T09:58:14.483778Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:566: TxId: ptqudunhcf12i8iu0jo0, task: 1. SendChannelData, channelId: 1, peer: [1:7561756763064981024:2143], chunks: 1, bytes: 4, watermark: 0, checkpoint: 0, seqNo: 1, finished: 1
2025-10-16T09:58:14.483847Z :KQP_COMPUTE DEBUG: dq_compute_actor_channels.cpp:669: TxId: ptqudunhcf12i8iu0jo0, task: 1. Tasks execution finished, waiting for chunk delivery in output channelId: 1, seqNo: [1]
2025-10-16T09:58:14.528817Z :YQ_RATE_LIMITER DEBUG: quoter_service.cpp:234: Scheduled retry in 0.006999s for resource {"/local/RateLimiter_15047324-d2c4-42f2-a58a-f40b7e857bb0/rate_limiter", "a_cloud/a_folder_a_cloud/ptqudunhcf12i8iu0jo0"}. Status: TIMEOUT 
2025-10-16T09:58:14.541510Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608714 nanos: 474000000 } }
2025-10-16T09:58:14.541610Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudunhcf12i8iu0jo0 Forward ping response. Success: 1. Cookie: 0
2025-10-16T09:58:14.542984Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:14.543000Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:14.555911Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1162: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. CPU quota delay: 72ms
2025-10-16T09:58:14.556321Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. update task runner stats
2025-10-16T09:58:14.556418Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Resume execution, run status: Finished checkpoint: 0 watermark injected: (empty maybe)
2025-10-16T09:58:14.556429Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-10-16T09:58:14.556442Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:423: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Do not drain channelId: 1, finished
2025-10-16T09:58:14.556478Z :KQP_COMPUTE DEBUG: dq_compute_actor_channels.cpp:669: TxId: ptqudunhcf12i8iu0jo0, task: 1. Tasks execution finished, waiting for chunk delivery in output channelId: 1, seqNo: [1]
2025-10-16T09:58:14.556490Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1105: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Ask CPU quota: 11ms
2025-10-16T09:58:14.575710Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1162: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. CPU quota delay: 19ms
2025-10-16T09:58:14.731964Z :FQ_PINGER TRACE: pinger.cpp:400: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Request Private::PingTask
2025-10-16T09:58:14.963116Z :FQ_RESULT_WRITER TRACE: result_writer.cpp:175: TraceId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud ChannelData, Records: 1 HasError: 0 Size: 4 Rows: 1 FreeSpace: 67108864 Duration: 0.580900s AvgSpeed: 0
2025-10-16T09:58:14.963189Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:179: TxId: ptqudunhcf12i8iu0jo0, task: 1. Received channel data ack for channelId: 1, seqNo: 1, lastSentSeqNo: 1, freeSpace: 67108864, early finish: 0
2025-10-16T09:58:14.963222Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:207: TxId: ptqudunhcf12i8iu0jo0, task: 1. PeerState, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;), sentSeqNo: 1, ackSeqNo: 1
2025-10-16T09:58:14.963233Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:220: TxId: ptqudunhcf12i8iu0jo0, task: 1. Resume compute actor
2025-10-16T09:58:14.963271Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1486: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Poll inputs
2025-10-16T09:58:14.963278Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1501: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Poll sources
2025-10-16T09:58:14.963286Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. DoExecuteImpl: 0
2025-10-16T09:58:14.963767Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. update task runner stats
2025-10-16T09:58:14.963835Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Resume execution, run status: Finished checkpoint: 0 watermark injected: (empty maybe)
2025-10-16T09:58:14.963845Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-10-16T09:58:14.963853Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:423: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Do not drain channelId: 1, finished
2025-10-16T09:58:14.963871Z :KQP_COMPUTE DEBUG: dq_compute_actor_channels.cpp:674: TxId: ptqudunhcf12i8iu0jo0, task: 1. Tasks execution finished
2025-10-16T09:58:14.963888Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:510: SelfId: [1:7561756763064981028:2147], TxId: ptqudunhcf12i8iu0jo0, task: 1. Ctx: {}. Compute state finished. All channels and sinks finished
2025-10-16T09:58:14.964015Z :KQP_COMPUTE DEBUG: dq_compute_actor_channels.cpp:494: TxId: ptqudunhcf12i8iu0jo0, task: 1. pass away
2025-10-16T09:58:14.965294Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.965 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:160: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud} [1:7561756763064981026:2145] EvState TaskId: 1 State: 3 PingCookie: 0 StatusCode: SUCCESS
2025-10-16T09:58:14.965414Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.965 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:174: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud}  [1:7561756763064981026:2145] AddStats 1
2025-10-16T09:58:14.965954Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.965 DEBUG ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:212: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud}  [1:7561756763064981026:2145] Finish TaskId: 1. Tasks finished: 1/1
2025-10-16T09:58:14.969714Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud 2025-10-16 09:58:14.969 TRACE ydbd(pid=611120, tid=0x00007BDBC7778640) [DQ] task_controller_impl.h:271: {ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud}  [1:7561756763064981026:2145] ExportStats Summary
2025-10-16T09:58:14.971252Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.971 DEBUG ydbd(pid=611120, tid=0x00007BDBC6F77640) [DQ] executer_actor.cpp:324: {ptqudunhcf12i8iu0jo0} OnGraphFinished
2025-10-16T09:58:14.971448Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.971 DEBUG ydbd(pid=611120, tid=0x00007BDBC6F77640) [DQ] executer_actor.cpp:284: {ptqudunhcf12i8iu0jo0} Finish with status=1 issues=
2025-10-16T09:58:14.972829Z :FQ_RESULT_WRITER DEBUG: result_writer.cpp:126: TraceId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud Send response to executer
2025-10-16T09:58:14.973036Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudunhcf12i8iu0jo0 2025-10-16 09:58:14.972 DEBUG ydbd(pid=611120, tid=0x00007BDBC6F77640) [DQ] executer_actor.cpp:341: {ptqudunhcf12i8iu0jo0} OnQueryResponse status=1 issuses_size=0
2025-10-16T09:58:14.973158Z :FQ_RESULT_WRITER INFO: result_writer.cpp:87: TraceId: ptqudunhcf12i8iu0jo0#ptrudunhc7bli2gmsneo#yandexcloud://a_folder@a_cloud#4aa0e678-a11b997a-c9d862bb-4d929a2c5#a_cloud FinishWrite, Records: 1 HasError: 0 Size: 4 Rows: 1 FreeSpace: 67108864 Duration: 0.590957s AvgSpeed: 0 ResultChunks.size(): 0
2025-10-16T09:58:14.973263Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1233: QueryId: ptqudunhcf12i8iu0jo0 Query response SUCCESS. Result set index: 0. Issues count: 0. Rows count: 1
2025-10-16T09:58:14.976838Z :YQL_PROXY TRACE: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.976 TRACE ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] task_controller_impl.h:271:  [1:7561756763064981026:2145] ExportStats Summary
2025-10-16T09:58:14.977989Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.977 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:228: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} TEvFreeWorkersNotify 281479271677953
2025-10-16T09:58:14.978130Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.978 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] resource_allocator.cpp:235: {ptqudunhcf12i8iu0jo0/[1:7561756763064981027:2146]} TEvFreeWorkersNotify 4294967297 (failed)
2025-10-16T09:58:14.978575Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.978 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 281479271677953
2025-10-16T09:58:14.978753Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: 4aa0e678-a11b997a-c9d862bb-4d929a2c 2025-10-16 09:58:14.978 DEBUG ydbd(pid=611120, tid=0x00007BDBC6776640) [DQ] local_worker_manager.cpp:440: Free Group 281479271677953
2025-10-16T09:58:14.981270Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1834: QueryId: ptqudunhcf12i8iu0jo0 Is about to finish query with status COMPLETED
2025-10-16T09:58:14.981295Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1827: QueryId: ptqudunhcf12i8iu0jo0 Write finalizing status: COMPLETING
2025-10-16T09:58:14.981453Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:635: [ptqudunhcf12i8iu0jo0-0.1] PassAway
2025-10-16T09:58:14.981641Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { status: COMPLETING }
2025-10-16T09:58:14.981653Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:15.176267Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608715 nanos: 96000000 } }
2025-10-16T09:58:15.176337Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:880: QueryId: ptqudunhcf12i8iu0jo0 Forward ping response. Success: 1. Cookie: 3
2025-10-16T09:58:15.176347Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:887: QueryId: ptqudunhcf12i8iu0jo0 Finalizing status is saved
2025-10-16T09:58:15.176365Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1507: QueryId: ptqudunhcf12i8iu0jo0 Start rate limiter resource deleter
2025-10-16T09:58:15.487438Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c12, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:15.531281Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:15.531301Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:15.987668Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1498: QueryId: ptqudunhcf12i8iu0jo0 Rate limiter resource deletion finished. Success: 1. Issues: 
2025-10-16T09:58:15.987709Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1926: QueryId: ptqudunhcf12i8iu0jo0 SendPingAndPassAway, FinalQueryStatus COMPLETED
2025-10-16T09:58:15.987782Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:536: QueryId: ptqudunhcf12i8iu0jo0 PassAway
2025-10-16T09:58:15.988500Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward ping request: { owner_id: "4aa0e678-a11b997a-c9d862bb-4d929a2c5" query_id { value: "ptqudunhcf12i8iu0jo0" } result_id { value: "ptrudunhc7bli2gmsneo" } status: COMPLETED result_set_count: 1 statistics: "{\"Graph=0\":{\"Actor\":{\"ActorCreateTimeUs\":{\"sum\":\"1ms\",\"count\":1,\"avg\":\"1ms\",\"max\":\"1ms\",\"min\":\"1ms\"}},\"AllocateWorkersUs\":{\"sum\":\"12ms\",\"count\":1,\"avg\":\"12ms\",\"max\":\"12ms\",\"min\":\"12ms\"},\"ExecutionTimeUs\":{\"sum\":\"0.56s\",\"count\":1,\"avg\":\"0.56s\",\"max\":\"0.56s\",\"min\":\"0.56s\"},\"TaskRunner\":{\"Output=0\":{\"Stage=1\":{\"PopBytes\":{\"sum\":4,\"count\":1,\"avg\":4,\"max\":4,\"min\":4},\"PopChunks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PopFirstMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.48s\",\"max\":\"09:58:14.48s\",\"min\":\"09:58:14.48s\"},\"PopLastMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.48s\",\"max\":\"09:58:14.48s\",\"min\":\"09:58:14.48s\"},\"PopQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PopQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PopRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PushChunks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PushFirstMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.47s\",\"max\":\"09:58:14.47s\",\"min\":\"09:58:14.47s\"},\"PushLastMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.47s\",\"max\":\"09:58:14.47s\",\"min\":\"09:58:14.47s\"},\"PushQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PushQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PushRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}},\"Output=Total\":{\"Stage=1\":{\"ChannelCount\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PopBytes\":{\"sum\":4,\"count\":1,\"avg\":4,\"max\":4,\"min\":4},\"PopChunks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PopFirstMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.48s\",\"max\":\"09:58:14.48s\",\"min\":\"09:58:14.48s\"},\"PopLastMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.48s\",\"max\":\"09:58:14.48s\",\"min\":\"09:58:14.48s\"},\"PopQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PopQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PopRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PushChunks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PushFirstMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.47s\",\"max\":\"09:58:14.47s\",\"min\":\"09:58:14.47s\"},\"PushLastMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.47s\",\"max\":\"09:58:14.47s\",\"min\":\"09:58:14.47s\"},\"PushQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PushQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PushRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"ChannelCount\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PopBytes\":{\"sum\":4,\"count\":1,\"avg\":4,\"max\":4,\"min\":4},\"PopChunks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PopFirstMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.48s\",\"max\":\"09:58:14.48s\",\"min\":\"09:58:14.48s\"},\"PopLastMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.48s\",\"max\":\"09:58:14.48s\",\"min\":\"09:58:14.48s\"},\"PopQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PopQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PopRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PushChunks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"PushFirstMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.47s\",\"max\":\"09:58:14.47s\",\"min\":\"09:58:14.47s\"},\"PushLastMessageMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.47s\",\"max\":\"09:58:14.47s\",\"min\":\"09:58:14.47s\"},\"PushQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PushQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"PushRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}},\"Stage=1\":{\"BuildCpuTimeUs\":{\"sum\":\"53ms\",\"count\":1,\"avg\":\"53ms\",\"max\":\"53ms\",\"min\":\"53ms\"},\"ComputeCpuTimeUs\":{\"sum\":\"954us\",\"count\":1,\"avg\":\"954us\",\"max\":\"954us\",\"min\":\"954us\"},\"CpuTimeUs\":{\"sum\":\"86ms\",\"count\":1,\"avg\":\"86ms\",\"max\":\"86ms\",\"min\":\"86ms\"},\"FinishTimeMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.96s\",\"max\":\"09:58:14.96s\",\"min\":\"09:58:14.96s\"},\"IngressQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"IngressQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"MkqlMaxMemoryUsage\":{\"sum\":196608,\"count\":1,\"avg\":196608,\"max\":196608,\"min\":196608},\"OutputBytes\":{\"sum\":4,\"count\":1,\"avg\":4,\"max\":4,\"min\":4},\"OutputRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"ResultBytes\":{\"sum\":4,\"count\":1,\"avg\":4,\"max\":4,\"min\":4},\"ResultRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"BuildCpuTimeUs\":{\"sum\":\"53ms\",\"count\":1,\"avg\":\"53ms\",\"max\":\"53ms\",\"min\":\"53ms\"},\"ComputeCpuTimeUs\":{\"sum\":\"954us\",\"count\":1,\"avg\":\"954us\",\"max\":\"954us\",\"min\":\"954us\"},\"CpuTimeUs\":{\"sum\":\"86ms\",\"count\":1,\"avg\":\"86ms\",\"max\":\"86ms\",\"min\":\"86ms\"},\"FinishTimeMs\":{\"sum\":\"N/A\",\"count\":1,\"avg\":\"09:58:14.96s\",\"max\":\"09:58:14.96s\",\"min\":\"09:58:14.96s\"},\"IngressQueuedBytes\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"IngressQueuedRows\":{\"sum\":0,\"count\":1,\"avg\":0,\"max\":0,\"min\":0},\"MkqlMaxMemoryUsage\":{\"sum\":196608,\"count\":1,\"avg\":196608,\"max\":196608,\"min\":196608},\"OutputBytes\":{\"sum\":4,\"count\":1,\"avg\":4,\"max\":4,\"min\":4},\"OutputRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"ResultBytes\":{\"sum\":4,\"count\":1,\"avg\":4,\"max\":4,\"min\":4},\"ResultRows\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}},\"UniqueWorkers\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}}" result_set_meta { column { name: "column0" type { type_id: INT32 } } rows_count: 1 } status_code: SUCCESS resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_15047324-d2c4-42f2-a58a-f40b7e857bb0/rate_limiter" } scope: "yandexcloud://a_folder@a_cloud" finished_at { seconds: 1760608694 nanos: 981000000 } }
2025-10-16T09:58:15.988516Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Forward request Private::PingTask
2025-10-16T09:58:16.165953Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: { expired_at { seconds: 1760608715 nanos: 655000000 } }
2025-10-16T09:58:16.423084Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:16.423121Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:16.423149Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:16.423161Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:16.423182Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:16.490261Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c13, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:16.558839Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:16.558868Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:17.216573Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Ping response success: {  }
2025-10-16T09:58:17.216649Z :FQ_PINGER DEBUG: pinger.cpp:379: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Query finished
2025-10-16T09:58:17.216661Z :FQ_PINGER TRACE: pinger.cpp:187: QueryId: ptqudunhcf12i8iu0jo0, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c5 Stop Pinger
2025-10-16T09:58:17.494090Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c14, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:17.531130Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-10-16T09:58:17.549872Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:17.549898Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-10-16T09:58:17.631244Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-10-16T09:58:18.424459Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:620: RowDispatcher: Send ping to [1:7561756711525373431:2115]
2025-10-16T09:58:18.424510Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1164: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-10-16T09:58:18.424538Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7561756711525373362:2065]
2025-10-16T09:58:18.424549Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7561756711525373362:2065]
2025-10-16T09:58:18.424573Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:626: RowDispatcher: NActors::TEvents::TEvPong
2025-10-16T09:58:18.497211Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 4aa0e678-a11b997a-c9d862bb-4d929a2c15, Host: ghrun-anqctpgcma, Tenant: /alpha
2025-10-16T09:58:18.552137Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-10-16T09:58:18.552158Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
