2025-12-09T09:47:45.533177Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-09T09:47:45.533558Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-09T09:47:45.534366Z :YQ_CONTROL_PLANE_STORAGE INFO: ydb_control_plane_storage.cpp:50: Starting ydb control plane storage service. Actor id: [1:7581792662589397241:2062]
2025-12-09T09:47:45.556804Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: b4d689f4-46b202e1-fb60adbb-42c3b9eb
2025-12-09T09:47:45.556820Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-12-09T09:47:45.556900Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7581792662589397253:2074]
2025-12-09T09:47:45.557104Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-12-09T09:47:45.557160Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-12-09T09:47:45.558230Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-12-09T09:47:45.558251Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-12-09T09:47:45.558321Z :YQ_CONTROL_PLANE_PROXY DEBUG: control_plane_proxy.cpp:525: Starting yandex query control plane proxy. Actor id: [1:7581792662589397243:2064]
2025-12-09T09:47:45.559059Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true ControlPlaneEnabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:8465" Database: "local" TablePrefix: "RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-12-09T09:47:45.585275Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564301, Host: ghrun-jhhelrhzfq, Tenant: /default
2025-12-09T09:47:45.585415Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:58: STARTING: [1:7581792662589397242:2063]
2025-12-09T09:47:45.585948Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:214: Bootstrap
2025-12-09T09:47:45.585954Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:217: Create sdk ydb connection
2025-12-09T09:47:45.589312Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:239: Successfully bootstrapped TStorageProxy [1:7581792662589397247:2068] with connection to localhost:8465:local
2025-12-09T09:47:45.589371Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create coordination node "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter" actor
2025-12-09T09:47:45.589386Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create coordination node "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter"
2025-12-09T09:47:45.589405Z :YQ_RATE_LIMITER DEBUG: schema.cpp:411: Call create coordination node "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter"
2025-12-09T09:47:45.589636Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:544: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7581792662589397248:2069], tenant /default
2025-12-09T09:47:45.589832Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581792662589397248:2069]
2025-12-09T09:47:45.589843Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:329: Coordinator: Add new row dispatcher to map (state 1)
2025-12-09T09:47:45.604601Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-12-09T09:47:45.807065Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/jobs" actor
2025-12-09T09:47:45.807275Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/nodes" actor
2025-12-09T09:47:45.807352Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/quotas" actor
2025-12-09T09:47:45.807459Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenants" actor
2025-12-09T09:47:45.807518Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenant_acks" actor
2025-12-09T09:47:45.807576Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/mappings" actor
2025-12-09T09:47:45.807702Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:293: Coordinator: Successfully bootstrapped coordinator, id [1:7581792662589397313:2127], NodesManagerId [0:4705493345362661702:78], rebalancing timeout 5.000000s
2025-12-09T09:47:45.807768Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7581792662589397314:2128] Successfully bootstrapped, local coordinator id [1:7581792662589397313:2127], tenant id default, local mode 0, coordination node path local/default, endpoint localhost:8465
2025-12-09T09:47:45.837903Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create directory "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20" actor
2025-12-09T09:47:45.837920Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create directory "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20"
2025-12-09T09:47:45.837939Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:383: Call create directory "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20"
2025-12-09T09:47:45.838394Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/result_sets" actor
2025-12-09T09:47:45.838544Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:637: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7581792662589397313:2127]
2025-12-09T09:47:45.838655Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/default" actor
2025-12-09T09:47:45.838665Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/default"
2025-12-09T09:47:45.838676Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/default"
2025-12-09T09:47:45.838754Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/queries" actor
2025-12-09T09:47:45.838811Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/pending_small" actor
2025-12-09T09:47:45.838846Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/connections" actor
2025-12-09T09:47:45.838884Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/bindings" actor
2025-12-09T09:47:45.838958Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/idempotency_keys" actor
2025-12-09T09:47:45.839193Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/compute_databases" actor
2025-12-09T09:47:45.849618Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-09T09:47:46.175072Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/default"
2025-12-09T09:47:46.175173Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/default": { <main>: Error: Check failed: path: '/local/default', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 7], type: EPathTypeKesus, state: EPathStateNoChanges) }
2025-12-09T09:47:46.175243Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7581792662589397314:2128] Coordination node successfully created
2025-12-09T09:47:46.175254Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7581792662589397314:2128] Start session
2025-12-09T09:47:46.186986Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/nodes". Create session OK
2025-12-09T09:47:46.187041Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/nodes"
2025-12-09T09:47:46.187050Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/nodes"
2025-12-09T09:47:46.188532Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenants". Create session OK
2025-12-09T09:47:46.188570Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenants"
2025-12-09T09:47:46.188590Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenants"
2025-12-09T09:47:46.202182Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/compute_databases". Create session OK
2025-12-09T09:47:46.202210Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/compute_databases"
2025-12-09T09:47:46.202217Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/compute_databases"
2025-12-09T09:47:46.204260Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created coordination node "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter"
2025-12-09T09:47:46.204332Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create coordination node "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter": { <main>: Error: Check failed: path: '/local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 32], type: EPathTypeKesus, state: EPathStateNoChanges) }
2025-12-09T09:47:46.204368Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created directory "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20"
2025-12-09T09:47:46.204413Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create directory "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 26], type: EPathTypeDir, state: EPathStateNoChanges) }
2025-12-09T09:47:46.205454Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenant_acks". Create session OK
2025-12-09T09:47:46.205469Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenant_acks"
2025-12-09T09:47:46.205477Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenant_acks"
2025-12-09T09:47:46.260605Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/quotas". Create session OK
2025-12-09T09:47:46.260661Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/quotas"
2025-12-09T09:47:46.260670Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/quotas"
2025-12-09T09:47:46.263454Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7581792662589397314:2128] Session successfully created
2025-12-09T09:47:46.299768Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/pending_small". Create session OK
2025-12-09T09:47:46.299807Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/pending_small"
2025-12-09T09:47:46.299836Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/pending_small"
2025-12-09T09:47:46.300207Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/mappings". Create session OK
2025-12-09T09:47:46.300226Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/mappings"
2025-12-09T09:47:46.300232Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/mappings"
2025-12-09T09:47:46.303265Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/result_sets". Create session OK
2025-12-09T09:47:46.303295Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/result_sets"
2025-12-09T09:47:46.303306Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/result_sets"
2025-12-09T09:47:46.309928Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/queries". Create session OK
2025-12-09T09:47:46.309951Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/queries"
2025-12-09T09:47:46.309959Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/queries"
2025-12-09T09:47:46.310239Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/connections". Create session OK
2025-12-09T09:47:46.310262Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/connections"
2025-12-09T09:47:46.310271Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/connections"
2025-12-09T09:47:46.312881Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/bindings". Create session OK
2025-12-09T09:47:46.312910Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/bindings"
2025-12-09T09:47:46.312916Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/bindings"
2025-12-09T09:47:46.313196Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/jobs". Create session OK
2025-12-09T09:47:46.313224Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/jobs"
2025-12-09T09:47:46.313231Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/jobs"
2025-12-09T09:47:46.315726Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/idempotency_keys". Create session OK
2025-12-09T09:47:46.315758Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/idempotency_keys"
2025-12-09T09:47:46.315767Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/idempotency_keys"
2025-12-09T09:47:46.317219Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7581792662589397314:2128] Semaphore successfully created
2025-12-09T09:47:46.317268Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7581792662589397314:2128] Try to acquire semaphore
2025-12-09T09:47:46.323371Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7581792662589397314:2128] Describe semaphore
2025-12-09T09:47:46.360995Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/nodes"
2025-12-09T09:47:46.365713Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/nodes": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/nodes', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 33], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.401512Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564301, Host: ghrun-jhhelrhzfq, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T09:47:46.401579Z :FQ_CONTROL_PLANE_CONFIG WARN: control_plane_config.cpp:115: TEvGetTenantInfoRequest: IS NOT READY yet
2025-12-09T09:47:46.406679Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenants"
2025-12-09T09:47:46.406728Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenants": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenants', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 27], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.409364Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/quotas"
2025-12-09T09:47:46.409406Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/quotas": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/quotas', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 34], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.413906Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7581792662589397314:2128] Semaphore successfully acquired
2025-12-09T09:47:46.413954Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/compute_databases"
2025-12-09T09:47:46.414004Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/compute_databases": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/compute_databases', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 40], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.414283Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenant_acks"
2025-12-09T09:47:46.414332Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenant_acks": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/tenant_acks', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 35], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.419206Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7581792662589397314:2128] Semaphore successfully described: coordinator id [1:7581792662589397313:2127] generation 4
2025-12-09T09:47:46.419236Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7581792662589397314:2128] Send TEvCoordinatorChanged to [1:7581792662589397248:2069], new coordinator id [1:7581792662589397313:2127], previous coordinator id [0:0:0]
2025-12-09T09:47:46.419325Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:569: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7581792662589397313:2127] generation 4
2025-12-09T09:47:46.419507Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581792662589397248:2069]
2025-12-09T09:47:46.419541Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581792662589397248:2069]
2025-12-09T09:47:46.419560Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581792662589397248:2069]
2025-12-09T09:47:46.419586Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:440: Coordinator: New leader [1:7581792662589397313:2127], SelfId [1:7581792662589397313:2127]
2025-12-09T09:47:46.419603Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T09:47:46.451571Z :YQ_CONTROL_PLANE_STORAGE TRACE: nodes_health_check.cpp:38: NodesHealthCheckRequest: {tenant: "/default"
node {
  node_id: 1
  instance_id: "b4d689f4-46b202e1-fb60adbb-42c3b9eb"
  hostname: "ghrun-jhhelrhzfq"
  interconnect_port: 10981
  node_address: "127.0.1.1"
}
}
2025-12-09T09:47:46.452071Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564301"
host: "ghrun-jhhelrhzfq"
node_id: 1
}
2025-12-09T09:47:46.462400Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:190: LOADED TenantInfo: State CHANGED at 2025-12-09T09:47:05.884430Z
2025-12-09T09:47:46.462546Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/jobs"
2025-12-09T09:47:46.462585Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/jobs": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/jobs', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 42], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.464852Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/result_sets"
2025-12-09T09:47:46.464952Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/result_sets": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/result_sets', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 41], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.475173Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/pending_small"
2025-12-09T09:47:46.475226Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/pending_small": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/pending_small', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 36], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.490055Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/queries"
2025-12-09T09:47:46.490113Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/queries": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/queries', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 37], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.495973Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/connections"
2025-12-09T09:47:46.496025Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/connections": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/connections', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 38], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.499880Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/idempotency_keys"
2025-12-09T09:47:46.499931Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/idempotency_keys": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/idempotency_keys', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 39], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.504746Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/mappings"
2025-12-09T09:47:46.504808Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/mappings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/mappings', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 28], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.516292Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/bindings"
2025-12-09T09:47:46.516370Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/bindings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_b4b49749-875b-46b6-a62d-2e8eac108e20/bindings', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 43], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-09T09:47:46.611395Z :YQ_CONTROL_PLANE_STORAGE WARN: task_get.cpp:391: GetTaskRequest: {tenant: "/default"
owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564301"
host: "ghrun-jhhelrhzfq"
node_id: 1
} FAILED: { <main>: Error: Control Plane is not ready yet. Please retry later., code: 1007 }
2025-12-09T09:47:46.611511Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564301, Host: ghrun-jhhelrhzfq, Tenant: /default, Got CP::GetTask Response
2025-12-09T09:47:46.612215Z :YQL_PRIVATE_PROXY ERROR: task_get.cpp:72: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564301, Host: ghrun-jhhelrhzfq, Tenant: /default, Failed with code: GENERIC_ERROR Details: <main>: Error: Control Plane is not ready yet. Please retry later., code: 1007
<main>: Error: ControlPlane::GetTaskError
2025-12-09T09:47:46.616705Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T09:47:46.616825Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: Control Plane is not ready yet. Please retry later., code: 1007
<main>: Error: ControlPlane::GetTaskError
2025-12-09T09:47:46.673663Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: NodesHealthCheckRequest - NodesHealthCheckResult: {nodes { node_id: 1 instance_id: "b4d689f4-46b202e1-fb60adbb-42c3b9eb" hostname: "ghrun-jhhelrhzfq" node_address: "127.0.1.1" } } SUCCESS
2025-12-09T09:47:46.682363Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-12-09T09:47:47.587049Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Host: ghrun-jhhelrhzfq, Tenant: /default
2025-12-09T09:47:47.590086Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581792662589397313:2127]
2025-12-09T09:47:47.590134Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T09:47:47.590193Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581792662589397248:2069]
2025-12-09T09:47:47.590215Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581792662589397248:2069]
2025-12-09T09:47:47.590237Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581792662589397248:2069]
2025-12-09T09:47:47.590252Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T09:47:47.602390Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Host: ghrun-jhhelrhzfq, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T09:47:47.602564Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
host: "ghrun-jhhelrhzfq"
node_id: 1
}
2025-12-09T09:47:47.632687Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:456: Task (Query): ptqudqfopqd46ndtftnf Lease TIMEOUT, RetryCounterUpdatedAt 2025-12-09T09:47:26.296246Z LastSeenAt: 2025-12-09T09:47:33.481049Z
2025-12-09T09:47:47.632794Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudqfopqd46ndtftnf RetryRate: 0 RetryCounter: 2 At: 2025-12-09T09:47:47.632316Z
2025-12-09T09:47:47.708083Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudqfopqd46ndtftnf" } job_id { value: "ptjudqfopqd46121sc65" } generation: 3 streaming: true dq_graph: "\022\302\005\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\006*\325\002Z\322\002\n\010PqSource\022\303\002\n4type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSource\022\212\002\n\016low_rate_input\022\024ptqudqfopqd46ndtftnf\032\016localhost:8465\"\032\n\030cluster:default_low_rate*\005local0\002@\001b\004Dataj\025[\"DataType\";\"String\"]\222\001\024ptcudqfopu1e9vcjv5il\232\001\003raw\242\001/[\"StructType\";[[\"Data\";[\"DataType\";\"String\"]]]]\262\001\002\022\000\272\001\026\n\010cloud_id\022\nmock_cloud\272\001 \n\005scope\022\027yandexcloud://my_folder\030\0012D\n@\010\001\020\240\234\001\030\001 \002*\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\001\000\000\0002\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\002\000\000\000P\001X\202\235\014`\223\235\014R\000:\203\001\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022U\010\202\235\014\022A\022(aacdc83876fc9e9fecb4111bf4adda306264256e\032\023dq_vanilla_job.lite \002\"\014\n\002pq\022\006\n\004\010\001\030\001H\202\235\014RClocal/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiterZ)mock_cloud/my_folder/ptqudqfopqd46ndtftnf`\200\200\200\200 \230\001\000\300\001\001\022\227\003\010\002\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\006*D\n@\010\001\020\240\234\001\030\001 \002*\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\001\000\000\0002\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\002\000\000\000P\001X\202\235\014`\223\235\014R\0002:\n6\010\002\020\240\234\001\030\002*\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\002\000\000\0002\024\n\022\t\001\000\000\000\000\000\000\000\021\000\000\000\000\001\000\000\000P\001R\000:u\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022G\010\223\235\014\022A\022(aacdc83876fc9e9fecb4111bf4adda306264256e\032\023dq_vanilla_job.lite \002H\223\235\014RClocal/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiterZ)mock_cloud/my_folder/ptqudqfopqd46ndtftnf`\200\200\200\200 \230\001\000\300\001\001\030\001\"\r\037\000\205\002\203\005@\020Data/*\004Data2pptqudqfopqd46ndtftnf#ptrudqfoplvvivhhaqj7#yandexcloud://my_folder#327d68a4-96c4d6d-ee7f6f6-1b2343ed17#mock_cloud:\027\n\016FallbackPolicy\022\005never:\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\0011B*\n\030cluster:default_low_rate\022\016{\"no_auth\":\"\"}bR\010\223\235\014\022L\037\002\006Arg\005\205\006\213\002\204\205\002\203\005@\020Data\205\000?\004\030Inputs(Parameters\034Program\013?\006)\251\000?\004\002\000\002\000)\251\000?\010\002\000\004?\016\001/b\202\002\010\202\235\014\022\373\001\037\004\006Arg\010Data\005\205\006\213\002\204\203\005@\205\000\204\205\002\203\005@\006\030Inputs(Parameters\034Program\013?\004)\251\000?\002\002\000\n\000)\251\000?\006\002\000\026)\211\002?\014\215?\n FromFlow\000)\211\006?\032\215\233\002?\010?\010?\n$NarrowMap\000)\211\006? \215\205\002?\010\006?$?\010$ExpandMap\000)\211\006?&\215?\000?\000?$\014Map\000)\211\002?*?\002\030ToFlow\000?\022\000\014)\251\000?\000\002\000\010\005?$?4\000\004\016)\251\000?$\002\000\004)\211\004?\010?$\203\004\030Member\000?<\003?>\000\002\006\000\020)\251\000?\010\002\000\002\005?\n?J\000\004\022\000\024\001/" text: "SELECT * FROM low_rate.`low_rate_input`;" connection { content { name: "low_rate" setting { data_streams { auth { none { } } endpoint: "localhost:8465" database: "local" } } acl { visibility: PRIVATE } } meta { id: "ptcudqfopu1e9vcjv5il" created_b...} SUCCESS
2025-12-09T09:47:47.708463Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Host: ghrun-jhhelrhzfq, Tenant: /default, Got CP::GetTask Response
2025-12-09T09:47:47.712786Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T09:47:47.712805Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-12-09T09:47:47.713233Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudqfopqd46ndtftnf UserId: root@builtin AuthToken: **** (B6C6F477)
2025-12-09T09:47:47.714400Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudqfopqd46ndtftnf Start run actor. Compute state: RUNNING
2025-12-09T09:47:47.714414Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudqfopqd46ndtftnf FillConnections
2025-12-09T09:47:47.714445Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564303, Host: ghrun-jhhelrhzfq, Tenant: /default
2025-12-09T09:47:47.714565Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudqfopqd46ndtftnf Run actors params: { QueryId: ptqudqfopqd46ndtftnf CloudId: mock_cloud UserId: root@builtin Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 PreviousQueryRevision: 3 Connections: 1 Bindings: 0 AccountIdSignatures: 0 QueryType: STREAMING ExecuteMode: RUN ResultId: ptrudqfop1lhh8boeopp StateLoadMode: FROM_LAST_CHECKPOINT StreamingDisposition: { from_last_checkpoint { } } Status: RUNNING DqGraphs: 1 DqGraphIndex: 0 Resource.TopicConsumers: 1 }
2025-12-09T09:47:47.715028Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Start Pinger
2025-12-09T09:47:47.725282Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudqfopqd46ndtftnf Overall dq tasks: 2
2025-12-09T09:47:47.732735Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward ping request: { resources { rate_limiter: READY compilation: READY topic_consumers_state: READY rate_limiter_path: "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter" topic_consumers { database: "local" topic_path: "low_rate_input" consumer_name: "ptqudqfopqd46ndtftnf" cluster_endpoint: "localhost:8465" token_name: "cluster:default_low_rate" add_bearer_to_token: true } } }
2025-12-09T09:47:47.732767Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward request Private::PingTask
2025-12-09T09:47:47.750457Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564303, Host: ghrun-jhhelrhzfq, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T09:47:47.751044Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564303"
host: "ghrun-jhhelrhzfq"
node_id: 1
}
2025-12-09T09:47:47.772866Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 284 bytes
2025-12-09T09:47:47.773785Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: READY
  rate_limiter_path: "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter"
  topic_consumers {
    database: "local"
    topic_path: "low_rate_input"
    consumer_name: "ptqudqfopqd46ndtftnf"
    cluster_endpoint: "localhost:8465"
    token_name: "cluster:default_low_rate"
    add_bearer_to_token: true
  }
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:47.798887Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T09:47:47.799026Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564303, Host: ghrun-jhhelrhzfq, Tenant: /default, Got CP::GetTask Response
2025-12-09T09:47:47.808792Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T09:47:47.808805Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T09:47:47.887075Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273668 nanos: 838000000 } } SUCCESS
2025-12-09T09:47:47.887383Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:47.889831Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273668 nanos: 838000000 } }
2025-12-09T09:47:47.907924Z :FQ_RESULT_WRITER INFO: result_writer.cpp:62: TraceId: ptqudqfopqd46ndtftnf#ptrudqfoplvvivhhaqj7#yandexcloud://my_folder#327d68a4-96c4d6d-ee7f6f6-1b2343ed17#mock_cloud Bootstrap
2025-12-09T09:47:47.908568Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.908 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] task_controller_impl.h:83: _AllResultsBytesLimit = 100000
2025-12-09T09:47:47.908696Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.908 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] task_controller_impl.h:86: _RowsLimitPerWrite = 1000
2025-12-09T09:47:47.909255Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1652: QueryId: ptqudqfopqd46ndtftnf Executer: [1:7581792671179331972:2178], Controller: [1:7581792671179331981:2187], ResultIdActor: [1:7581792671179331979:2185]
2025-12-09T09:47:47.909374Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudqfopqd46ndtftnf Forward ping response. Success: 1. Cookie: 0
2025-12-09T09:47:47.909504Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.909 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:140: {ptqudqfopqd46ndtftnf} TDqExecuter::OnGraph
2025-12-09T09:47:47.909649Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.909 INFO  ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:157: {ptqudqfopqd46ndtftnf} Trying to allocate 2 workers [EnableComputeActor=1]
2025-12-09T09:47:47.915656Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.915 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:247: {ptqudqfopqd46ndtftnf} Dq timeouts are set to: 0.000000s (global), 600.000000s (workers allocation fail), 30.000000s (workers allocation warn) 
2025-12-09T09:47:47.934100Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:125: TEvAllocateWorkersResponse Nodes {
  ResourceId: 4294967297
  Worker {
    NodeId: 1
    Guid: "b4d689f4-46b202e1-fb60adbb-42c3b9eb,ghrun-jhhelrhzfq"
  }
  Worker {
    NodeId: 1
    Guid: "b4d689f4-46b202e1-fb60adbb-42c3b9eb,ghrun-jhhelrhzfq"
  }
}
2025-12-09T09:47:47.934403Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.934 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:141: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} TEvAllocateWorkersResponse 1
2025-12-09T09:47:47.934509Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.934 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:106: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} RequestActorIdsFromNodes 1
2025-12-09T09:47:47.934767Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.934 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:115: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} RequestActorIdsFromNodes 1 4294967297
2025-12-09T09:47:47.934876Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.934 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:119: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} RequestedNode: 1
2025-12-09T09:47:47.935059Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.935 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:119: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} RequestedNode: 1
2025-12-09T09:47:47.935305Z :YQL_PROXY WARN: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.935 WARN  ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:259: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} Send TEvAllocateWorkersRequest to Id:b4d689f4-46b202e1-fb60adbb-42c3b9eb,ghrun-jhhelrhzfq,NodeId:1,
2025-12-09T09:47:47.935413Z :YQL_PROXY WARN: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.935 WARN  ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:259: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} Send TEvAllocateWorkersRequest to Id:b4d689f4-46b202e1-fb60adbb-42c3b9eb,ghrun-jhhelrhzfq,NodeId:1,
2025-12-09T09:47:47.935599Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.935 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] local_worker_manager.cpp:316: {ptqudqfopqd46ndtftnf} TLocalWorkerManager::TEvAllocateWorkersRequest 562954248388609
2025-12-09T09:47:47.935757Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.935 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] local_worker_manager.cpp:376: {ptqudqfopqd46ndtftnf} Create compute actor: async
2025-12-09T09:47:47.936797Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.936 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] local_worker_manager.cpp:316: {ptqudqfopqd46ndtftnf} TLocalWorkerManager::TEvAllocateWorkersRequest 281479271677953
2025-12-09T09:47:47.936882Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.936 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] local_worker_manager.cpp:376: {ptqudqfopqd46ndtftnf} Create compute actor: async
2025-12-09T09:47:47.937385Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.937 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:141: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} TEvAllocateWorkersResponse 1
2025-12-09T09:47:47.937525Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:144: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Start compute actor [1:7581792671179331983:2189], task: 2
2025-12-09T09:47:47.937683Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:114: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter", "mock_cloud/my_folder/ptqudqfopqd46ndtftnf" }
2025-12-09T09:47:47.938110Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:144: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Start compute actor [1:7581792671179331984:2190], task: 1
2025-12-09T09:47:47.938211Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:114: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_b4b49749-875b-46b6-a62d-2e8eac108e20/rate_limiter", "mock_cloud/my_folder/ptqudqfopqd46ndtftnf" }
2025-12-09T09:47:47.938939Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:47.938 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] resource_allocator.cpp:141: {ptqudqfopqd46ndtftnf/[1:7581792671179331982:2188]} TEvAllocateWorkersResponse 1
2025-12-09T09:47:47.940002Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.939 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:358: {ptqudqfopqd46ndtftnf} TDqExecuter::TEvAllocateWorkersResponse
2025-12-09T09:47:47.940198Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.940 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:384: {ptqudqfopqd46ndtftnf} Allocated resource 4294967297
2025-12-09T09:47:47.940891Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.940 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:401: {ptqudqfopqd46ndtftnf} WorkerInfo: Id:b4d689f4-46b202e1-fb60adbb-42c3b9eb,ghrun-jhhelrhzfq,NodeId:1,StageId:200322
2025-12-09T09:47:47.941055Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.940 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:402: {ptqudqfopqd46ndtftnf} TaskInfo: 0/1
2025-12-09T09:47:47.941411Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.941 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:404: {ptqudqfopqd46ndtftnf}  ObjectId: aacdc83876fc9e9fecb4111bf4adda306264256e, dq_vanilla_job.lite, 
2025-12-09T09:47:47.941646Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.941 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:401: {ptqudqfopqd46ndtftnf} WorkerInfo: Id:b4d689f4-46b202e1-fb60adbb-42c3b9eb,ghrun-jhhelrhzfq,NodeId:1,StageId:200339
2025-12-09T09:47:47.941745Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.941 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:402: {ptqudqfopqd46ndtftnf} TaskInfo: 1/2
2025-12-09T09:47:47.942097Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.942 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:404: {ptqudqfopqd46ndtftnf}  ObjectId: aacdc83876fc9e9fecb4111bf4adda306264256e, dq_vanilla_job.lite, 
2025-12-09T09:47:47.942272Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudqfopqd46ndtftnf 2025-12-09 09:47:47.942 INFO  ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] executer_actor.cpp:415: {ptqudqfopqd46ndtftnf} 2 workers allocated
2025-12-09T09:47:47.943437Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:47.943 TRACE ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] task_controller_impl.h:271:  [1:7581792671179331981:2187] ExportStats Summary
2025-12-09T09:47:47.943719Z :YQL_PROXY DEBUG: log.cpp:64: SessionId:  2025-12-09 09:47:47.943 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] task_controller_impl.h:526: Ready State: [1:7581792671179331981:2187]
2025-12-09T09:47:47.944078Z :YQL_PROXY DEBUG: log.cpp:64: SessionId:  2025-12-09 09:47:47.944 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] task_controller_impl.h:537: Forward: [1:7581792671179331981:2187] to [1:7581792671179331980:2186]
2025-12-09T09:47:47.944251Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:70: [ptqudqfopqd46ndtftnf-0.3] TEvReadyState, streaming disposition { from_last_checkpoint { } }, state load mode FROM_LAST_CHECKPOINT, checkpointing period 1.000000s
2025-12-09T09:47:47.944321Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:104: [ptqudqfopqd46ndtftnf-0.3] AllActors count: 2, ActorsToTrigger count: 1, ActorsToNotify count: 1, ActorsToWaitFor count: 2
2025-12-09T09:47:47.944344Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:114: [ptqudqfopqd46ndtftnf-0.3] Send TEvRegisterCoordinatorRequest
2025-12-09T09:47:47.944380Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:261: [ptqudqfopqd46ndtftnf-0.3] Got TEvRegisterCoordinatorRequest
2025-12-09T09:47:47.944386Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:243: StartInitialization, EnableSecureScriptExecutions: 0
2025-12-09T09:47:47.956933Z :STREAMS_STORAGE_SERVICE NOTICE: storage_proxy.cpp:585: Add to delayed
2025-12-09T09:47:47.980954Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:47.980 TRACE ydbd(pid=772428, tid=0x00007BF033393640) [DQ] task_controller_impl.h:160: [1:7581792671179331981:2187] EvState TaskId: 2 State: 2 PingCookie: 0 StatusCode: UNSPECIFIED
2025-12-09T09:47:47.981246Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:47.981 TRACE ydbd(pid=772428, tid=0x00007BF033393640) [DQ] task_controller_impl.h:201:  [1:7581792671179331981:2187] Executing TaskId: 2
2025-12-09T09:47:48.040749Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:557: Checkpoint storage and state storage were successfully initted
2025-12-09T09:47:48.040787Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:261: [ptqudqfopqd46ndtftnf-0.3] Got TEvRegisterCoordinatorRequest
2025-12-09T09:47:48.214758Z :FQ_PINGER TRACE: pinger.cpp:400: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Request Private::PingTask
2025-12-09T09:47:48.227831Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:279: [ptqudqfopqd46ndtftnf-0.3] Graph registered
2025-12-09T09:47:48.227864Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:281: [ptqudqfopqd46ndtftnf-0.3] Send TEvRegisterCoordinatorResponse
2025-12-09T09:47:48.228008Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:131: [ptqudqfopqd46ndtftnf-0.3] Got TEvRegisterCoordinatorResponse; issues: 
2025-12-09T09:47:48.228023Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:140: [ptqudqfopqd46ndtftnf-0.3] Successfully registered in storage
2025-12-09T09:47:48.228034Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:141: [ptqudqfopqd46ndtftnf-0.3] Send TEvNewCheckpointCoordinator to 2 actor(s)
2025-12-09T09:47:48.228095Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:149: [ptqudqfopqd46ndtftnf-0.3] Send TEvGetCheckpointsMetadataRequest; state load mode: FROM_LAST_CHECKPOINT; load graph: 0
2025-12-09T09:47:48.228294Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:197: [] Task: 2. Got TEvNewCheckpointCoordinator event: generation 3, actorId: [1:7581792671179331980:2186]
2025-12-09T09:47:48.228310Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:208: [] Task: 2. Assign checkpoint coordinator (generation = 3)
2025-12-09T09:47:48.228353Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:434: [ptqudqfopqd46ndtftnf-0] Got TEvGetCheckpointsMetadataRequest
2025-12-09T09:47:48.257234Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 114 bytes
2025-12-09T09:47:48.257426Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:48.341753Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273669 nanos: 297000000 } } SUCCESS
2025-12-09T09:47:48.341960Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:48.344348Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273669 nanos: 297000000 } }
2025-12-09T09:47:48.588222Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564304, Host: ghrun-jhhelrhzfq, Tenant: /default
2025-12-09T09:47:48.608713Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564304, Host: ghrun-jhhelrhzfq, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T09:47:48.608882Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564304"
host: "ghrun-jhhelrhzfq"
node_id: 1
}
2025-12-09T09:47:48.628684Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:197: [] Task: 1. Got TEvNewCheckpointCoordinator event: generation 3, actorId: [1:7581792671179331980:2186]
2025-12-09T09:47:48.628714Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:208: [] Task: 1. Assign checkpoint coordinator (generation = 3)
2025-12-09T09:47:48.633101Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T09:47:48.633313Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564304, Host: ghrun-jhhelrhzfq, Tenant: /default, Got CP::GetTask Response
2025-12-09T09:47:48.634784Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1360: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Create source for input 0 { Source { Type: "PqSource" Settings { type_url: "type.googleapis.com/NYql.NPq.NProto.TDqPqTopicSource" value: "\n\016low_rate_input\022\024ptqudqfopqd46ndtftnf\032\016localhost:8465\"\032\n\030cluster:default_low_rate*\005local0\002@\001b\004Dataj\025[\"DataType\";\"String\"]\222\001\024ptcudqfopu1e9vcjv5il\232\001\003raw\242\001/[\"StructType\";[[\"Data\";[\"DataType\";\"String\"]]]]\262\001\002\022\000\272\001\026\n\010cloud_id\022\nmock_cloud\272\001 \n\005scope\022\027yandexcloud://my_folder" } WatermarksMode: WATERMARKS_MODE_DISABLED } }
2025-12-09T09:47:48.637011Z :KQP_COMPUTE DEBUG: dq_pq_read_actor_base.cpp:153: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: empty Watermarks enabled: 0 granularity: 0.000000s late arrival delay: 0.000000s idle: 0 idle timeout: 0.000000s
2025-12-09T09:47:48.640877Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:48.640 TRACE ydbd(pid=772428, tid=0x00007BF030D4D640) [DQ] task_controller_impl.h:160: [1:7581792671179331981:2187] EvState TaskId: 1 State: 2 PingCookie: 0 StatusCode: UNSPECIFIED
2025-12-09T09:47:48.640987Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:48.640 TRACE ydbd(pid=772428, tid=0x00007BF030D4D640) [DQ] task_controller_impl.h:201:  [1:7581792671179331981:2187] Executing TaskId: 1
2025-12-09T09:47:48.641125Z :YQL_PROXY DEBUG: log.cpp:64: SessionId:  2025-12-09 09:47:48.641 DEBUG ydbd(pid=772428, tid=0x00007BF030D4D640) [DQ] task_controller_impl.h:561: Update channels
2025-12-09T09:47:48.644361Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T09:47:48.644375Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T09:47:48.644631Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:48.641 TRACE ydbd(pid=772428, tid=0x00007BF030D4D640) [DQ] task_controller_impl.h:577: 1 Update { Id: 1 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 1 DstTaskId: 2 SrcEndpoint { ActorId { RawX1: 7581792671179331984 RawX2: 4503603922339982 } } DstEndpoint { ActorId { RawX1: 7581792671179331983 RawX2: 4503603922339981 } } WatermarksMode: WATERMARKS_MODE_DISABLED SrcStageId: 200322 DstStageId: 200339 }
2025-12-09T09:47:48.644963Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:48.644 TRACE ydbd(pid=772428, tid=0x00007BF030D4D640) [DQ] task_controller_impl.h:577: 2 Update { Id: 1 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 1 DstTaskId: 2 SrcEndpoint { ActorId { RawX1: 7581792671179331984 RawX2: 4503603922339982 } } DstEndpoint { ActorId { RawX1: 7581792671179331983 RawX2: 4503603922339981 } } WatermarksMode: WATERMARKS_MODE_DISABLED SrcStageId: 200322 DstStageId: 200339 } Update { Id: 2 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 2 SrcEndpoint { ActorId { RawX1: 7581792671179331983 RawX2: 4503603922339981 } } DstEndpoint { ActorId { RawX1: 7581792671179331979 RawX2: 4503603922339977 } } WatermarksMode: WATERMARKS_MODE_DISABLED }
2025-12-09T09:47:48.645084Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1064: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Received channels info: Update { Id: 1 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 1 DstTaskId: 2 SrcEndpoint { ActorId { RawX1: 7581792671179331984 RawX2: 4503603922339982 } } DstEndpoint { ActorId { RawX1: 7581792671179331983 RawX2: 4503603922339981 } } WatermarksMode: WATERMARKS_MODE_DISABLED SrcStageId: 200322 DstStageId: 200339 }
2025-12-09T09:47:48.645123Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1083: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Update output channelId: 1, peer: [1:7581792671179331983:2189]
2025-12-09T09:47:48.645229Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1064: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Received channels info: Update { Id: 1 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 1 DstTaskId: 2 SrcEndpoint { ActorId { RawX1: 7581792671179331984 RawX2: 4503603922339982 } } DstEndpoint { ActorId { RawX1: 7581792671179331983 RawX2: 4503603922339981 } } WatermarksMode: WATERMARKS_MODE_DISABLED SrcStageId: 200322 DstStageId: 200339 } Update { Id: 2 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 2 SrcEndpoint { ActorId { RawX1: 7581792671179331983 RawX2: 4503603922339981 } } DstEndpoint { ActorId { RawX1: 7581792671179331979 RawX2: 4503603922339977 } } WatermarksMode: WATERMARKS_MODE_DISABLED }
2025-12-09T09:47:48.645247Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1071: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Update input channelId: 1, peer: [1:7581792671179331984:2190]
2025-12-09T09:47:48.645259Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1083: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Update output channelId: 2, peer: [1:7581792671179331979:2185]
2025-12-09T09:47:48.765070Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:451: [ptqudqfopqd46ndtftnf-0] Send TEvGetCheckpointsMetadataResponse
2025-12-09T09:47:48.765371Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:186: [ptqudqfopqd46ndtftnf-0.3] Got TEvGetCheckpointsMetadataResponse
2025-12-09T09:47:48.765393Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:219: [ptqudqfopqd46ndtftnf-0.3] Will restore from checkpoint 2:1
2025-12-09T09:47:48.766878Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:265: [ptqudqfopqd46ndtftnf-0] Task: 2. [Checkpoint 2.1] TEvRestoreFromCheckpoint, StateLoadPlan = { StateType: STATE_TYPE_OWN }
2025-12-09T09:47:48.766951Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:265: [ptqudqfopqd46ndtftnf-0] Task: 1. [Checkpoint 2.1] TEvRestoreFromCheckpoint, StateLoadPlan = { StateType: STATE_TYPE_OWN }
2025-12-09T09:47:48.767010Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:508: [ptqudqfopqd46ndtftnf-0] [2:1] Got TEvGetTaskState: tasks {2}
2025-12-09T09:47:48.767056Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:519: [ptqudqfopqd46ndtftnf-0] [2:1] GetState, tasks: 2
2025-12-09T09:47:48.767221Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:508: [ptqudqfopqd46ndtftnf-0] [2:1] Got TEvGetTaskState: tasks {1}
2025-12-09T09:47:48.767235Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:519: [ptqudqfopqd46ndtftnf-0] [2:1] GetState, tasks: 1
2025-12-09T09:47:48.852678Z :FQ_PINGER TRACE: pinger.cpp:400: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Request Private::PingTask
2025-12-09T09:47:48.866191Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 114 bytes
2025-12-09T09:47:48.866388Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:48.889014Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-09T09:47:48.944517Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:48.944 TRACE ydbd(pid=772428, tid=0x00007BF033393640) [DQ] task_controller_impl.h:271:  [1:7581792671179331981:2187] ExportStats Summary
2025-12-09T09:47:48.945656Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward ping request: { statistics: "{\"Graph=0\":{\"TaskRunner\":{\"Stage=200322\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=200339\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1}}}}}" }
2025-12-09T09:47:48.945679Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward request Private::PingTask
2025-12-09T09:47:48.949741Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273669 nanos: 896000000 } } SUCCESS
2025-12-09T09:47:48.950014Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:48.957529Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273669 nanos: 896000000 } }
2025-12-09T09:47:48.974873Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 591 bytes
2025-12-09T09:47:48.975142Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
statistics: "{\"Graph=0\":{\"TaskRunner\":{\"Stage=200322\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=200339\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1}}}}}"
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:49.077923Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273670 nanos: 22000000 } } SUCCESS
2025-12-09T09:47:49.078196Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:49.084157Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273670 nanos: 22000000 } }
2025-12-09T09:47:49.084237Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudqfopqd46ndtftnf Forward ping response. Success: 1. Cookie: 6
2025-12-09T09:47:49.456907Z :FQ_PINGER TRACE: pinger.cpp:400: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Request Private::PingTask
2025-12-09T09:47:49.479622Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 114 bytes
2025-12-09T09:47:49.479796Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:49.546557Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273670 nanos: 513000000 } } SUCCESS
2025-12-09T09:47:49.546778Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:49.549009Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273670 nanos: 513000000 } }
2025-12-09T09:47:49.589101Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564305, Host: ghrun-jhhelrhzfq, Tenant: /default
2025-12-09T09:47:49.591768Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581792662589397313:2127]
2025-12-09T09:47:49.591798Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T09:47:49.591819Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581792662589397248:2069]
2025-12-09T09:47:49.591834Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581792662589397248:2069]
2025-12-09T09:47:49.591845Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581792662589397248:2069]
2025-12-09T09:47:49.591864Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T09:47:49.613448Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564305, Host: ghrun-jhhelrhzfq, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T09:47:49.613567Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564305"
host: "ghrun-jhhelrhzfq"
node_id: 1
}
2025-12-09T09:47:49.643372Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T09:47:49.643533Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564305, Host: ghrun-jhhelrhzfq, Tenant: /default, Got CP::GetTask Response
2025-12-09T09:47:49.646375Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T09:47:49.646389Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T09:47:49.718489Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:654: [ptqudqfopqd46ndtftnf-0] [2:1] ListOfStates results:
2025-12-09T09:47:49.718586Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:675: [ptqudqfopqd46ndtftnf-0] [2:1] taskId 1 checkpoint id: 2:1, rows count: 1
2025-12-09T09:47:49.718620Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:901: [ptqudqfopqd46ndtftnf-0] [2:1] SkipStatesInFuture, skip 0 checkpoints
2025-12-09T09:47:49.718834Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:796: [ptqudqfopqd46ndtftnf-0] [2:1] SelectState: task_id 1, seq_no 1, blob_seq_num 0
2025-12-09T09:47:49.725375Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:654: [ptqudqfopqd46ndtftnf-0] [2:1] ListOfStates results:
2025-12-09T09:47:49.725454Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:675: [ptqudqfopqd46ndtftnf-0] [2:1] taskId 2 checkpoint id: 2:1, rows count: 1
2025-12-09T09:47:49.725490Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:901: [ptqudqfopqd46ndtftnf-0] [2:1] SkipStatesInFuture, skip 0 checkpoints
2025-12-09T09:47:49.725680Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:796: [ptqudqfopqd46ndtftnf-0] [2:1] SelectState: task_id 2, seq_no 1, blob_seq_num 0
2025-12-09T09:47:49.945558Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:49.945 TRACE ydbd(pid=772428, tid=0x00007BF033393640) [DQ] task_controller_impl.h:271:  [1:7581792671179331981:2187] ExportStats Summary
2025-12-09T09:47:49.946512Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward ping request: { statistics: "{\"Graph=0\":{\"TaskRunner\":{\"Stage=200322\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=200339\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1}}}}}" }
2025-12-09T09:47:49.946528Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward request Private::PingTask
2025-12-09T09:47:49.967641Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 591 bytes
2025-12-09T09:47:49.967868Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
statistics: "{\"Graph=0\":{\"TaskRunner\":{\"Stage=200322\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=200339\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1}}}}}"
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:50.048804Z :FQ_PINGER TRACE: pinger.cpp:400: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Request Private::PingTask
2025-12-09T09:47:50.058283Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273671 nanos: 7000000 } } SUCCESS
2025-12-09T09:47:50.058510Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:50.062397Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273671 nanos: 7000000 } }
2025-12-09T09:47:50.062473Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudqfopqd46ndtftnf Forward ping response. Success: 1. Cookie: 6
2025-12-09T09:47:50.070598Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 114 bytes
2025-12-09T09:47:50.070835Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:50.150173Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273671 nanos: 112000000 } } SUCCESS
2025-12-09T09:47:50.150377Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:50.153663Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273671 nanos: 112000000 } }
2025-12-09T09:47:50.355937Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:410: [ptqudqfopqd46ndtftnf-0] [2:1] DeserializeState, task id 1, blob size 101
2025-12-09T09:47:50.356010Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:960: [ptqudqfopqd46ndtftnf-0] [2:1] ApplyIncrements
2025-12-09T09:47:50.356469Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:527: [ptqudqfopqd46ndtftnf-0] [{ Id: 1 Generation: 2 }] Send TEvGetTaskStateResult: tasks: {1}
2025-12-09T09:47:50.356730Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:336: [ptqudqfopqd46ndtftnf-0] Task: 1. [Checkpoint 2.1] TEvGetTaskStateResult: restoring state
2025-12-09T09:47:50.356749Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:793: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Load state
2025-12-09T09:47:50.356787Z :KQP_COMPUTE DEBUG: dq_pq_read_actor_base.cpp:153: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: empty Watermarks enabled: 0 granularity: 0.000000s late arrival delay: 0.000000s idle: 0 idle timeout: 0.000000s
2025-12-09T09:47:50.356846Z :KQP_COMPUTE DEBUG: dq_pq_read_actor_base.cpp:133: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: empty StartingMessageTs 2025-12-09T09:47:29.669000Z Restoring offset: 
2025-12-09T09:47:50.356888Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:362: [ptqudqfopqd46ndtftnf-0] Task: 1. [Checkpoint 2.1] Checkpoint state restored
2025-12-09T09:47:50.356971Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:297: [ptqudqfopqd46ndtftnf-0.3] [2:1] Got TEvRestoreFromCheckpointResult; taskId: 1, checkpoint: 2:1, status: OK, issues: 
2025-12-09T09:47:50.356989Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:320: [ptqudqfopqd46ndtftnf-0.3] [2:1] Task state restored, need 1 more acks
2025-12-09T09:47:50.361686Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:410: [ptqudqfopqd46ndtftnf-0] [2:1] DeserializeState, task id 2, blob size 29
2025-12-09T09:47:50.361741Z :STREAMS_STORAGE_SERVICE DEBUG: ydb_state_storage.cpp:960: [ptqudqfopqd46ndtftnf-0] [2:1] ApplyIncrements
2025-12-09T09:47:50.361859Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:527: [ptqudqfopqd46ndtftnf-0] [{ Id: 1 Generation: 2 }] Send TEvGetTaskStateResult: tasks: {2}
2025-12-09T09:47:50.362199Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:336: [ptqudqfopqd46ndtftnf-0] Task: 2. [Checkpoint 2.1] TEvGetTaskStateResult: restoring state
2025-12-09T09:47:50.362231Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:793: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Load state
2025-12-09T09:47:50.362265Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:362: [ptqudqfopqd46ndtftnf-0] Task: 2. [Checkpoint 2.1] Checkpoint state restored
2025-12-09T09:47:50.362312Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:297: [ptqudqfopqd46ndtftnf-0.3] [2:1] Got TEvRestoreFromCheckpointResult; taskId: 2, checkpoint: 2:1, status: OK, issues: 
2025-12-09T09:47:50.362330Z :STREAMS_CHECKPOINT_COORDINATOR DEBUG: checkpoint_coordinator.cpp:320: [ptqudqfopqd46ndtftnf-0.3] [2:1] Task state restored, need 0 more acks
2025-12-09T09:47:50.362353Z :STREAMS_CHECKPOINT_COORDINATOR INFO: checkpoint_coordinator.cpp:445: [ptqudqfopqd46ndtftnf-0.3] Send TEvRun to all actors
2025-12-09T09:47:50.362394Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1164: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Got TEvRun from actor [1:7581792671179331980:2186]
2025-12-09T09:47:50.362432Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1483: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll inputs
2025-12-09T09:47:50.362441Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1498: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll sources
2025-12-09T09:47:50.362469Z :KQP_COMPUTE TRACE: dq_pq_read_actor.cpp:542: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: empty GetAsyncInputData freeSpace = 1
2025-12-09T09:47:50.362560Z :KQP_COMPUTE DEBUG: dq_pq_read_actor.cpp:648: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: empty PartitionsToRead: 0
2025-12-09T09:47:50.362777Z :YDB_SDK INFO: actor.cpp:37: 2025-12-09T09:47:50.362754Z :INFO: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] Starting read session
2025-12-09T09:47:50.362821Z :YDB_SDK DEBUG: actor.cpp:37: 2025-12-09T09:47:50.362805Z :DEBUG: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] Starting single session
2025-12-09T09:47:50.362835Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1164: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Got TEvRun from actor [1:7581792671179331980:2186]
2025-12-09T09:47:50.362869Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1483: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Poll inputs
2025-12-09T09:47:50.362876Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1498: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Poll sources
2025-12-09T09:47:50.362890Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:698: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-09T09:47:50.363031Z :YDB_SDK DEBUG: actor.cpp:37: 2025-12-09T09:47:50.363009Z :DEBUG: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] In Reconnect, ReadSizeBudget = 8388608, ReadSizeServerDelta = 0
2025-12-09T09:47:50.363082Z :YDB_SDK DEBUG: actor.cpp:37: 2025-12-09T09:47:50.363058Z :DEBUG: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] New values: ReadSizeBudget = 8388608, ReadSizeServerDelta = 0
2025-12-09T09:47:50.363125Z :YDB_SDK DEBUG: actor.cpp:37: 2025-12-09T09:47:50.363108Z :DEBUG: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] Reconnecting session to cluster  in 0.000000s
2025-12-09T09:47:50.363142Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ec7cf084-8541cf9b-8ac00f8d-fbe56430 2025-12-09 09:47:50.362 DEBUG ydbd(pid=772428, tid=0x00007BF032B92640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-09T09:47:50.363316Z :KQP_COMPUTE TRACE: dq_compute_memory_quota.h:97: TxId: ptqudqfopqd46ndtftnf, task: 2. Peak memory usage: 2031616
2025-12-09T09:47:50.363348Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:493: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. update task runner stats
2025-12-09T09:47:50.363483Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:762: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-09T09:47:50.363498Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:406: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-09T09:47:50.363530Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:534: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:0;inFlightCount:0;)
2025-12-09T09:47:50.363576Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1107: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. Ask CPU quota: 45ms
2025-12-09T09:47:50.363659Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:605: TxId: ptqudqfopqd46ndtftnf, task: 2. no poll, channelId: 1, hasPeer: 1, finished: 0, retry: 0, lastSeqNo: 0, freeSpace: 1000000
2025-12-09T09:47:50.365664Z :KQP_COMPUTE INFO: dq_pq_read_actor.cpp:315: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: 95cb03fc-a8283210-ae5efa91-c63d606f CreateReadSession
2025-12-09T09:47:50.365736Z :KQP_COMPUTE TRACE: dq_compute_actor_async_input_helper.h:81: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll async input 0. Buffer free space: 1, read from async input: 0 bytes, 0 rows, finished: 0
2025-12-09T09:47:50.365856Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:844: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Send TEvContinueRun on OnAsyncInputPushFinished
2025-12-09T09:47:50.366046Z :KQP_COMPUTE TRACE: dq_compute_memory_quota.h:97: TxId: ptqudqfopqd46ndtftnf, task: 1. Peak memory usage: 2031616
2025-12-09T09:47:50.366067Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:493: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. update task runner stats
2025-12-09T09:47:50.366162Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:762: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-09T09:47:50.366171Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:406: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-09T09:47:50.366190Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:534: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. About to drain channelId: 1, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:0;inFlightCount:0;)
2025-12-09T09:47:50.366204Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1107: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Ask CPU quota: 702ms
2025-12-09T09:47:50.370970Z :YDB_SDK DEBUG: actor.cpp:37: 2025-12-09T09:47:50.370922Z :DEBUG: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] Successfully connected. Initializing session
2025-12-09T09:47:50.374641Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1165: SelfId: [1:7581792671179331983:2189], TxId: ptqudqfopqd46ndtftnf, task: 2. Ctx: {}. CPU quota delay: 11ms
2025-12-09T09:47:50.375153Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1165: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. CPU quota delay: 8ms
2025-12-09T09:47:50.378736Z :YDB_SDK INFO: actor.cpp:37: 2025-12-09T09:47:50.378679Z :INFO: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] Got InitResponse. ReadSessionId: ptqudqfopqd46ndtftnf_1_2_17448934547013382850_v1
2025-12-09T09:47:50.378803Z :YDB_SDK DEBUG: actor.cpp:37: 2025-12-09T09:47:50.378774Z :DEBUG: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] In ContinueReadingDataImpl, ReadSizeBudget = 8388608, ReadSizeServerDelta = 0
2025-12-09T09:47:50.379030Z :YDB_SDK DEBUG: actor.cpp:37: 2025-12-09T09:47:50.379004Z :DEBUG: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 8388608
2025-12-09T09:47:50.383506Z :KQP_COMPUTE TRACE: dq_pq_read_actor.cpp:363: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: 95cb03fc-a8283210-ae5efa91-c63d606f Source data ready
2025-12-09T09:47:50.383579Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1483: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll inputs
2025-12-09T09:47:50.383603Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1498: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll sources
2025-12-09T09:47:50.383637Z :KQP_COMPUTE TRACE: dq_pq_read_actor.cpp:542: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: 95cb03fc-a8283210-ae5efa91-c63d606f GetAsyncInputData freeSpace = 1000000
2025-12-09T09:47:50.383729Z :KQP_COMPUTE DEBUG: dq_pq_read_actor.cpp:820: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: 95cb03fc-a8283210-ae5efa91-c63d606f Key: 0@ StartPartitionSessionEvent received
2025-12-09T09:47:50.383752Z :KQP_COMPUTE DEBUG: dq_pq_read_actor.cpp:827: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: 95cb03fc-a8283210-ae5efa91-c63d606f Key: 0@ Confirm StartPartitionSession with offset (NULL)
2025-12-09T09:47:50.383807Z :YDB_SDK INFO: actor.cpp:37: 2025-12-09T09:47:50.383781Z :INFO: [local] [local] [95cb03fc-a8283210-ae5efa91-c63d606f] [] Confirm partition stream create. Partition stream id: 1. Cluster: "-". Topic: "low_rate_input". Partition: 0. Read offset: (NULL)
2025-12-09T09:47:50.383976Z :KQP_COMPUTE TRACE: dq_compute_actor_async_input_helper.h:81: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll async input 0. Buffer free space: 1000000, read from async input: 0 bytes, 0 rows, finished: 0
2025-12-09T09:47:50.384026Z :KQP_COMPUTE TRACE: dq_pq_read_actor.cpp:363: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: 95cb03fc-a8283210-ae5efa91-c63d606f Source data ready
2025-12-09T09:47:50.384040Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:844: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Send TEvContinueRun on OnAsyncInputPushFinished
2025-12-09T09:47:50.384249Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1483: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll inputs
2025-12-09T09:47:50.384255Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1498: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll sources
2025-12-09T09:47:50.384277Z :KQP_COMPUTE TRACE: dq_pq_read_actor.cpp:542: SelfId: [0:0:0], TxId: ptqudqfopqd46ndtftnf, task: 1. PQ source. SessionId: 95cb03fc-a8283210-ae5efa91-c63d606f GetAsyncInputData freeSpace = 1000000
2025-12-09T09:47:50.384298Z :KQP_COMPUTE TRACE: dq_compute_actor_async_input_helper.h:81: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Poll async input 0. Buffer free space: 1000000, read from async input: 0 bytes, 0 rows, finished: 0
2025-12-09T09:47:50.384334Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:493: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. update task runner stats
2025-12-09T09:47:50.384462Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:762: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-09T09:47:50.384474Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:406: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-09T09:47:50.384496Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:534: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. About to drain channelId: 1, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:0;inFlightCount:0;)
2025-12-09T09:47:50.384511Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:844: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Send TEvContinueRun on OnAsyncInputPushFinished
2025-12-09T09:47:50.384633Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:493: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. update task runner stats
2025-12-09T09:47:50.384673Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:762: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-09T09:47:50.384678Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:406: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-09T09:47:50.384691Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:534: SelfId: [1:7581792671179331984:2190], TxId: ptqudqfopqd46ndtftnf, task: 1. Ctx: {}. About to drain channelId: 1, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:0;inFlightCount:0;)
2025-12-09T09:47:50.589422Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564306, Host: ghrun-jhhelrhzfq, Tenant: /default
2025-12-09T09:47:50.604634Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564306, Host: ghrun-jhhelrhzfq, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T09:47:50.604787Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564306"
host: "ghrun-jhhelrhzfq"
node_id: 1
}
2025-12-09T09:47:50.637246Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T09:47:50.637420Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564306, Host: ghrun-jhhelrhzfq, Tenant: /default, Got CP::GetTask Response
2025-12-09T09:47:50.641133Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T09:47:50.641155Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T09:47:50.659658Z :FQ_PINGER TRACE: pinger.cpp:400: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Request Private::PingTask
2025-12-09T09:47:50.669618Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 114 bytes
2025-12-09T09:47:50.669813Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
2025-12-09T09:47:50.738363Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765273671 nanos: 708000000 } } SUCCESS
2025-12-09T09:47:50.738473Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Got CP::PingTaskResponse
2025-12-09T09:47:50.739714Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Ping response success: { expired_at { seconds: 1765273671 nanos: 708000000 } }
2025-12-09T09:47:50.804424Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:665: Coordinator: Change global state to Started (by timeout)
2025-12-09T09:47:50.852222Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-09T09:47:50.868451Z :YQ_CONTROL_PLANE_STORAGE TRACE: nodes_health_check.cpp:38: NodesHealthCheckRequest: {tenant: "/default"
node {
  node_id: 1
  instance_id: "b4d689f4-46b202e1-fb60adbb-42c3b9eb"
  hostname: "ghrun-jhhelrhzfq"
  active_workers: 2
  memory_allocated: 17179869184
  interconnect_port: 10981
  node_address: "127.0.1.1"
}
}
2025-12-09T09:47:50.930245Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: NodesHealthCheckRequest - NodesHealthCheckResult: {nodes { node_id: 1 instance_id: "b4d689f4-46b202e1-fb60adbb-42c3b9eb" hostname: "ghrun-jhhelrhzfq" active_workers: 2 memory_allocated: 17179869184 node_address: "127.0.1.1" } } SUCCESS
2025-12-09T09:47:50.932508Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-12-09T09:47:50.948643Z :YQL_PROXY TRACE: log.cpp:64: SessionId:  2025-12-09 09:47:50.948 TRACE ydbd(pid=772428, tid=0x00007BF033393640) [DQ] task_controller_impl.h:271:  [1:7581792671179331981:2187] ExportStats Summary
2025-12-09T09:47:50.949429Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward ping request: { statistics: "{\"Graph=0\":{\"TaskRunner\":{\"Stage=200322\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=200339\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1}}}}}" }
2025-12-09T09:47:50.949441Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302 Forward request Private::PingTask
2025-12-09T09:47:50.961469Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqfopqd46ndtftnf, Owner: ec7cf084-8541cf9b-8ac00f8d-fbe564302, Request CP::PingTask with size: 591 bytes
2025-12-09T09:47:50.961700Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "ec7cf084-8541cf9b-8ac00f8d-fbe564302"
query_id {
  value: "ptqudqfopqd46ndtftnf"
}
statistics: "{\"Graph=0\":{\"TaskRunner\":{\"Stage=200322\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=200339\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1}}}}}"
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765360067
  nanos: 668091000
}
tenant: "/default"
}
