2025-11-11T14:15:57.542084Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-11-11T14:15:57.543462Z :GRPC_SERVER NOTICE: grpc_request_proxy_simple.cpp:161: Grpc simple request proxy started, nodeid# 1
2025-11-11T14:15:57.547316Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-11-11T14:15:57.547328Z :FQ_LOG_UPDATER CRIT: log.cpp:55: Failed to deliver subscription request to config dispatcher.
2025-11-11T14:15:57.547354Z :YQ_CONTROL_PLANE_STORAGE INFO: ydb_control_plane_storage.cpp:50: Starting ydb control plane storage service. Actor id: [1:7571471392574641145:2062]
2025-11-11T14:15:57.549912Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:58: STARTING: [1:7571471392574641146:2063]
2025-11-11T14:15:57.550394Z :YQ_CONTROL_PLANE_PROXY DEBUG: control_plane_proxy.cpp:532: Starting yandex query control plane proxy. Actor id: [1:7571471392574641147:2064]
2025-11-11T14:15:57.552001Z :YQ_RATE_LIMITER INFO: quoter_service.cpp:124: Bootstraping with config: { Enabled: true ControlPlaneEnabled: true DataPlaneEnabled: true Database { Endpoint: "localhost:27593" Database: "local" TablePrefix: "RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c" } Limiters { CoordinationNodePath: "rate_limiter" } }
2025-11-11T14:15:57.552116Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:188: Bootstrap
2025-11-11T14:15:57.552117Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:191: Create sdk ydb connection
2025-11-11T14:15:57.552142Z :STREAMS_STORAGE_SERVICE TRACE: storage_proxy.cpp:213: Initialize
2025-11-11T14:15:57.552250Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:209: Successfully bootstrapped TStorageProxy [1:7571471392574641151:2068] with connection to localhost:27593:local
2025-11-11T14:15:57.552256Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:534: RowDispatcher: Successfully bootstrapped row dispatcher, id [1:7571471392574641152:2069], tenant /default
2025-11-11T14:15:57.552397Z :YQL_NODES_MANAGER INFO: nodes_manager.cpp:88: Bootstrap, InstanceId: 56a102af-49e6240a-ed1c51a5-969b4664
2025-11-11T14:15:57.552398Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:310: TNodesManagerActor::ResolveSelfAddress
2025-11-11T14:15:57.552414Z :YQ_TEST_CONNECTION DEBUG: test_connection.cpp:142: Starting yandex query test connection. Actor id: [1:7571471392574641157:2074]
2025-11-11T14:15:57.552453Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe1, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:15:57.553260Z :FQ_QUOTA_SERVICE INFO: quota_manager.cpp:196: STARTED
2025-11-11T14:15:57.553264Z :FQ_QUOTA_PROXY INFO: quota_proxy.cpp:90: STARTED
2025-11-11T14:15:57.553490Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create coordination node "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter" actor
2025-11-11T14:15:57.553492Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create coordination node "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter"
2025-11-11T14:15:57.553494Z :YQ_RATE_LIMITER DEBUG: schema.cpp:411: Call create coordination node "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter"
2025-11-11T14:15:57.553524Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:267: Coordinator: Successfully bootstrapped coordinator, id [1:7571471392574641187:2087], NodesManagerId [0:4705493345362661702:78]
2025-11-11T14:15:57.553533Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:232: TLeaderElection [1:7571471392574641188:2088] Successfully bootstrapped, local coordinator id [1:7571471392574641187:2087], tenant id default, local mode 0, coordination node path local/row_dispatcher/default, endpoint localhost:27593
2025-11-11T14:15:57.554081Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-11-11T14:15:57.556945Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create directory "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c" actor
2025-11-11T14:15:57.556948Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create directory "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c"
2025-11-11T14:15:57.556953Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:383: Call create directory "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c"
2025-11-11T14:15:57.557536Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:627: RowDispatcher: TEvCoordinatorChangesSubscribe from [1:7571471392574641187:2087]
2025-11-11T14:15:57.557556Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/queries" actor
2025-11-11T14:15:57.557573Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/pending_small" actor
2025-11-11T14:15:57.561548Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/connections" actor
2025-11-11T14:15:57.561563Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/bindings" actor
2025-11-11T14:15:57.561569Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/idempotency_keys" actor
2025-11-11T14:15:57.561576Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/result_sets" actor
2025-11-11T14:15:57.561583Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/jobs" actor
2025-11-11T14:15:57.561589Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/nodes" actor
2025-11-11T14:15:57.561594Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/quotas" actor
2025-11-11T14:15:57.561657Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenants" actor
2025-11-11T14:15:57.562503Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenant_acks" actor
2025-11-11T14:15:57.562517Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/mappings" actor
2025-11-11T14:15:57.562523Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:71: Run create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/compute_databases" actor
2025-11-11T14:15:57.569305Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:15:57.570212Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:71: Run create coordination node "local/row_dispatcher/default" actor
2025-11-11T14:15:57.570231Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:113: Call create coordination node "local/row_dispatcher/default"
2025-11-11T14:15:57.570233Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:411: Call create coordination node "local/row_dispatcher/default"
2025-11-11T14:15:57.577760Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:15:57.577781Z :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:11885: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:11885
2025-11-11T14:15:57.578447Z :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:11885: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:11885
2025-11-11T14:15:57.648478Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/jobs". Create session OK
2025-11-11T14:15:57.648493Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/jobs"
2025-11-11T14:15:57.648498Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/jobs"
2025-11-11T14:15:57.648809Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenants". Create session OK
2025-11-11T14:15:57.648817Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenants"
2025-11-11T14:15:57.648819Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenants"
2025-11-11T14:15:57.649211Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/nodes". Create session OK
2025-11-11T14:15:57.649226Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/nodes"
2025-11-11T14:15:57.649228Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/nodes"
2025-11-11T14:15:57.649383Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/connections". Create session OK
2025-11-11T14:15:57.649392Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/connections"
2025-11-11T14:15:57.649394Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/connections"
2025-11-11T14:15:57.650295Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/compute_databases". Create session OK
2025-11-11T14:15:57.650305Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/compute_databases"
2025-11-11T14:15:57.650308Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/compute_databases"
2025-11-11T14:15:57.650440Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/quotas". Create session OK
2025-11-11T14:15:57.650441Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/quotas"
2025-11-11T14:15:57.650443Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/quotas"
2025-11-11T14:15:57.650512Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/mappings". Create session OK
2025-11-11T14:15:57.650513Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/mappings"
2025-11-11T14:15:57.650852Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/bindings". Create session OK
2025-11-11T14:15:57.650857Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/bindings"
2025-11-11T14:15:57.650859Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/bindings"
2025-11-11T14:15:57.650959Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/queries". Create session OK
2025-11-11T14:15:57.650960Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/queries"
2025-11-11T14:15:57.650961Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/queries"
2025-11-11T14:15:57.651054Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/result_sets". Create session OK
2025-11-11T14:15:57.651055Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/result_sets"
2025-11-11T14:15:57.651056Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/result_sets"
2025-11-11T14:15:57.651232Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenant_acks". Create session OK
2025-11-11T14:15:57.651995Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created directory "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c"
2025-11-11T14:15:57.652008Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create directory "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c": { <main>: Error: Check failed: path: '/local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c', 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-11-11T14:15:57.652014Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/pending_small". Create session OK
2025-11-11T14:15:57.652016Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/pending_small"
2025-11-11T14:15:57.652017Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/pending_small"
2025-11-11T14:15:57.652119Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:293: Create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/idempotency_keys". Create session OK
2025-11-11T14:15:57.652120Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/idempotency_keys"
2025-11-11T14:15:57.657538Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:113: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenant_acks"
2025-11-11T14:15:57.657554Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenant_acks"
2025-11-11T14:15:57.657626Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/idempotency_keys"
2025-11-11T14:15:57.657790Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:354: Call create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/mappings"
2025-11-11T14:15:57.711509Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/mappings"
2025-11-11T14:15:57.711528Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/mappings": { <main>: Error: Check failed: path: '/local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/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-11-11T14:15:57.711695Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenants"
2025-11-11T14:15:57.711699Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenants": { <main>: Error: Check failed: path: '/local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/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-11-11T14:15:57.758056Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created coordination node "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter"
2025-11-11T14:15:57.758066Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create coordination node "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter": 
2025-11-11T14:15:57.765680Z :FQ_CONTROL_PLANE_CONFIG DEBUG: control_plane_config.cpp:190: LOADED TenantInfo: State CHANGED at 2025-11-11T14:15:54.983835Z
2025-11-11T14:15:57.781896Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/connections"
2025-11-11T14:15:57.781907Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/connections": 
2025-11-11T14:15:57.782623Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/compute_databases"
2025-11-11T14:15:57.782627Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/compute_databases": 
2025-11-11T14:15:57.782748Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/result_sets"
2025-11-11T14:15:57.782750Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/result_sets": 
2025-11-11T14:15:57.782843Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/quotas"
2025-11-11T14:15:57.782845Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/quotas": 
2025-11-11T14:15:57.782923Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/idempotency_keys"
2025-11-11T14:15:57.782925Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/idempotency_keys": 
2025-11-11T14:15:57.794347Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/queries"
2025-11-11T14:15:57.794358Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/queries": 
2025-11-11T14:15:57.794542Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/bindings"
2025-11-11T14:15:57.794544Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/bindings": 
2025-11-11T14:15:57.794637Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/pending_small"
2025-11-11T14:15:57.794639Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/pending_small": 
2025-11-11T14:15:57.794710Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/nodes"
2025-11-11T14:15:57.794712Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/nodes": 
2025-11-11T14:15:57.794779Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/jobs"
2025-11-11T14:15:57.794781Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/jobs": 
2025-11-11T14:15:57.800157Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:155: Successfully created coordination node "local/row_dispatcher/default"
2025-11-11T14:15:57.800167Z :FQ_ROW_DISPATCHER DEBUG: schema.cpp:122: Reply for create coordination node "local/row_dispatcher/default": 
2025-11-11T14:15:57.800205Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:345: TLeaderElection [1:7571471392574641188:2088] Coordination node successfully created
2025-11-11T14:15:57.800207Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:323: TLeaderElection [1:7571471392574641188:2088] Start session
2025-11-11T14:15:57.805444Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:155: Successfully created table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenant_acks"
2025-11-11T14:15:57.805456Z :YQ_CONTROL_PLANE_STORAGE DEBUG: schema.cpp:122: Reply for create table "local/DbPoolStorage_a4bbd6e0-96c0-4657-af54-e012c7717c8c/tenant_acks": 
2025-11-11T14:15:57.822132Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:359: TLeaderElection [1:7571471392574641188:2088] Session successfully created
2025-11-11T14:15:57.850429Z :STREAMS_STORAGE_SERVICE INFO: storage_proxy.cpp:499: Checkpoint storage and state storage were successfully inited
2025-11-11T14:15:57.857510Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:372: TLeaderElection [1:7571471392574641188:2088] Semaphore successfully created
2025-11-11T14:15:57.857517Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:304: TLeaderElection [1:7571471392574641188:2088] Try to acquire semaphore
2025-11-11T14:15:57.857555Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:420: TLeaderElection [1:7571471392574641188:2088] Describe semaphore
2025-11-11T14:15:57.859573Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:386: TLeaderElection [1:7571471392574641188:2088] Semaphore successfully acquired
2025-11-11T14:15:57.859589Z :FQ_ROW_DISPATCHER DEBUG: leader_election.cpp:469: TLeaderElection [1:7571471392574641188:2088] Semaphore successfully described: coordinator id [1:7571471392574641187:2087] generation 1
2025-11-11T14:15:57.859592Z :FQ_ROW_DISPATCHER INFO: leader_election.cpp:471: TLeaderElection [1:7571471392574641188:2088] Send TEvCoordinatorChanged to [1:7571471392574641152:2069], new coordinator id [1:7571471392574641187:2087], previous coordinator id [0:0:0]
2025-11-11T14:15:57.859611Z :FQ_ROW_DISPATCHER DEBUG: row_dispatcher.cpp:559: RowDispatcher: Coordinator changed, old leader (empty maybe), new [1:7571471392574641187:2087] generation 1
2025-11-11T14:15:57.859626Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471392574641152:2069]
2025-11-11T14:15:57.859629Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471392574641152:2069]
2025-11-11T14:15:57.859644Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:15:57.859653Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:386: Coordinator: New leader [1:7571471392574641187:2087], SelfId [1:7571471392574641187:2087]
2025-11-11T14:15:58.532913Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe2, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:15:58.532935Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:15:58.533729Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:15:58.533759Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-11-11T14:15:58.603378Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe2, Host: ghrun-vb3yoermsa, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-11-11T14:15:58.604281Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe2"
host: "ghrun-vb3yoermsa"
node_id: 1
}
2025-11-11T14:15:58.659270Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-11-11T14:15:58.659316Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe2, Host: ghrun-vb3yoermsa, Tenant: /default, Got CP::GetTask Response
2025-11-11T14:15:58.659782Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:15:58.659790Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:15:59.534856Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471392574641187:2087]
2025-11-11T14:15:59.534873Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:15:59.534888Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe3, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:15:59.534903Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471392574641152:2069]
2025-11-11T14:15:59.534907Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471392574641152:2069]
2025-11-11T14:15:59.534964Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:15:59.536134Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe3, Host: ghrun-vb3yoermsa, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-11-11T14:15:59.536178Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe3"
host: "ghrun-vb3yoermsa"
node_id: 1
}
2025-11-11T14:15:59.536486Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:15:59.536503Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:15:59.536508Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-11-11T14:15:59.542028Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-11-11T14:15:59.542071Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe3, Host: ghrun-vb3yoermsa, Tenant: /default, Got CP::GetTask Response
2025-11-11T14:15:59.542945Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:15:59.542954Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:15:59.782959Z :TICKET_PARSER DEBUG: ticket_parser_impl.h:1828: Ticket **** (B6C6F477) (ipv6:[::1]:47018) has now valid token of root@builtin
2025-11-11T14:15:59.783402Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:655: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "SELECT unwrap(42 / 0) AS error_column"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "90e4af93-8d11-4c0a-bd09-9eb9a7e5788d"
2025-11-11T14:15:59.783563Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:313: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7571471401164575861:2162]
2025-11-11T14:15:59.783661Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:375: Cloud id: mock_cloud Folder id: my_folder
2025-11-11T14:15:59.783681Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:92: Get quotas bootstrap. Cloud id: mock_cloud Actor id: [1:7571471401164575862:2163]
2025-11-11T14:15:59.783703Z :FQ_QUOTA_SERVICE DEBUG: quota_manager.cpp:255: cloud.mock_cloud NOT CASHED, Loading ...
2025-11-11T14:15:59.814849Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:441: cloud.mock_cloud {} FROM DB
2025-11-11T14:15:59.814875Z :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-11-11T14:15:59.814880Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.analyticsQuery.count IS STALE, Refreshing ...
2025-11-11T14:15:59.814890Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.cpuPercent.count IS STALE, Refreshing ...
2025-11-11T14:15:59.814893Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:291: cloud.mock_cloud.yq.streamingQuery.count IS STALE, Refreshing ...
2025-11-11T14:15:59.814921Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:703: cloud.mock_cloud.yq.cpuPercent.count=0/10000 REFRESHED
2025-11-11T14:15:59.871134Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.cpuPercent.count=0/10000 NOTIFY CHANGE
2025-11-11T14:15:59.887629Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:703: cloud.mock_cloud.yq.analyticsQuery.count=0/100 REFRESHED
2025-11-11T14:15:59.887669Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:703: cloud.mock_cloud.yq.streamingQuery.count=0/100 REFRESHED
2025-11-11T14:15:59.887690Z :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-11-11T14:15:59.887806Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:108: Cloud id: mock_cloud Quota count: 8
2025-11-11T14:15:59.887860Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:655: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "SELECT unwrap(42 / 0) AS error_column"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "90e4af93-8d11-4c0a-bd09-9eb9a7e5788d"
2025-11-11T14:15:59.888396Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:176: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7571471401164575868:2169]
2025-11-11T14:15:59.888445Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:237: Subject Type: federated_account Token: **** (B6C6F477)
2025-11-11T14:15:59.888467Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:655: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "SELECT unwrap(42 / 0) AS error_column"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "90e4af93-8d11-4c0a-bd09-9eb9a7e5788d"
2025-11-11T14:15:59.888476Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:444: Create database bootstrap. CloudId: mock_cloud Scope: yandexcloud://my_folder Actor id: [1:7571471401164575869:2170]
2025-11-11T14:15:59.888529Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:655: CreateQueryRequest: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "SELECT unwrap(42 / 0) AS error_column"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "90e4af93-8d11-4c0a-bd09-9eb9a7e5788d"
2025-11-11T14:15:59.888537Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:69: Request actor. Actor id: [1:7571471401164575870:2171]
2025-11-11T14:15:59.888551Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:253: Create rate limiter resource for cloud with limit 100000ms
2025-11-11T14:15:59.888585Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create rate limiter resource "mock_cloud" actor
2025-11-11T14:15:59.888586Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud"
2025-11-11T14:15:59.893321Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created rate limiter resource "mock_cloud"
2025-11-11T14:15:59.893333Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create rate limiter resource "mock_cloud": 
2025-11-11T14:15:59.893356Z :YQ_CONTROL_PLANE_PROXY DEBUG: request_actor.h:277: Create response from rate limiter service. Success: 1
2025-11-11T14:15:59.893945Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:226: CreateQueryRequest: {content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "SELECT unwrap(42 / 0) AS error_column"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "90e4af93-8d11-4c0a-bd09-9eb9a7e5788d"
} [root@builtin, **** (B6C6F477)] 
2025-11-11T14:15:59.898213Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.analyticsQuery.count=0/100 NOTIFY CHANGE
2025-11-11T14:15:59.898231Z :FQ_QUOTA_SERVICE TRACE: quota_manager.cpp:510: cloud.mock_cloud.yq.streamingQuery.count=0/100 NOTIFY CHANGE
2025-11-11T14:16:00.048207Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: CreateQueryRequest - CreateQueryResult: {query_id: "ptqudslmr7s5hin4c4uh" } SUCCESS
2025-11-11T14:16:00.054075Z :TICKET_PARSER DEBUG: ticket_parser_impl.h:1828: Ticket **** (B6C6F477) (ipv6:[::1]:47018) has now valid token of root@builtin
2025-11-11T14:16:00.054150Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.054179Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:313: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7571471405459543171:2175]
2025-11-11T14:16:00.054190Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:375: Cloud id: mock_cloud Folder id: my_folder
2025-11-11T14:16:00.054201Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.054230Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:176: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7571471405459543172:2176]
2025-11-11T14:16:00.054240Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:237: Subject Type: federated_account Token: **** (B6C6F477)
2025-11-11T14:16:00.054247Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.054255Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:69: Request actor. Actor id: [1:7571471405459543173:2177]
2025-11-11T14:16:00.054284Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudslmr7s5hin4c4uh"
} [root@builtin, **** (B6C6F477)] 
2025-11-11T14:16:00.097741Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudslmr7s5hin4c4uh" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1762870559 nanos: 893369000 } modified_at { seconds: 1762870559 nanos: 893369000 } revision: 1 } execute_mode: RUN status: STARTING last_job_query_revision: 1 last_job_id: "ptjudslmr7s5gv9d6q4j-ptqudslmr7s5hin4c4uh" started_by: "root@builtin" submitted_at { seconds: 1762870559 nanos: 893369000 } } content { type: ANALYTICS name: "simple" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "SELECT unwrap(42 / 0) AS error_column" syntax: YQL_V1 } } } SUCCESS
2025-11-11T14:16:00.536001Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:16:00.536717Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:16:00.536736Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:16:00.536740Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-11-11T14:16:00.537721Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Host: ghrun-vb3yoermsa, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-11-11T14:16:00.537755Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
host: "ghrun-vb3yoermsa"
node_id: 1
}
2025-11-11T14:16:00.543887Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudslmr7s5hin4c4uh RetryRate: 0 RetryCounter: 0 At: 2025-11-11T14:15:59.989972Z
2025-11-11T14:16:00.569290Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-11-11T14:16:00.600624Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.600675Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:313: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7571471405459543182:2185]
2025-11-11T14:16:00.600706Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:375: Cloud id: mock_cloud Folder id: my_folder
2025-11-11T14:16:00.600748Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.600778Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:176: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7571471405459543183:2186]
2025-11-11T14:16:00.600791Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:237: Subject Type: federated_account Token: **** (B6C6F477)
2025-11-11T14:16:00.600803Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.600811Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:69: Request actor. Actor id: [1:7571471405459543184:2187]
2025-11-11T14:16:00.600835Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudslmr7s5hin4c4uh"
} [root@builtin, **** (B6C6F477)] 
2025-11-11T14:16:00.606144Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudslmr7s5hin4c4uh" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1762870559 nanos: 893369000 } modified_at { seconds: 1762870559 nanos: 893369000 } revision: 1 } execute_mode: RUN status: STARTING last_job_query_revision: 1 last_job_id: "ptjudslmr7s5gv9d6q4j-ptqudslmr7s5hin4c4uh" started_by: "root@builtin" submitted_at { seconds: 1762870559 nanos: 893369000 } } content { type: ANALYTICS name: "simple" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "SELECT unwrap(42 / 0) AS error_column" syntax: YQL_V1 } } } SUCCESS
2025-11-11T14:16:00.650641Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudslmr7s5hin4c4uh" } job_id { value: "ptjudslmr7s5gv9d6q4j" } generation: 1 text: "SELECT unwrap(42 / 0) AS error_column" 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: "simple" deadline { seconds: 1762956960 nanos: 594608000 } 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: 1762870559 nanos: 893369000 } } } SUCCESS
2025-11-11T14:16:00.650695Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Host: ghrun-vb3yoermsa, Tenant: /default, Got CP::GetTask Response
2025-11-11T14:16:00.652460Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:00.652467Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-11-11T14:16:00.652478Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudslmr7s5hin4c4uh UserId: root@builtin AuthToken: **** (B6C6F477)
2025-11-11T14:16:00.652891Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe5, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:16:00.652901Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudslmr7s5hin4c4uh Start run actor. Compute state: STARTING
2025-11-11T14:16:00.652905Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudslmr7s5hin4c4uh FillConnections
2025-11-11T14:16:00.652951Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudslmr7s5hin4c4uh Run actors params: { QueryId: ptqudslmr7s5hin4c4uh CloudId: mock_cloud UserId: root@builtin Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 PreviousQueryRevision: 1 Connections: 0 Bindings: 0 AccountIdSignatures: 0 QueryType: ANALYTICS ExecuteMode: RUN ResultId: ptrudslmr750osc1njdj StateLoadMode: EMPTY StreamingDisposition: {  } Status: STARTING DqGraphs: 0 DqGraphIndex: 0 Resource.TopicConsumers: 0 }
2025-11-11T14:16:00.652967Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:571: QueryId: ptqudslmr7s5hin4c4uh Start rate limiter resource creator
2025-11-11T14:16:00.652983Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Start Pinger
2025-11-11T14:16:00.653014Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward ping request: { started_at { seconds: 1762870560 nanos: 652000000 } }
2025-11-11T14:16:00.653018Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward request Private::PingTask
2025-11-11T14:16:00.653036Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward ping request: { status: RUNNING resources { rate_limiter: PREPARE compilation: PREPARE } }
2025-11-11T14:16:00.653608Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe5, Host: ghrun-vb3yoermsa, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-11-11T14:16:00.653640Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe5"
host: "ghrun-vb3yoermsa"
node_id: 1
}
2025-11-11T14:16:00.653766Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::PingTask with size: 129 bytes
2025-11-11T14:16:00.653770Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:78: PrivateCreateRateLimiterResource - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::CreateRateLimiterResource with size: 97 bytes
2025-11-11T14:16:00.654060Z :YQ_CONTROL_PLANE_STORAGE TRACE: request_actor.h:57: CreateRateLimiterResourceRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
scope: "yandexcloud://my_folder"
tenant: "/default"
} 
2025-11-11T14:16:00.654145Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
scope: "yandexcloud://my_folder"
started_at {
  seconds: 1762870560
  nanos: 652000000
}
deadline {
  seconds: 1762956960
  nanos: 594608000
}
tenant: "/default"
}
2025-11-11T14:16:00.657204Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-11-11T14:16:00.657248Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe5, Host: ghrun-vb3yoermsa, Tenant: /default, Got CP::GetTask Response
2025-11-11T14:16:00.657594Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:00.657600Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:00.695508Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:74: CreateRateLimiterResourceRequest. Got response from database: SUCCESS
2025-11-11T14:16:00.695592Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run create rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh" actor
2025-11-11T14:16:00.695593Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.696601Z :YQ_RATE_LIMITER DEBUG: schema.cpp:261: Trying to recursively create rate limiter resource "mock_cloud/my_folder"
2025-11-11T14:16:00.696607Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder"
2025-11-11T14:16:00.698853Z :YQ_RATE_LIMITER DEBUG: schema.cpp:244: Successfully created rate limiter resource "mock_cloud/my_folder"
2025-11-11T14:16:00.698859Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call create rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.701647Z :YQ_RATE_LIMITER DEBUG: schema.cpp:155: Successfully created rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:00.701657Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for create rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh": 
2025-11-11T14:16:00.701685Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:180: Got create response from rate limiter service
2025-11-11T14:16:00.701704Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:102: PrivateCreateRateLimiterResource - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::CreateRateLimiterResourceResponse
2025-11-11T14:16:00.701969Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1477: QueryId: ptqudslmr7s5hin4c4uh Rate limiter resource creation finished. Success: 1
2025-11-11T14:16:00.701987Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1966: QueryId: ptqudslmr7s5hin4c4uh Compiling query ...
2025-11-11T14:16:00.704716Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward ping request: { status: RUNNING resources { rate_limiter: READY compilation: PREPARE rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter" } }
2025-11-11T14:16:00.875609Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1762870580 nanos: 785000000 } } SUCCESS
2025-11-11T14:16:00.875680Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::PingTaskResponse
2025-11-11T14:16:00.875979Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Ping response success: { expired_at { seconds: 1762870580 nanos: 785000000 } }
2025-11-11T14:16:00.875991Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward request Private::PingTask
2025-11-11T14:16:00.876009Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudslmr7s5hin4c4uh Forward ping response. Success: 1. Cookie: 2
2025-11-11T14:16:00.876458Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::PingTask with size: 123 bytes
2025-11-11T14:16:00.876515Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
status: RUNNING
resources {
  rate_limiter: PREPARE
  compilation: PREPARE
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1762956960
  nanos: 594608000
}
tenant: "/default"
}
2025-11-11T14:16:00.876848Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.876 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_facade.cpp:899: Execution of [ParseSql] took 171ms
2025-11-11T14:16:00.877220Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_facade.cpp:940: Execution of [Compile] took 343us
2025-11-11T14:16:00.877355Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [DQ] yql_dq_provider.cpp:109: OpenSession ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud
2025-11-11T14:16:00.877367Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [DQ] yql_dq_provider.cpp:112: OpenSession ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud complete in 0
2025-11-11T14:16:00.877476Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_optimize.cpp:671: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ExpandApply] took 18us
2025-11-11T14:16:00.877562Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [core eval] yql_eval_expr.cpp:421: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} EvaluateExpression - start
2025-11-11T14:16:00.877689Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [core eval] yql_eval_expr.cpp:1204: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} EvaluateExpression - finish
2025-11-11T14:16:00.877849Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [PQ] yql_pq_helpers.cpp:100: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Ids to resolve: 0
2025-11-11T14:16:00.877952Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.877 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [RESULT] yql_result_provider.cpp:1415: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} RewriteIO
2025-11-11T14:16:00.878529Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 552us
2025-11-11T14:16:00.878543Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-11-11T14:16:00.878587Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 35us
2025-11-11T14:16:00.878620Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.878631Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.878656Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 17us
2025-11-11T14:16:00.878676Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [UpdateCompletness] took 7us
2025-11-11T14:16:00.878714Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [EliminateCommonSubExpressions] took 31us
2025-11-11T14:16:00.878765Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"error_column"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedSqlProject (AsList (AsStruct)) '((SqlProjectItem (ListType (StructType)) '"error_column" (lambda '($5) (Unwrap (/ (Int32 '"42") (Int32 '"0")))))))) $3))
(return (Commit! $4 $2))
)

2025-11-11T14:16:00.878922Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_co_simple1.cpp:6780: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Expand OrderedSqlProject
2025-11-11T14:16:00.878985Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.878 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.879034Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 41us
2025-11-11T14:16:00.879051Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 10us
2025-11-11T14:16:00.879071Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.879079Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-11-11T14:16:00.879101Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 16us
2025-11-11T14:16:00.879114Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [UpdateCompletness] took 5us
2025-11-11T14:16:00.879140Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [EliminateCommonSubExpressions] took 21us
2025-11-11T14:16:00.879177Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"error_column"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedFlatMap (AsList (AsStruct)) (lambda '($5) (AsList (FlattenMembers '('"" (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))))))) $3))
(return (Commit! $4 $2))
)

2025-11-11T14:16:00.879200Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_opt_utils.cpp:896: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Enumerate struct literal for FlattenMembers
2025-11-11T14:16:00.879237Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.879266Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 23us
2025-11-11T14:16:00.879280Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 6us
2025-11-11T14:16:00.879300Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.879312Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-11-11T14:16:00.879330Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 11us
2025-11-11T14:16:00.879341Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [UpdateCompletness] took 4us
2025-11-11T14:16:00.879365Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [EliminateCommonSubExpressions] took 18us
2025-11-11T14:16:00.879397Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"error_column"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (OrderedFlatMap (AsList (AsStruct)) (lambda '($5) (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))))) $3))
(return (Commit! $4 $2))
)

2025-11-11T14:16:00.879427Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_co_simple1.cpp:2115: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Fuse OrderedFlatMap over AsList
2025-11-11T14:16:00.879463Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.879483Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 12us
2025-11-11T14:16:00.879494Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 5us
2025-11-11T14:16:00.879504Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [UpdateCompletness] took 4us
2025-11-11T14:16:00.879526Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [EliminateCommonSubExpressions] took 16us
2025-11-11T14:16:00.879553Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"error_column"))))
(let $4 (ResWrite! $1 $2 (Key) (Unordered (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))) $3))
(return (Commit! $4 $2))
)

2025-11-11T14:16:00.879567Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_co_simple1.cpp:6497: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Drop Unordered over AsList
2025-11-11T14:16:00.879595Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.879609Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 8us
2025-11-11T14:16:00.879619Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 4us
2025-11-11T14:16:00.879629Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [UpdateCompletness] took 4us
2025-11-11T14:16:00.879648Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [EliminateCommonSubExpressions] took 13us
2025-11-11T14:16:00.879671Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.879 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"error_column"))))
(let $4 (ResWrite! $1 $2 (Key) (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0")))))) $3))
(return (Commit! $4 $2))
)

2025-11-11T14:16:00.880047Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} DqReplicateStageMultiOutput: start traverse node #209090, Commit!
2025-11-11T14:16:00.880094Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [RESULT] yql_result_provider.cpp:801: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} ResFill
2025-11-11T14:16:00.880122Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 0us
2025-11-11T14:16:00.880146Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [TypeAnnotationTransformer::DoTransform] took 17us
2025-11-11T14:16:00.880160Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [ConstraintTransformer::DoTransform] took 8us
2025-11-11T14:16:00.880171Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [UpdateCompletness] took 3us
2025-11-11T14:16:00.880193Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution of [EliminateCommonSubExpressions] took 15us
2025-11-11T14:16:00.880229Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} OptimizedExpr:
(
(let $1 (Configure! world (DataSource '"config") '"DqEngine" '"force"))
(let $2 (DataSink 'result))
(let $3 '('('type) '('autoref) '('columns '('"error_column"))))
(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0")))))) $3 '"dq"))
(return (Commit! $4 $2))
)

2025-11-11T14:16:00.880336Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} DqReplicateStageMultiOutput: start traverse node #209094, Commit!
2025-11-11T14:16:00.880400Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:61: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Begin, root #209094
2025-11-11T14:16:00.880407Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:74: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Collect unused nodes for root #209094, status: Ok
2025-11-11T14:16:00.880413Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:411: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} {0}, callable #209094 <Commit!>
2025-11-11T14:16:00.880419Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:411: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} {1}, callable #209093 <ResFill!>
2025-11-11T14:16:00.880428Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:411: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} {2}, callable #208965 <Configure!>
2025-11-11T14:16:00.880437Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:411: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} {2}, callable #208965 <Configure!>
2025-11-11T14:16:00.880445Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:630: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Node #208965<Configure!> finished execution
2025-11-11T14:16:00.880452Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:647: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Node #208965<Configure!> created 0 trackable nodes: 
2025-11-11T14:16:00.880458Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:411: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} {1}, callable #209093 <ResFill!>
2025-11-11T14:16:00.880497Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [DQ] yql_dq_exectransformer.cpp:890: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Executing Result (UniqueId=209103)
2025-11-11T14:16:00.880568Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [core] type_ann_expr.cpp:159: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Repeated callable stats:
ToStream=1

2025-11-11T14:16:00.880578Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [TypeAnnotationTransformer::DoTransform] took 20us
2025-11-11T14:16:00.880586Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [TypeAnnotationTransformer::DoTransform] took 1us
2025-11-11T14:16:00.880596Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [ConstraintTransformer::DoTransform] took 4us
2025-11-11T14:16:00.880605Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [UpdateCompletness] took 3us
2025-11-11T14:16:00.880618Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [EliminateCommonSubExpressionsForSubGraph] took 6us
2025-11-11T14:16:00.880634Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:00.880 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core peephole] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} PeepHoleOpt:
(
(return (Iterator (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))))
)

2025-11-11T14:16:00.886302Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1762870580 nanos: 881000000 } } SUCCESS
2025-11-11T14:16:00.886341Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::PingTaskResponse
2025-11-11T14:16:00.886643Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Ping response success: { expired_at { seconds: 1762870580 nanos: 881000000 } }
2025-11-11T14:16:00.886662Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward request Private::PingTask
2025-11-11T14:16:00.886670Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudslmr7s5hin4c4uh Forward ping response. Success: 1. Cookie: 0
2025-11-11T14:16:00.887105Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::PingTask with size: 192 bytes
2025-11-11T14:16:00.887159Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
status: RUNNING
resources {
  rate_limiter: READY
  compilation: PREPARE
  rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1762956960
  nanos: 594608000
}
tenant: "/default"
}
2025-11-11T14:16:00.934894Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1762870580 nanos: 930000000 } } SUCCESS
2025-11-11T14:16:00.934934Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::PingTaskResponse
2025-11-11T14:16:00.935633Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Ping response success: { expired_at { seconds: 1762870580 nanos: 930000000 } }
2025-11-11T14:16:00.935667Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudslmr7s5hin4c4uh Forward ping response. Success: 1. Cookie: 0
2025-11-11T14:16:01.047176Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core dq] dq_opt_phy_finalizing.cpp:488: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} DqReplicateStageMultiOutput: start traverse node #209105, Iterator
2025-11-11T14:16:01.047231Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core dq] dq_opt_build.cpp:235: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} [DQ/Build/TransformConsumers] (
(return (Iterator (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))))
)

2025-11-11T14:16:01.047252Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core dq] dq_opt_build.cpp:325: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} [DQ/Build/TransformPhysical] (
(return (Iterator (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))))
)

2025-11-11T14:16:01.047422Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] type_ann_expr.cpp:48: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [TypeAnnotationTransformer::DoTransform] took 5us
2025-11-11T14:16:01.047440Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_constraint.cpp:3312: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [ConstraintTransformer::DoTransform] took 3us
2025-11-11T14:16:01.047462Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:638: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [UpdateCompletness] took 4us
2025-11-11T14:16:01.047486Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [perf] yql_expr_csee.cpp:651: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} Execution of [EliminateCommonSubExpressionsForSubGraph] took 15us
2025-11-11T14:16:01.047510Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core peephole] yql_out_transformers.cpp:62: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} PeepHoleOpt:
(
(return (Iterator (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))))
)

2025-11-11T14:16:01.047617Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.047 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [DQ] yql_dq_exectransformer.cpp:2143: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud/#209093/#209103} After PeepHole
(
(return (Iterator (AsList (AsStruct '('"error_column" (Unwrap (/ (Int32 '"42") (Int32 '"0"))))))))
)

2025-11-11T14:16:01.048194Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:519: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Register async execution for node #209093
2025-11-11T14:16:01.048221Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:89: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Finish, output #209094, status: Async
2025-11-11T14:16:01.048282Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:928: QueryId: ptqudslmr7s5hin4c4uh Graph (execution) with tasks: 1
2025-11-11T14:16:01.048314Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:135: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Completed async execution for node #209093
2025-11-11T14:16:01.048329Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 DEBUG ydbd(pid=932090, tid=0x00007FF3723D1640) [DQ] yql_dq_exectransformer.cpp:1083: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud WrapFutureCallback
2025-11-11T14:16:01.048336Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [DQ] yql_dq_exectransformer.cpp:1086: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Execution Result complete, duration: 0.167833s
2025-11-11T14:16:01.048374Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:155: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} State is ExecutionComplete after apply async changes for node #209093
2025-11-11T14:16:01.048389Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:61: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Begin, root #209094
2025-11-11T14:16:01.048396Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:74: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Collect unused nodes for root #209094, status: Ok
2025-11-11T14:16:01.048404Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 TRACE ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:411: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} {0}, callable #209094 <Commit!>
2025-11-11T14:16:01.048422Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:630: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Node #209094<Commit!> finished execution
2025-11-11T14:16:01.048439Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:647: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Node #209094<Commit!> created 0 trackable nodes: 
2025-11-11T14:16:01.048452Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:89: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Finish, output #209094, status: Ok
2025-11-11T14:16:01.048459Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.048 INFO  ydbd(pid=932090, tid=0x00007FF3723D1640) [core exec] yql_execution.cpp:95: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Creating finalizing transformer, output #209094
2025-11-11T14:16:01.048602Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudslmr7s5hin4c4uh Overall dq tasks: 1
2025-11-11T14:16:01.048759Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:758: QueryId: ptqudslmr7s5hin4c4uh Graph 0
2025-11-11T14:16:01.048826Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward ping request: { ast: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"error_column\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"error_column\" (Unwrap (/ (Int32 \'\"42\") (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-11-11T14:16:01.048834Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward request Private::PingTask
2025-11-11T14:16:01.048877Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward ping request: { result_set_count: 1 result_set_meta { column { name: "error_column" type { type_id: INT32 } } } dq_graph: "\022\320\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(8d73c3b672aec01e659c446142c7a17d47fb7b86\032\023dq_vanilla_job.lite \002H\001RClocal/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiterZ)mock_cloud/my_folder/ptqudslmr7s5hin4c4uh`\200\200\200\200 \312\001\010/default\"\024\037\000\205\002\203\0020error_column/*\014error_column2qptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#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\270\001\010\001\022\263\001\037\000\005\205\006\213\000\205\000\204\205\002\203\0020error_column\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?&\250\003?(\000\007\002\003?\032\000\003?\034\000\003?\036\002\003? \020\036\004\000\001\001\006\001/" }
2025-11-11T14:16:01.049343Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::PingTask with size: 634 bytes
2025-11-11T14:16:01.049452Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
ast: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (DataSink \'result))\n(let $3 \'(\'(\'type) \'(\'autoref) \'(\'columns \'(\'\"error_column\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"error_column\" (Unwrap (/ (Int32 \'\"42\") (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: 1762956960
  nanos: 594608000
}
tenant: "/default"
}
2025-11-11T14:16:01.060893Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1762870581 nanos: 56000000 } } SUCCESS
2025-11-11T14:16:01.060931Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::PingTaskResponse
2025-11-11T14:16:01.061221Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Ping response success: { expired_at { seconds: 1762870581 nanos: 56000000 } }
2025-11-11T14:16:01.061236Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward request Private::PingTask
2025-11-11T14:16:01.061259Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudslmr7s5hin4c4uh Forward ping response. Success: 1. Cookie: 0
2025-11-11T14:16:01.061613Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::PingTask with size: 1169 bytes
2025-11-11T14:16:01.061705Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
result_set_count: 1
result_set_meta {
  column {
    name: "error_column"
    type {
      type_id: INT32
    }
  }
}
dq_graph: "\022\320\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(8d73c3b672aec01e659c446142c7a17d47fb7b86\032\023dq_vanilla_job.lite \002H\001RClocal/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiterZ)mock_cloud/my_folder/ptqudslmr7s5hin4c4uh`\200\200\200\200 \312\001\010/default\"\024\037\000\205\002\203\0020error_column/*\014error_column2qptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#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\270\001\010\001\022\263\001\037\000\005\205\006\213\000\205\000\204\205\002\203\0020error_column\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?&\250\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: 1762956960
  nanos: 594608000
}
tenant: "/default"
}
2025-11-11T14:16:01.080050Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1762870581 nanos: 72000000 } } SUCCESS
2025-11-11T14:16:01.080081Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::PingTaskResponse
2025-11-11T14:16:01.080438Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Ping response success: { expired_at { seconds: 1762870581 nanos: 72000000 } }
2025-11-11T14:16:01.080470Z :FQ_RUN_ACTOR TRACE: run_actor.cpp:861: QueryId: ptqudslmr7s5hin4c4uh Forward ping response. Success: 1. Cookie: 1
2025-11-11T14:16:01.080488Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:911: QueryId: ptqudslmr7s5hin4c4uh Overall dq tasks: 1
2025-11-11T14:16:01.080530Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward ping request: { owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4" query_id { value: "ptqudslmr7s5hin4c4uh" } status: RUNNING result_set_count: 1 result_set_meta { column { name: "error_column" type { type_id: INT32 } } } resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter" } scope: "yandexcloud://my_folder" }
2025-11-11T14:16:01.080533Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward request Private::PingTask
2025-11-11T14:16:01.080686Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.080 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:83: _AllResultsBytesLimit = 100000
2025-11-11T14:16:01.080692Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.080 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:86: _RowsLimitPerWrite = 1000
2025-11-11T14:16:01.080692Z :FQ_RESULT_WRITER INFO: result_writer.cpp:62: TraceId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud Bootstrap
2025-11-11T14:16:01.080733Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1653: QueryId: ptqudslmr7s5hin4c4uh Executer: [1:7571471409754510509:2216], Controller: [1:7571471409754510511:2218], ResultIdActor: [1:7571471409754510510:2217]
2025-11-11T14:16:01.080752Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.080 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:140: {ptqudslmr7s5hin4c4uh} TDqExecuter::OnGraph
2025-11-11T14:16:01.080779Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.080 INFO  ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:157: {ptqudslmr7s5hin4c4uh} Trying to allocate 1 workers [EnableComputeActor=1]
2025-11-11T14:16:01.080859Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.080 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:247: {ptqudslmr7s5hin4c4uh} Dq timeouts are set to: 0.000000s (global), 600.000000s (workers allocation fail), 30.000000s (workers allocation warn) 
2025-11-11T14:16:01.080956Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::PingTask with size: 218 bytes
2025-11-11T14:16:01.081013Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
status: RUNNING
result_set_count: 1
result_set_meta {
  column {
    name: "error_column"
    type {
      type_id: INT32
    }
  }
}
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: NOT_NEEDED
  rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1762956960
  nanos: 594608000
}
tenant: "/default"
}
2025-11-11T14:16:01.081499Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:125: TEvAllocateWorkersResponse Nodes {
  ResourceId: 4294967297
  Worker {
    NodeId: 1
    Guid: "56a102af-49e6240a-ed1c51a5-969b4664,ghrun-vb3yoermsa"
  }
}
2025-11-11T14:16:01.081692Z :KQP_COMPUTE DEBUG: dq_compute_actor_impl.h:134: SelfId: [1:7571471409754510516:2223], TxId: ptqudslmr7s5hin4c4uh, task: 1. Ctx: {}. Start compute actor [1:7571471409754510516:2223], task: 1
2025-11-11T14:16:01.081706Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.081 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:358: {ptqudslmr7s5hin4c4uh} TDqExecuter::TEvAllocateWorkersResponse
2025-11-11T14:16:01.081714Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.081 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:384: {ptqudslmr7s5hin4c4uh} Allocated resource 4294967297
2025-11-11T14:16:01.081719Z :KQP_COMPUTE DEBUG: dq_async_compute_actor.cpp:113: SelfId: [1:7571471409754510516:2223], TxId: ptqudslmr7s5hin4c4uh, task: 1. Ctx: {}. Use CPU quota: 1. Rate limiter resource: { "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter", "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh" }
2025-11-11T14:16:01.081734Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.081 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:401: {ptqudslmr7s5hin4c4uh} WorkerInfo: Id:56a102af-49e6240a-ed1c51a5-969b4664,ghrun-vb3yoermsa,NodeId:1,StageId:1
2025-11-11T14:16:01.081741Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.081 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:402: {ptqudslmr7s5hin4c4uh} TaskInfo: 0/1
2025-11-11T14:16:01.081746Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.081 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:404: {ptqudslmr7s5hin4c4uh}  ObjectId: 8d73c3b672aec01e659c446142c7a17d47fb7b86, dq_vanilla_job.lite, 
2025-11-11T14:16:01.081756Z :YQL_PROXY INFO: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.081 INFO  ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:415: {ptqudslmr7s5hin4c4uh} 1 workers allocated
2025-11-11T14:16:01.081808Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.081 TRACE ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:271: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud}  [1:7571471409754510511:2218] ExportStats Summary
2025-11-11T14:16:01.081829Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.081 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:526: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Ready State: [1:7571471409754510511:2218]
2025-11-11T14:16:01.083935Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1491: SelfId: [1:7571471409754510516:2223], TxId: ptqudslmr7s5hin4c4uh, task: 1. Ctx: {}. Poll inputs
2025-11-11T14:16:01.083945Z :KQP_COMPUTE TRACE: dq_compute_actor_impl.h:1506: SelfId: [1:7571471409754510516:2223], TxId: ptqudslmr7s5hin4c4uh, task: 1. Ctx: {}. Poll sources
2025-11-11T14:16:01.083946Z :KQP_COMPUTE TRACE: dq_async_compute_actor.cpp:694: SelfId: [1:7571471409754510516:2223], TxId: ptqudslmr7s5hin4c4uh, task: 1. Ctx: {}. DoExecuteImpl: 0
2025-11-11T14:16:01.083968Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.083 TRACE ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:160: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} [1:7571471409754510511:2218] EvState TaskId: 1 State: 2 PingCookie: 0 StatusCode: UNSPECIFIED
2025-11-11T14:16:01.083977Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.083 TRACE ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:201: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud}  [1:7571471409754510511:2218] Executing TaskId: 1
2025-11-11T14:16:01.083991Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.083 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:561: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} Update channels
2025-11-11T14:16:01.084511Z :KQP_COMPUTE ERROR: dq_compute_actor_impl.h:1215: SelfId: [1:7571471409754510516:2223], TxId: ptqudslmr7s5hin4c4uh, task: 1. Ctx: {}. Handle abort execution event from: [1:7571471409754510518:2223], 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-11-11T14:16:01.084523Z :KQP_COMPUTE DEBUG: dq_compute_actor_channels.cpp:494: TxId: ptqudslmr7s5hin4c4uh, task: 1. pass away
2025-11-11T14:16:01.085048Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.083 TRACE ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:577: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} 1 Update { Id: 1 SrcTaskId: 2 DstTaskId: 1 SrcEndpoint { ActorId { RawX1: 0 RawX2: 0 } } DstEndpoint { ActorId { RawX1: 7571471409754510510 RawX2: 4503603922340009 } } SrcStageId: 1 }
2025-11-11T14:16:01.085083Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.085 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:130: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} AbortExecution from [1:7571471409754510518:2223]:BAD_REQUEST { <main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional, code: 6000 }
2025-11-11T14:16:01.085091Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.085 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:598: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#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-11-11T14:16:01.085121Z :YQL_PROXY TRACE: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.085 TRACE ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:160: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} [1:7571471409754510511:2218] EvState TaskId: 1 State: 1 PingCookie: 0 StatusCode: UNSPECIFIED
2025-11-11T14:16:01.085138Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.085 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:598: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#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-11-11T14:16:01.085126Z) } UNSPECIFIED
2025-11-11T14:16:01.085143Z :YQL_PROXY WARN: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud 2025-11-11 14:16:01.085 WARN  ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] task_controller_impl.h:601: {ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud} OnError IGNORED when Finished
2025-11-11T14:16:01.085155Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.085 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:304: {ptqudslmr7s5hin4c4uh} OnFailure, status=6, issues size=1, sender=[1:7571471409754510511:2218]
2025-11-11T14:16:01.085164Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.085 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:315: {ptqudslmr7s5hin4c4uh} Issues: <main>: Error: (NKikimr::NMiniKQL::TTerminateException) Terminate was called, reason(43): <main>:1:8: Failed to unwrap empty optional
, code: 6000

2025-11-11T14:16:01.085173Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.085 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:284: {ptqudslmr7s5hin4c4uh} 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-11-11T14:16:01.085200Z :FQ_RESULT_WRITER DEBUG: result_writer.cpp:126: TraceId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud Send response to executer
2025-11-11T14:16:01.085208Z :YQL_PROXY DEBUG: log.cpp:64: SessionId: ptqudslmr7s5hin4c4uh 2025-11-11 14:16:01.085 DEBUG ydbd(pid=932090, tid=0x00007FF36FBCC640) [DQ] executer_actor.cpp:341: {ptqudslmr7s5hin4c4uh} OnQueryResponse status=6 issuses_size=1
2025-11-11T14:16:01.085223Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1234: QueryId: ptqudslmr7s5hin4c4uh Query response BAD_REQUEST. Result set index: 0. Issues count: 1. Rows count: 0
2025-11-11T14:16:01.085227Z :FQ_RESULT_WRITER INFO: result_writer.cpp:87: TraceId: ptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#mock_cloud FinishWrite, Records: 0 HasError: 0 Size: 0 Rows: 0 FreeSpace: 67108864 Duration: 0.004574s AvgSpeed: 0 ResultChunks.size(): 0
2025-11-11T14:16:01.085307Z :FQ_RUN_ACTOR WARN: run_actor.cpp:1914: QueryId: ptqudslmr7s5hin4c4uh ResignQuery, status BAD_REQUEST
2025-11-11T14:16:01.085311Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1919: QueryId: ptqudslmr7s5hin4c4uh SendPingAndPassAway, FinalQueryStatus COMPUTE_STATUS_UNSPECIFIED
2025-11-11T14:16:01.085324Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:536: QueryId: ptqudslmr7s5hin4c4uh PassAway
2025-11-11T14:16:01.085462Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward ping request: { owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4" query_id { value: "ptqudslmr7s5hin4c4uh" } result_id { value: "ptrudslmr750osc1njdj" } status: RUNNING result_set_count: 1 statistics: "{\"Graph=0\":{\"Actor\":{\"ActorCreateTimeUs\":{\"sum\":\"157us\",\"count\":1,\"avg\":\"157us\",\"max\":\"157us\",\"min\":\"157us\"}},\"AllocateWorkersUs\":{\"sum\":\"862us\",\"count\":1,\"avg\":\"862us\",\"max\":\"862us\",\"min\":\"862us\"},\"ExecutionTimeUs\":{\"sum\":\"3ms\",\"count\":1,\"avg\":\"3ms\",\"max\":\"3ms\",\"min\":\"3ms\"},\"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: "error_column" 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-11-11T14:16:01.085126Z)" severity: 1 } status_code: BAD_REQUEST resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter" } scope: "yandexcloud://my_folder" }
2025-11-11T14:16:01.091593Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1762870581 nanos: 85000000 } } SUCCESS
2025-11-11T14:16:01.091673Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::PingTaskResponse
2025-11-11T14:16:01.092906Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Ping response success: { expired_at { seconds: 1762870581 nanos: 85000000 } }
2025-11-11T14:16:01.092919Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Forward request Private::PingTask
2025-11-11T14:16:01.094540Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Request CP::PingTask with size: 1071 bytes
2025-11-11T14:16:01.094622Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe4"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
result_id {
  value: "ptrudslmr750osc1njdj"
}
status: RUNNING
result_set_count: 1
statistics: "{\"Graph=0\":{\"Actor\":{\"ActorCreateTimeUs\":{\"sum\":\"157us\",\"count\":1,\"avg\":\"157us\",\"max\":\"157us\",\"min\":\"157us\"}},\"AllocateWorkersUs\":{\"sum\":\"862us\",\"count\":1,\"avg\":\"862us\",\"max\":\"862us\",\"min\":\"862us\"},\"ExecutionTimeUs\":{\"sum\":\"3ms\",\"count\":1,\"avg\":\"3ms\",\"max\":\"3ms\",\"min\":\"3ms\"},\"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: "error_column"
    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-11-11T14:16:01.085126Z)"
  severity: 1
}
status_code: BAD_REQUEST
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: NOT_NEEDED
  rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter"
}
scope: "yandexcloud://my_folder"
deadline {
  seconds: 1762956960
  nanos: 594608000
}
tenant: "/default"
}
2025-11-11T14:16:01.099550Z :YQ_CONTROL_PLANE_STORAGE DEBUG: task_ping.cpp:427: PingTaskRequest (resign):  FAILURE BAD_REQUEST 0 2025-11-11T14:15:59.989972Z 0.000000s
2025-11-11T14:16:01.117715Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.117750Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:313: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7571471409754510524:2228]
2025-11-11T14:16:01.117759Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:375: Cloud id: mock_cloud Folder id: my_folder
2025-11-11T14:16:01.117768Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.117787Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:176: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7571471409754510525:2229]
2025-11-11T14:16:01.117797Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:237: Subject Type: federated_account Token: **** (B6C6F477)
2025-11-11T14:16:01.117807Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.117815Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:69: Request actor. Actor id: [1:7571471409754510526:2230]
2025-11-11T14:16:01.117834Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudslmr7s5hin4c4uh"
} [root@builtin, **** (B6C6F477)] 
2025-11-11T14:16:01.130017Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudslmr7s5hin4c4uh" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1762870559 nanos: 893369000 } modified_at { seconds: 1762870559 nanos: 893369000 } revision: 1 } started_at { seconds: 1762870560 nanos: 652000000 } execute_mode: RUN status: RUNNING last_job_query_revision: 1 last_job_id: "ptjudslmr7s5gv9d6q4j-ptqudslmr7s5hin4c4uh" started_by: "root@builtin" submitted_at { seconds: 1762870559 nanos: 893369000 } } content { type: ANALYTICS name: "simple" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "SELECT unwrap(42 / 0) AS error_column" 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\":[]}}" } result_set_meta { column { name: "error_column" 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 \'(\'\"error_column\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"error_column\" (Unwrap (/ (Int32 \'\"42\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" } } } SUCCESS
2025-11-11T14:16:01.163065Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {expired_at { seconds: 1762870561 nanos: 99000000 } } SUCCESS
2025-11-11T14:16:01.163136Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4, Got CP::PingTaskResponse
2025-11-11T14:16:01.163943Z :FQ_PINGER ERROR: pinger.cpp:365: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Ping response error: Query ownership time is over: expired_at=2025-11-11T14:16:01.099000Z < now=2025-11-11T14:16:01.161443Z. Retried 0 times during 0.071715s
2025-11-11T14:16:01.163960Z :FQ_PINGER DEBUG: pinger.cpp:379: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Query finished
2025-11-11T14:16:01.163962Z :FQ_PINGER TRACE: pinger.cpp:187: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe4 Stop Pinger
2025-11-11T14:16:01.534088Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471392574641187:2087]
2025-11-11T14:16:01.534118Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:01.534136Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471392574641152:2069]
2025-11-11T14:16:01.534143Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471392574641152:2069]
2025-11-11T14:16:01.534164Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:01.536142Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:16:01.536664Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6, Host: ghrun-vb3yoermsa, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-11-11T14:16:01.536709Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe6"
host: "ghrun-vb3yoermsa"
node_id: 1
}
2025-11-11T14:16:01.538252Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:16:01.538280Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:16:01.538285Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-11-11T14:16:01.539019Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:456: Task (Query): ptqudslmr7s5hin4c4uh Lease TIMEOUT, RetryCounterUpdatedAt 2025-11-11T14:15:59.989972Z LastSeenAt: 2025-11-11T14:16:01.099716Z
2025-11-11T14:16:01.539032Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:463: Task (Query): ptqudslmr7s5hin4c4uh RetryRate: 1.999743115 RetryCounter: 1 At: 2025-11-11T14:16:01.538985Z
2025-11-11T14:16:01.546731Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {tasks { query_id { value: "ptqudslmr7s5hin4c4uh" } job_id { value: "ptjudslmr7s5gv9d6q4j" } generation: 2 dq_graph: "\022\320\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(8d73c3b672aec01e659c446142c7a17d47fb7b86\032\023dq_vanilla_job.lite \002H\001RClocal/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiterZ)mock_cloud/my_folder/ptqudslmr7s5hin4c4uh`\200\200\200\200 \312\001\010/default\"\024\037\000\205\002\203\0020error_column/*\014error_column2qptqudslmr7s5hin4c4uh#ptrudslmr750osc1njdj#yandexcloud://my_folder#819d7412-7d3f33c7-88efd6ad-785bc9fe4#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\270\001\010\001\022\263\001\037\000\005\205\006\213\000\205\000\204\205\002\203\0020error_column\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?&\250\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(42 / 0) AS error_column" 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: "error_column" type { type_id: INT32 } } } sensor_labels { key: "cloud_id" value: "mock_cloud" } sensor_labels { key: "scope" value: "yandexcloud://my_folder" } query_name: "simple" deadline { seconds: 1762956961 nanos: 541666000 } disposition { } result_limit: 20971520 execution_limit { seconds: 86400 } request_started_at { seconds: 1762870560 nanos: 652000000 } restart_count: 1 resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter" } query_syntax: YQL_V1 compute_connection { } result_ttl { seconds: 86400 } request_submitted_at { seconds: 1762870559 nanos: 893369000 } } } SUCCESS
2025-11-11T14:16:01.546773Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6, Host: ghrun-vb3yoermsa, Tenant: /default, Got CP::GetTask Response
2025-11-11T14:16:01.547067Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:01.547077Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 1
2025-11-11T14:16:01.547085Z :FQ_PENDING_FETCHER DEBUG: pending_fetcher.cpp:361: NewTask: Scope: yandexcloud://my_folder Id: ptqudslmr7s5hin4c4uh UserId: root@builtin AuthToken: **** (B6C6F477)
2025-11-11T14:16:01.547176Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe7, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:16:01.547177Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:375: QueryId: ptqudslmr7s5hin4c4uh Start run actor. Compute state: FAILING
2025-11-11T14:16:01.547180Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:469: QueryId: ptqudslmr7s5hin4c4uh FillConnections
2025-11-11T14:16:01.547209Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:2333: QueryId: ptqudslmr7s5hin4c4uh Run actors params: { QueryId: ptqudslmr7s5hin4c4uh CloudId: mock_cloud UserId: root@builtin Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6 PreviousQueryRevision: 2 Connections: 0 Bindings: 0 AccountIdSignatures: 0 QueryType: ANALYTICS ExecuteMode: RUN ResultId: ptrudslmr69n4j8n1nee StateLoadMode: EMPTY StreamingDisposition: {  } Status: FAILING DqGraphs: 1 DqGraphIndex: 0 Resource.TopicConsumers: 0 }
2025-11-11T14:16:01.547228Z :FQ_PINGER TRACE: pinger.cpp:171: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6 Start Pinger
2025-11-11T14:16:01.547252Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1827: QueryId: ptqudslmr7s5hin4c4uh Is about to finish query with status FAILED
2025-11-11T14:16:01.547257Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1508: QueryId: ptqudslmr7s5hin4c4uh Start rate limiter resource deleter
2025-11-11T14:16:01.547539Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:86: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe7, Host: ghrun-vb3yoermsa, Tenant: /default, Request CP::GetTask with size: 68 bytes
2025-11-11T14:16:01.547589Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_get.cpp:388: GetTaskRequest: {tenant: "/default"
owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe7"
host: "ghrun-vb3yoermsa"
node_id: 1
}
2025-11-11T14:16:01.547620Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:175: PrivateDeleteRateLimiterResource - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6, Request CP::DeleteRateLimiterResource with size: 97 bytes
2025-11-11T14:16:01.547664Z :YQ_CONTROL_PLANE_STORAGE TRACE: request_actor.h:57: DeleteRateLimiterResourceRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe6"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
scope: "yandexcloud://my_folder"
tenant: "/default"
} 
2025-11-11T14:16:01.549405Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: GetTaskRequest - GetTaskResult: {} SUCCESS
2025-11-11T14:16:01.549444Z :YQL_PRIVATE_PROXY DEBUG: task_get.cpp:93: PrivateGetTask - Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe7, Host: ghrun-vb3yoermsa, Tenant: /default, Got CP::GetTask Response
2025-11-11T14:16:01.549700Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:01.549707Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:266: Tasks count: 0
2025-11-11T14:16:01.579110Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:74: DeleteRateLimiterResourceRequest. Got response from database: SUCCESS
2025-11-11T14:16:01.579181Z :YQ_RATE_LIMITER DEBUG: schema.cpp:71: Run delete rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh" actor
2025-11-11T14:16:01.579186Z :YQ_RATE_LIMITER DEBUG: schema.cpp:113: Call delete rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.581562Z :YQ_RATE_LIMITER DEBUG: schema.cpp:179: Successfully deleted rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.581573Z :YQ_RATE_LIMITER DEBUG: schema.cpp:122: Reply for delete rate limiter resource "mock_cloud/my_folder/ptqudslmr7s5hin4c4uh": 
2025-11-11T14:16:01.581609Z :YQ_CONTROL_PLANE_STORAGE DEBUG: rate_limiter_resources.cpp:219: Got delete response from rate limiter service
2025-11-11T14:16:01.581625Z :YQL_PRIVATE_PROXY DEBUG: rate_limiter_resources.cpp:199: PrivateDeleteRateLimiterResource - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6, Got CP::DeleteRateLimiterResourceResponse
2025-11-11T14:16:01.581905Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1499: QueryId: ptqudslmr7s5hin4c4uh Rate limiter resource deletion finished. Success: 1. Issues: 
2025-11-11T14:16:01.581914Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:1919: QueryId: ptqudslmr7s5hin4c4uh SendPingAndPassAway, FinalQueryStatus FAILED
2025-11-11T14:16:01.581922Z :FQ_RUN_ACTOR DEBUG: run_actor.cpp:536: QueryId: ptqudslmr7s5hin4c4uh PassAway
2025-11-11T14:16:01.582044Z :FQ_PINGER TRACE: pinger.cpp:250: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6 Forward ping request: { result_id { } status: FAILED resources { rate_limiter: READY compilation: READY topic_consumers_state: NOT_NEEDED rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter" } finished_at { seconds: 1762870561 nanos: 547000000 } }
2025-11-11T14:16:01.582051Z :FQ_PINGER TRACE: pinger.cpp:393: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6 Forward request Private::PingTask
2025-11-11T14:16:01.582536Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:85: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6, Request CP::PingTask with size: 211 bytes
2025-11-11T14:16:01.582590Z :YQ_CONTROL_PLANE_STORAGE TRACE: task_ping.cpp:651: PingTaskRequest: {owner_id: "819d7412-7d3f33c7-88efd6ad-785bc9fe6"
query_id {
  value: "ptqudslmr7s5hin4c4uh"
}
result_id {
}
status: FAILED
resources {
  rate_limiter: READY
  compilation: READY
  topic_consumers_state: NOT_NEEDED
  rate_limiter_path: "local/RateLimiter_a4bbd6e0-96c0-4657-af54-e012c7717c8c/rate_limiter"
}
scope: "yandexcloud://my_folder"
finished_at {
  seconds: 1762870561
  nanos: 547000000
}
deadline {
  seconds: 1762956961
  nanos: 541666000
}
tenant: "/default"
}
2025-11-11T14:16:01.638444Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.638483Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:313: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7571471409754510548:2251]
2025-11-11T14:16:01.638503Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:375: Cloud id: mock_cloud Folder id: my_folder
2025-11-11T14:16:01.638534Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.638590Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:176: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7571471409754510549:2252]
2025-11-11T14:16:01.638612Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:237: Subject Type: federated_account Token: **** (B6C6F477)
2025-11-11T14:16:01.638640Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:01.638685Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:69: Request actor. Actor id: [1:7571471409754510550:2253]
2025-11-11T14:16:01.638728Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudslmr7s5hin4c4uh"
} [root@builtin, **** (B6C6F477)] 
2025-11-11T14:16:01.640882Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudslmr7s5hin4c4uh" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1762870559 nanos: 893369000 } modified_at { seconds: 1762870559 nanos: 893369000 } revision: 1 } started_at { seconds: 1762870560 nanos: 652000000 } execute_mode: RUN status: FAILING last_job_query_revision: 1 last_job_id: "ptjudslmr7s5gv9d6q4j-ptqudslmr7s5hin4c4uh" started_by: "root@builtin" submitted_at { seconds: 1762870559 nanos: 893369000 } } content { type: ANALYTICS name: "simple" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "SELECT unwrap(42 / 0) AS error_column" 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-11-11T14:16:01.099526Z" 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-11-11T14:16:01.085126Z)" 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: "error_column" 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 \'(\'\"error_column\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"error_column\" (Unwrap (/ (Int32 \'\"42\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" } } } SUCCESS
2025-11-11T14:16:01.649647Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: PingTaskRequest - PingTaskResult: {} SUCCESS
2025-11-11T14:16:01.649715Z :YQL_PRIVATE_PROXY DEBUG: task_ping.cpp:105: PrivatePingTask - QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6, Got CP::PingTaskResponse
2025-11-11T14:16:01.650069Z :FQ_PINGER TRACE: pinger.cpp:338: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6 Ping response success: {  }
2025-11-11T14:16:01.650083Z :FQ_PINGER DEBUG: pinger.cpp:379: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6 Query finished
2025-11-11T14:16:01.650085Z :FQ_PINGER TRACE: pinger.cpp:187: QueryId: ptqudslmr7s5hin4c4uh, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe6 Stop Pinger
2025-11-11T14:16:02.145723Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:02.145758Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:313: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7571471414049477850:2256]
2025-11-11T14:16:02.145767Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:375: Cloud id: mock_cloud Folder id: my_folder
2025-11-11T14:16:02.145777Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:02.145795Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:176: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7571471414049477851:2257]
2025-11-11T14:16:02.145809Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:237: Subject Type: federated_account Token: **** (B6C6F477)
2025-11-11T14:16:02.145817Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:02.145829Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:69: Request actor. Actor id: [1:7571471414049477852:2258]
2025-11-11T14:16:02.145849Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudslmr7s5hin4c4uh"
} [root@builtin, **** (B6C6F477)] 
2025-11-11T14:16:02.148819Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudslmr7s5hin4c4uh" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1762870559 nanos: 893369000 } modified_at { seconds: 1762870559 nanos: 893369000 } revision: 1 } started_at { seconds: 1762870560 nanos: 652000000 } finished_at { seconds: 1762870561 nanos: 547000000 } execute_mode: RUN status: FAILED last_job_query_revision: 1 last_job_id: "ptjudslmr7s5gv9d6q4j-ptqudslmr7s5hin4c4uh" started_by: "root@builtin" submitted_at { seconds: 1762870559 nanos: 893369000 } } content { type: ANALYTICS name: "simple" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "SELECT unwrap(42 / 0) AS error_column" 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-11-11T14:16:01.099526Z" 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-11-11T14:16:01.085126Z)" 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: "error_column" 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 \'(\'\"error_column\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"error_column\" (Unwrap (/ (Int32 \'\"42\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" } } } SUCCESS
2025-11-11T14:16:02.150572Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:02.150597Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:313: Resolve folder bootstrap. Folder id: my_folder Actor id: [1:7571471414049477856:2261]
2025-11-11T14:16:02.150606Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:375: Cloud id: mock_cloud Folder id: my_folder
2025-11-11T14:16:02.150616Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:02.150633Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:176: Resolve subject type bootstrap. Token: **** (B6C6F477) Actor id: [1:7571471414049477857:2262]
2025-11-11T14:16:02.150642Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:237: Subject Type: federated_account Token: **** (B6C6F477)
2025-11-11T14:16:02.150648Z :YQ_CONTROL_PLANE_PROXY TRACE: control_plane_proxy.cpp:790: DescribeQueryRequest: query_id: "ptqudslmr7s5hin4c4uh"
2025-11-11T14:16:02.150655Z :YQ_CONTROL_PLANE_PROXY TRACE: request_actor.h:69: Request actor. Actor id: [1:7571471414049477858:2263]
2025-11-11T14:16:02.150673Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_queries.cpp:641: DescribeQueryRequest: {query_id: "ptqudslmr7s5hin4c4uh"
} [root@builtin, **** (B6C6F477)] 
2025-11-11T14:16:02.152907Z :YQ_CONTROL_PLANE_STORAGE TRACE: ydb_control_plane_storage_impl.h:788: DescribeQueryRequest - DescribeQueryResult: {query { meta { common { id: "ptqudslmr7s5hin4c4uh" created_by: "root@builtin" modified_by: "root@builtin" created_at { seconds: 1762870559 nanos: 893369000 } modified_at { seconds: 1762870559 nanos: 893369000 } revision: 1 } started_at { seconds: 1762870560 nanos: 652000000 } finished_at { seconds: 1762870561 nanos: 547000000 } execute_mode: RUN status: FAILED last_job_query_revision: 1 last_job_id: "ptjudslmr7s5gv9d6q4j-ptqudslmr7s5hin4c4uh" started_by: "root@builtin" submitted_at { seconds: 1762870559 nanos: 893369000 } } content { type: ANALYTICS name: "simple" acl { visibility: PRIVATE } limits { execution_timeout { } } text: "SELECT unwrap(42 / 0) AS error_column" 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-11-11T14:16:01.099526Z" 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-11-11T14:16:01.085126Z)" 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: "error_column" 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 \'(\'\"error_column\"))))\n(let $4 (ResFill! $1 $2 (Key) (AsList (AsStruct \'(\'\"error_column\" (Unwrap (/ (Int32 \'\"42\") (Int32 \'\"0\")))))) $3 \'\"dq\"))\n(return (Commit! $4 $2))\n)\n" } } } SUCCESS
2025-11-11T14:16:02.537295Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe8, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:16:02.538625Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:16:02.538646Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:16:02.538662Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-11-11T14:16:02.549534Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:02.549554Z :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.1.1:11885: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:11885
2025-11-11T14:16:02.569284Z :YQL_NODES_MANAGER TRACE: nodes_manager.cpp:318: TNodesManagerActor::NodesHealthCheck
2025-11-11T14:16:02.577870Z :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.1.1:11885: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:11885
2025-11-11T14:16:03.535390Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:617: RowDispatcher: Send ping to [1:7571471392574641187:2087]
2025-11-11T14:16:03.535404Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:1161: RowDispatcher: TEvPrivate::TEvSendStatistic
2025-11-11T14:16:03.535420Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:314: Coordinator: TEvPing received, [1:7571471392574641152:2069]
2025-11-11T14:16:03.535423Z :FQ_ROW_DISPATCHER TRACE: coordinator.cpp:317: Coordinator: Send TEvPong to [1:7571471392574641152:2069]
2025-11-11T14:16:03.535432Z :FQ_ROW_DISPATCHER TRACE: row_dispatcher.cpp:623: RowDispatcher: NActors::TEvents::TEvPong
2025-11-11T14:16:03.538073Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe9, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:16:03.539991Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:16:03.540001Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:16:03.540005Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-11-11T14:16:03.572701Z :FQ_CONTROL_PLANE_CONFIG TRACE: control_plane_config.cpp:192: LOADED TenantInfo: State NOT changed
2025-11-11T14:16:03.646487Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:03.646506Z :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.1.1:11885: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:11885
2025-11-11T14:16:04.538487Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:329: Request Private::GetTask, Owner: 819d7412-7d3f33c7-88efd6ad-785bc9fe10, Host: ghrun-vb3yoermsa, Tenant: /default
2025-11-11T14:16:04.540530Z :FQ_ROW_DISPATCHER DEBUG: coordinator.cpp:542: Coordinator: Send TEvGetNodesRequest to NodesManager
2025-11-11T14:16:04.540552Z :YQL_NODES_MANAGER DEBUG: nodes_manager.cpp:300: TNodesManagerActor::TEvGetNodesRequest
2025-11-11T14:16:04.540557Z :FQ_ROW_DISPATCHER INFO: coordinator.cpp:573: Coordinator: Updated node info, node count: 0
2025-11-11T14:16:04.546535Z :FQ_PENDING_FETCHER TRACE: pending_fetcher.cpp:254: Got GetTask response from PrivateApi
2025-11-11T14:16:04.546557Z :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.1.1:11885: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint ghrun-vb3yoermsa.auto.internal:11885
