2025-12-12T12:27:08.281025Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:544: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7582946989801591434:2069], tenant /default
2025-12-12T12:27:08.281050Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7582946989801591434:2069]
2025-12-12T12:27:08.281053Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:329: Coordinator: Add new row dispatcher to map (state 1)
2025-12-12T12:27:08.281349Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-12T12:27:08.281407Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-12-12T12:27:08.281639Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:58: STARTING: [1:7582946989801591428:2063]
2025-12-12T12:27:08.281768Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: 9d7a09f6-488df656-dbee48aa-60b42c26
2025-12-12T12:27:08.281771Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-12-12T12:27:08.281797Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7582946989801591439:2074]
2025-12-12T12:27:08.281828Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-12-12T12:27:08.281834Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-12-12T12:27:08.282344Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-12-12T12:27:08.282353Z :YQ_CONTROL_PLANE_STORAGE INFO: ydb_control_plane_storage.cpp:50: Starting ydb control plane storage service. Actor id: [1:7582946989801591427:2062]
2025-12-12T12:27:08.282608Z :YQ_CONTROL_PLANE_PROXY DEBUG: control_plane_proxy.cpp:525: Starting yandex query control plane proxy. Actor id: [1:7582946989801591429:2064]
2025-12-12T12:27:08.282714Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true ControlPlaneEnabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:28901" Database: "local" TablePrefix: "RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-12-12T12:27:08.282862Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/bindings" actor
2025-12-12T12:27:08.282896Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:293: Coordinator: Successfully bootstrapped coordinator, id [1:7582946989801591450:2081], NodesManagerId [0:4705493345362661702:78], rebalancing timeout 5.000000s
2025-12-12T12:27:08.282908Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7582946989801591451:2082] Successfully bootstrapped, local coordinator id [1:7582946989801591450:2081], tenant id default, local mode 0, coordination node path local/default, endpoint localhost:28901
2025-12-12T12:27:08.283073Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create coordination node "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter" actor
2025-12-12T12:27:08.283077Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create coordination node "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter"
2025-12-12T12:27:08.283080Z :YQ_RATE_LIMITER DEBUG: schema.cpp:411: Call create coordination node "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter"
2025-12-12T12:27:08.283099Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create directory "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081" actor
2025-12-12T12:27:08.283100Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create directory "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081"
2025-12-12T12:27:08.283102Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:383: Call create directory "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081"
2025-12-12T12:27:08.283120Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/queries" actor
2025-12-12T12:27:08.283133Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/pending_small" actor
2025-12-12T12:27:08.283142Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:214: Bootstrap
2025-12-12T12:27:08.283144Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:217: Create sdk ydb connection
2025-12-12T12:27:08.283188Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:239: Successfully bootstrapped TStorageProxy [1:7582946989801591433:2068] with connection to localhost:28901:local
2025-12-12T12:27:08.283199Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/idempotency_keys" actor
2025-12-12T12:27:08.283207Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/result_sets" actor
2025-12-12T12:27:08.283216Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/jobs" actor
2025-12-12T12:27:08.283253Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a21, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:08.283264Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/mappings" actor
2025-12-12T12:27:08.283272Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-12-12T12:27:08.283275Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/connections" actor
2025-12-12T12:27:08.283285Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:637: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7582946989801591450:2081]
2025-12-12T12:27:08.284013Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/nodes" actor
2025-12-12T12:27:08.284040Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/quotas" actor
2025-12-12T12:27:08.284049Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenants" actor
2025-12-12T12:27:08.284057Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenant_acks" actor
2025-12-12T12:27:08.284067Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/compute_databases" actor
2025-12-12T12:27:08.284577Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-12T12:27:08.285818Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/default" actor
2025-12-12T12:27:08.285820Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/default"
2025-12-12T12:27:08.285823Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/default"
2025-12-12T12:27:08.303128Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-12-12T12:27:08.311155Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:08.311176Z :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:64739: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:64739
2025-12-12T12:27:08.311231Z :YQL_NODES_MANAGER ERROR: nodes_manager.cpp:395: ydb/core/fq/libs/actors/nodes_manager.cpp:353: TRANSPORT_UNAVAILABLE
<main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.0.1:64739: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:64739
2025-12-12T12:27:08.318522Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/connections". Create session OK
2025-12-12T12:27:08.318534Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/connections"
2025-12-12T12:27:08.318536Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/connections"
2025-12-12T12:27:08.318678Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/mappings". Create session OK
2025-12-12T12:27:08.318680Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/mappings"
2025-12-12T12:27:08.318680Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/mappings"
2025-12-12T12:27:08.318739Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/jobs". Create session OK
2025-12-12T12:27:08.318740Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/jobs"
2025-12-12T12:27:08.318741Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/jobs"
2025-12-12T12:27:08.318812Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/bindings". Create session OK
2025-12-12T12:27:08.318813Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/bindings"
2025-12-12T12:27:08.318814Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/bindings"
2025-12-12T12:27:08.318869Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/pending_small". Create session OK
2025-12-12T12:27:08.318871Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/pending_small"
2025-12-12T12:27:08.318872Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/pending_small"
2025-12-12T12:27:08.318920Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenants". Create session OK
2025-12-12T12:27:08.318922Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenants"
2025-12-12T12:27:08.318923Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenants"
2025-12-12T12:27:08.318960Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/nodes". Create session OK
2025-12-12T12:27:08.318961Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/nodes"
2025-12-12T12:27:08.318961Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/nodes"
2025-12-12T12:27:08.319023Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/idempotency_keys". Create session OK
2025-12-12T12:27:08.319025Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/idempotency_keys"
2025-12-12T12:27:08.319026Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/idempotency_keys"
2025-12-12T12:27:08.319111Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/compute_databases". Create session OK
2025-12-12T12:27:08.319113Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/compute_databases"
2025-12-12T12:27:08.319114Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/compute_databases"
2025-12-12T12:27:08.319161Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenant_acks". Create session OK
2025-12-12T12:27:08.319163Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenant_acks"
2025-12-12T12:27:08.319164Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenant_acks"
2025-12-12T12:27:08.319205Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/quotas". Create session OK
2025-12-12T12:27:08.319206Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/quotas"
2025-12-12T12:27:08.319207Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/quotas"
2025-12-12T12:27:08.319243Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/result_sets". Create session OK
2025-12-12T12:27:08.319245Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/result_sets"
2025-12-12T12:27:08.319246Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/result_sets"
2025-12-12T12:27:08.319283Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/queries". Create session OK
2025-12-12T12:27:08.319284Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/queries"
2025-12-12T12:27:08.319285Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/queries"
2025-12-12T12:27:08.319345Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created directory "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081"
2025-12-12T12:27:08.319355Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create directory "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081": { <main>: Error: Check failed: path: '/local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081', 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-12T12:27:08.331841Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/mappings"
2025-12-12T12:27:08.331866Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/mappings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/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-12T12:27:08.332180Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenants"
2025-12-12T12:27:08.332187Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenants": { <main>: Error: Check failed: path: '/local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/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-12T12:27:08.372071Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/default"
2025-12-12T12:27:08.372083Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/default": 
2025-12-12T12:27:08.372100Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7582946989801591451:2082] Coordination node successfully created
2025-12-12T12:27:08.372103Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7582946989801591451:2082] Start session
2025-12-12T12:27:08.377334Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created coordination node "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter"
2025-12-12T12:27:08.377356Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create coordination node "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter": 
2025-12-12T12:27:08.382542Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7582946989801591451:2082] Session successfully created
2025-12-12T12:27:08.395250Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7582946989801591451:2082] Semaphore successfully created
2025-12-12T12:27:08.395256Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7582946989801591451:2082] Try to acquire semaphore
2025-12-12T12:27:08.395314Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7582946989801591451:2082] Describe semaphore
2025-12-12T12:27:08.399990Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7582946989801591451:2082] Semaphore successfully acquired
2025-12-12T12:27:08.400003Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7582946989801591451:2082] Semaphore successfully described: coordinator id [1:7582946989801591450:2081] generation 1
2025-12-12T12:27:08.400007Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7582946989801591451:2082] Send TEvCoordinatorChanged to [1:7582946989801591434:2069], new coordinator id [1:7582946989801591450:2081], previous coordinator id [0:0:0]
2025-12-12T12:27:08.400028Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:569: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7582946989801591450:2081] generation 1
2025-12-12T12:27:08.400056Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7582946989801591434:2069]
2025-12-12T12:27:08.400064Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7582946989801591434:2069]
2025-12-12T12:27:08.400067Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7582946989801591434:2069]
2025-12-12T12:27:08.400098Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:440: Coordinator: New leader [1:7582946989801591450:2081], SelfId [1:7582946989801591450:2081]
2025-12-12T12:27:08.400104Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-12T12:27:08.402341Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/bindings"
2025-12-12T12:27:08.402347Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/bindings": 
2025-12-12T12:27:08.402358Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/connections"
2025-12-12T12:27:08.402376Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/connections": 
2025-12-12T12:27:08.402550Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/result_sets"
2025-12-12T12:27:08.402552Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/result_sets": 
2025-12-12T12:27:08.402763Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenant_acks"
2025-12-12T12:27:08.402767Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/tenant_acks": 
2025-12-12T12:27:08.402786Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/quotas"
2025-12-12T12:27:08.402790Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/quotas": 
2025-12-12T12:27:08.403354Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/jobs"
2025-12-12T12:27:08.403357Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/jobs": 
2025-12-12T12:27:08.404361Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/pending_small"
2025-12-12T12:27:08.404370Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/pending_small": 
2025-12-12T12:27:08.404526Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/queries"
2025-12-12T12:27:08.404528Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/queries": 
2025-12-12T12:27:08.404630Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/nodes"
2025-12-12T12:27:08.404633Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/nodes": 
2025-12-12T12:27:08.404718Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:190: LOADED TenantInfo: State CHANGED at 2025-12-12T12:27:07.408123Z
2025-12-12T12:27:08.404741Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/compute_databases"
2025-12-12T12:27:08.404743Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/compute_databases": 
2025-12-12T12:27:08.404997Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/idempotency_keys"
2025-12-12T12:27:08.404999Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_49120ace-5dfc-44ff-836c-4843ea528081/idempotency_keys": 
2025-12-12T12:27:09.278437Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a22, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:09.301200Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a22, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:09.302039Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a22"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:09.357609Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-12T12:27:09.357681Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a22, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:09.358080Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:09.358091Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-12T12:27:10.278850Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a23, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:10.278870Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7582946989801591450:2081]
2025-12-12T12:27:10.278876Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-12T12:27:10.278884Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7582946989801591434:2069]
2025-12-12T12:27:10.278890Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7582946989801591434:2069]
2025-12-12T12:27:10.278893Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7582946989801591434:2069]
2025-12-12T12:27:10.278909Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-12T12:27:10.279348Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a23, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:10.279408Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a23"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:10.281972Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-12T12:27:10.282021Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a23, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:10.282335Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:10.282345Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-12T12:27:11.279305Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a24, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:11.280039Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a24, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:11.280099Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a24"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:11.283692Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-12T12:27:11.283744Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a24, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:11.284228Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:11.284233Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-12T12:27:11.298109Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-12T12:27:12.235880Z :TICKET_PARSER DEBUG: ticket_parser_impl.h:1875: Ticket **** (B6C6F477) (ipv6:[::1]:36414) has now valid token of root@builtin
2025-12-12T12:27:12.236036Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple3"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select unwrap(1/0);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "fbe29a09-c341-4f7e-9ec8-ff501990245f"
2025-12-12T12:27:12.236076Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7582947006981460738:2165]
2025-12-12T12:27:12.236106Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-12T12:27:12.236139Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7582947006981460739:2166]
2025-12-12T12:27:12.236157Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:255: cloud.mock_cloud NOT CASHED, Loading ...
2025-12-12T12:27:12.266431Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:441: cloud.mock_cloud {} FROM DB
2025-12-12T12:27:12.266483Z :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-12T12:27:12.266487Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:12.266499Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-12T12:27:12.266515Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:12.266535Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-12T12:27:12.279798Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7582946989801591450:2081]
2025-12-12T12:27:12.279818Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-12T12:27:12.279831Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a25, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:12.279862Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7582946989801591434:2069]
2025-12-12T12:27:12.279867Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7582946989801591434:2069]
2025-12-12T12:27:12.279869Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7582946989801591434:2069]
2025-12-12T12:27:12.280086Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-12T12:27:12.280380Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a25, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:12.280419Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a25"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:12.284215Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-12T12:27:12.284245Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a25, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:12.284582Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:12.284592Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-12T12:27:12.318430Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-12T12:27:12.325402Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-12-12T12:27:12.325437Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-12T12:27:12.325461Z :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-12T12:27:12.325473Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-12T12:27:12.325536Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple3"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select unwrap(1/0);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "fbe29a09-c341-4f7e-9ec8-ff501990245f"
2025-12-12T12:27:12.325619Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7582947006981460748:2175]
2025-12-12T12:27:12.325648Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-12T12:27:12.325704Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple3"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select unwrap(1/0);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "fbe29a09-c341-4f7e-9ec8-ff501990245f"
2025-12-12T12:27:12.325746Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:435: Create database bootstrap. CloudId: mock_cloud Scope: yandexcloud://my_folder Actor id: [1:7582947006981460749:2176]
2025-12-12T12:27:12.325779Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:648: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple3"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select unwrap(1/0);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "fbe29a09-c341-4f7e-9ec8-ff501990245f"
2025-12-12T12:27:12.325792Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7582947006981460750:2177]
2025-12-12T12:27:12.325809Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:248: Create rate limiter resource for cloud with limit 100000ms
2025-12-12T12:27:12.325835Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create rate limiter resource "mock_cloud" actor
2025-12-12T12:27:12.325837Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud"
2025-12-12T12:27:12.336253Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created rate limiter resource "mock_cloud"
2025-12-12T12:27:12.336269Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create rate limiter resource "mock_cloud": 
2025-12-12T12:27:12.336307Z :YQ_CONTROL_PLANE_PROXY DEBUG: request_actor.h:272: Create response from rate limiter service. Success: 1
2025-12-12T12:27:12.336339Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:12.336388Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:12.336408Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:226: CreateQueryRequest: {content {
  type: ANALYTICS
  name: "simple3"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "select unwrap(1/0);"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "fbe29a09-c341-4f7e-9ec8-ff501990245f"
} [root@builtin, **** (B6C6F477)] 
2025-12-12T12:27:12.552708Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: CreateQueryRequest - CreateQueryResult: {query_id: "ptqudq7uf7o5em7iasvn" } SUCCESS
2025-12-12T12:27:12.556386Z :TICKET_PARSER DEBUG: ticket_parser_impl.h:1875: Ticket **** (B6C6F477) (ipv6:[::1]:36414) has now valid token of root@builtin
2025-12-12T12:27:12.556489Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:12.556534Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7582947006981460755:2181]
2025-12-12T12:27:12.556573Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-12T12:27:12.556600Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7582947006981460756:2182]
2025-12-12T12:27:12.556625Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:12.556639Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-12T12:27:12.556643Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:12.556657Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-12T12:27:12.565383Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-12T12:27:12.584440Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=1/100 REFRESHED
2025-12-12T12:27:12.584474Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-12T12:27:12.584507Z :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-12T12:27:12.584523Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-12T12:27:12.584582Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:12.584660Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7582947006981460761:2187]
2025-12-12T12:27:12.584683Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-12T12:27:12.584708Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:12.584751Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7582947006981460762:2188]
2025-12-12T12:27:12.584818Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudq7uf7o5em7iasvn"
} [root@builtin, **** (B6C6F477)] 
2025-12-12T12:27:12.590953Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=1/100 NOTIFY CHANGE
2025-12-12T12:27:12.590974Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:12.620025Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudq7uf7o5em7iasvn" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765542432 nanos: 336334000 } modified_at { seconds: 1765542432 nanos: 336334000 } revision: 1 } execute_mode: RUN status: STARTING last_job_query_revision: 1 last_job_id: "ptjudq7uf7o5e1hroror-ptqudq7uf7o5em7iasvn" started_by: "root@builtin" submitted_at { seconds: 1765542432 nanos: 336334000 } } content { type: ANALYTICS name: "simple3" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select unwrap(1/0);" syntax: YQL_V1 } } } SUCCESS
2025-12-12T12:27:13.122596Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.122647Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7582947011276428062:2191]
2025-12-12T12:27:13.122681Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-12T12:27:13.122703Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7582947011276428063:2192]
2025-12-12T12:27:13.122732Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:13.122749Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-12T12:27:13.122759Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:13.122770Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-12T12:27:13.128922Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=1/100 REFRESHED
2025-12-12T12:27:13.128953Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-12T12:27:13.128976Z :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-12T12:27:13.128984Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-12T12:27:13.129008Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.129068Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7582947011276428068:2197]
2025-12-12T12:27:13.129091Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-12T12:27:13.129123Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.129163Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7582947011276428069:2198]
2025-12-12T12:27:13.129208Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudq7uf7o5em7iasvn"
} [root@builtin, **** (B6C6F477)] 
2025-12-12T12:27:13.129788Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-12T12:27:13.132481Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudq7uf7o5em7iasvn" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765542432 nanos: 336334000 } modified_at { seconds: 1765542432 nanos: 336334000 } revision: 1 } execute_mode: RUN status: STARTING last_job_query_revision: 1 last_job_id: "ptjudq7uf7o5e1hroror-ptqudq7uf7o5em7iasvn" started_by: "root@builtin" submitted_at { seconds: 1765542432 nanos: 336334000 } } content { type: ANALYTICS name: "simple3" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select unwrap(1/0);" syntax: YQL_V1 } } } SUCCESS
2025-12-12T12:27:13.134910Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:13.135057Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=1/100 NOTIFY CHANGE
2025-12-12T12:27:13.279199Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:665: Coordinator: Change global state to Started (by timeout)
2025-12-12T12:27:13.279219Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-12-12T12:27:13.280112Z :YQ_CONTROL_PLANE_STORAGE TRACE: nodes_health_check.cpp:38: NodesHealthCheckRequest: {tenant: "/default"
node {
  node_id: 1
  instance_id: "9d7a09f6-488df656-dbee48aa-60b42c26"
  hostname: "ghrun-fhkyrtz6p4"
  interconnect_port: 24344
  node_address: "127.0.1.1"
}
}
2025-12-12T12:27:13.280193Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:13.280642Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:13.280695Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:13.282990Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudq7uf7o5em7iasvn RetryRate: 0 RetryCounter: 0 At: 2025-12-12T12:27:12.417314Z
2025-12-12T12:27:13.352636Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: NodesHealthCheckRequest - NodesHealthCheckResult: {nodes { node_id: 1 instance_id: "9d7a09f6-488df656-dbee48aa-60b42c26" hostname: "ghrun-fhkyrtz6p4" node_address: "127.0.1.1" } } SUCCESS
2025-12-12T12:27:13.353229Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:389: Send NodeInfo with size: 0 to DynamicNameserver
2025-12-12T12:27:13.403560Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudq7uf7o5em7iasvn" } job_id { value: "ptjudq7uf7o5e1hroror" } generation: 1 text: "select unwrap(1/0);" 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: "simple3" deadline { seconds: 1765628833 nanos: 339419000 } 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: 1765542432 nanos: 336334000 } } } SUCCESS
2025-12-12T12:27:13.403621Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:13.404022Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:13.404033Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-12-12T12:27:13.404046Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudq7uf7o5em7iasvn UserId: root@builtin AuthToken: **** (B6C6F477)
2025-12-12T12:27:13.404187Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a27, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:13.404195Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudq7uf7o5em7iasvn Start run actor. Compute state: STARTING
2025-12-12T12:27:13.404198Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudq7uf7o5em7iasvn FillConnections
2025-12-12T12:27:13.404221Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudq7uf7o5em7iasvn Run actors params: { QueryId: ptqudq7uf7o5em7iasvn CloudId: mock_cloud UserId: root@builtin Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 PreviousQueryRevision: 1 Connections: 0 Bindings: 0 AccountIdSignatures: 0 QueryType: ANALYTICS ExecuteMode: RUN ResultId: ptrudq7uf6niq3amo0iu StateLoadMode: EMPTY StreamingDisposition: {  } Status: STARTING DqGraphs: 0 DqGraphIndex: 0 Resource.TopicConsumers: 0 }
2025-12-12T12:27:13.404247Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Start Pinger
2025-12-12T12:27:13.404252Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:571: QueryId: ptqudq7uf7o5em7iasvn Start rate limiter resource creator
2025-12-12T12:27:13.404267Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward ping request: { started_at { seconds: 1765542433 nanos: 404000000 } }
2025-12-12T12:27:13.404271Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward request Private::PingTask
2025-12-12T12:27:13.404285Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward ping request: { status: RUNNING resources { rate_limiter: PREPARE compilation: PREPARE } }
2025-12-12T12:27:13.404621Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a27, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:13.404681Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a27"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:13.404832Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:78: PrivateCreateRateLimiterResource - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::CreateRateLimiterResource with size: 96 bytes
2025-12-12T12:27:13.404863Z :YQ_CONTROL_PLANE_STORAGE TRACE: request_actor.h:57: CreateRateLimiterResourceRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
scope: "yandexcloud://my_folder"
tenant: "/default"
} 
2025-12-12T12:27:13.404879Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::PingTask with size: 128 bytes
2025-12-12T12:27:13.404971Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
scope: "yandexcloud://my_folder"
started_at {
  seconds: 1765542433
  nanos: 404000000
}
deadline {
  seconds: 1765628833
  nanos: 339419000
}
tenant: "/default"
}
2025-12-12T12:27:13.406982Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-12T12:27:13.407018Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a27, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:13.407262Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:13.407270Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-12T12:27:13.448812Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:74: CreateRateLimiterResourceRequest. Got response from database: SUCCESS
2025-12-12T12:27:13.448945Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn" actor
2025-12-12T12:27:13.448953Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.450027Z :YQ_RATE_LIMITER DEBUG: schema.cpp:261: Trying to recursively create rate limiter resource "mock_cloud/my_folder"
2025-12-12T12:27:13.450036Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder"
2025-12-12T12:27:13.452250Z :YQ_RATE_LIMITER DEBUG: schema.cpp:244: Successfully created rate limiter resource "mock_cloud/my_folder"
2025-12-12T12:27:13.452260Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.454153Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.454164Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn": 
2025-12-12T12:27:13.454197Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:180: Got create response from rate limiter service
2025-12-12T12:27:13.454218Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:102: PrivateCreateRateLimiterResource - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::CreateRateLimiterResourceResponse
2025-12-12T12:27:13.454494Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1476: QueryId: ptqudq7uf7o5em7iasvn Rate limiter resource creation finished. Success: 1
2025-12-12T12:27:13.454510Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1966: QueryId: ptqudq7uf7o5em7iasvn Compiling query ...
2025-12-12T12:27:13.454568Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward ping request: { status: RUNNING resources { rate_limiter: READY compilation: PREPARE rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter" } }
2025-12-12T12:27:13.496096Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.495 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_facade.cpp:855: Execution of [ParseSql] took 40.1ms
2025-12-12T12:27:13.496571Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.496 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_facade.cpp:898: Execution of [Compile] took 433us
2025-12-12T12:27:13.496716Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.496 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [DQ] yql_dq_provider.cpp:109: OpenSession ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud
2025-12-12T12:27:13.496724Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.496 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [DQ] yql_dq_provider.cpp:112: OpenSession ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud complete in 0
2025-12-12T12:27:13.496817Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.496 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_optimize.cpp:671: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ExpandApply] took 25us
2025-12-12T12:27:13.496927Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.496 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core eval] yql_eval_expr.cpp:410: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} EvaluateExpression - start
2025-12-12T12:27:13.497056Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.497 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core eval] yql_eval_expr.cpp:1181: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} EvaluateExpression - finish
2025-12-12T12:27:13.497249Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.497 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [PQ] yql_pq_helpers.cpp:100: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Ids to resolve: 0
2025-12-12T12:27:13.497371Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.497 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [RESULT] yql_result_provider.cpp:1418: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} RewriteIO
2025-12-12T12:27:13.498053Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] type_ann_expr.cpp:159: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Repeated callable stats:
RemovePrefixMembers=1
TypeOf=1
PersistableRepr=1
OrderedSqlProject=1
/MayWarn=1
SqlProjectItem=1
ResWrite!=1

2025-12-12T12:27:13.498068Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 676us
2025-12-12T12:27:13.498135Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 28us
2025-12-12T12:27:13.498144Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-12-12T12:27:13.498193Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 39us
2025-12-12T12:27:13.498220Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.498228Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-12-12T12:27:13.498260Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 23us
2025-12-12T12:27:13.498279Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [UpdateCompletness] took 9us
2025-12-12T12:27:13.498319Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [EliminateCommonSubExpressions] took 32us
2025-12-12T12:27:13.498380Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedSqlProject (AsList (AsStruct)) '((SqlProjectItem (ListType (StructType)) '"column0" (lambda '($5) (Unwrap (/ (Int32 '"1") (Int32 '"0")))) '())))) $3))
(return (Commit! $4 $2))
)

2025-12-12T12:27:13.498551Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_co_simple1.cpp:6761: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Expand OrderedSqlProject
2025-12-12T12:27:13.498623Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.498691Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 60us
2025-12-12T12:27:13.498714Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 14us
2025-12-12T12:27:13.498743Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.498751Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.498783Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 24us
2025-12-12T12:27:13.498799Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [UpdateCompletness] took 8us
2025-12-12T12:27:13.498835Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [EliminateCommonSubExpressions] took 28us
2025-12-12T12:27:13.498881Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedFlatMap (AsList (AsStruct)) (lambda '($5) (AsList (FlattenMembers '('"" (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))))))) $3))
(return (Commit! $4 $2))
)

2025-12-12T12:27:13.498907Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_opt_utils.cpp:880: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Enumerate struct literal for FlattenMembers
2025-12-12T12:27:13.498949Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.498983Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 26us
2025-12-12T12:27:13.499264Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.498 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 8us
2025-12-12T12:27:13.499307Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.499315Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-12-12T12:27:13.499340Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 18us
2025-12-12T12:27:13.499360Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [UpdateCompletness] took 6us
2025-12-12T12:27:13.499388Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [EliminateCommonSubExpressions] took 21us
2025-12-12T12:27:13.499434Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedFlatMap (AsList (AsStruct)) (lambda '($5) (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))))) $3))
(return (Commit! $4 $2))
)

2025-12-12T12:27:13.499484Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_co_simple1.cpp:2115: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Fuse OrderedFlatMap over AsList
2025-12-12T12:27:13.499534Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.499569Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 21us
2025-12-12T12:27:13.499589Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 7us
2025-12-12T12:27:13.499604Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [UpdateCompletness] took 4us
2025-12-12T12:27:13.499629Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [EliminateCommonSubExpressions] took 18us
2025-12-12T12:27:13.499665Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))) $3))
(return (Commit! $4 $2))
)

2025-12-12T12:27:13.499688Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_co_simple1.cpp:6478: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Drop Unordered over AsList
2025-12-12T12:27:13.499729Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.499755Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 12us
2025-12-12T12:27:13.499771Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 5us
2025-12-12T12:27:13.499783Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [UpdateCompletness] took 4us
2025-12-12T12:27:13.499815Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [EliminateCommonSubExpressions] took 17us
2025-12-12T12:27:13.499852Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.499 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResWrite! $1 $2 (Key) (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0")))))) $3))
(return (Commit! $4 $2))
)

2025-12-12T12:27:13.500322Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} DqReplicateStageMultiOutput: start traverse node #199956, Commit!
2025-12-12T12:27:13.500385Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [RESULT] yql_result_provider.cpp:802: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} ResFill
2025-12-12T12:27:13.500427Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-12-12T12:27:13.500462Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 22us
2025-12-12T12:27:13.500484Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 9us
2025-12-12T12:27:13.500501Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [UpdateCompletness] took 4us
2025-12-12T12:27:13.500530Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution of [EliminateCommonSubExpressions] took 17us
2025-12-12T12:27:13.500566Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"column0"))))
(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0")))))) $3 '"dq"))
(return (Commit! $4 $2))
)

2025-12-12T12:27:13.500694Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} DqReplicateStageMultiOutput: start traverse node #199960, Commit!
2025-12-12T12:27:13.500773Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:60: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Begin, root #199960
2025-12-12T12:27:13.500785Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:73: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Collect unused nodes for root #199960, status: Ok
2025-12-12T12:27:13.500792Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:410: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} {0}, callable #199960 <Commit!>
2025-12-12T12:27:13.500805Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:410: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} {1}, callable #199959 <ResFill!>
2025-12-12T12:27:13.500816Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:410: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} {2}, callable #199812 <Configure!>
2025-12-12T12:27:13.500828Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:410: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} {2}, callable #199812 <Configure!>
2025-12-12T12:27:13.500838Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:629: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Node #199812<Configure!> finished execution
2025-12-12T12:27:13.500845Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:646: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Node #199812<Configure!> created 0 trackable nodes: 
2025-12-12T12:27:13.500858Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:410: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} {1}, callable #199959 <ResFill!>
2025-12-12T12:27:13.500900Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.500 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [DQ] yql_dq_exectransformer.cpp:884: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Executing Result (UniqueId=199969)
2025-12-12T12:27:13.501012Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.501 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core] type_ann_expr.cpp:159: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Repeated callable stats:
ToStream=1

2025-12-12T12:27:13.501032Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.501 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [TypeAnnotationTransformer::DoTransform] took 35us
2025-12-12T12:27:13.501041Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.501 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-12-12T12:27:13.501052Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.501 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [ConstraintTransformer::DoTransform] took 4us
2025-12-12T12:27:13.501061Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.501 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [UpdateCompletness] took 2us
2025-12-12T12:27:13.501076Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.501 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [EliminateCommonSubExpressionsForSubGraph] took 7us
2025-12-12T12:27:13.501097Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.501 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core peephole] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} PeepHoleOpt:
(
(return (Iterator (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))))
)

2025-12-12T12:27:13.540008Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.539 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} DqReplicateStageMultiOutput: start traverse node #199971, Iterator
2025-12-12T12:27:13.540074Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core dq] dq_opt_build.cpp:235: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} [DQ/Build/TransformConsumers] (
(return (Iterator (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))))
)

2025-12-12T12:27:13.540100Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core dq] dq_opt_build.cpp:325: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} [DQ/Build/TransformPhysical] (
(return (Iterator (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))))
)

2025-12-12T12:27:13.540283Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] type_ann_expr.cpp:48: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-12-12T12:27:13.540304Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_constraint.cpp:3307: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [ConstraintTransformer::DoTransform] took 2us
2025-12-12T12:27:13.540317Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:638: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [UpdateCompletness] took 3us
2025-12-12T12:27:13.540337Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [perf] yql_expr_csee.cpp:651: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} Execution of [EliminateCommonSubExpressionsForSubGraph] took 12us
2025-12-12T12:27:13.540355Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core peephole] yql_out_transformers.cpp:62: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} PeepHoleOpt:
(
(return (Iterator (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))))
)

2025-12-12T12:27:13.540471Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.540 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [DQ] yql_dq_exectransformer.cpp:2098: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud/#199959/#199969} After PeepHole
(
(return (Iterator (AsList (AsStruct '('"column0" (Unwrap (/ (Int32 '"1") (Int32 '"0"))))))))
)

2025-12-12T12:27:13.541100Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:928: QueryId: ptqudq7uf7o5em7iasvn Graph (execution) with tasks: 1
2025-12-12T12:27:13.541119Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:518: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Register async execution for node #199959
2025-12-12T12:27:13.541135Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:88: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Finish, output #199960, status: Async
2025-12-12T12:27:13.541202Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:134: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Completed async execution for node #199959
2025-12-12T12:27:13.541211Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 DEBUG ydbd(pid=1196852, tid=0x00007FDDE0A51640) [DQ] yql_dq_exectransformer.cpp:1050: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud WrapFutureCallback
2025-12-12T12:27:13.541223Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [DQ] yql_dq_exectransformer.cpp:1053: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Execution Result complete, duration: 0.040312s
2025-12-12T12:27:13.541325Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:154: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} State is ExecutionComplete after apply async changes for node #199959
2025-12-12T12:27:13.541342Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:60: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Begin, root #199960
2025-12-12T12:27:13.541351Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:73: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Collect unused nodes for root #199960, status: Ok
2025-12-12T12:27:13.541359Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 TRACE ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:410: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} {0}, callable #199960 <Commit!>
2025-12-12T12:27:13.541372Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:629: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Node #199960<Commit!> finished execution
2025-12-12T12:27:13.541387Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:646: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Node #199960<Commit!> created 0 trackable nodes: 
2025-12-12T12:27:13.541393Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:88: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Finish, output #199960, status: Ok
2025-12-12T12:27:13.541400Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.541 INFO  ydbd(pid=1196852, tid=0x00007FDDE0A51640) [core exec] yql_execution.cpp:94: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Creating finalizing transformer, output #199960
2025-12-12T12:27:13.541569Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudq7uf7o5em7iasvn Overall dq tasks: 1
2025-12-12T12:27:13.541618Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward ping request: { ast: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"column0\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"column0\" (Unwrap (/ (Int32 \'\"1\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" plan: "{\"Detailed\":{\"Operations\":[{\"Id\":2,\"Name\":\"ResFill!\"}],\"OperationRoot\":2,\"Providers\":[],\"OperationStats\":{\"ResFill!\":1}},\"Basic\":{\"nodes\":[{\"id\":2,\"level\":1,\"name\":\"ResFill!\",\"type\":\"op\"}],\"links\":[]}}" }
2025-12-12T12:27:13.541702Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:758: QueryId: ptqudq7uf7o5em7iasvn Graph 0
2025-12-12T12:27:13.541798Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward ping request: { result_set_count: 1 result_set_meta { column { name: "column0" type { type_id: INT32 } } } dq_graph: "\022\305\002\010\001\022\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\000\032\004\010\240\215\00628\n4\010\001\030\002 \001*\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\0002\024\n\022\t\001\000\000\000\000\000\000\000\021\000\000\000\000\001\000\000\000X\001R\000:s\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022E\010\001\022A\022(88a034dc314301cb1522a933e690a4c49a7591e8\032\023dq_vanilla_job.lite \002H\001RClocal/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiterZ)mock_cloud/my_folder/ptqudq7uf7o5em7iasvn`\200\200\200\200 \"\017\037\000\205\002\203\002\034column0/*\007column02pptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud:\025\n\020AnalyticsHopping\022\0011:\034\n\017_LiteralTimeout\022\t604800000:\032\n\r_TableTimeout\022\t604800000:\037\n\025_AllResultsBytesLimit\022\006100000:\032\n\022_RowsLimitPerWrite\022\0041000:\027\n\022EnableComputeActor\022\0011:\016\n\014WorkerFilter:\031\n\020ComputeActorType\022\005async:\031\n\016WatermarksMode\022\007disable:\037\n\027WatermarksGranularityMs\022\0041000:$\n\034WatermarksLateArrivalDelayMs\022\0045000:\027\n\022EnableChannelStats\022\0011:\020\n\013ExportStats\022\0011b\263\001\010\001\022\256\001\037\000\005\205\006\213\000\205\000\204\205\002\203\002\034column0\030Inputs(Parameters\034Program\013\213\000)\251\000?\002\014Arg\000\010)\211\002?\010\206?\006 Iterator\000\006\002?\024\005?\006)\211\n?\004\207?\004\203\005@\203\005@\203\004\203\004\030Unwrap\000)\211\006?\030\203\005@\203\002\203\002\030Invoke\000\003?$\006Div\003?&\004\003?(\000\007\002\003?\032\000\003?\034\000\003?\036\002\003? \020\036\004\000\001\001\006\001/" }
2025-12-12T12:27:13.618653Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765542453 nanos: 525000000 } } SUCCESS
2025-12-12T12:27:13.618701Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::PingTaskResponse
2025-12-12T12:27:13.619060Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Ping response success: { expired_at { seconds: 1765542453 nanos: 525000000 } }
2025-12-12T12:27:13.619083Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward request Private::PingTask
2025-12-12T12:27:13.619114Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudq7uf7o5em7iasvn Forward ping response. Success: 1. Cookie: 2
2025-12-12T12:27:13.619556Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::PingTask with size: 122 bytes
2025-12-12T12:27:13.619647Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
status: RUNNING
resources {
  rate_limiter: PREPARE
  compilation: PREPARE
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765628833
  nanos: 339419000
}
tenant: "/default"
}
2025-12-12T12:27:13.632231Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765542453 nanos: 625000000 } } SUCCESS
2025-12-12T12:27:13.632276Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::PingTaskResponse
2025-12-12T12:27:13.632671Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Ping response success: { expired_at { seconds: 1765542453 nanos: 625000000 } }
2025-12-12T12:27:13.632690Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward request Private::PingTask
2025-12-12T12:27:13.632710Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudq7uf7o5em7iasvn Forward ping response. Success: 1. Cookie: 0
2025-12-12T12:27:13.633184Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::PingTask with size: 191 bytes
2025-12-12T12:27:13.633264Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
status: RUNNING
resources {
  rate_limiter: READY
  compilation: PREPARE
  rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765628833
  nanos: 339419000
}
tenant: "/default"
}
2025-12-12T12:27:13.636219Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.636277Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7582947011276428101:2229]
2025-12-12T12:27:13.636311Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-12T12:27:13.636331Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7582947011276428102:2230]
2025-12-12T12:27:13.636354Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:13.636367Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-12T12:27:13.636371Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:13.636424Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-12T12:27:13.644061Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765542453 nanos: 638000000 } } SUCCESS
2025-12-12T12:27:13.644109Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::PingTaskResponse
2025-12-12T12:27:13.644581Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Ping response success: { expired_at { seconds: 1765542453 nanos: 638000000 } }
2025-12-12T12:27:13.644602Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward request Private::PingTask
2025-12-12T12:27:13.644628Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudq7uf7o5em7iasvn Forward ping response. Success: 1. Cookie: 0
2025-12-12T12:27:13.645061Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::PingTask with size: 622 bytes
2025-12-12T12:27:13.645149Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
ast: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"column0\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"column0\" (Unwrap (/ (Int32 \'\"1\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n"
plan: "{\"Detailed\":{\"Operations\":[{\"Id\":2,\"Name\":\"ResFill!\"}],\"OperationRoot\":2,\"Providers\":[],\"OperationStats\":{\"ResFill!\":1}},\"Basic\":{\"nodes\":[{\"id\":2,\"level\":1,\"name\":\"ResFill!\",\"type\":\"op\"}],\"links\":[]}}"
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765628833
  nanos: 339419000
}
tenant: "/default"
}
2025-12-12T12:27:13.646478Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=1/100 REFRESHED
2025-12-12T12:27:13.646502Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-12T12:27:13.646537Z :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-12T12:27:13.646551Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-12T12:27:13.646580Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.646634Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7582947011276428110:2238]
2025-12-12T12:27:13.646670Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-12T12:27:13.646704Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:13.646727Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7582947011276428111:2239]
2025-12-12T12:27:13.646757Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudq7uf7o5em7iasvn"
} [root@builtin, **** (B6C6F477)] 
2025-12-12T12:27:13.647307Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-12T12:27:13.654278Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudq7uf7o5em7iasvn" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765542432 nanos: 336334000 } modified_at { seconds: 1765542432 nanos: 336334000 } revision: 1 } started_at { seconds: 1765542433 nanos: 404000000 } execute_mode: RUN status: RUNNING last_job_query_revision: 1 last_job_id: "ptjudq7uf7o5e1hroror-ptqudq7uf7o5em7iasvn" started_by: "root@builtin" submitted_at { seconds: 1765542432 nanos: 336334000 } } content { type: ANALYTICS name: "simple3" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select unwrap(1/0);" syntax: YQL_V1 } } } SUCCESS
2025-12-12T12:27:13.655187Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=1/100 NOTIFY CHANGE
2025-12-12T12:27:13.656338Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:13.656787Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765542453 nanos: 650000000 } } SUCCESS
2025-12-12T12:27:13.656857Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::PingTaskResponse
2025-12-12T12:27:13.657176Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Ping response success: { expired_at { seconds: 1765542453 nanos: 650000000 } }
2025-12-12T12:27:13.657202Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward request Private::PingTask
2025-12-12T12:27:13.657227Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudq7uf7o5em7iasvn Forward ping response. Success: 1. Cookie: 0
2025-12-12T12:27:13.657674Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::PingTask with size: 1136 bytes
2025-12-12T12:27:13.657784Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
result_set_count: 1
result_set_meta {
  column {
    name: "column0"
    type {
      type_id: INT32
    }
  }
}
dq_graph: "\022\305\002\010\001\022\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\000\032\004\010\240\215\00628\n4\010\001\030\002 \001*\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\0002\024\n\022\t\001\000\000\000\000\000\000\000\021\000\000\000\000\001\000\000\000X\001R\000:s\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022E\010\001\022A\022(88a034dc314301cb1522a933e690a4c49a7591e8\032\023dq_vanilla_job.lite \002H\001RClocal/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiterZ)mock_cloud/my_folder/ptqudq7uf7o5em7iasvn`\200\200\200\200 \"\017\037\000\205\002\203\002\034column0/*\007column02pptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud:\025\n\020AnalyticsHopping\022\0011:\034\n\017_LiteralTimeout\022\t604800000:\032\n\r_TableTimeout\022\t604800000:\037\n\025_AllResultsBytesLimit\022\006100000:\032\n\022_RowsLimitPerWrite\022\0041000:\027\n\022EnableComputeActor\022\0011:\016\n\014WorkerFilter:\031\n\020ComputeActorType\022\005async:\031\n\016WatermarksMode\022\007disable:\037\n\027WatermarksGranularityMs\022\0041000:$\n\034WatermarksLateArrivalDelayMs\022\0045000:\027\n\022EnableChannelStats\022\0011:\020\n\013ExportStats\022\0011b\263\001\010\001\022\256\001\037\000\005\205\006\213\000\205\000\204\205\002\203\002\034column0\030Inputs(Parameters\034Program\013\213\000)\251\000?\002\014Arg\000\010)\211\002?\010\206?\006 Iterator\000\006\002?\024\005?\006)\211\n?\004\207?\004\203\005@\203\005@\203\004\203\004\030Unwrap\000)\211\006?\030\203\005@\203\002\203\002\030Invoke\000\003?$\006Div\003?&\004\003?(\000\007\002\003?\032\000\003?\034\000\003?\036\002\003? \020\036\004\000\001\001\006\001/"
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765628833
  nanos: 339419000
}
tenant: "/default"
}
2025-12-12T12:27:13.668389Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765542453 nanos: 662000000 } } SUCCESS
2025-12-12T12:27:13.668431Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::PingTaskResponse
2025-12-12T12:27:13.668865Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Ping response success: { expired_at { seconds: 1765542453 nanos: 662000000 } }
2025-12-12T12:27:13.668913Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudq7uf7o5em7iasvn Forward ping response. Success: 1. Cookie: 1
2025-12-12T12:27:13.668943Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudq7uf7o5em7iasvn Overall dq tasks: 1
2025-12-12T12:27:13.669048Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward ping request: { owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26" query_id { value: "ptqudq7uf7o5em7iasvn" } status: RUNNING result_set_count: 1 result_set_meta { column { name: "column0" type { type_id: INT32 } } } resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter" } scope: "yandexcloud://my_folder" }
2025-12-12T12:27:13.669052Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward request Private::PingTask
2025-12-12T12:27:13.669220Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.669 DEBUG ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] task_controller_impl.h:83: _AllResultsBytesLimit = 100000
2025-12-12T12:27:13.669250Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.669 DEBUG ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] task_controller_impl.h:86: _RowsLimitPerWrite = 1000
2025-12-12T12:27:13.669262Z :FQ_RESULT_WRITER INFO: result_writer.cpp:62: TraceId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud Bootstrap
2025-12-12T12:27:13.669313Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1652: QueryId: ptqudq7uf7o5em7iasvn Executer: [1:7582947011276428116:2244], Controller: [1:7582947011276428118:2246], ResultIdActor: [1:7582947011276428117:2245]
2025-12-12T12:27:13.669351Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.669 DEBUG ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] executer_actor.cpp:140: {ptqudq7uf7o5em7iasvn} TDqExecuter::OnGraph
2025-12-12T12:27:13.669377Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.669 INFO  ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] executer_actor.cpp:157: {ptqudq7uf7o5em7iasvn} Trying to allocate 1 workers [EnableComputeActor=1]
2025-12-12T12:27:13.669505Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.669 DEBUG ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] executer_actor.cpp:247: {ptqudq7uf7o5em7iasvn} Dq timeouts are set to: 0.000000s (global), 600.000000s (workers allocation fail), 30.000000s (workers allocation warn) 
2025-12-12T12:27:13.669574Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::PingTask with size: 212 bytes
2025-12-12T12:27:13.669648Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
status: RUNNING
result_set_count: 1
result_set_meta {
  column {
    name: "column0"
    type {
      type_id: INT32
    }
  }
}
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: NOT_NEEDED
  rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765628833
  nanos: 339419000
}
tenant: "/default"
}
2025-12-12T12:27:13.670373Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:125: TEvAllocateWorkersResponse Nodes {
  ResourceId: 4294967297
  Worker {
    NodeId: 1
    Guid: "9d7a09f6-488df656-dbee48aa-60b42c26,ghrun-fhkyrtz6p4"
  }
}
2025-12-12T12:27:13.670772Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.670 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:358: {ptqudq7uf7o5em7iasvn} TDqExecuter::TEvAllocateWorkersResponse
2025-12-12T12:27:13.670783Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.670 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:384: {ptqudq7uf7o5em7iasvn} Allocated resource 4294967297
2025-12-12T12:27:13.670814Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.670 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:401: {ptqudq7uf7o5em7iasvn} WorkerInfo: Id:9d7a09f6-488df656-dbee48aa-60b42c26,ghrun-fhkyrtz6p4,NodeId:1,StageId:1
2025-12-12T12:27:13.670821Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.670 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:402: {ptqudq7uf7o5em7iasvn} TaskInfo: 0/1
2025-12-12T12:27:13.670828Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.670 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:404: {ptqudq7uf7o5em7iasvn}  ObjectId: 88a034dc314301cb1522a933e690a4c49a7591e8, dq_vanilla_job.lite, 
2025-12-12T12:27:13.670841Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.670 INFO  ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:415: {ptqudq7uf7o5em7iasvn} 1 workers allocated
2025-12-12T12:27:13.670905Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.670 TRACE ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] task_controller_impl.h:271: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud}  [1:7582947011276428118:2246] ExportStats Summary
2025-12-12T12:27:13.670928Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.670 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] task_controller_impl.h:526: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Ready State: [1:7582947011276428118:2246]
2025-12-12T12:27:13.670943Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:144: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Start compute actor [1:7582947011276428123:2251], task: 1
2025-12-12T12:27:13.670963Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:114: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter", "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn" }
2025-12-12T12:27:13.674010Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.673 TRACE ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] task_controller_impl.h:160: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} [1:7582947011276428118:2246] EvState TaskId: 1 State: 2 PingCookie: 0 StatusCode: UNSPECIFIED
2025-12-12T12:27:13.674034Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.674 TRACE ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] task_controller_impl.h:201: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud}  [1:7582947011276428118:2246] Executing TaskId: 1
2025-12-12T12:27:13.674063Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.674 DEBUG ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] task_controller_impl.h:561: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} Update channels
2025-12-12T12:27:13.675553Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.674 TRACE ydbd(pid=1196852, tid=0x00007FDDE1A53640) [DQ] task_controller_impl.h:577: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} 1 Update { Id: 1 SrcTaskId: 2 DstTaskId: 1 SrcEndpoint { ActorId { RawX1: 0 RawX2: 0 } } DstEndpoint { ActorId { RawX1: 7582947011276428117 RawX2: 4503603922340037 } } SrcStageId: 1 }
2025-12-12T12:27:13.675983Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1064: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Received channels info: Update { Id: 1 SrcTaskId: 2 DstTaskId: 1 SrcEndpoint { ActorId { RawX1: 0 RawX2: 0 } } DstEndpoint { ActorId { RawX1: 7582947011276428117 RawX2: 4503603922340037 } } SrcStageId: 1 }
2025-12-12T12:27:13.675989Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:1083: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Update output channelId: 1, peer: [1:7582947011276428117:2245]
2025-12-12T12:27:13.675997Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1483: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Poll inputs
2025-12-12T12:27:13.675999Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1498: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Poll sources
2025-12-12T12:27:13.676004Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:698: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. DoExecuteImpl: 0
2025-12-12T12:27:13.676014Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1483: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Poll inputs
2025-12-12T12:27:13.676015Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1498: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Poll sources
2025-12-12T12:27:13.676016Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:698: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. DoExecuteImpl: 0
2025-12-12T12:27:13.676327Z :KQP_COMPUTE ERROR: dq_compute_actor_impl.h:1205: SelfId: [1:7582947011276428123:2251], TxId: ptqudq7uf7o5em7iasvn, task: 1. Ctx: {}. Handle abort execution event from: [1:7582947011276428125:2251], status: BAD_REQUEST, reason: { <main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional, code: 6000 }
2025-12-12T12:27:13.676368Z :KQP_COMPUTE DEBUG: dq_compute_actor_channels.cpp:494: TxId: ptqudq7uf7o5em7iasvn, task: 1. pass away
2025-12-12T12:27:13.676585Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.676 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] task_controller_impl.h:130: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} AbortExecution from [1:7582947011276428125:2251]:BAD_REQUEST { <main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional, code: 6000 }
2025-12-12T12:27:13.676602Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.676 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] task_controller_impl.h:598: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OnError { <main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional, code: 6000 } BAD_REQUEST
2025-12-12T12:27:13.676645Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.676 TRACE ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] task_controller_impl.h:160: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} [1:7582947011276428118:2246] EvState TaskId: 1 State: 1 PingCookie: 0 StatusCode: UNSPECIFIED
2025-12-12T12:27:13.676672Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.676 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] task_controller_impl.h:598: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OnError { <main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional  (appeared 1 time at 2025-12-12T12:27:13.676657Z) } UNSPECIFIED
2025-12-12T12:27:13.676686Z :YQL_PROXY WARN: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud 2025-12-12 12:27:13.676 WARN  ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] task_controller_impl.h:601: {ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud} OnError IGNORED when Finished
2025-12-12T12:27:13.676711Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.676 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:304: {ptqudq7uf7o5em7iasvn} OnFailure, status=6, issues size=1, sender=[1:7582947011276428118:2246]
2025-12-12T12:27:13.676723Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.676 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:315: {ptqudq7uf7o5em7iasvn} Issues: <main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional
, code: 6000

2025-12-12T12:27:13.676732Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.676 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:284: {ptqudq7uf7o5em7iasvn} Finish with status=6 issues=<main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional
, code: 6000

2025-12-12T12:27:13.676767Z :FQ_RESULT_WRITER DEBUG: result_writer.cpp:126: TraceId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud Send response to executer
2025-12-12T12:27:13.676785Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudq7uf7o5em7iasvn 2025-12-12 12:27:13.676 DEBUG ydbd(pid=1196852, tid=0x00007FDDDF24E640) [DQ] executer_actor.cpp:341: {ptqudq7uf7o5em7iasvn} OnQueryResponse status=6 issuses_size=1
2025-12-12T12:27:13.676814Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1233: QueryId: ptqudq7uf7o5em7iasvn Query response BAD_REQUEST. Result set index: 0. Issues count: 1. Rows count: 0
2025-12-12T12:27:13.676900Z :FQ_RUN_ACTOR WARN: run_actor.cpp:1914: QueryId: ptqudq7uf7o5em7iasvn ResignQuery, status BAD_REQUEST
2025-12-12T12:27:13.676905Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1919: QueryId: ptqudq7uf7o5em7iasvn SendPingAndPassAway, FinalQueryStatus COMPUTE_STATUS_UNSPECIFIED
2025-12-12T12:27:13.676922Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:536: QueryId: ptqudq7uf7o5em7iasvn PassAway
2025-12-12T12:27:13.677034Z :FQ_RESULT_WRITER INFO: result_writer.cpp:87: TraceId: ptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud FinishWrite, Records: 0 HasError: 0 Size: 0 Rows: 0 FreeSpace: 67108864 Duration: 0.007857s AvgSpeed: 0 ResultChunks.size(): 0
2025-12-12T12:27:13.677338Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward ping request: { owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26" query_id { value: "ptqudq7uf7o5em7iasvn" } result_id { value: "ptrudq7uf6niq3amo0iu" } status: RUNNING result_set_count: 1 statistics: "{\"Graph=0\":{\"Actor\":{\"ActorCreateTimeUs\":{\"sum\":\"293us\",\"count\":1,\"avg\":\"293us\",\"max\":\"293us\",\"min\":\"293us\"}},\"AllocateWorkersUs\":{\"sum\":\"1ms\",\"count\":1,\"avg\":\"1ms\",\"max\":\"1ms\",\"min\":\"1ms\"},\"ExecutionTimeUs\":{\"sum\":\"5ms\",\"count\":1,\"avg\":\"5ms\",\"max\":\"5ms\",\"min\":\"5ms\"},\"TaskRunner\":{\"Stage=1\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}},\"UniqueWorkers\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}}" result_set_meta { column { name: "column0" type { type_id: INT32 } } } resign_query: true transient_issues { message: "(NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional\n (appeared 1 time at 2025-12-12T12:27:13.676657Z)" severity: 1 } status_code: BAD_REQUEST resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter" } scope: "yandexcloud://my_folder" }
2025-12-12T12:27:13.682771Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765542453 nanos: 677000000 } } SUCCESS
2025-12-12T12:27:13.682875Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::PingTaskResponse
2025-12-12T12:27:13.683601Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Ping response success: { expired_at { seconds: 1765542453 nanos: 677000000 } }
2025-12-12T12:27:13.683620Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Forward request Private::PingTask
2025-12-12T12:27:13.684220Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Request CP::PingTask with size: 1057 bytes
2025-12-12T12:27:13.684328Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a26"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
result_id {
  value: "ptrudq7uf6niq3amo0iu"
}
status: RUNNING
result_set_count: 1
statistics: "{\"Graph=0\":{\"Actor\":{\"ActorCreateTimeUs\":{\"sum\":\"293us\",\"count\":1,\"avg\":\"293us\",\"max\":\"293us\",\"min\":\"293us\"}},\"AllocateWorkersUs\":{\"sum\":\"1ms\",\"count\":1,\"avg\":\"1ms\",\"max\":\"1ms\",\"min\":\"1ms\"},\"ExecutionTimeUs\":{\"sum\":\"5ms\",\"count\":1,\"avg\":\"5ms\",\"max\":\"5ms\",\"min\":\"5ms\"},\"TaskRunner\":{\"Stage=1\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}},\"Stage=Total\":{\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"},\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}},\"UniqueWorkers\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1}}}"
result_set_meta {
  column {
    name: "column0"
    type {
      type_id: INT32
    }
  }
}
resign_query: true
transient_issues {
  message: "(NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional\n (appeared 1 time at 2025-12-12T12:27:13.676657Z)"
  severity: 1
}
status_code: BAD_REQUEST
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: NOT_NEEDED
  rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1765628833
  nanos: 339419000
}
tenant: "/default"
}
2025-12-12T12:27:13.691281Z :YQ_CONTROL_PLANE_STORAGE DEBUG: task_ping.cpp:427: PingTaskRequest (resign):  FAILURE BAD_REQUEST 0 2025-12-12T12:27:12.417314Z 0.000000s
2025-12-12T12:27:13.769529Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1765542433 nanos: 691000000 } } SUCCESS
2025-12-12T12:27:13.769564Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26, Got CP::PingTaskResponse
2025-12-12T12:27:13.769931Z :FQ_PINGER ERROR: pinger.cpp:365: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Ping response error: Query ownership time is over: expired_at=2025-12-12T12:27:13.691000Z < now=2025-12-12T12:27:13.769614Z. Retried 0 times during 0.087058s
2025-12-12T12:27:13.769959Z :FQ_PINGER DEBUG: pinger.cpp:379: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Query finished
2025-12-12T12:27:13.769963Z :FQ_PINGER TRACE: pinger.cpp:187: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a26 Stop Pinger
2025-12-12T12:27:14.157034Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.157081Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7582947015571395427:2256]
2025-12-12T12:27:14.157100Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-12T12:27:14.157125Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7582947015571395428:2257]
2025-12-12T12:27:14.157153Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:14.157166Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-12T12:27:14.157178Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:14.157194Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-12T12:27:14.161049Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=1/100 REFRESHED
2025-12-12T12:27:14.161094Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-12T12:27:14.161126Z :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-12T12:27:14.161143Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-12T12:27:14.161169Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.161236Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7582947015571395433:2262]
2025-12-12T12:27:14.161275Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-12T12:27:14.161297Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.161324Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7582947015571395434:2263]
2025-12-12T12:27:14.161387Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudq7uf7o5em7iasvn"
} [root@builtin, **** (B6C6F477)] 
2025-12-12T12:27:14.163154Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-12T12:27:14.164098Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudq7uf7o5em7iasvn" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765542432 nanos: 336334000 } modified_at { seconds: 1765542432 nanos: 336334000 } revision: 1 } started_at { seconds: 1765542433 nanos: 404000000 } execute_mode: RUN status: FAILING last_job_query_revision: 1 last_job_id: "ptjudq7uf7o5e1hroror-ptqudq7uf7o5em7iasvn" started_by: "root@builtin" submitted_at { seconds: 1765542432 nanos: 336334000 } } content { type: ANALYTICS name: "simple3" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select unwrap(1/0);" syntax: YQL_V1 } plan { json: "{\"Detailed\":{\"Operations\":[{\"Id\":2,\"Name\":\"ResFill!\"}],\"OperationRoot\":2,\"Providers\":[],\"OperationStats\":{\"ResFill!\":1}},\"Basic\":{\"nodes\":[{\"id\":2,\"level\":1,\"name\":\"ResFill!\",\"type\":\"op\"}],\"links\":[]}}" } issue { message: "Query failed with code BAD_REQUEST at 2025-12-12T12:27:13.691250Z" severity: 1 issues { message: "(NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional\n (appeared 1 time at 2025-12-12T12:27:13.676657Z)" severity: 1 } } statistics { json: "{\"Graph=0\":{\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"}}}" } result_set_meta { column { name: "column0" type { type_id: INT32 } } } ast { data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"column0\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"column0\" (Unwrap (/ (Int32 \'\"1\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" } } } SUCCESS
2025-12-12T12:27:14.166394Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:14.166436Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=1/100 NOTIFY CHANGE
2025-12-12T12:27:14.280643Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:627: RowDispatcher: Send ping to [1:7582946989801591450:2081]
2025-12-12T12:27:14.280656Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:14.280661Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1174: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-12-12T12:27:14.280708Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:356: Coordinator: TEvPing received, [1:7582946989801591434:2069]
2025-12-12T12:27:14.280715Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:299: Coordinator: UpdateKnownRowDispatchers [1:7582946989801591434:2069]
2025-12-12T12:27:14.280721Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:359: Coordinator: Send TEvPong to [1:7582946989801591434:2069]
2025-12-12T12:27:14.280766Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:633: RowDispatcher: NActors::TEvents::TEvPong
2025-12-12T12:27:14.281260Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:14.281339Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a28"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:14.284470Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:456: Task (Query): ptqudq7uf7o5em7iasvn Lease TIMEOUT, RetryCounterUpdatedAt 2025-12-12T12:27:12.417314Z LastSeenAt: 2025-12-12T12:27:13.691506Z
2025-12-12T12:27:14.284490Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudq7uf7o5em7iasvn RetryRate: 1.999705048 RetryCounter: 1 At: 2025-12-12T12:27:14.284429Z
2025-12-12T12:27:14.295621Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudq7uf7o5em7iasvn" } job_id { value: "ptjudq7uf7o5e1hroror" } generation: 2 dq_graph: "\022\305\002\010\001\022\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\000\032\004\010\240\215\00628\n4\010\001\030\002 \001*\024\n\022\t\000\000\000\000\000\000\000\000\021\000\000\000\000\000\000\000\0002\024\n\022\t\001\000\000\000\000\000\000\000\021\000\000\000\000\001\000\000\000X\001R\000:s\n*type.googleapis.com/Yql.DqsProto.TTaskMeta\022E\010\001\022A\022(88a034dc314301cb1522a933e690a4c49a7591e8\032\023dq_vanilla_job.lite \002H\001RClocal/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiterZ)mock_cloud/my_folder/ptqudq7uf7o5em7iasvn`\200\200\200\200 \"\017\037\000\205\002\203\002\034column0/*\007column02pptqudq7uf7o5em7iasvn#ptrudq7uf6niq3amo0iu#yandexcloud://my_folder#3e1c996e-dd395fd9-1fe03b2-3ba4d8a26#mock_cloud:\025\n\020AnalyticsHopping\022\0011:\034\n\017_LiteralTimeout\022\t604800000:\032\n\r_TableTimeout\022\t604800000:\037\n\025_AllResultsBytesLimit\022\006100000:\032\n\022_RowsLimitPerWrite\022\0041000:\027\n\022EnableComputeActor\022\0011:\016\n\014WorkerFilter:\031\n\020ComputeActorType\022\005async:\031\n\016WatermarksMode\022\007disable:\037\n\027WatermarksGranularityMs\022\0041000:$\n\034WatermarksLateArrivalDelayMs\022\0045000:\027\n\022EnableChannelStats\022\0011:\020\n\013ExportStats\022\0011b\263\001\010\001\022\256\001\037\000\005\205\006\213\000\205\000\204\205\002\203\002\034column0\030Inputs(Parameters\034Program\013\213\000)\251\000?\002\014Arg\000\010)\211\002?\010\206?\006 Iterator\000\006\002?\024\005?\006)\211\n?\004\207?\004\203\005@\203\005@\203\004\203\004\030Unwrap\000)\211\006?\030\203\005@\203\002\203\002\030Invoke\000\003?$\006Div\003?&\004\003?(\000\007\002\003?\032\000\003?\034\000\003?\036\002\003? \020\036\004\000\001\001\006\001/" text: "select unwrap(1/0);" user_token: "root@builtin" user_id: "root@builtin" query_type: ANALYTICS scope: "yandexcloud://my_folder" execute_mode: RUN state_load_mode: EMPTY status: FAILING result_set_meta { column { name: "column0" type { type_id: INT32 } } } sensor_labels { key: "cloud_id" value: "mock_cloud" } sensor_labels { key: "scope" value: "yandexcloud://my_folder" } query_name: "simple3" deadline { seconds: 1765628834 nanos: 287789000 } disposition { } result_limit: 20971520 execution_limit { seconds: 86400 } request_started_at { seconds: 1765542433 nanos: 404000000 } restart_count: 1 resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter" } query_syntax: YQL_V1 compute_connection { } result_ttl { seconds: 86400 } request_submitted_at { seconds: 1765542432 nanos: 336334000 } } } SUCCESS
2025-12-12T12:27:14.295743Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:14.296198Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:14.296211Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-12-12T12:27:14.296224Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudq7uf7o5em7iasvn UserId: root@builtin AuthToken: **** (B6C6F477)
2025-12-12T12:27:14.296358Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a29, Host: ghrun-fhkyrtz6p4, Tenant: /default
2025-12-12T12:27:14.296382Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudq7uf7o5em7iasvn Start run actor. Compute state: FAILING
2025-12-12T12:27:14.296388Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudq7uf7o5em7iasvn FillConnections
2025-12-12T12:27:14.296406Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudq7uf7o5em7iasvn Run actors params: { QueryId: ptqudq7uf7o5em7iasvn CloudId: mock_cloud UserId: root@builtin Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28 PreviousQueryRevision: 2 Connections: 0 Bindings: 0 AccountIdSignatures: 0 QueryType: ANALYTICS ExecuteMode: RUN ResultId: ptrudq7uf5sbhnn235s4 StateLoadMode: EMPTY StreamingDisposition: {  } Status: FAILING DqGraphs: 1 DqGraphIndex: 0 Resource.TopicConsumers: 0 }
2025-12-12T12:27:14.296448Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1827: QueryId: ptqudq7uf7o5em7iasvn Is about to finish query with status FAILED
2025-12-12T12:27:14.296453Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1507: QueryId: ptqudq7uf7o5em7iasvn Start rate limiter resource deleter
2025-12-12T12:27:14.296466Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28 Start Pinger
2025-12-12T12:27:14.296648Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-12-12T12:27:14.296836Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a29, Host: ghrun-fhkyrtz6p4, Tenant: /default, Request CP::GetTask with size: 67 bytes
2025-12-12T12:27:14.296874Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:175: PrivateDeleteRateLimiterResource - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28, Request CP::DeleteRateLimiterResource with size: 96 bytes
2025-12-12T12:27:14.296880Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a29"
host: "ghrun-fhkyrtz6p4"
node_id: 1
}
2025-12-12T12:27:14.296975Z :YQ_CONTROL_PLANE_STORAGE TRACE: request_actor.h:57: DeleteRateLimiterResourceRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a28"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
scope: "yandexcloud://my_folder"
tenant: "/default"
} 
2025-12-12T12:27:14.299725Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-12-12T12:27:14.299798Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a29, Host: ghrun-fhkyrtz6p4, Tenant: /default, Got CP::GetTask Response
2025-12-12T12:27:14.300113Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-12-12T12:27:14.300123Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-12-12T12:27:14.337671Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:74: DeleteRateLimiterResourceRequest. Got response from database: SUCCESS
2025-12-12T12:27:14.337787Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run delete rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn" actor
2025-12-12T12:27:14.337799Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call delete rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.340105Z :YQ_RATE_LIMITER DEBUG: schema.cpp:179: Successfully deleted rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.340121Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for delete rate limiter resource "mock_cloud/my_folder/ptqudq7uf7o5em7iasvn": 
2025-12-12T12:27:14.340150Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:219: Got delete response from rate limiter service
2025-12-12T12:27:14.340174Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:199: PrivateDeleteRateLimiterResource - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28, Got CP::DeleteRateLimiterResourceResponse
2025-12-12T12:27:14.340466Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1498: QueryId: ptqudq7uf7o5em7iasvn Rate limiter resource deletion finished. Success: 1. Issues: 
2025-12-12T12:27:14.340478Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1919: QueryId: ptqudq7uf7o5em7iasvn SendPingAndPassAway, FinalQueryStatus FAILED
2025-12-12T12:27:14.340494Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:536: QueryId: ptqudq7uf7o5em7iasvn PassAway
2025-12-12T12:27:14.340568Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28 Forward ping request: { result_id { } status: FAILED resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter" } finished_at { seconds: 1765542434 nanos: 296000000 } }
2025-12-12T12:27:14.340587Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28 Forward request Private::PingTask
2025-12-12T12:27:14.341015Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28, Request CP::PingTask with size: 210 bytes
2025-12-12T12:27:14.341095Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "3e1c996e-dd395fd9-1fe03b2-3ba4d8a28"
query_id {
  value: "ptqudq7uf7o5em7iasvn"
}
result_id {
}
status: FAILED
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: NOT_NEEDED
  rate_limiter_path: "local/RateLimiter_49120ace-5dfc-44ff-836c-4843ea528081/rate_limiter"
}
scope: "yandexcloud://my_folder"
finished_at {
  seconds: 1765542434
  nanos: 296000000
}
deadline {
  seconds: 1765628834
  nanos: 287789000
}
tenant: "/default"
}
2025-12-12T12:27:14.425600Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {} SUCCESS
2025-12-12T12:27:14.425655Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28, Got CP::PingTaskResponse
2025-12-12T12:27:14.426067Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28 Ping response success: {  }
2025-12-12T12:27:14.426097Z :FQ_PINGER DEBUG: pinger.cpp:379: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28 Query finished
2025-12-12T12:27:14.426100Z :FQ_PINGER TRACE: pinger.cpp:187: QueryId: ptqudq7uf7o5em7iasvn, Owner: 3e1c996e-dd395fd9-1fe03b2-3ba4d8a28 Stop Pinger
2025-12-12T12:27:14.667112Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.667164Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7582947015571395457:2285]
2025-12-12T12:27:14.667184Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-12T12:27:14.667204Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7582947015571395458:2286]
2025-12-12T12:27:14.667242Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:14.667258Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-12T12:27:14.667268Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:14.667310Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-12T12:27:14.669441Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-12-12T12:27:14.669475Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-12T12:27:14.669496Z :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-12T12:27:14.669514Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-12T12:27:14.669541Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.669607Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7582947015571395463:2291]
2025-12-12T12:27:14.669643Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-12T12:27:14.669675Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.669720Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7582947015571395464:2292]
2025-12-12T12:27:14.669784Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudq7uf7o5em7iasvn"
} [root@builtin, **** (B6C6F477)] 
2025-12-12T12:27:14.671955Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudq7uf7o5em7iasvn" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765542432 nanos: 336334000 } modified_at { seconds: 1765542432 nanos: 336334000 } revision: 1 } started_at { seconds: 1765542433 nanos: 404000000 } finished_at { seconds: 1765542434 nanos: 296000000 } execute_mode: RUN status: FAILED last_job_query_revision: 1 last_job_id: "ptjudq7uf7o5e1hroror-ptqudq7uf7o5em7iasvn" started_by: "root@builtin" submitted_at { seconds: 1765542432 nanos: 336334000 } } content { type: ANALYTICS name: "simple3" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select unwrap(1/0);" syntax: YQL_V1 } plan { json: "{\"Detailed\":{\"Operations\":[{\"Id\":2,\"Name\":\"ResFill!\"}],\"OperationRoot\":2,\"Providers\":[],\"OperationStats\":{\"ResFill!\":1}},\"Basic\":{\"nodes\":[{\"id\":2,\"level\":1,\"name\":\"ResFill!\",\"type\":\"op\"}],\"links\":[]}}" } issue { message: "Query failed with code BAD_REQUEST at 2025-12-12T12:27:13.691250Z" severity: 1 issues { message: "(NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional\n (appeared 1 time at 2025-12-12T12:27:13.676657Z)" severity: 1 } } statistics { json: "{\"Graph=0\":{\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"}}}" } result_set_meta { column { name: "column0" type { type_id: INT32 } } } ast { data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"column0\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"column0\" (Unwrap (/ (Int32 \'\"1\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" } } } SUCCESS
2025-12-12T12:27:14.673037Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-12T12:27:14.674725Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.674769Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:305: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7582947015571395468:2295]
2025-12-12T12:27:14.674784Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:367: Cloud id: mock_cloud Folder id: my_folder
2025-12-12T12:27:14.674795Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:91: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7582947015571395469:2296]
2025-12-12T12:27:14.674809Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:14.674816Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-12-12T12:27:14.674820Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-12-12T12:27:14.674853Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-12-12T12:27:14.675357Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:14.675425Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:14.676627Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-12-12T12:27:14.676649Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:707: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-12-12T12:27:14.676672Z :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-12T12:27:14.676686Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:103: Cloud id: mock_cloud Quota count: 8
2025-12-12T12:27:14.676708Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.676766Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:169: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7582947015571395474:2301]
2025-12-12T12:27:14.676797Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:230: Subject Type: federated_account Token: **** (B6C6F477)
2025-12-12T12:27:14.676811Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:784: DescribeQueryRequest: query_id: "ptqudq7uf7o5em7iasvn"
2025-12-12T12:27:14.676838Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:63: Request actor. Actor id: [1:7582947015571395475:2302]
2025-12-12T12:27:14.676880Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudq7uf7o5em7iasvn"
} [root@builtin, **** (B6C6F477)] 
2025-12-12T12:27:14.678832Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudq7uf7o5em7iasvn" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1765542432 nanos: 336334000 } modified_at { seconds: 1765542432 nanos: 336334000 } revision: 1 } started_at { seconds: 1765542433 nanos: 404000000 } finished_at { seconds: 1765542434 nanos: 296000000 } execute_mode: RUN status: FAILED last_job_query_revision: 1 last_job_id: "ptjudq7uf7o5e1hroror-ptqudq7uf7o5em7iasvn" started_by: "root@builtin" submitted_at { seconds: 1765542432 nanos: 336334000 } } content { type: ANALYTICS name: "simple3" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "select unwrap(1/0);" syntax: YQL_V1 } plan { json: "{\"Detailed\":{\"Operations\":[{\"Id\":2,\"Name\":\"ResFill!\"}],\"OperationRoot\":2,\"Providers\":[],\"OperationStats\":{\"ResFill!\":1}},\"Basic\":{\"nodes\":[{\"id\":2,\"level\":1,\"name\":\"ResFill!\",\"type\":\"op\"}],\"links\":[]}}" } issue { message: "Query failed with code BAD_REQUEST at 2025-12-12T12:27:13.691250Z" severity: 1 issues { message: "(NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional\n (appeared 1 time at 2025-12-12T12:27:13.676657Z)" severity: 1 } } statistics { json: "{\"Graph=0\":{\"Tasks\":{\"sum\":1,\"count\":1,\"avg\":1,\"max\":1,\"min\":1},\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":1,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"}}}" } result_set_meta { column { name: "column0" type { type_id: INT32 } } } ast { data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"column0\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"column0\" (Unwrap (/ (Int32 \'\"1\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" } } } SUCCESS
2025-12-12T12:27:14.680163Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-12-12T12:27:14.682153Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
2025-12-12T12:27:14.682210Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
