2025-12-09T03:42:05.683617Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:544: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7581698429302872512:2069], tenant /default
2025-12-09T03:42:05.683641Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581698429302872512:2069]
2025-12-09T03:42:05.683642Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:329: Coordinator: Add new row dispatcher to map (state 1)
2025-12-09T03:42:05.683878Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-09T03:42:05.683927Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-09T03:42:05.686820Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: 201d0eb3-46b4ac76-774edad-27696159
2025-12-09T03:42:05.686824Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-12-09T03:42:05.686836Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7581698429302872517:2074]
2025-12-09T03:42:05.686868Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-12-09T03:42:05.686873Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-12-09T03:42:05.686892Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:58: STARTING: [1:7581698429302872506:2063]
2025-12-09T03:42:05.688254Z :YQ_CONTROL_PLANE_PROXY DEBUG: control_plane_proxy.cpp:525: Starting yandex query control plane proxy. Actor id: [1:7581698429302872507:2064]
2025-12-09T03:42:05.688444Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true ControlPlaneEnabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:31865" Database: "local" TablePrefix: "RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-12-09T03:42:05.688559Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:293: Coordinator: Successfully bootstrapped coordinator, id [1:7581698429302872528:2081], NodesManagerId [0:4705493345362661702:78], rebalancing timeout 5.000000s
2025-12-09T03:42:05.688570Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7581698429302872529:2082] Successfully bootstrapped, local coordinator id [1:7581698429302872528:2081], tenant id default, local mode 0, coordination node path local/default, endpoint localhost:31865
2025-12-09T03:42:05.690409Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-12-09T03:42:05.690423Z :YQ_CONTROL_PLANE_STORAGE INFO: ydb_control_plane_storage.cpp:50: Starting ydb control plane storage service. Actor id: [1:7581698429302872505:2062]
2025-12-09T03:42:05.690690Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/idempotency_keys" actor
2025-12-09T03:42:05.690709Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/result_sets" actor
2025-12-09T03:42:05.690716Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/jobs" actor
2025-12-09T03:42:05.690722Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/nodes" actor
2025-12-09T03:42:05.690731Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:214: Bootstrap
2025-12-09T03:42:05.690732Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:217: Create sdk ydb connection
2025-12-09T03:42:05.690757Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:239: Successfully bootstrapped TStorageProxy [1:7581698429302872511:2068] with connection to localhost:31865:local
2025-12-09T03:42:05.690862Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585531, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:05.690868Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/queries" actor
2025-12-09T03:42:05.690875Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/pending_small" actor
2025-12-09T03:42:05.690883Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-12-09T03:42:05.690886Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/bindings" actor
2025-12-09T03:42:05.690893Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create coordination node "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter" actor
2025-12-09T03:42:05.690895Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create coordination node "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter"
2025-12-09T03:42:05.690896Z :YQ_RATE_LIMITER DEBUG: schema.cpp:411: Call create coordination node "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter"
2025-12-09T03:42:05.690911Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/quotas" actor
2025-12-09T03:42:05.690920Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenant_acks" actor
2025-12-09T03:42:05.690926Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/mappings" actor
2025-12-09T03:42:05.690935Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:637: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7581698429302872528:2081]
2025-12-09T03:42:05.690974Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create directory "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207" actor
2025-12-09T03:42:05.690976Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create directory "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207"
2025-12-09T03:42:05.690978Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:383: Call create directory "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207"
2025-12-09T03:42:05.690997Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/compute_databases" actor
2025-12-09T03:42:05.691035Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/connections" actor
2025-12-09T03:42:05.691054Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenants" actor
2025-12-09T03:42:05.691072Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/default" actor
2025-12-09T03:42:05.691073Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/default"
2025-12-09T03:42:05.691075Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/default"
2025-12-09T03:42:05.694432Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-12-09T03:42:05.704440Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:05.704467Z :FQ_PENDING_FETCHER ERROR: pending_fetcher.cpp:259: Error with GetTask: <main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.0.1:31496: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:31496
2025-12-09T03:42:05.709391Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-09T03:42:05.742525Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenants". Create session OK
2025-12-09T03:42:05.742540Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenants"
2025-12-09T03:42:05.742553Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenants"
2025-12-09T03:42:05.742713Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/compute_databases". Create session OK
2025-12-09T03:42:05.742718Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/compute_databases"
2025-12-09T03:42:05.742720Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/compute_databases"
2025-12-09T03:42:05.742761Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/bindings". Create session OK
2025-12-09T03:42:05.742764Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/bindings"
2025-12-09T03:42:05.742765Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/bindings"
2025-12-09T03:42:05.742846Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/mappings". Create session OK
2025-12-09T03:42:05.742854Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/mappings"
2025-12-09T03:42:05.742855Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/mappings"
2025-12-09T03:42:05.742867Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/nodes". Create session OK
2025-12-09T03:42:05.742869Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/nodes"
2025-12-09T03:42:05.742870Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/nodes"
2025-12-09T03:42:05.742929Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/pending_small". Create session OK
2025-12-09T03:42:05.742930Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/pending_small"
2025-12-09T03:42:05.742931Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/pending_small"
2025-12-09T03:42:05.744825Z :YQ_CONTROL_PLANE_STORAGE TRACE: nodes_health_check.cpp:38: NodesHealthCheckRequest: {tenant: "/default"
node {
  node_id: 1
  instance_id: "201d0eb3-46b4ac76-774edad-27696159"
  hostname: "ghrun-syjwtgwtdu"
  interconnect_port: 6366
  node_address: "127.0.1.1"
}
}
2025-12-09T03:42:05.745606Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/jobs". Create session OK
2025-12-09T03:42:05.745621Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/jobs"
2025-12-09T03:42:05.745623Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/jobs"
2025-12-09T03:42:05.745696Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/quotas". Create session OK
2025-12-09T03:42:05.745701Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/quotas"
2025-12-09T03:42:05.745703Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/quotas"
2025-12-09T03:42:05.745783Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/connections". Create session OK
2025-12-09T03:42:05.745785Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/connections"
2025-12-09T03:42:05.745787Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/connections"
2025-12-09T03:42:05.745834Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/result_sets". Create session OK
2025-12-09T03:42:05.745836Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/result_sets"
2025-12-09T03:42:05.745838Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/result_sets"
2025-12-09T03:42:05.745870Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created directory "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207"
2025-12-09T03:42:05.745877Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create directory "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207": { <main>: Error: Check failed: path: '/local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 2], type: EPathTypeDir, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_mkdir.cpp:155 }
2025-12-09T03:42:05.745885Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/idempotency_keys". Create session OK
2025-12-09T03:42:05.745887Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/idempotency_keys"
2025-12-09T03:42:05.745887Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/idempotency_keys"
2025-12-09T03:42:05.745919Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenant_acks". Create session OK
2025-12-09T03:42:05.745920Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenant_acks"
2025-12-09T03:42:05.745923Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenant_acks"
2025-12-09T03:42:05.745960Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/queries". Create session OK
2025-12-09T03:42:05.745962Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/queries"
2025-12-09T03:42:05.745964Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/queries"
2025-12-09T03:42:05.759155Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenants"
2025-12-09T03:42:05.759174Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenants": { <main>: Error: Check failed: path: '/local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenants', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 3], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:42:05.762612Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/mappings"
2025-12-09T03:42:05.762627Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/mappings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/mappings', error: path exist, request accepts it (id: [OwnerId: 72057594046678944, LocalPathId: 4], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504 }
2025-12-09T03:42:05.821701Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created coordination node "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter"
2025-12-09T03:42:05.821712Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create coordination node "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter": 
2025-12-09T03:42:05.825360Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/default"
2025-12-09T03:42:05.825365Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/default": 
2025-12-09T03:42:05.825377Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7581698429302872529:2082] Coordination node successfully created
2025-12-09T03:42:05.825380Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7581698429302872529:2082] Start session
2025-12-09T03:42:05.826354Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:190: LOADED TenantInfo: State CHANGED at 2025-12-09T03:42:04.314076Z
2025-12-09T03:42:05.834825Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/bindings"
2025-12-09T03:42:05.834837Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/bindings": 
2025-12-09T03:42:05.839508Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7581698429302872529:2082] Session successfully created
2025-12-09T03:42:05.845634Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7581698429302872529:2082] Semaphore successfully created
2025-12-09T03:42:05.845645Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7581698429302872529:2082] Try to acquire semaphore
2025-12-09T03:42:05.845689Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7581698429302872529:2082] Describe semaphore
2025-12-09T03:42:05.847920Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/pending_small"
2025-12-09T03:42:05.847931Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/pending_small": 
2025-12-09T03:42:05.847950Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/queries"
2025-12-09T03:42:05.847956Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/queries": 
2025-12-09T03:42:05.848078Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenant_acks"
2025-12-09T03:42:05.848079Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/tenant_acks": 
2025-12-09T03:42:05.848122Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/result_sets"
2025-12-09T03:42:05.848125Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/result_sets": 
2025-12-09T03:42:05.848148Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/quotas"
2025-12-09T03:42:05.848150Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/quotas": 
2025-12-09T03:42:05.848196Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/nodes"
2025-12-09T03:42:05.848197Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/nodes": 
2025-12-09T03:42:05.850532Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/idempotency_keys"
2025-12-09T03:42:05.850541Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/jobs"
2025-12-09T03:42:05.850544Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/idempotency_keys": 
2025-12-09T03:42:05.850546Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/jobs": 
2025-12-09T03:42:05.850671Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/connections"
2025-12-09T03:42:05.850673Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/connections": 
2025-12-09T03:42:05.853864Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/compute_databases"
2025-12-09T03:42:05.853874Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_c654eb5a-ed78-4755-83b2-830557e16207/compute_databases": 
2025-12-09T03:42:05.860609Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7581698429302872529:2082] Semaphore successfully acquired
2025-12-09T03:42:05.860625Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7581698429302872529:2082] Semaphore successfully described: coordinator id [1:7581698429302872528:2081] generation 1
2025-12-09T03:42:05.860629Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7581698429302872529:2082] Send TEvCoordinatorChanged to [1:7581698429302872512:2069], new coordinator id [1:7581698429302872528:2081], previous coordinator id [0:0:0]
2025-12-09T03:42:05.860644Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:569: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7581698429302872528:2081] generation 1
2025-12-09T03:42:05.860659Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581698429302872512:2069]
2025-12-09T03:42:05.860924Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581698429302872512:2069]
2025-12-09T03:42:05.860931Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581698429302872512:2069]
2025-12-09T03:42:05.860941Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:440: Coordinator: New leader [1:7581698429302872528:2081], SelfId [1:7581698429302872528:2081]
2025-12-09T03:42:05.860945Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:42:05.927477Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: NodesHealthCheckRequest - NodesHealthCheckResult: {nodes { node_id: 1 instance_id: "201d0eb3-46b4ac76-774edad-27696159" hostname: "ghrun-syjwtgwtdu" node_address: "127.0.1.1" } } SUCCESS
2025-12-09T03:42:05.927925Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-12-09T03:42:06.690421Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585532, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:06.693494Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585532, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:42:06.693551Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "a9022137-8f037aa8-aa79d3db-f6b585532"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:42:06.757441Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:42:06.757483Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585532, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:42:06.757895Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:06.757907Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:42:07.682674Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581698429302872528:2081]
2025-12-09T03:42:07.682702Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T03:42:07.682710Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581698429302872512:2069]
2025-12-09T03:42:07.682719Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581698429302872512:2069]
2025-12-09T03:42:07.682721Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581698429302872512:2069]
2025-12-09T03:42:07.682731Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:42:07.694822Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585533, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:07.696928Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585533, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:42:07.696969Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "a9022137-8f037aa8-aa79d3db-f6b585533"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:42:07.706467Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:42:07.706503Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585533, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:42:07.706896Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:07.706901Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:42:08.695274Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585534, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:08.695855Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585534, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:42:08.695903Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "a9022137-8f037aa8-aa79d3db-f6b585534"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:42:08.700851Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:42:08.700909Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585534, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:42:08.701591Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:08.701606Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:42:08.701616Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-09T03:42:09.204415Z :TICKET_PARSER DEBUG: ticket_parser_impl.h:1875: Ticket **** (B6C6F477) (ipv6:[::1]:47472) has now valid token of root@builtin
2025-12-09T03:42:09.204587Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "bad"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select max(DateTime) from AS_TABLE([<|x:1|>]);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "7e032b11-6442-42eb-865e-5931ed89acc3"
2025-12-09T03:42:09.204636Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7581698446482741819:2168]
2025-12-09T03:42:09.204666Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-09T03:42:09.204714Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7581698446482741820:2169]
2025-12-09T03:42:09.204730Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:255: cloud.mock_cloud NOT CASHED, Loading ...
2025-12-09T03:42:09.270279Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:441: cloud.mock_cloud {} FROM DB
2025-12-09T03:42:09.270322Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:269: cloud.mock_cloud{yq.streamingQueryDurationMinutes.count=*/0, yq.queryResult.size=*/20971520, yq.analyticsQuery.count=*/100, yq.analyticsQueryDurationMinutes.count=*/1440, yq.memory.size=*/0, yq.result.size=*/0, yq.cpuPercent.count=*/10000, yq.streamingQuery.count=*/100} LOADED
2025-12-09T03:42:09.270326Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:09.270342Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-09T03:42:09.270345Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:09.270361Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-09T03:42:09.354150Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-09T03:42:09.362339Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-12-09T03:42:09.362386Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-09T03:42:09.362423Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:493: cloud.mock_cloud{yq.streamingQueryDurationMinutes.count=*/0, yq.queryResult.size=*/20971520, yq.analyticsQuery.count=0/100, yq.analyticsQueryDurationMinutes.count=*/1440, yq.memory.size=*/0, yq.result.size=*/0, yq.cpuPercent.count=0/10000, yq.streamingQuery.count=0/100} SEND QUOTAS
2025-12-09T03:42:09.362435Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-09T03:42:09.362503Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "bad"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select max(DateTime) from AS_TABLE([<|x:1|>]);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "7e032b11-6442-42eb-865e-5931ed89acc3"
2025-12-09T03:42:09.362613Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7581698446482741826:2175]
2025-12-09T03:42:09.362631Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-09T03:42:09.362700Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "bad"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select max(DateTime) from AS_TABLE([<|x:1|>]);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "7e032b11-6442-42eb-865e-5931ed89acc3"
2025-12-09T03:42:09.362741Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:435: Create database bootstrap. CloudId: mock_cloud Scope: yandexcloud://my_folder Actor id: [1:7581698446482741827:2176]
2025-12-09T03:42:09.362790Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "bad"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select max(DateTime) from AS_TABLE([<|x:1|>]);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "7e032b11-6442-42eb-865e-5931ed89acc3"
2025-12-09T03:42:09.362829Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7581698446482741828:2177]
2025-12-09T03:42:09.362850Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:248: Create rate limiter resource for cloud with limit 100000ms
2025-12-09T03:42:09.362889Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create rate limiter resource "mock_cloud" actor
2025-12-09T03:42:09.362890Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud"
2025-12-09T03:42:09.370868Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created rate limiter resource "mock_cloud"
2025-12-09T03:42:09.370883Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create rate limiter resource "mock_cloud": 
2025-12-09T03:42:09.370936Z :YQ_CONTROL_PLANE_PROXY DEBUG: request_actor.h:272: Create response from rate limiter service. Success: 1
2025-12-09T03:42:09.371057Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:226: CreateQueryRequest: {content {
  type: ANALYTICS
  name: "bad"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select max(DateTime) from AS_TABLE([<|x:1|>]);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "7e032b11-6442-42eb-865e-5931ed89acc3"
} [root@builtin, **** (B6C6F477)] 
2025-12-09T03:42:09.378639Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:09.378660Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:09.598916Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: CreateQueryRequest - CreateQueryResult: {query_id: "ptqudqgd6rmk1ocinppc" } SUCCESS
2025-12-09T03:42:09.603010Z :TICKET_PARSER DEBUG: ticket_parser_impl.h:1875: Ticket **** (B6C6F477) (ipv6:[::1]:47472) has now valid token of root@builtin
2025-12-09T03:42:09.603096Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:09.603138Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7581698446482741833:2181]
2025-12-09T03:42:09.603149Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-09T03:42:09.603163Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7581698446482741834:2182]
2025-12-09T03:42:09.603178Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:09.603184Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-09T03:42:09.603190Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:09.603202Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-09T03:42:09.612300Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-09T03:42:09.633762Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=1/100 REFRESHED
2025-12-09T03:42:09.633803Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-09T03:42:09.633841Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:493: cloud.mock_cloud{yq.streamingQueryDurationMinutes.count=*/0, yq.queryResult.size=*/20971520, yq.analyticsQuery.count=1/100, yq.analyticsQueryDurationMinutes.count=*/1440, yq.memory.size=*/0, yq.result.size=*/0, yq.cpuPercent.count=0/10000, yq.streamingQuery.count=0/100} SEND QUOTAS
2025-12-09T03:42:09.633867Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-09T03:42:09.633903Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:09.633970Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7581698446482741839:2187]
2025-12-09T03:42:09.634024Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-09T03:42:09.634091Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:09.634149Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7581698446482741840:2188]
2025-12-09T03:42:09.634237Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudqgd6rmk1ocinppc"
} [root@builtin, **** (B6C6F477)] 
2025-12-09T03:42:09.648910Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=1/100 NOTIFY CHANGE
2025-12-09T03:42:09.648952Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:09.677960Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudqgd6rmk1ocinppc" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765251729 nanos: 370964000 } modified_at { seconds: 1765251729 nanos: 370964000 } revision: 1 } execute_mode: RUN status: STARTING last_job_query_revision: 1 last_job_id: "ptjudqgd6rmk107d45ge-ptqudqgd6rmk1ocinppc" started_by: "root@builtin" submitted_at { seconds: 1765251729 nanos: 370964000 } } content { type: ANALYTICS name: "bad" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select max(DateTime) from AS_TABLE([<|x:1|>]);" syntax: YQL_V1 } } } SUCCESS
2025-12-09T03:42:09.683490Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7581698429302872528:2081]
2025-12-09T03:42:09.683512Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-09T03:42:09.683519Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7581698429302872512:2069]
2025-12-09T03:42:09.683523Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7581698429302872512:2069]
2025-12-09T03:42:09.683528Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7581698429302872512:2069]
2025-12-09T03:42:09.683539Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-09T03:42:09.695793Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:09.696360Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:42:09.696423Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:42:09.699105Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudqgd6rmk1ocinppc RetryRate: 0 RetryCounter: 0 At: 2025-12-09T03:42:09.460698Z
2025-12-09T03:42:09.860871Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudqgd6rmk1ocinppc" } job_id { value: "ptjudqgd6rmk107d45ge" } generation: 1 text: "select max(DateTime) from AS_TABLE([<|x:1|>]);" user_token: "root@builtin" user_id: "root@builtin" query_type: ANALYTICS scope: "yandexcloud://my_folder" execute_mode: RUN state_load_mode: EMPTY status: STARTING sensor_labels { key: "cloud_id" value: "mock_cloud" } sensor_labels { key: "scope" value: "yandexcloud://my_folder" } query_name: "bad" deadline { seconds: 1765338129 nanos: 765316000 } disposition { } result_limit: 20971520 execution_limit { seconds: 86400 } request_started_at { } resources { } query_syntax: YQL_V1 compute_connection { } result_ttl { seconds: 86400 } request_submitted_at { seconds: 1765251729 nanos: 370964000 } } } SUCCESS
2025-12-09T03:42:09.860969Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:42:09.861481Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:09.861491Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-12-09T03:42:09.861500Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudqgd6rmk1ocinppc UserId: root@builtin AuthToken: **** (B6C6F477)
2025-12-09T03:42:09.861608Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585536, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:09.861615Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudqgd6rmk1ocinppc Start run actor. Compute state: STARTING
2025-12-09T03:42:09.861617Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudqgd6rmk1ocinppc FillConnections
2025-12-09T03:42:09.861635Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudqgd6rmk1ocinppc Run actors params: { QueryId: ptqudqgd6rmk1ocinppc CloudId: mock_cloud UserId: root@builtin Owner: a9022137-8f037aa8-aa79d3db-f6b585535 PreviousQueryRevision: 1 Connections: 0 Bindings: 0 AccountIdSignatures: 0 QueryType: ANALYTICS ExecuteMode: RUN ResultId: ptrudqgd6r7l386842rc StateLoadMode: EMPTY StreamingDisposition: {  } Status: STARTING DqGraphs: 0 DqGraphIndex: 0 Resource.TopicConsumers: 0 }
2025-12-09T03:42:09.861648Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:571: QueryId: ptqudqgd6rmk1ocinppc Start rate limiter resource creator
2025-12-09T03:42:09.861662Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Start Pinger
2025-12-09T03:42:09.861694Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward ping request: { started_at { seconds: 1765251729 nanos: 861000000 } }
2025-12-09T03:42:09.861698Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward request Private::PingTask
2025-12-09T03:42:09.861711Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward ping request: { status: RUNNING resources { rate_limiter: PREPARE compilation: PREPARE } }
2025-12-09T03:42:09.862019Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585536, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:42:09.862049Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "a9022137-8f037aa8-aa79d3db-f6b585536"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:42:09.862121Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Request CP::PingTask with size: 129 bytes
2025-12-09T03:42:09.862129Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:78: PrivateCreateRateLimiterResource - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Request CP::CreateRateLimiterResource with size: 97 bytes
2025-12-09T03:42:09.862161Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
scope: "yandexcloud://my_folder"
started_at {
  seconds: 1765251729
  nanos: 861000000
}
deadline {
  seconds: 1765338129
  nanos: 765316000
}
tenant: "/default"
}
2025-12-09T03:42:09.862236Z :YQ_CONTROL_PLANE_STORAGE TRACE: request_actor.h:57: CreateRateLimiterResourceRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
scope: "yandexcloud://my_folder"
tenant: "/default"
} 
2025-12-09T03:42:09.876646Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:42:09.876699Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585536, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:42:09.877445Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:09.877453Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:42:09.931521Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:74: CreateRateLimiterResourceRequest. Got response from database: SUCCESS
2025-12-09T03:42:09.931592Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc" actor
2025-12-09T03:42:09.931594Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:09.932648Z :YQ_RATE_LIMITER DEBUG: schema.cpp:261: Trying to recursively create rate limiter resource "mock_cloud/my_folder"
2025-12-09T03:42:09.932654Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder"
2025-12-09T03:42:09.934560Z :YQ_RATE_LIMITER DEBUG: schema.cpp:244: Successfully created rate limiter resource "mock_cloud/my_folder"
2025-12-09T03:42:09.934566Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:09.939724Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:09.939733Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc": 
2025-12-09T03:42:09.939758Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:180: Got create response from rate limiter service
2025-12-09T03:42:09.939773Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:102: PrivateCreateRateLimiterResource - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Got CP::CreateRateLimiterResourceResponse
2025-12-09T03:42:09.940160Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1476: QueryId: ptqudqgd6rmk1ocinppc Rate limiter resource creation finished. Success: 1
2025-12-09T03:42:09.940170Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1966: QueryId: ptqudqgd6rmk1ocinppc Compiling query ...
2025-12-09T03:42:09.941101Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward ping request: { status: RUNNING resources { rate_limiter: READY compilation: PREPARE rate_limiter_path: "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter" } }
2025-12-09T03:42:09.988411Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.988 TRACE ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [perf] yql_facade.cpp:855: Execution of [ParseSql] took 47.3ms
2025-12-09T03:42:09.988818Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.988 TRACE ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [perf] yql_facade.cpp:898: Execution of [Compile] took 375us
2025-12-09T03:42:09.988944Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.988 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [DQ] yql_dq_provider.cpp:109: OpenSession ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud
2025-12-09T03:42:09.988951Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.988 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [DQ] yql_dq_provider.cpp:112: OpenSession ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud complete in 0
2025-12-09T03:42:09.989077Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.989 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [perf] yql_expr_optimize.cpp:671: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} Execution of [ExpandApply] took 45us
2025-12-09T03:42:09.989147Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.989 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [perf] yql_expr_optimize.cpp:671: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} Execution of [ExpandApply] took 26us
2025-12-09T03:42:09.989224Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.989 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [perf] yql_expr_optimize.cpp:671: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} Execution of [ExpandApply] took 35us
2025-12-09T03:42:09.989379Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.989 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [core eval] yql_eval_expr.cpp:410: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} EvaluateExpression - start
2025-12-09T03:42:09.989502Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.989 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [core eval] yql_eval_expr.cpp:1181: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} EvaluateExpression - finish
2025-12-09T03:42:09.989727Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.989 INFO  ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [PQ] yql_pq_helpers.cpp:100: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} Ids to resolve: 0
2025-12-09T03:42:09.989856Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.989 INFO  ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [RESULT] yql_result_provider.cpp:1418: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} RewriteIO
2025-12-09T03:42:09.991954Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.991 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [core] type_ann_expr.cpp:159: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} Repeated callable stats:
ToList=1
TypeOf=1
ListItemType=1
AsListMayWarn=1
PersistableRepr=2
AggregationTraits=2

2025-12-09T03:42:09.991976Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.991 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [perf] type_ann_expr.cpp:48: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 2.09ms
2025-12-09T03:42:09.991986Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudqgd6rmk1ocinppc 2025-12-09 03:42:09.991 INFO  ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [default] yql_facade.cpp:1627: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} Fallback skipped due to per query policy
2025-12-09T03:42:09.992222Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: a9022137-8f037aa8-aa79d3db-f6b58553 2025-12-09 03:42:09.992 DEBUG ydbd(pid=1601029, tid=0x00007FA6BD7A5640) [DQ] yql_dq_provider.cpp:126: {ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud} CloseSession ptqudqgd6rmk1ocinppc#ptrudqgd6r7l386842rc#yandexcloud://my_folder#a9022137-8f037aa8-aa79d3db-f6b585535#mock_cloud
2025-12-09T03:42:09.992231Z :FQ_RUN_ACTOR WARN: run_actor.cpp:1914: QueryId: ptqudqgd6rmk1ocinppc ResignQuery, status ABORTED
2025-12-09T03:42:09.992237Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1919: QueryId: ptqudqgd6rmk1ocinppc SendPingAndPassAway, FinalQueryStatus COMPUTE_STATUS_UNSPECIFIED
2025-12-09T03:42:09.992275Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:536: QueryId: ptqudqgd6rmk1ocinppc PassAway
2025-12-09T03:42:09.992385Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward ping request: { ast: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 (AggregationTraits (StructType \'(\'\"x\" (DataType \'Int32))) (lambda \'($8) (SqlPlainColumnOrType $8 \'\"DateTime\")) (lambda \'($9 $10) (AggrMax (SqlPlainColumnOrType $9 \'\"DateTime\") $10)) (lambda \'($11) $11) (lambda \'($12) $12) (lambda \'($13 $14) (AggrMax $13 $14)) (lambda \'($15) $15) (Null)))\n(let $4 (Aggregate (ToList (AsList (AsStruct \'(\'\"x\" (Int32 \'\"1\"))))) \'() \'(\'(\'Max0 $3)) \'()))\n(let $5 (AssumeColumnOrderPartial $4 \'()))\n(let $6 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'(\'auto)))))\n(let $7 (ResWrite! $1 $2 (Key) (RemovePrefixMembers (Unordered (PersistableRepr (SqlProject $5 \'((SqlProjectItem (TypeOf $5) \'\"\" (lambda \'($16) (Member $16 \'Max0)) \'(\'(\'autoName))))))) \'(\'_yql_sys_)) $6))\n(return (Commit! $7 $2))\n)\n" plan: "{\"Detailed\":{\"Operations\":[],\"OperationRoot\":0,\"Providers\":[],\"OperationStats\":{}},\"Basic\":{\"nodes\":[],\"links\":[]}}" }
2025-12-09T03:42:09.992453Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward ping request: { owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535" query_id { value: "ptqudqgd6rmk1ocinppc" } result_id { } status: RUNNING resign_query: true transient_issues { message: "Run query failed: Error" severity: 1 issues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 1 column: 1 } message: "At function: RemovePrefixMembers, At function: Unordered, At function: PersistableRepr, At function: SqlProject" end_position { row: 1 column: 1 } severity: 1 issues { position { row: 1 column: 41 } message: "At function: AssumeColumnOrderPartial, At function: Aggregate, At tuple" end_position { row: 1 column: 41 } severity: 1 issues { position { row: 1 column: 8 } message: "At tuple" end_position { row: 1 column: 8 } severity: 1 issues { position { row: 650 column: 12 file: "/lib/yql/aggregate.yqls" } message: "At function: AggregationTraits" end_position { row: 650 column: 12 } severity: 1 issues { position { row: 649 column: 20 file: "/lib/yql/aggregate.yqls" } message: "At lambda" end_position { row: 649 column: 20 } severity: 1 issues { position { row: 594 column: 109 file: "/lib/yql/aggregate.yqls" } message: "At function: AggrMax" end_position { row: 594 column: 109 } severity: 1 issues { position { row: 1 column: 12 } message: "Member not found: DateTime" end_position { row: 1 column: 12 } severity: 1 } } } } } } } } } status_code: ABORTED resources { rate_limiter: READY compilation: PREPARE rate_limiter_path: "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter" } scope: "yandexcloud://my_folder" }
2025-12-09T03:42:10.155683Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765251750 nanos: 23000000 } } SUCCESS
2025-12-09T03:42:10.155758Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Got CP::PingTaskResponse
2025-12-09T03:42:10.156085Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Ping response success: { expired_at { seconds: 1765251750 nanos: 23000000 } }
2025-12-09T03:42:10.156095Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward request Private::PingTask
2025-12-09T03:42:10.158099Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Request CP::PingTask with size: 123 bytes
2025-12-09T03:42:10.158162Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
status: RUNNING
resources {
  rate_limiter: PREPARE
  compilation: PREPARE
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765338129
  nanos: 765316000
}
tenant: "/default"
}
2025-12-09T03:42:10.172497Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765251750 nanos: 163000000 } } SUCCESS
2025-12-09T03:42:10.172608Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Got CP::PingTaskResponse
2025-12-09T03:42:10.173248Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Ping response success: { expired_at { seconds: 1765251750 nanos: 163000000 } }
2025-12-09T03:42:10.173265Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward request Private::PingTask
2025-12-09T03:42:10.173738Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Request CP::PingTask with size: 192 bytes
2025-12-09T03:42:10.173827Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
status: RUNNING
resources {
  rate_limiter: READY
  compilation: PREPARE
  rate_limiter_path: "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765338129
  nanos: 765316000
}
tenant: "/default"
}
2025-12-09T03:42:10.180743Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.180806Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7581698450777709165:2216]
2025-12-09T03:42:10.180828Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-09T03:42:10.180850Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7581698450777709166:2217]
2025-12-09T03:42:10.180886Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:10.180900Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-09T03:42:10.180906Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:10.180932Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-09T03:42:10.185475Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765251750 nanos: 178000000 } } SUCCESS
2025-12-09T03:42:10.185515Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Got CP::PingTaskResponse
2025-12-09T03:42:10.186155Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Ping response success: { expired_at { seconds: 1765251750 nanos: 178000000 } }
2025-12-09T03:42:10.186177Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward request Private::PingTask
2025-12-09T03:42:10.186876Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Request CP::PingTask with size: 1059 bytes
2025-12-09T03:42:10.186942Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
ast: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 (AggregationTraits (StructType \'(\'\"x\" (DataType \'Int32))) (lambda \'($8) (SqlPlainColumnOrType $8 \'\"DateTime\")) (lambda \'($9 $10) (AggrMax (SqlPlainColumnOrType $9 \'\"DateTime\") $10)) (lambda \'($11) $11) (lambda \'($12) $12) (lambda \'($13 $14) (AggrMax $13 $14)) (lambda \'($15) $15) (Null)))\n(let $4 (Aggregate (ToList (AsList (AsStruct \'(\'\"x\" (Int32 \'\"1\"))))) \'() \'(\'(\'Max0 $3)) \'()))\n(let $5 (AssumeColumnOrderPartial $4 \'()))\n(let $6 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'(\'auto)))))\n(let $7 (ResWrite! $1 $2 (Key) (RemovePrefixMembers (Unordered (PersistableRepr (SqlProject $5 \'((SqlProjectItem (TypeOf $5) \'\"\" (lambda \'($16) (Member $16 \'Max0)) \'(\'(\'autoName))))))) \'(\'_yql_sys_)) $6))\n(return (Commit! $7 $2))\n)\n"
plan: "{\"Detailed\":{\"Operations\":[],\"OperationRoot\":0,\"Providers\":[],\"OperationStats\":{}},\"Basic\":{\"nodes\":[],\"links\":[]}}"
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765338129
  nanos: 765316000
}
tenant: "/default"
}
2025-12-09T03:42:10.188174Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=1/100 REFRESHED
2025-12-09T03:42:10.188189Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-09T03:42:10.188205Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:493: cloud.mock_cloud{yq.streamingQueryDurationMinutes.count=*/0, yq.queryResult.size=*/20971520, yq.analyticsQuery.count=1/100, yq.analyticsQueryDurationMinutes.count=*/1440, yq.memory.size=*/0, yq.result.size=*/0, yq.cpuPercent.count=0/10000, yq.streamingQuery.count=0/100} SEND QUOTAS
2025-12-09T03:42:10.188219Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-09T03:42:10.188403Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.188450Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7581698450777709174:2225]
2025-12-09T03:42:10.188460Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-09T03:42:10.188472Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.188481Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7581698450777709175:2226]
2025-12-09T03:42:10.188509Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudqgd6rmk1ocinppc"
} [root@builtin, **** (B6C6F477)] 
2025-12-09T03:42:10.189012Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-09T03:42:10.198685Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudqgd6rmk1ocinppc" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765251729 nanos: 370964000 } modified_at { seconds: 1765251729 nanos: 370964000 } revision: 1 } started_at { seconds: 1765251729 nanos: 861000000 } execute_mode: RUN status: RUNNING last_job_query_revision: 1 last_job_id: "ptjudqgd6rmk107d45ge-ptqudqgd6rmk1ocinppc" started_by: "root@builtin" submitted_at { seconds: 1765251729 nanos: 370964000 } } content { type: ANALYTICS name: "bad" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select max(DateTime) from AS_TABLE([<|x:1|>]);" syntax: YQL_V1 } } } SUCCESS
2025-12-09T03:42:10.207777Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=1/100 NOTIFY CHANGE
2025-12-09T03:42:10.210617Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:10.210717Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765251750 nanos: 194000000 } } SUCCESS
2025-12-09T03:42:10.210769Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Got CP::PingTaskResponse
2025-12-09T03:42:10.211188Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Ping response success: { expired_at { seconds: 1765251750 nanos: 194000000 } }
2025-12-09T03:42:10.211208Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Forward request Private::PingTask
2025-12-09T03:42:10.211989Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Request CP::PingTask with size: 742 bytes
2025-12-09T03:42:10.212153Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585535"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
result_id {
}
status: RUNNING
resign_query: true
transient_issues {
  message: "Run query failed: Error"
  severity: 1
  issues {
    message: "Type annotation"
    issue_code: 1030
    severity: 1
    issues {
      position {
        row: 1
        column: 1
      }
      message: "At function: RemovePrefixMembers, At function: Unordered, At function: PersistableRepr, At function: SqlProject"
      end_position {
        row: 1
        column: 1
      }
      severity: 1
      issues {
        position {
          row: 1
          column: 41
        }
        message: "At function: AssumeColumnOrderPartial, At function: Aggregate, At tuple"
        end_position {
          row: 1
          column: 41
        }
        severity: 1
        issues {
          position {
            row: 1
            column: 8
          }
          message: "At tuple"
          end_position {
            row: 1
            column: 8
          }
          severity: 1
          issues {
            position {
              row: 650
              column: 12
              file: "/lib/yql/aggregate.yqls"
            }
            message: "At function: AggregationTraits"
            end_position {
              row: 650
              column: 12
            }
            severity: 1
            issues {
              position {
                row: 649
                column: 20
                file: "/lib/yql/aggregate.yqls"
              }
              message: "At lambda"
              end_position {
                row: 649
                column: 20
              }
              severity: 1
              issues {
                position {
                  row: 594
                  column: 109
                  file: "/lib/yql/aggregate.yqls"
                }
                message: "At function: AggrMax"
                end_position {
                  row: 594
                  column: 109
                }
                severity: 1
                issues {
                  position {
                    row: 1
                    column: 12
                  }
                  message: "Member not found: DateTime"
                  end_position {
                    row: 1
                    column: 12
                  }
                  severity: 1
                }
              }
            }
          }
        }
      }
    }
  }
}
status_code: ABORTED
resources {
  rate_limiter: READY
  compilation: PREPARE
  rate_limiter_path: "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765338129
  nanos: 765316000
}
tenant: "/default"
}
2025-12-09T03:42:10.220750Z :YQ_CONTROL_PLANE_STORAGE DEBUG: task_ping.cpp:427: PingTaskRequest (resign):  FAILURE ABORTED 0 2025-12-09T03:42:09.460698Z 0.000000s
2025-12-09T03:42:10.344555Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765251730 nanos: 220000000 } } SUCCESS
2025-12-09T03:42:10.344598Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535, Got CP::PingTaskResponse
2025-12-09T03:42:10.345071Z :FQ_PINGER ERROR: pinger.cpp:365: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Ping response error: Query ownership time is over: expired_at=2025-12-09T03:42:10.220000Z < now=2025-12-09T03:42:10.344486Z. Retried 0 times during 0.133761s
2025-12-09T03:42:10.345093Z :FQ_PINGER DEBUG: pinger.cpp:379: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Query finished
2025-12-09T03:42:10.345095Z :FQ_PINGER TRACE: pinger.cpp:187: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585535 Stop Pinger
2025-12-09T03:42:10.687978Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:665: Coordinator: Change global state to Started (by timeout)
2025-12-09T03:42:10.696170Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585537, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:10.698340Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585537, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:42:10.698389Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "a9022137-8f037aa8-aa79d3db-f6b585537"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:42:10.701438Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.701475Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7581698450777709185:2235]
2025-12-09T03:42:10.701486Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-09T03:42:10.701496Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7581698450777709186:2236]
2025-12-09T03:42:10.701510Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:10.701516Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-09T03:42:10.701519Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:10.701566Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-09T03:42:10.702305Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:456: Task (Query): ptqudqgd6rmk1ocinppc Lease TIMEOUT, RetryCounterUpdatedAt 2025-12-09T03:42:09.460698Z LastSeenAt: 2025-12-09T03:42:10.220853Z
2025-12-09T03:42:10.702322Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudqgd6rmk1ocinppc RetryRate: 1.999824038 RetryCounter: 1 At: 2025-12-09T03:42:10.702283Z
2025-12-09T03:42:10.709563Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-09T03:42:10.709963Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=1/100 REFRESHED
2025-12-09T03:42:10.709974Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-09T03:42:10.710010Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:493: cloud.mock_cloud{yq.streamingQueryDurationMinutes.count=*/0, yq.queryResult.size=*/20971520, yq.analyticsQuery.count=1/100, yq.analyticsQueryDurationMinutes.count=*/1440, yq.memory.size=*/0, yq.result.size=*/0, yq.cpuPercent.count=0/10000, yq.streamingQuery.count=0/100} SEND QUOTAS
2025-12-09T03:42:10.710017Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-09T03:42:10.710040Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.710071Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7581698450777709192:2242]
2025-12-09T03:42:10.710089Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-09T03:42:10.710103Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.710124Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7581698450777709193:2243]
2025-12-09T03:42:10.710154Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudqgd6rmk1ocinppc"
} [root@builtin, **** (B6C6F477)] 
2025-12-09T03:42:10.711143Z :YQ_CONTROL_PLANE_STORAGE TRACE: nodes_health_check.cpp:38: NodesHealthCheckRequest: {tenant: "/default"
node {
  node_id: 1
  instance_id: "201d0eb3-46b4ac76-774edad-27696159"
  hostname: "ghrun-syjwtgwtdu"
  interconnect_port: 6366
  node_address: "127.0.1.1"
}
}
2025-12-09T03:42:10.719363Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-09T03:42:10.720166Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudqgd6rmk1ocinppc" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765251729 nanos: 370964000 } modified_at { seconds: 1765251729 nanos: 370964000 } revision: 1 } started_at { seconds: 1765251729 nanos: 861000000 } execute_mode: RUN status: FAILING last_job_query_revision: 1 last_job_id: "ptjudqgd6rmk107d45ge-ptqudqgd6rmk1ocinppc" started_by: "root@builtin" submitted_at { seconds: 1765251729 nanos: 370964000 } } content { type: ANALYTICS name: "bad" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select max(DateTime) from AS_TABLE([<|x:1|>]);" syntax: YQL_V1 } plan { json: "{\"Detailed\":{\"Operations\":[],\"OperationRoot\":0,\"Providers\":[],\"OperationStats\":{}},\"Basic\":{\"nodes\":[],\"links\":[]}}" } issue { message: "Query failed with code ABORTED at 2025-12-09T03:42:10.220717Z" severity: 1 issues { message: "Run query failed: Error" severity: 1 issues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 1 column: 1 } message: "At function: RemovePrefixMembers, At function: Unordered, At function: PersistableRepr, At function: SqlProject" end_position { row: 1 column: 1 } severity: 1 issues { position { row: 1 column: 41 } message: "At function: AssumeColumnOrderPartial, At function: Aggregate, At tuple" end_position { row: 1 column: 41 } severity: 1 issues { position { row: 1 column: 8 } message: "At tuple" end_position { row: 1 column: 8 } severity: 1 issues { position { row: 650 column: 12 file: "/lib/yql/aggregate.yqls" } message: "At function: AggregationTraits" end_position { row: 650 column: 12 } severity: 1 issues { position { row: 649 column: 20 file: "/lib/yql/aggregate.yqls" } message: "At lambda" end_position { row: 649 column: 20 } severity: 1 issues { position { row: 594 column: 109 file: "/lib/yql/aggregate.yqls" } message: "At function: AggrMax" end_position { row: 594 column: 109 } severity: 1 issues { position { row: 1 column: 12 } message: "Member not found: DateTime" end_position { row: 1 column: 12 } severity: 1 } } } } } } } } } } ast { data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 (AggregationTraits (StructType \'(\'\"x\" (DataType \'Int32))) (lambda \'($8) (SqlPlainColumnOrType $8 \'\"DateTime\")) (lambda \'($9 $10) (AggrMax (SqlPlainColumnOrType $9 \'\"DateTime\") $10)) (lambda \'($11) $11) (lambda \'($12) $12) (lambda \'($13 $14) (AggrMax $13 $14)) (lambda \'($15) $15) (Null)))\n(let $4 (Aggregate (ToList (AsList (AsStruct \'(\'\"x\" (Int32 \'\"1\"))))) \'() \'(\'(\'Max0 $3)) \'()))\n(let $5 (AssumeColumnOrderPartial $4 \'()))\n(let $6 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'(\'auto)))))\n(let $7 (ResWrite! $1 $2 (Key) (RemovePrefixMembers (Unordered (PersistableRepr (SqlProject $5 \'((SqlProjectItem (TypeOf $5) \'\"\" (lambda \'($16) (Member $16 \'Max0)) \'(\'(\'autoName))))))) \'(\'_yql_sys_)) $6))\n(return (Commit! $7 $2))\n)\n" } } } SUCCESS
2025-12-09T03:42:10.720199Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudqgd6rmk1ocinppc" } job_id { value: "ptjudqgd6rmk107d45ge" } generation: 2 text: "select max(DateTime) from AS_TABLE([<|x:1|>]);" user_token: "root@builtin" user_id: "root@builtin" query_type: ANALYTICS scope: "yandexcloud://my_folder" execute_mode: RUN state_load_mode: EMPTY status: FAILING sensor_labels { key: "cloud_id" value: "mock_cloud" } sensor_labels { key: "scope" value: "yandexcloud://my_folder" } query_name: "bad" deadline { seconds: 1765338130 nanos: 706729000 } disposition { } result_limit: 20971520 execution_limit { seconds: 86400 } request_started_at { seconds: 1765251729 nanos: 861000000 } restart_count: 1 resources { rate_limiter: READY compilation: PREPARE rate_limiter_path: "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter" } query_syntax: YQL_V1 compute_connection { } result_ttl { seconds: 86400 } request_submitted_at { seconds: 1765251729 nanos: 370964000 } } } SUCCESS
2025-12-09T03:42:10.720280Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585537, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:42:10.722149Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:10.722165Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-12-09T03:42:10.722178Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudqgd6rmk1ocinppc UserId: root@builtin AuthToken: **** (B6C6F477)
2025-12-09T03:42:10.722323Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: a9022137-8f037aa8-aa79d3db-f6b585538, Host: ghrun-syjwtgwtdu, Tenant: /default
2025-12-09T03:42:10.722389Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudqgd6rmk1ocinppc Start run actor. Compute state: FAILING
2025-12-09T03:42:10.722390Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudqgd6rmk1ocinppc FillConnections
2025-12-09T03:42:10.722407Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudqgd6rmk1ocinppc Run actors params: { QueryId: ptqudqgd6rmk1ocinppc CloudId: mock_cloud UserId: root@builtin Owner: a9022137-8f037aa8-aa79d3db-f6b585537 PreviousQueryRevision: 2 Connections: 0 Bindings: 0 AccountIdSignatures: 0 QueryType: ANALYTICS ExecuteMode: RUN ResultId: ptrudqgd6qdciotvuvnb StateLoadMode: EMPTY StreamingDisposition: {  } Status: FAILING DqGraphs: 0 DqGraphIndex: 0 Resource.TopicConsumers: 0 }
2025-12-09T03:42:10.722415Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1827: QueryId: ptqudqgd6rmk1ocinppc Is about to finish query with status FAILED
2025-12-09T03:42:10.722420Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1507: QueryId: ptqudqgd6rmk1ocinppc Start rate limiter resource deleter
2025-12-09T03:42:10.722432Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537 Start Pinger
2025-12-09T03:42:10.724400Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=1/100 NOTIFY CHANGE
2025-12-09T03:42:10.724422Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:10.724461Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:175: PrivateDeleteRateLimiterResource - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537, Request CP::DeleteRateLimiterResource with size: 97 bytes
2025-12-09T03:42:10.724487Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585538, Host: ghrun-syjwtgwtdu, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-12-09T03:42:10.724514Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "a9022137-8f037aa8-aa79d3db-f6b585538"
host: "ghrun-syjwtgwtdu"
node_id: 1
}
2025-12-09T03:42:10.724563Z :YQ_CONTROL_PLANE_STORAGE TRACE: request_actor.h:57: DeleteRateLimiterResourceRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585537"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
scope: "yandexcloud://my_folder"
tenant: "/default"
} 
2025-12-09T03:42:10.735488Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-09T03:42:10.735541Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: a9022137-8f037aa8-aa79d3db-f6b585538, Host: ghrun-syjwtgwtdu, Tenant: /default, Got CP::GetTask Response
2025-12-09T03:42:10.736197Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-09T03:42:10.736211Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-09T03:42:10.743851Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: NodesHealthCheckRequest - NodesHealthCheckResult: {nodes { node_id: 1 instance_id: "201d0eb3-46b4ac76-774edad-27696159" hostname: "ghrun-syjwtgwtdu" node_address: "127.0.1.1" } } SUCCESS
2025-12-09T03:42:10.744339Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-12-09T03:42:10.796825Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:74: DeleteRateLimiterResourceRequest. Got response from database: SUCCESS
2025-12-09T03:42:10.796951Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run delete rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc" actor
2025-12-09T03:42:10.796957Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call delete rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.799183Z :YQ_RATE_LIMITER DEBUG: schema.cpp:179: Successfully deleted rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:10.799192Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for delete rate limiter resource "mock_cloud/my_folder/ptqudqgd6rmk1ocinppc": 
2025-12-09T03:42:10.799221Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:219: Got delete response from rate limiter service
2025-12-09T03:42:10.799237Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:199: PrivateDeleteRateLimiterResource - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537, Got CP::DeleteRateLimiterResourceResponse
2025-12-09T03:42:10.799696Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1498: QueryId: ptqudqgd6rmk1ocinppc Rate limiter resource deletion finished. Success: 1. Issues: 
2025-12-09T03:42:10.799704Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1919: QueryId: ptqudqgd6rmk1ocinppc SendPingAndPassAway, FinalQueryStatus FAILED
2025-12-09T03:42:10.799716Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:536: QueryId: ptqudqgd6rmk1ocinppc PassAway
2025-12-09T03:42:10.799828Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537 Forward ping request: { result_id { } status: FAILED resources { rate_limiter: READY compilation: PREPARE rate_limiter_path: "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter" } finished_at { seconds: 1765251730 nanos: 722000000 } }
2025-12-09T03:42:10.799831Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537 Forward request Private::PingTask
2025-12-09T03:42:10.800336Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537, Request CP::PingTask with size: 209 bytes
2025-12-09T03:42:10.800391Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "a9022137-8f037aa8-aa79d3db-f6b585537"
query_id {
  value: "ptqudqgd6rmk1ocinppc"
}
result_id {
}
status: FAILED
resources {
  rate_limiter: READY
  compilation: PREPARE
  rate_limiter_path: "local/RateLimiter_c654eb5a-ed78-4755-83b2-830557e16207/rate_limiter"
}
scope: "yandexcloud://my_folder"
finished_at {
  seconds: 1765251730
  nanos: 722000000
}
deadline {
  seconds: 1765338130
  nanos: 706729000
}
tenant: "/default"
}
2025-12-09T03:42:10.807830Z :YQ_CONTROL_PLANE_STORAGE NOTICE: task_ping.cpp:224: Query ptqudqgd6rmk1ocinppc is NOT billable, status: FAILED, statusCode: ABORTED
2025-12-09T03:42:10.918054Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {} SUCCESS
2025-12-09T03:42:10.918177Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537, Got CP::PingTaskResponse
2025-12-09T03:42:10.918685Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537 Ping response success: {  }
2025-12-09T03:42:10.918699Z :FQ_PINGER DEBUG: pinger.cpp:379: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537 Query finished
2025-12-09T03:42:10.918702Z :FQ_PINGER TRACE: pinger.cpp:187: QueryId: ptqudqgd6rmk1ocinppc, Owner: a9022137-8f037aa8-aa79d3db-f6b585537 Stop Pinger
2025-12-09T03:42:11.223218Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:11.223276Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7581698455072676510:2263]
2025-12-09T03:42:11.223294Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-09T03:42:11.223308Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7581698455072676511:2264]
2025-12-09T03:42:11.223334Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:11.223343Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-09T03:42:11.223348Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:11.223379Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-09T03:42:11.227519Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-12-09T03:42:11.227550Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-09T03:42:11.227574Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:493: cloud.mock_cloud{yq.streamingQueryDurationMinutes.count=*/0, yq.queryResult.size=*/20971520, yq.analyticsQuery.count=0/100, yq.analyticsQueryDurationMinutes.count=*/1440, yq.memory.size=*/0, yq.result.size=*/0, yq.cpuPercent.count=0/10000, yq.streamingQuery.count=0/100} SEND QUOTAS
2025-12-09T03:42:11.227594Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-09T03:42:11.227666Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:11.227716Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7581698455072676516:2269]
2025-12-09T03:42:11.227734Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-09T03:42:11.227749Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:11.227761Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7581698455072676517:2270]
2025-12-09T03:42:11.227792Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudqgd6rmk1ocinppc"
} [root@builtin, **** (B6C6F477)] 
2025-12-09T03:42:11.233182Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudqgd6rmk1ocinppc" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765251729 nanos: 370964000 } modified_at { seconds: 1765251729 nanos: 370964000 } revision: 1 } started_at { seconds: 1765251729 nanos: 861000000 } finished_at { seconds: 1765251730 nanos: 722000000 } execute_mode: RUN status: FAILED last_job_query_revision: 1 last_job_id: "ptjudqgd6rmk107d45ge-ptqudqgd6rmk1ocinppc" started_by: "root@builtin" submitted_at { seconds: 1765251729 nanos: 370964000 } } content { type: ANALYTICS name: "bad" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select max(DateTime) from AS_TABLE([<|x:1|>]);" syntax: YQL_V1 } plan { json: "{\"Detailed\":{\"Operations\":[],\"OperationRoot\":0,\"Providers\":[],\"OperationStats\":{}},\"Basic\":{\"nodes\":[],\"links\":[]}}" } issue { message: "Query failed with code ABORTED at 2025-12-09T03:42:10.220717Z" severity: 1 issues { message: "Run query failed: Error" severity: 1 issues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 1 column: 1 } message: "At function: RemovePrefixMembers, At function: Unordered, At function: PersistableRepr, At function: SqlProject" end_position { row: 1 column: 1 } severity: 1 issues { position { row: 1 column: 41 } message: "At function: AssumeColumnOrderPartial, At function: Aggregate, At tuple" end_position { row: 1 column: 41 } severity: 1 issues { position { row: 1 column: 8 } message: "At tuple" end_position { row: 1 column: 8 } severity: 1 issues { position { row: 650 column: 12 file: "/lib/yql/aggregate.yqls" } message: "At function: AggregationTraits" end_position { row: 650 column: 12 } severity: 1 issues { position { row: 649 column: 20 file: "/lib/yql/aggregate.yqls" } message: "At lambda" end_position { row: 649 column: 20 } severity: 1 issues { position { row: 594 column: 109 file: "/lib/yql/aggregate.yqls" } message: "At function: AggrMax" end_position { row: 594 column: 109 } severity: 1 issues { position { row: 1 column: 12 } message: "Member not found: DateTime" end_position { row: 1 column: 12 } severity: 1 } } } } } } } } } } ast { data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 (AggregationTraits (StructType \'(\'\"x\" (DataType \'Int32))) (lambda \'($8) (SqlPlainColumnOrType $8 \'\"DateTime\")) (lambda \'($9 $10) (AggrMax (SqlPlainColumnOrType $9 \'\"DateTime\") $10)) (lambda \'($11) $11) (lambda \'($12) $12) (lambda \'($13 $14) (AggrMax $13 $14)) (lambda \'($15) $15) (Null)))\n(let $4 (Aggregate (ToList (AsList (AsStruct \'(\'\"x\" (Int32 \'\"1\"))))) \'() \'(\'(\'Max0 $3)) \'()))\n(let $5 (AssumeColumnOrderPartial $4 \'()))\n(let $6 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'(\'auto)))))\n(let $7 (ResWrite! $1 $2 (Key) (RemovePrefixMembers (Unordered (PersistableRepr (SqlProject $5 \'((SqlProjectItem (TypeOf $5) \'\"\" (lambda \'($16) (Member $16 \'Max0)) \'(\'(\'autoName))))))) \'(\'_yql_sys_)) $6))\n(return (Commit! $7 $2))\n)\n" } } } SUCCESS
2025-12-09T03:42:11.239458Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:11.239499Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7581698455072676521:2273]
2025-12-09T03:42:11.239513Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-09T03:42:11.239525Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7581698455072676522:2274]
2025-12-09T03:42:11.239540Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:11.239546Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-09T03:42:11.239549Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-09T03:42:11.239573Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-09T03:42:11.240297Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-09T03:42:11.240303Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:636: cloud.mock_cloud.yq.cpuPercent.count=0/10000 RESYNC
2025-12-09T03:42:11.240882Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:11.241658Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-12-09T03:42:11.241677Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-09T03:42:11.241690Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:493: cloud.mock_cloud{yq.streamingQueryDurationMinutes.count=*/0, yq.queryResult.size=*/20971520, yq.analyticsQuery.count=0/100, yq.analyticsQueryDurationMinutes.count=*/1440, yq.memory.size=*/0, yq.result.size=*/0, yq.cpuPercent.count=0/10000, yq.streamingQuery.count=0/100} SEND QUOTAS
2025-12-09T03:42:11.241703Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-09T03:42:11.241730Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:11.241766Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7581698455072676526:2278]
2025-12-09T03:42:11.241779Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-09T03:42:11.241793Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudqgd6rmk1ocinppc"
2025-12-09T03:42:11.241814Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7581698455072676527:2279]
2025-12-09T03:42:11.241857Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudqgd6rmk1ocinppc"
} [root@builtin, **** (B6C6F477)] 
2025-12-09T03:42:11.244139Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudqgd6rmk1ocinppc" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765251729 nanos: 370964000 } modified_at { seconds: 1765251729 nanos: 370964000 } revision: 1 } started_at { seconds: 1765251729 nanos: 861000000 } finished_at { seconds: 1765251730 nanos: 722000000 } execute_mode: RUN status: FAILED last_job_query_revision: 1 last_job_id: "ptjudqgd6rmk107d45ge-ptqudqgd6rmk1ocinppc" started_by: "root@builtin" submitted_at { seconds: 1765251729 nanos: 370964000 } } content { type: ANALYTICS name: "bad" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select max(DateTime) from AS_TABLE([<|x:1|>]);" syntax: YQL_V1 } plan { json: "{\"Detailed\":{\"Operations\":[],\"OperationRoot\":0,\"Providers\":[],\"OperationStats\":{}},\"Basic\":{\"nodes\":[],\"links\":[]}}" } issue { message: "Query failed with code ABORTED at 2025-12-09T03:42:10.220717Z" severity: 1 issues { message: "Run query failed: Error" severity: 1 issues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 1 column: 1 } message: "At function: RemovePrefixMembers, At function: Unordered, At function: PersistableRepr, At function: SqlProject" end_position { row: 1 column: 1 } severity: 1 issues { position { row: 1 column: 41 } message: "At function: AssumeColumnOrderPartial, At function: Aggregate, At tuple" end_position { row: 1 column: 41 } severity: 1 issues { position { row: 1 column: 8 } message: "At tuple" end_position { row: 1 column: 8 } severity: 1 issues { position { row: 650 column: 12 file: "/lib/yql/aggregate.yqls" } message: "At function: AggregationTraits" end_position { row: 650 column: 12 } severity: 1 issues { position { row: 649 column: 20 file: "/lib/yql/aggregate.yqls" } message: "At lambda" end_position { row: 649 column: 20 } severity: 1 issues { position { row: 594 column: 109 file: "/lib/yql/aggregate.yqls" } message: "At function: AggrMax" end_position { row: 594 column: 109 } severity: 1 issues { position { row: 1 column: 12 } message: "Member not found: DateTime" end_position { row: 1 column: 12 } severity: 1 } } } } } } } } } } ast { data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 (AggregationTraits (StructType \'(\'\"x\" (DataType \'Int32))) (lambda \'($8) (SqlPlainColumnOrType $8 \'\"DateTime\")) (lambda \'($9 $10) (AggrMax (SqlPlainColumnOrType $9 \'\"DateTime\") $10)) (lambda \'($11) $11) (lambda \'($12) $12) (lambda \'($13 $14) (AggrMax $13 $14)) (lambda \'($15) $15) (Null)))\n(let $4 (Aggregate (ToList (AsList (AsStruct \'(\'\"x\" (Int32 \'\"1\"))))) \'() \'(\'(\'Max0 $3)) \'()))\n(let $5 (AssumeColumnOrderPartial $4 \'()))\n(let $6 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'(\'auto)))))\n(let $7 (ResWrite! $1 $2 (Key) (RemovePrefixMembers (Unordered (PersistableRepr (SqlProject $5 \'((SqlProjectItem (TypeOf $5) \'\"\" (lambda \'($16) (Member $16 \'Max0)) \'(\'(\'autoName))))))) \'(\'_yql_sys_)) $6))\n(return (Commit! $7 $2))\n)\n" } } } SUCCESS
2025-12-09T03:42:11.247256Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:11.247267Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:636: cloud.mock_cloud.yq.streamingQuery.count=0/100 RESYNC
2025-12-09T03:42:11.250896Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
2025-12-09T03:42:11.253242Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-09T03:42:11.265777Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
