2025-12-24T12:47:36.419993Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: 963ca84a-4ef48bab-19703b0-7e9d3d20
2025-12-24T12:47:36.420017Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-12-24T12:47:36.421531Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 3
2025-12-24T12:47:36.421840Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 3
2025-12-24T12:47:36.422643Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:58: STARTING: [3:7587405284495728410:2061]
2025-12-24T12:47:36.428844Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [3:7587405284495728421:2072]
2025-12-24T12:47:36.429197Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071101, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:36.429268Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-12-24T12:47:36.429300Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-12-24T12:47:36.429337Z :YQ_CONTROL_PLANE_STORAGE INFO: ydb_control_plane_storage.cpp:50: Starting ydb control plane storage service. Actor id: [3:7587405284495728409:2060]
2025-12-24T12:47:36.432633Z :YQ_CONTROL_PLANE_PROXY DEBUG: control_plane_proxy.cpp:525: Starting yandex query control plane proxy. Actor id: [3:7587405284495728411:2062]
2025-12-24T12:47:36.433035Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true ControlPlaneEnabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:4533" Database: "local" TablePrefix: "RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-12-24T12:47:36.483057Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-12-24T12:47:36.489767Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/result_sets" actor
2025-12-24T12:47:36.490172Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/jobs" actor
2025-12-24T12:47:36.490579Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-12-24T12:47:36.490594Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-12-24T12:47:36.490615Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenants" actor
2025-12-24T12:47:36.490701Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenant_acks" actor
2025-12-24T12:47:36.490913Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/compute_databases" actor
2025-12-24T12:47:36.490971Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:158: Bootstrap
2025-12-24T12:47:36.491143Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:178: Initialize
2025-12-24T12:47:36.610995Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/mappings" actor
2025-12-24T12:47:36.611151Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:532: RowDispatcher: Successfully bootstrapped row dispatcher, id [3:7587405284495728416:2067], tenant /default
2025-12-24T12:47:36.611778Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create directory "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea" actor
2025-12-24T12:47:36.611794Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create directory "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea"
2025-12-24T12:47:36.611802Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:383: Call create directory "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea"
2025-12-24T12:47:36.611990Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/queries" actor
2025-12-24T12:47:36.612217Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/idempotency_keys" actor
2025-12-24T12:47:36.612284Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-24T12:47:36.613197Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/nodes" actor
2025-12-24T12:47:36.613296Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/quotas" actor
2025-12-24T12:47:36.625924Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/pending_small" actor
2025-12-24T12:47:36.626097Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/bindings" actor
2025-12-24T12:47:36.626233Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:261: Coordinator: Successfully bootstrapped coordinator, id [3:7587405284495728523:2143]
2025-12-24T12:47:36.626286Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:223: TLeaderElection [3:7587405284495728524:2144] Successfully bootstrapped, local coordinator id [3:7587405284495728523:2143], tenant id default, local mode 0, coordination node path local/row_dispatcher/default
2025-12-24T12:47:36.626718Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:625: RowDispatcher: TEvCoordinatorChangesSubscribe from [3:7587405284495728523:2143]
2025-12-24T12:47:36.626785Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/row_dispatcher/default" actor
2025-12-24T12:47:36.626798Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/row_dispatcher/default"
2025-12-24T12:47:36.626810Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/row_dispatcher/default"
2025-12-24T12:47:36.626974Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/connections" actor
2025-12-24T12:47:36.627063Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create coordination node "local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter" actor
2025-12-24T12:47:36.627070Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create coordination node "local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter"
2025-12-24T12:47:36.627074Z :YQ_RATE_LIMITER DEBUG: schema.cpp:411: Call create coordination node "local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter"
2025-12-24T12:47:36.977749Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/jobs". Create session OK
2025-12-24T12:47:36.977788Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/jobs"
2025-12-24T12:47:36.977805Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/jobs"
2025-12-24T12:47:36.982458Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/result_sets". Create session OK
2025-12-24T12:47:36.982498Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/result_sets"
2025-12-24T12:47:36.982514Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/result_sets"
2025-12-24T12:47:36.985897Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenants". Create session OK
2025-12-24T12:47:36.985928Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenants"
2025-12-24T12:47:36.985938Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenants"
2025-12-24T12:47:36.988088Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/idempotency_keys". Create session OK
2025-12-24T12:47:36.988137Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/idempotency_keys"
2025-12-24T12:47:36.988162Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/idempotency_keys"
2025-12-24T12:47:36.988615Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/connections". Create session OK
2025-12-24T12:47:36.988667Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/connections"
2025-12-24T12:47:36.988675Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/connections"
2025-12-24T12:47:36.990929Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created coordination node "local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter"
2025-12-24T12:47:36.991005Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create coordination node "local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter": { <main>: Error: Check failed: path: '/local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 232], type: EPathTypeKesus, state: EPathStateNoChanges) }
2025-12-24T12:47:36.991068Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created directory "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea"
2025-12-24T12:47:36.991089Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create directory "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 226], type: EPathTypeDir, state: EPathStateNoChanges) }
2025-12-24T12:47:36.991127Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenant_acks". Create session OK
2025-12-24T12:47:36.991139Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenant_acks"
2025-12-24T12:47:36.991146Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenant_acks"
2025-12-24T12:47:36.991368Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/row_dispatcher/default"
2025-12-24T12:47:36.991415Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/row_dispatcher/default": { <main>: Error: Check failed: path: '/local/row_dispatcher/default', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 9], type: EPathTypeKesus, state: EPathStateNoChanges) }
2025-12-24T12:47:36.991463Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/queries". Create session OK
2025-12-24T12:47:36.991493Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/queries"
2025-12-24T12:47:36.991498Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/queries"
2025-12-24T12:47:37.002692Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/compute_databases". Create session OK
2025-12-24T12:47:37.002726Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/compute_databases"
2025-12-24T12:47:37.002733Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/compute_databases"
2025-12-24T12:47:37.005691Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:37.005708Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:37.005815Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 2 to DynamicNameserver
2025-12-24T12:47:37.005907Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/pending_small". Create session OK
2025-12-24T12:47:37.005929Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/pending_small"
2025-12-24T12:47:37.005942Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/pending_small"
2025-12-24T12:47:37.007636Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/bindings". Create session OK
2025-12-24T12:47:37.007658Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/bindings"
2025-12-24T12:47:37.007664Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/bindings"
2025-12-24T12:47:37.023425Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/nodes". Create session OK
2025-12-24T12:47:37.026315Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/nodes"
2025-12-24T12:47:37.026325Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/nodes"
2025-12-24T12:47:37.034913Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/mappings". Create session OK
2025-12-24T12:47:37.034934Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/mappings"
2025-12-24T12:47:37.034945Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:190: LOADED TenantInfo: State CHANGED at 2025-12-24T12:47:30.384922Z
2025-12-24T12:47:37.034951Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/mappings"
2025-12-24T12:47:37.035151Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/quotas". Create session OK
2025-12-24T12:47:37.035162Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/quotas"
2025-12-24T12:47:37.035167Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/quotas"
2025-12-24T12:47:37.040284Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:333: TLeaderElection [3:7587405284495728524:2144] Coordination node successfully created
2025-12-24T12:47:37.040309Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:311: TLeaderElection [3:7587405284495728524:2144] Start session
2025-12-24T12:47:37.082677Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/result_sets"
2025-12-24T12:47:37.082748Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/result_sets": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/result_sets', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 233], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.084075Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/jobs"
2025-12-24T12:47:37.084117Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/jobs": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/jobs', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 239], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.099711Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/connections"
2025-12-24T12:47:37.099763Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/connections": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/connections', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 242], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.106264Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenants"
2025-12-24T12:47:37.106323Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenants": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenants', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 227], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.106639Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/queries"
2025-12-24T12:47:37.106673Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/queries": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/queries', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 238], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.110249Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/idempotency_keys"
2025-12-24T12:47:37.110286Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/idempotency_keys": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/idempotency_keys', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 240], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.123257Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/nodes"
2025-12-24T12:47:37.123259Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/bindings"
2025-12-24T12:47:37.123309Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/nodes": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/nodes', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 241], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.123323Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/bindings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/bindings', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 243], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.129541Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/compute_databases"
2025-12-24T12:47:37.129600Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/compute_databases": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/compute_databases', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 236], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.131867Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/mappings"
2025-12-24T12:47:37.131922Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/mappings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/mappings', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 228], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.135702Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/quotas"
2025-12-24T12:47:37.135769Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/quotas": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/quotas', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 235], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.139928Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/pending_small"
2025-12-24T12:47:37.139934Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenant_acks"
2025-12-24T12:47:37.139967Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenant_acks": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/tenant_acks', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 234], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.139985Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/pending_small": { <main>: Error: Check failed: path: '/local/DbPoolStorage_8924f289-7dc4-43a3-a406-08e471df7aea/pending_small', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 237], type: EPathTypeTable, state: EPathStateNoChanges) }
2025-12-24T12:47:37.142245Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:347: TLeaderElection [3:7587405284495728524:2144] Session successfully created
2025-12-24T12:47:37.156818Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:360: TLeaderElection [3:7587405284495728524:2144] Semaphore successfully created
2025-12-24T12:47:37.156856Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:292: TLeaderElection [3:7587405284495728524:2144] Try to acquire semaphore
2025-12-24T12:47:37.157151Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:408: TLeaderElection [3:7587405284495728524:2144] Describe semaphore
2025-12-24T12:47:37.169296Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:457: TLeaderElection [3:7587405284495728524:2144] Semaphore successfully described: coordinator id [1:7587405277228412607:2144] generation 11
2025-12-24T12:47:37.169381Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:459: TLeaderElection [3:7587405284495728524:2144] Send TEvCoordinatorChanged to [3:7587405284495728416:2067], new coordinator id [1:7587405277228412607:2144], previous coordinator id [0:0:0]
2025-12-24T12:47:37.169490Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:557: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7587405277228412607:2144] generation 11
2025-12-24T12:47:37.169628Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:374: Coordinator: New leader [1:7587405277228412607:2144], SelfId [3:7587405284495728523:2143]
2025-12-24T12:47:37.282046Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:171: Successfully bootstrapped TStorageProxy [3:7587405284495728415:2066] with connection to localhost:4533:local
2025-12-24T12:47:37.395960Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:595: RowDispatcher: TEvUndelivered, from [1:7587405277228412607:2144], reason Disconnected
2025-12-24T12:47:37.415320Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071102, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:37.513754Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:37.513782Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:37.627832Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:230: IC Peers[2]: [1, 2]
2025-12-24T12:47:37.842993Z :FQ_ROW_DISPATCHER ERROR: coordinator.cpp:519: Coordinator: Failed to get TEvLookupResult, try later...
2025-12-24T12:47:38.417780Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071103, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:38.479153Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:62:  ICN06 Unknown nodeId: 4
2025-12-24T12:47:38.510587Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:38.510607Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:38.615337Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:38.615388Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:39.058663Z :FQ_ROW_DISPATCHER ERROR: coordinator.cpp:519: Coordinator: Failed to get TEvLookupResult, try later...
2025-12-24T12:47:39.418015Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071104, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:39.514015Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:39.514043Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:39.649804Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-24T12:47:39.701985Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:62:  ICN06 Unknown nodeId: 4
2025-12-24T12:47:40.121006Z :INTERCONNECT WARN: interconnect_handshake.cpp:501: Handshake [3:7587405301675597740:2061] [node 1] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-12-24T12:47:40.267375Z :FQ_ROW_DISPATCHER ERROR: coordinator.cpp:519: Coordinator: Failed to get TEvLookupResult, try later...
2025-12-24T12:47:40.419925Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071105, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:40.588037Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:40.588069Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:40.617995Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:40.618050Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:40.622420Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:40.911758Z :INTERCONNECT ERROR: interconnect_nameserver_dynamic.cpp:62:  ICN06 Unknown nodeId: 4
2025-12-24T12:47:41.287995Z :INTERCONNECT WARN: interconnect_handshake.cpp:501: Handshake [3:7587405305970565074:2066] [node 2] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-12-24T12:47:41.289592Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:523: Coordinator: Updated node info, node count: 3, AssignedNodes: 1, 2, 3
2025-12-24T12:47:41.421221Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071106, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:41.510418Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:41.510448Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:41.613966Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-24T12:47:41.741517Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 3 to DynamicNameserver
2025-12-24T12:47:41.894993Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.cpuPercent.count=0/10000 UPDATE from Peer 1
2025-12-24T12:47:41.914222Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.analyticsQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:41.919622Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.streamingQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:42.422686Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071107, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:42.499935Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:42.499962Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:42.620817Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:42.620847Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:42.625962Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:42.631355Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:230: IC Peers[3]: [1, 2, 4]
2025-12-24T12:47:42.678530Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-24T12:47:43.143625Z :INTERCONNECT WARN: interconnect_handshake.cpp:501: Handshake [3:7587405314560499690:2068] [node 4] ICH09 Neither CompatibilityInfo nor VersionTag of the peer can be validated, accepting by default
2025-12-24T12:47:43.422931Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071108, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:43.458741Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:43.458771Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:44.423338Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-563071109, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:44.475808Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:44.475845Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:44.620960Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:44.621002Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:44.621951Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:44.676543Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.cpuPercent.count=0/10000 UPDATE from Peer 1
2025-12-24T12:47:45.018730Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.streamingQuery.count=1/100 UPDATE from Peer 1
2025-12-24T12:47:45.035623Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.analyticsQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:45.423741Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711010, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:45.637899Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-24T12:47:46.562013Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:46.562036Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:46.614705Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-24T12:47:46.621834Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:46.621894Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:46.625608Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:46.677585Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 3 to DynamicNameserver
2025-12-24T12:47:47.424684Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711011, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:47.488356Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.cpuPercent.count=0/10000 UPDATE from Peer 1
2025-12-24T12:47:47.539776Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.analyticsQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:47.539832Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.streamingQuery.count=1/100 UPDATE from Peer 1
2025-12-24T12:47:48.623847Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:48.623901Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:48.629047Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:48.647169Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-24T12:47:48.986005Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:48.986031Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:49.284428Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:49.284 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] local_worker_manager.cpp:316: {ptqudp9nnhk3huamc17p} TLocalWorkerManager::TEvAllocateWorkersRequest 562954248388609
2025-12-24T12:47:49.284606Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:49.284 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] local_worker_manager.cpp:376: {ptqudp9nnhk3huamc17p} Create compute actor: async
2025-12-24T12:47:49.399926Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:134: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Start compute actor [3:7587405340330303475:2201], task: 2
2025-12-24T12:47:49.400107Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:113: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter", "mock_cloud/my_folder/ptqudp9nnhk3huamc17p" }
2025-12-24T12:47:49.425585Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711012, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:49.474196Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:49.474223Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:49.594413Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.cpuPercent.count=0/10000 UPDATE from Peer 1
2025-12-24T12:47:49.634405Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.analyticsQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:49.646258Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.streamingQuery.count=1/100 UPDATE from Peer 1
2025-12-24T12:47:49.802223Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:197: [] Task: 2. Got TEvNewCheckpointCoordinator event: generation 1, actorId: [1:7587405341652922383:2434]
2025-12-24T12:47:49.802248Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:208: [] Task: 2. Assign checkpoint coordinator (generation = 1)
2025-12-24T12:47:49.802552Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:395: [ptqudp9nnhk3huamc17p-0] Task: 2. Handle connected node 1
2025-12-24T12:47:49.879895Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1072: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Received channels info: Update { Id: 1 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 1 DstTaskId: 2 SrcEndpoint { ActorId { RawX1: 7587405341652922386 RawX2: 4503603922340229 } } DstEndpoint { ActorId { RawX1: 7587405340330303475 RawX2: 4503612512274585 } } SrcStageId: 200322 DstStageId: 200339 } Update { Id: 2 TransportVersion: DATA_TRANSPORT_UV_PICKLE_1_0 SrcTaskId: 2 SrcEndpoint { ActorId { RawX1: 7587405340330303475 RawX2: 4503612512274585 } } DstEndpoint { ActorId { RawX1: 7587405341652922382 RawX2: 4503603922340225 } } }
2025-12-24T12:47:49.879923Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1079: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Update input channelId: 1, peer: [1:7587405341652922386:2437]
2025-12-24T12:47:49.879950Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1091: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Update output channelId: 2, peer: [1:7587405341652922382:2433]
2025-12-24T12:47:50.426026Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711013, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:50.471457Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:50.471485Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:50.624672Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:50.624720Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:50.625703Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:51.426562Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711014, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:51.461096Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:51.461121Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:51.496509Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1167: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Got TEvRun from actor [1:7587405341652922383:2434]
2025-12-24T12:47:51.496628Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:51.496644Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:51.496666Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:51.496849Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:51.496 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:51.497044Z :KQP_COMPUTE TRACE: dq_compute_memory_quota.h:97: TxId: ptqudp9nnhk3huamc17p, task: 2. Peak memory usage: 2031616
2025-12-24T12:47:51.497120Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:51.497249Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:51.497275Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:51.497323Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:51.497340Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1105: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Ask CPU quota: 65ms
2025-12-24T12:47:51.497432Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:129: TxId: ptqudp9nnhk3huamc17p, task: 2. Received input for channelId: 1, seqNo: 1, size: 0, chunks: 0, watermark: 0, checkpoint: 1, finished: 0, from: [1:7587405341652922387:2437], expected seqNo: 1
2025-12-24T12:47:51.497451Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:609: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. took input
2025-12-24T12:47:51.497526Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:485: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.1] Got checkpoint barrier from channel 1
2025-12-24T12:47:51.497626Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:396: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. No watermark 
2025-12-24T12:47:51.497652Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:51.497666Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:51.497674Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 1
2025-12-24T12:47:51.497701Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1177: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. AsyncCheckRunStatus: TakeInputChannelDataRequests: 1
2025-12-24T12:47:51.497746Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:965: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Input data push finished. Cookie: 0 Watermark: (empty maybe) Ack: 1 TakeInputChannelDataRequests: 1
2025-12-24T12:47:51.498289Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:738: TxId: ptqudp9nnhk3huamc17p, task: 2. Sending channel data ack to channelId: 1, peer: [1:7587405341652922386:2437], from: [3:7587405340330303475:2201], freeSpace: 1000000, seqNo: 1, finished: 0
2025-12-24T12:47:51.498371Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:51.498382Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:51.498391Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:51.509284Z :YQ_RATE_LIMITER DEBUG: quoter_service.cpp:234: Scheduled retry in 0.005488s for resource {"/local/RateLimiter_8924f289-7dc4-43a3-a406-08e471df7aea/rate_limiter", "mock_cloud/my_folder/ptqudp9nnhk3huamc17p"}. Status: TIMEOUT 
2025-12-24T12:47:51.526109Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1162: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. CPU quota delay: 28ms
2025-12-24T12:47:51.526510Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:511: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Pending 1 checkpoints to be sent
2025-12-24T12:47:51.526555Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:51.526688Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 1 watermark injected: (empty maybe)
2025-12-24T12:47:51.526709Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:447: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.1] Performing task checkpoint
2025-12-24T12:47:51.526720Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:822: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Save state
2025-12-24T12:47:51.526761Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:474: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.1] CA state saved
2025-12-24T12:47:51.526792Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:449: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.1] Injecting checkpoint barrier to outputs
2025-12-24T12:47:51.526820Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:536: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.1] Task checkpoint is done. Send to storage
2025-12-24T12:47:51.526829Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:51.526850Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:51.526908Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:566: TxId: ptqudp9nnhk3huamc17p, task: 2. SendChannelData, channelId: 2, peer: [1:7587405341652922382:2433], chunks: 0, bytes: 0, watermark: 0, checkpoint: 1, seqNo: 1, finished: 0
2025-12-24T12:47:51.526989Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [1], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:51.527007Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:51.527014Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:51.527029Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:51.527149Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:51.527183Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:51.527190Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:51.527203Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:1000000;inFlightBytes:96;inFlightCount:1;)
2025-12-24T12:47:51.527232Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [1], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:51.527264Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:379: [ptqudp9nnhk3huamc17p-0] [1:1] Got TEvSaveTaskState: task 2
2025-12-24T12:47:51.527853Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:179: TxId: ptqudp9nnhk3huamc17p, task: 2. Received channel data ack for channelId: 2, seqNo: 1, lastSentSeqNo: 1, freeSpace: 67108864, early finish: 0
2025-12-24T12:47:51.527908Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:207: TxId: ptqudp9nnhk3huamc17p, task: 2. PeerState, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;), sentSeqNo: 1, ackSeqNo: 1
2025-12-24T12:47:51.527918Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:220: TxId: ptqudp9nnhk3huamc17p, task: 2. Resume compute actor
2025-12-24T12:47:51.527967Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:51.527976Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:51.527984Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:51.528208Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:51.528307Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:51.528330Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:51.528349Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:51.528425Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [1], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:51.614884Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-24T12:47:51.637169Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-24T12:47:51.681934Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 3 to DynamicNameserver
2025-12-24T12:47:51.746964Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:403: [ptqudp9nnhk3huamc17p-0] [1:1] TEvSaveTaskState Apply: task: 2
2025-12-24T12:47:51.747010Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:418: [ptqudp9nnhk3huamc17p-0] [1:1] Send TEvSaveTaskStateResult: task: 2
2025-12-24T12:47:52.427048Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711015, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:52.460552Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:52.460581Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:52.625526Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:52.625579Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:52.626279Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:53.427369Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711016, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:53.451387Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:53.451415Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:53.519406Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:129: TxId: ptqudp9nnhk3huamc17p, task: 2. Received input for channelId: 1, seqNo: 2, size: 0, chunks: 0, watermark: 0, checkpoint: 1, finished: 0, from: [1:7587405341652922387:2437], expected seqNo: 2
2025-12-24T12:47:53.519439Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:609: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. took input
2025-12-24T12:47:53.519504Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:485: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.2] Got checkpoint barrier from channel 1
2025-12-24T12:47:53.519559Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:396: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. No watermark 
2025-12-24T12:47:53.519590Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.519599Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:53.519609Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 1
2025-12-24T12:47:53.519636Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:965: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Input data push finished. Cookie: 1 Watermark: (empty maybe) Ack: 1 TakeInputChannelDataRequests: 1
2025-12-24T12:47:53.519658Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:738: TxId: ptqudp9nnhk3huamc17p, task: 2. Sending channel data ack to channelId: 1, peer: [1:7587405341652922386:2437], from: [3:7587405340330303475:2201], freeSpace: 1000000, seqNo: 2, finished: 0
2025-12-24T12:47:53.520140Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.520 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.520221Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:511: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Pending 1 checkpoints to be sent
2025-12-24T12:47:53.520255Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.520279Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:53.520288Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:53.520324Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.520417Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 1 watermark injected: (empty maybe)
2025-12-24T12:47:53.520433Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:447: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.2] Performing task checkpoint
2025-12-24T12:47:53.520442Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:822: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Save state
2025-12-24T12:47:53.520453Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:474: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.2] CA state saved
2025-12-24T12:47:53.520463Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:449: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.2] Injecting checkpoint barrier to outputs
2025-12-24T12:47:53.520507Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:536: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.2] Task checkpoint is done. Send to storage
2025-12-24T12:47:53.520519Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.520538Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:53.520546Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:379: [ptqudp9nnhk3huamc17p-0] [1:2] Got TEvSaveTaskState: task 2
2025-12-24T12:47:53.520682Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.520 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.520744Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:566: TxId: ptqudp9nnhk3huamc17p, task: 2. SendChannelData, channelId: 2, peer: [1:7587405341652922382:2433], chunks: 0, bytes: 0, watermark: 0, checkpoint: 1, seqNo: 2, finished: 0
2025-12-24T12:47:53.520798Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [2], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.520815Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.521033Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:53.521045Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.521062Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:96;inFlightCount:1;)
2025-12-24T12:47:53.521084Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.521097Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:53.521105Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:53.521249Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.521 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.521341Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [2], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.521352Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.521396Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:53.521447Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.521473Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:96;inFlightCount:1;)
2025-12-24T12:47:53.521546Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:179: TxId: ptqudp9nnhk3huamc17p, task: 2. Received channel data ack for channelId: 2, seqNo: 2, lastSentSeqNo: 2, freeSpace: 67108864, early finish: 0
2025-12-24T12:47:53.521575Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:207: TxId: ptqudp9nnhk3huamc17p, task: 2. PeerState, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;), sentSeqNo: 2, ackSeqNo: 2
2025-12-24T12:47:53.521595Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:220: TxId: ptqudp9nnhk3huamc17p, task: 2. Resume compute actor
2025-12-24T12:47:53.521658Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [2], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.521682Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.521689Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:53.521694Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:53.521942Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.521 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.522024Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.522078Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:53.522094Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.522112Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:53.522163Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [2], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.537221Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:403: [ptqudp9nnhk3huamc17p-0] [1:2] TEvSaveTaskState Apply: task: 2
2025-12-24T12:47:53.537252Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:418: [ptqudp9nnhk3huamc17p-0] [1:2] Send TEvSaveTaskStateResult: task: 2
2025-12-24T12:47:53.852904Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:129: TxId: ptqudp9nnhk3huamc17p, task: 2. Received input for channelId: 1, seqNo: 3, size: 0, chunks: 0, watermark: 0, checkpoint: 1, finished: 0, from: [1:7587405341652922387:2437], expected seqNo: 3
2025-12-24T12:47:53.852933Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:609: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. took input
2025-12-24T12:47:53.852977Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:485: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.3] Got checkpoint barrier from channel 1
2025-12-24T12:47:53.853033Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:396: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. No watermark 
2025-12-24T12:47:53.853072Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.853081Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:53.853090Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 1
2025-12-24T12:47:53.853114Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:965: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Input data push finished. Cookie: 2 Watermark: (empty maybe) Ack: 1 TakeInputChannelDataRequests: 1
2025-12-24T12:47:53.853160Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:738: TxId: ptqudp9nnhk3huamc17p, task: 2. Sending channel data ack to channelId: 1, peer: [1:7587405341652922386:2437], from: [3:7587405340330303475:2201], freeSpace: 1000000, seqNo: 3, finished: 0
2025-12-24T12:47:53.853347Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.853 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.853426Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:511: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Pending 1 checkpoints to be sent
2025-12-24T12:47:53.853457Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.853463Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:53.853469Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:53.853480Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.853535Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 1 watermark injected: (empty maybe)
2025-12-24T12:47:53.853549Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:447: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.3] Performing task checkpoint
2025-12-24T12:47:53.853556Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:822: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Save state
2025-12-24T12:47:53.853575Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:474: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.3] CA state saved
2025-12-24T12:47:53.853586Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:449: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.3] Injecting checkpoint barrier to outputs
2025-12-24T12:47:53.853618Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:536: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.3] Task checkpoint is done. Send to storage
2025-12-24T12:47:53.853630Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.853661Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:53.853675Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:379: [ptqudp9nnhk3huamc17p-0] [1:3] Got TEvSaveTaskState: task 2
2025-12-24T12:47:53.853843Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.853 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.853939Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:566: TxId: ptqudp9nnhk3huamc17p, task: 2. SendChannelData, channelId: 2, peer: [1:7587405341652922382:2433], chunks: 0, bytes: 0, watermark: 0, checkpoint: 1, seqNo: 3, finished: 0
2025-12-24T12:47:53.854003Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [3], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.854024Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.854075Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:53.854092Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.854105Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:96;inFlightCount:1;)
2025-12-24T12:47:53.854129Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.854136Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:53.854142Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:53.854260Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.854 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.854317Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [3], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.854345Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.854394Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:53.854407Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.854419Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:96;inFlightCount:1;)
2025-12-24T12:47:53.854451Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [3], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.854689Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:179: TxId: ptqudp9nnhk3huamc17p, task: 2. Received channel data ack for channelId: 2, seqNo: 3, lastSentSeqNo: 3, freeSpace: 67108864, early finish: 0
2025-12-24T12:47:53.854717Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:207: TxId: ptqudp9nnhk3huamc17p, task: 2. PeerState, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;), sentSeqNo: 3, ackSeqNo: 3
2025-12-24T12:47:53.854724Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:220: TxId: ptqudp9nnhk3huamc17p, task: 2. Resume compute actor
2025-12-24T12:47:53.854740Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:53.854746Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:53.854752Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:53.854861Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:53.854 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:53.854913Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:53.854946Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:53.854955Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:53.854983Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:53.855024Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [3], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:53.867185Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:403: [ptqudp9nnhk3huamc17p-0] [1:3] TEvSaveTaskState Apply: task: 2
2025-12-24T12:47:53.867214Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:418: [ptqudp9nnhk3huamc17p-0] [1:3] Send TEvSaveTaskStateResult: task: 2
2025-12-24T12:47:54.427821Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711017, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:54.483461Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:54.483489Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:54.543200Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.cpuPercent.count=0/10000 UPDATE from Peer 1
2025-12-24T12:47:54.552399Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:451: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Got TEvStateRequest from actor [1:7587405341652922384:2435] PingCookie: 1
2025-12-24T12:47:54.552444Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:54.569383Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.streamingQuery.count=1/100 UPDATE from Peer 1
2025-12-24T12:47:54.569696Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.analyticsQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:54.626459Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:54.626500Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:54.627265Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:54.641797Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-24T12:47:54.885131Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:129: TxId: ptqudp9nnhk3huamc17p, task: 2. Received input for channelId: 1, seqNo: 4, size: 0, chunks: 0, watermark: 0, checkpoint: 1, finished: 0, from: [1:7587405341652922387:2437], expected seqNo: 4
2025-12-24T12:47:54.885153Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:609: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. took input
2025-12-24T12:47:54.885186Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:485: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.4] Got checkpoint barrier from channel 1
2025-12-24T12:47:54.885223Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:396: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. No watermark 
2025-12-24T12:47:54.885237Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:54.885245Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:54.885254Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 1
2025-12-24T12:47:54.885280Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:965: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Input data push finished. Cookie: 3 Watermark: (empty maybe) Ack: 1 TakeInputChannelDataRequests: 1
2025-12-24T12:47:54.885331Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:738: TxId: ptqudp9nnhk3huamc17p, task: 2. Sending channel data ack to channelId: 1, peer: [1:7587405341652922386:2437], from: [3:7587405340330303475:2201], freeSpace: 1000000, seqNo: 4, finished: 0
2025-12-24T12:47:54.885501Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:54.885 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:54.885578Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:511: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Pending 1 checkpoints to be sent
2025-12-24T12:47:54.885594Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:54.885598Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:54.885603Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:54.885611Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:54.885659Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 1 watermark injected: (empty maybe)
2025-12-24T12:47:54.885670Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:447: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.4] Performing task checkpoint
2025-12-24T12:47:54.885676Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:822: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Save state
2025-12-24T12:47:54.885684Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:474: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.4] CA state saved
2025-12-24T12:47:54.885698Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:449: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.4] Injecting checkpoint barrier to outputs
2025-12-24T12:47:54.885720Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:536: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.4] Task checkpoint is done. Send to storage
2025-12-24T12:47:54.885727Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:54.885746Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:54.885794Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:379: [ptqudp9nnhk3huamc17p-0] [1:4] Got TEvSaveTaskState: task 2
2025-12-24T12:47:54.885920Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:54.885 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:54.885987Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:566: TxId: ptqudp9nnhk3huamc17p, task: 2. SendChannelData, channelId: 2, peer: [1:7587405341652922382:2433], chunks: 0, bytes: 0, watermark: 0, checkpoint: 1, seqNo: 4, finished: 0
2025-12-24T12:47:54.886044Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [4], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:54.886067Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:54.886114Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:54.886124Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:54.886137Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:96;inFlightCount:1;)
2025-12-24T12:47:54.886154Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:54.886163Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:54.886168Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:54.886263Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:54.886 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:54.886328Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [4], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:54.886335Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:54.886366Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:54.886376Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:54.886391Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:96;inFlightCount:1;)
2025-12-24T12:47:54.886423Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [4], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:54.887062Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:179: TxId: ptqudp9nnhk3huamc17p, task: 2. Received channel data ack for channelId: 2, seqNo: 4, lastSentSeqNo: 4, freeSpace: 67108864, early finish: 0
2025-12-24T12:47:54.887086Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:207: TxId: ptqudp9nnhk3huamc17p, task: 2. PeerState, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;), sentSeqNo: 4, ackSeqNo: 4
2025-12-24T12:47:54.887094Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:220: TxId: ptqudp9nnhk3huamc17p, task: 2. Resume compute actor
2025-12-24T12:47:54.887115Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:54.887128Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:54.887140Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:54.887259Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:54.887 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:54.887309Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:54.887352Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:54.887367Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:54.887392Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:54.887447Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [4], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:54.904956Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:403: [ptqudp9nnhk3huamc17p-0] [1:4] TEvSaveTaskState Apply: task: 2
2025-12-24T12:47:54.904978Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:418: [ptqudp9nnhk3huamc17p-0] [1:4] Send TEvSaveTaskStateResult: task: 2
2025-12-24T12:47:55.430124Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711018, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:55.469239Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:55.469277Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:55.866280Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:129: TxId: ptqudp9nnhk3huamc17p, task: 2. Received input for channelId: 1, seqNo: 5, size: 0, chunks: 0, watermark: 0, checkpoint: 1, finished: 0, from: [1:7587405341652922387:2437], expected seqNo: 5
2025-12-24T12:47:55.866314Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:609: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. took input
2025-12-24T12:47:55.866356Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:485: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.5] Got checkpoint barrier from channel 1
2025-12-24T12:47:55.866406Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:396: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. No watermark 
2025-12-24T12:47:55.866423Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:55.866432Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:55.866442Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 1
2025-12-24T12:47:55.866470Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:965: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Input data push finished. Cookie: 4 Watermark: (empty maybe) Ack: 1 TakeInputChannelDataRequests: 1
2025-12-24T12:47:55.866492Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:738: TxId: ptqudp9nnhk3huamc17p, task: 2. Sending channel data ack to channelId: 1, peer: [1:7587405341652922386:2437], from: [3:7587405340330303475:2201], freeSpace: 1000000, seqNo: 5, finished: 0
2025-12-24T12:47:55.866648Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:55.866 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] tasks_runner_local.cpp:267: {TODO/2} Run task: 2
2025-12-24T12:47:55.866722Z :DQ_TASK_RUNNER TRACE: task_runner_actor_local.cpp:511: SelfId: [3:7587405340330303477:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Pending 1 checkpoints to be sent
2025-12-24T12:47:55.866743Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:55.866749Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1495: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Skip polling sources because of pending checkpoint
2025-12-24T12:47:55.866757Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:55.866769Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:55.866851Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 1 watermark injected: (empty maybe)
2025-12-24T12:47:55.866871Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:447: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.5] Performing task checkpoint
2025-12-24T12:47:55.866879Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:822: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Save state
2025-12-24T12:47:55.866891Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:474: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.5] CA state saved
2025-12-24T12:47:55.866902Z :KQP_COMPUTE TRACE: dq_compute_actor_checkpoints.cpp:449: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.5] Injecting checkpoint barrier to outputs
2025-12-24T12:47:55.866941Z :KQP_COMPUTE DEBUG: dq_compute_actor_checkpoints.cpp:536: [ptqudp9nnhk3huamc17p-0] Task: 2. [Checkpoint 1.5] Task checkpoint is done. Send to storage
2025-12-24T12:47:55.866952Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:55.866973Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:55.866987Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1105: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Ask CPU quota: 10ms
2025-12-24T12:47:55.866989Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:379: [ptqudp9nnhk3huamc17p-0] [1:5] Got TEvSaveTaskState: task 2
2025-12-24T12:47:55.867054Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:566: TxId: ptqudp9nnhk3huamc17p, task: 2. SendChannelData, channelId: 2, peer: [1:7587405341652922382:2433], chunks: 0, bytes: 0, watermark: 0, checkpoint: 1, seqNo: 5, finished: 0
2025-12-24T12:47:55.867098Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [5], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:55.867133Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:55.867140Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:55.867147Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:55.867886Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:179: TxId: ptqudp9nnhk3huamc17p, task: 2. Received channel data ack for channelId: 2, seqNo: 5, lastSentSeqNo: 5, freeSpace: 67108864, early finish: 0
2025-12-24T12:47:55.867925Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:207: TxId: ptqudp9nnhk3huamc17p, task: 2. PeerState, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;), sentSeqNo: 5, ackSeqNo: 5
2025-12-24T12:47:55.867934Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:220: TxId: ptqudp9nnhk3huamc17p, task: 2. Resume compute actor
2025-12-24T12:47:55.867977Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1484: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll inputs
2025-12-24T12:47:55.867986Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1499: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Poll sources
2025-12-24T12:47:55.867992Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. DoExecuteImpl: 0
2025-12-24T12:47:55.876039Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:1162: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. CPU quota delay: 9ms
2025-12-24T12:47:55.876290Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:491: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. update task runner stats
2025-12-24T12:47:55.876360Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:758: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Resume execution, run status: PendingInput checkpoint: 0 watermark injected: (empty maybe)
2025-12-24T12:47:55.876369Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:393: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. ProcessOutputsState.Inflight: 0
2025-12-24T12:47:55.876389Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:532: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. About to drain channelId: 2, hasPeer: 1, peerState:(freeSpace:67108864;inFlightBytes:0;inFlightCount:0;)
2025-12-24T12:47:55.876444Z :KQP_COMPUTE TRACE: dq_compute_actor_channels.cpp:625: TxId: ptqudp9nnhk3huamc17p, task: 2. no poll, channelId: 1, has inflight messages: [5], last with freeSpace: 1000000, current freeSpace: 1000000
2025-12-24T12:47:55.885049Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:403: [ptqudp9nnhk3huamc17p-0] [1:5] TEvSaveTaskState Apply: task: 2
2025-12-24T12:47:55.885082Z :STREAMS_STORAGE_SERVICE DEBUG: storage_proxy.cpp:418: [ptqudp9nnhk3huamc17p-0] [1:5] Send TEvSaveTaskStateResult: task: 2
2025-12-24T12:47:56.430735Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711019, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:56.455478Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:56.455511Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:56.498121Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.cpuPercent.count=0/10000 UPDATE from Peer 1
2025-12-24T12:47:56.511260Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:56.511 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] local_worker_manager.cpp:416: TEvFreeWorkersNotify 562954248388609
2025-12-24T12:47:56.511388Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:56.511 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] local_worker_manager.cpp:440: Free Group 562954248388609
2025-12-24T12:47:56.512008Z :KQP_COMPUTE ERROR: dq_compute_actor_impl.h:1208: SelfId: [3:7587405340330303475:2201], TxId: ptqudp9nnhk3huamc17p, task: 2. Ctx: {}. Handle abort execution event from: [3:7587405284495728417:2068], status: UNAVAILABLE, reason: { <main>: Error: Aborted by LWM }
2025-12-24T12:47:56.512179Z :KQP_COMPUTE DEBUG: dq_compute_actor_channels.cpp:494: TxId: ptqudp9nnhk3huamc17p, task: 2. pass away
2025-12-24T12:47:56.515932Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.analyticsQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:56.515981Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.streamingQuery.count=1/100 UPDATE from Peer 1
2025-12-24T12:47:56.615035Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-24T12:47:56.627312Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:615: RowDispatcher: Send ping to [1:7587405277228412607:2144]
2025-12-24T12:47:56.627350Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1160: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-24T12:47:56.627938Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:621: RowDispatcher: NActors::TEvents::TEvPong
2025-12-24T12:47:56.663220Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 3 to DynamicNameserver
2025-12-24T12:47:57.431183Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711020, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:57.463902Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:57.463933Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:57.635417Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-24T12:47:58.431634Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a5f97291-de230a0f-8be843fd-5630711021, Host: ghrun-3p2rliathy, Tenant: /default
2025-12-24T12:47:58.456617Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-24T12:47:58.456648Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-24T12:47:58.547486Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.cpuPercent.count=0/10000 UPDATE from Peer 1
2025-12-24T12:47:58.556342Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:505: cloud.mock_cloud.yq.streamingQuery.count=0/100 UPDATE from Peer 1
2025-12-24T12:47:58.560462Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:58.560 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] local_worker_manager.cpp:257: Disconnected 1
2025-12-24T12:47:58.560633Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a5f97291-de230a0f-8be843fd-56307110 2025-12-24 12:47:58.560 DEBUG ydbd(pid=2376265, tid=0x00007BF0D2EE9640) [DQ] local_worker_manager.cpp:228: Deallocate 1
